-- Logs begin at Wed 2025-05-21 21:24:55 CEST, end at Wed 2025-05-21 21:46:42 CEST. -- May 21 21:45:00 volumio5bern volumio[5335]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] May 21 21:45:00 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions May 21 21:45:00 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Port: 19 May 21 21:45:00 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Inverted: true May 21 21:45:00 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Delay: 10 May 21 21:45:00 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Latched: false May 21 21:45:00 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: On Pulse width: 500 May 21 21:45:00 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Off Pulse width: 500 May 21 21:45:00 volumio5bern volumio[5335]: error: Failed callmethod call: TypeError: Cannot read property 'unexport' of undefined May 21 21:45:03 volumio5bern volumio[5335]: verbose: New Socket.io Connection to 172.22.16.140 from 172.22.16.188 UA: Mozilla/5.0 (Linux; Android 15; Pixel 9 Pro XL Build/BP1A.250505.005.A1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.61 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 21 21:45:03 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 21:45:03 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetVisibleSources May 21 21:45:03 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 21:45:03 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:45:03 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 21 21:45:03 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 21 21:45:03 volumio5bern volumio[5335]: info: Received Get System Info May 21 21:45:03 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:45:03 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:45:03 volumio5bern volumio[5335]: info: Discovery: Getting this device information May 21 21:45:03 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:45:03 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:45:03 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:45:03 volumio5bern volumio[5335]: info: Listing playlists May 21 21:45:03 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 21 21:45:04 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPlay May 21 21:45:04 volumio5bern volumio[5335]: info: CoreStateMachine::play index undefined May 21 21:45:04 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 21:45:04 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:04 volumio5bern volumio[5335]: info: CoreStateMachine::startPlaybackTimer May 21 21:45:04 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:04 volumio5bern volumio[5335]: info: [1747856704864] ControllerTidal::resume May 21 21:45:04 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:45:04 volumio5bern volumio[5335]: info: ControllerMpd::resume May 21 21:45:04 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand play May 21 21:45:04 volumio5bern volumio[5335]: info: May 21 21:45:04 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:04 volumio5bern volumio[5335]: info: sendMpdCommand play took 3 milliseconds May 21 21:45:04 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:04 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:04 volumio5bern volumio[5335]: info: May 21 21:45:04 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:04 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:04 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:04 volumio5bern volumio[5335]: info: May 21 21:45:04 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:04 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:04 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:04 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:04 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:04 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:04 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:04 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:04 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:04 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:04 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:45:04 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:04 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:04 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:04 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:04 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7870,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"824 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:04 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:04 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:04 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:04 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:04 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:04 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:04 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:04 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:04 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:04 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7939,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"776 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:04 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:04 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:04 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:04 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:04 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:04 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:04 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:04 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:04 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:04 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:04 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:04 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:04 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7962,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"776 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:04 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:04 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:04 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:04 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:04 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:04 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:04 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:04 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:04 volumio5bern volumio[5335]: info: ------------------------------ 12ms May 21 21:45:04 volumio5bern volumio[5335]: info: ------------------------------ 12ms May 21 21:45:04 volumio5bern volumio[5335]: info: ------------------------------ 11ms May 21 21:45:06 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPause May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::pause May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::servicePause May 21 21:45:06 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:06 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePause May 21 21:45:06 volumio5bern volumio[5335]: info: [1747856706189] ControllerTidal::pause May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:45:06 volumio5bern volumio[5335]: info: ControllerMpd::pause May 21 21:45:06 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand pause May 21 21:45:06 volumio5bern volumio[5335]: info: May 21 21:45:06 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:06 volumio5bern volumio[5335]: info: sendMpdCommand pause took 2 milliseconds May 21 21:45:06 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:06 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:06 volumio5bern volumio[5335]: info: May 21 21:45:06 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:06 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:06 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:06 volumio5bern volumio[5335]: info: May 21 21:45:06 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:06 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:06 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:06 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:06 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:06 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:06 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:06 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:06 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:06 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:06 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:06 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:06 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:06 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:06 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:06 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:06 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:06 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:06 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:06 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10021,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"648 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:06 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:06 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:06 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:06 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:06 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:06 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:06 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10021,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"648 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:06 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:06 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:06 volumio5bern volumio[5335]: info: ------------------------------ 6ms May 21 21:45:06 volumio5bern volumio[5335]: info: ------------------------------ 6ms May 21 21:45:06 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 5 milliseconds May 21 21:45:06 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:06 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:06 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:06 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:06 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":10021,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"648 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:06 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:06 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:06 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:06 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:45:08 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPlay May 21 21:45:08 volumio5bern volumio[5335]: info: CoreStateMachine::play index undefined May 21 21:45:08 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 21:45:08 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:08 volumio5bern volumio[5335]: info: CoreStateMachine::startPlaybackTimer May 21 21:45:08 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:08 volumio5bern volumio[5335]: info: [1747856708322] ControllerTidal::resume May 21 21:45:08 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:45:08 volumio5bern volumio[5335]: info: ControllerMpd::resume May 21 21:45:08 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand play May 21 21:45:08 volumio5bern volumio[5335]: info: May 21 21:45:08 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:08 volumio5bern volumio[5335]: info: sendMpdCommand play took 8 milliseconds May 21 21:45:08 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:08 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:08 volumio5bern volumio[5335]: info: May 21 21:45:08 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:08 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:08 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:08 volumio5bern volumio[5335]: info: May 21 21:45:08 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:08 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:08 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:08 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:08 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:45:08 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:08 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:08 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:08 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:08 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:08 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:08 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:08 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:08 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:08 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:08 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:08 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:08 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:08 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:08 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:08 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:08 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10845,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"680 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:08 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:08 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:08 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:08 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:08 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:08 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:08 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:08 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:08 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:08 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10867,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"680 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:08 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:08 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:08 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:08 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:08 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:08 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:08 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:08 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:08 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:08 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:08 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:08 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:08 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10890,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"680 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:08 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:08 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:08 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:08 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:08 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:08 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:08 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:08 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:08 volumio5bern volumio[5335]: info: ------------------------------ 13ms May 21 21:45:08 volumio5bern volumio[5335]: info: ------------------------------ 13ms May 21 21:45:08 volumio5bern volumio[5335]: info: ------------------------------ 12ms May 21 21:45:11 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPause May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::pause May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::servicePause May 21 21:45:11 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:11 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePause May 21 21:45:11 volumio5bern volumio[5335]: info: [1747856711806] ControllerTidal::pause May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:45:11 volumio5bern volumio[5335]: info: ControllerMpd::pause May 21 21:45:11 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand pause May 21 21:45:11 volumio5bern volumio[5335]: info: May 21 21:45:11 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:11 volumio5bern volumio[5335]: info: sendMpdCommand pause took 1 milliseconds May 21 21:45:11 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:11 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:11 volumio5bern volumio[5335]: info: May 21 21:45:11 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:11 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:11 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:11 volumio5bern volumio[5335]: info: May 21 21:45:11 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:11 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:11 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:11 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:45:11 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:45:11 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:45:11 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:11 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:11 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:11 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:11 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:11 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:11 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:11 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:11 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:11 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:11 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:11 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:11 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:11 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:11 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:11 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14414,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"793 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:11 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:11 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:11 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:11 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:11 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:11 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:11 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14414,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"793 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:11 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:11 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:11 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:11 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:11 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:11 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":14414,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"793 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:11 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:11 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:11 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:11 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:45:11 volumio5bern volumio[5335]: info: ------------------------------ 7ms May 21 21:45:11 volumio5bern volumio[5335]: info: ------------------------------ 7ms May 21 21:45:13 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPlay May 21 21:45:13 volumio5bern volumio[5335]: info: CoreStateMachine::play index undefined May 21 21:45:13 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 21:45:13 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:13 volumio5bern volumio[5335]: info: CoreStateMachine::startPlaybackTimer May 21 21:45:13 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:13 volumio5bern volumio[5335]: info: [1747856713170] ControllerTidal::resume May 21 21:45:13 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:45:13 volumio5bern volumio[5335]: info: ControllerMpd::resume May 21 21:45:13 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand play May 21 21:45:13 volumio5bern volumio[5335]: info: sendMpdCommand play took 2 milliseconds May 21 21:45:13 volumio5bern volumio[5335]: info: May 21 21:45:13 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:13 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:13 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:13 volumio5bern volumio[5335]: info: May 21 21:45:13 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:13 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:13 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:13 volumio5bern volumio[5335]: info: May 21 21:45:13 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:13 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:13 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:13 volumio5bern volumio[5335]: info: sendMpdCommand status took 2 milliseconds May 21 21:45:13 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:13 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:13 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:13 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:13 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:13 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:13 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:45:13 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:45:13 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:13 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:13 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:13 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:13 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:13 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:13 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:13 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14620,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"728 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:13 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:13 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:13 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:13 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:13 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:13 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:13 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:13 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:13 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:13 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14665,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"800 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:13 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:13 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:13 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:13 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:13 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:13 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:13 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:13 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:13 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:13 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:45:13 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:45:13 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 21:45:13 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:13 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:13 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:13 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:13 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14688,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"800 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:13 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:13 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:13 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:13 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:13 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:13 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:13 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:13 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:13 volumio5bern volumio[5335]: info: ------------------------------ 10ms May 21 21:45:15 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:45:15 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:45:15 volumio5bern volumio[5335]: info: Discovery: Getting this device information May 21 21:45:15 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:45:15 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:45:17 volumio5bern volumio[5335]: verbose: New Socket.io Connection to 172.22.16.140:3000 from 172.22.16.188 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 21 21:45:18 volumio5bern volumio[5335]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] May 21 21:45:18 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions May 21 21:45:18 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Port: 19 May 21 21:45:18 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Inverted: false May 21 21:45:18 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Delay: 10 May 21 21:45:18 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Latched: false May 21 21:45:18 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: On Pulse width: 500 May 21 21:45:18 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Off Pulse width: 500 May 21 21:45:18 volumio5bern volumio[5335]: error: Failed callmethod call: TypeError: Cannot read property 'unexport' of undefined May 21 21:45:20 volumio5bern volumio[5335]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] May 21 21:45:20 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions May 21 21:45:20 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Port: 19 May 21 21:45:20 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Inverted: false May 21 21:45:20 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Delay: 10 May 21 21:45:20 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Latched: false May 21 21:45:20 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: On Pulse width: 500 May 21 21:45:20 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Off Pulse width: 500 May 21 21:45:20 volumio5bern volumio[5335]: error: Failed callmethod call: TypeError: Cannot read property 'unexport' of undefined May 21 21:45:23 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:45:24 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPause May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::pause May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::servicePause May 21 21:45:24 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:24 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePause May 21 21:45:24 volumio5bern volumio[5335]: info: [1747856724422] ControllerTidal::pause May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:45:24 volumio5bern volumio[5335]: info: ControllerMpd::pause May 21 21:45:24 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand pause May 21 21:45:24 volumio5bern volumio[5335]: info: May 21 21:45:24 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:24 volumio5bern volumio[5335]: info: sendMpdCommand pause took 1 milliseconds May 21 21:45:24 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:24 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:24 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:24 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:24 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:24 volumio5bern volumio[5335]: info: May 21 21:45:24 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:24 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:24 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:24 volumio5bern volumio[5335]: info: May 21 21:45:24 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:24 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:24 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:24 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:45:24 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:24 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:24 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:24 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:24 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:24 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:24 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:24 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:24 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:24 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:24 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26540,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"849 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:24 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:24 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:24 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:24 volumio5bern volumio[5335]: info: ------------------------------ 5ms May 21 21:45:24 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 3 milliseconds May 21 21:45:24 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 3 milliseconds May 21 21:45:24 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:24 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:24 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:24 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:24 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:24 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26540,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"849 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:24 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:24 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:24 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:24 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:24 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:24 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":26540,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"849 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:24 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:24 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:24 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:24 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:45:24 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:45:24 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:45:24 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:45:24 volumio5bern volumio[5335]: info: Discovery: Getting this device information May 21 21:45:24 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:45:24 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:45:27 volumio5bern volumio[5335]: verbose: New Socket.io Connection to 172.22.16.140:3000 from 172.22.16.188 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 21 21:45:29 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPlay May 21 21:45:29 volumio5bern volumio[5335]: info: CoreStateMachine::play index undefined May 21 21:45:29 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 21:45:29 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:29 volumio5bern volumio[5335]: info: CoreStateMachine::startPlaybackTimer May 21 21:45:29 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:29 volumio5bern volumio[5335]: info: [1747856729229] ControllerTidal::resume May 21 21:45:29 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:45:29 volumio5bern volumio[5335]: info: ControllerMpd::resume May 21 21:45:29 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand play May 21 21:45:29 volumio5bern volumio[5335]: info: May 21 21:45:29 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:29 volumio5bern volumio[5335]: info: sendMpdCommand play took 1 milliseconds May 21 21:45:29 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:29 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:29 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:29 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:29 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:29 volumio5bern volumio[5335]: info: May 21 21:45:29 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:29 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:29 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:29 volumio5bern volumio[5335]: info: May 21 21:45:29 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:29 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:29 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:29 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:29 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:45:29 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:29 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:29 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:29 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:29 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:29 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:29 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:29 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:29 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:29 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26609,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"757 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:29 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:29 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:29 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:29 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:29 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:29 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:29 volumio5bern volumio[5335]: info: ------------------------------ 4ms May 21 21:45:29 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 8 milliseconds May 21 21:45:29 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 8 milliseconds May 21 21:45:29 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:29 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:29 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:29 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:29 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:29 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26677,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"757 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:29 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:29 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:29 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:29 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:29 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:29 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:29 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:29 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:29 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:29 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:29 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:29 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:29 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26700,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"793 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:29 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:29 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:29 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:29 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:29 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:29 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:29 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:29 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:29 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:29 volumio5bern volumio[5335]: info: ------------------------------ 16ms May 21 21:45:29 volumio5bern volumio[5335]: info: ------------------------------ 16ms May 21 21:45:32 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPause May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::pause May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::servicePause May 21 21:45:32 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:32 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePause May 21 21:45:32 volumio5bern volumio[5335]: info: [1747856732117] ControllerTidal::pause May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:45:32 volumio5bern volumio[5335]: info: ControllerMpd::pause May 21 21:45:32 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand pause May 21 21:45:32 volumio5bern volumio[5335]: info: May 21 21:45:32 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:32 volumio5bern volumio[5335]: info: sendMpdCommand pause took 2 milliseconds May 21 21:45:32 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:32 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:32 volumio5bern volumio[5335]: info: May 21 21:45:32 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:32 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:32 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:32 volumio5bern volumio[5335]: info: May 21 21:45:32 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:32 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:32 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:32 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:45:32 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:32 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:32 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:32 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:32 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:32 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:32 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:32 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:32 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:32 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:32 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:32 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:32 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:32 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:32 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:32 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:32 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:32 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":30430,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1009 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:32 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:32 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:32 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:32 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:32 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:32 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:32 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":30430,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1009 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:32 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:32 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:32 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:32 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:32 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:32 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":30430,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1009 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:32 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:32 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:32 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:32 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:45:32 volumio5bern volumio[5335]: info: ------------------------------ 7ms May 21 21:45:32 volumio5bern volumio[5335]: info: ------------------------------ 7ms May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPlay May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::play index undefined May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 21:45:34 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::startPlaybackTimer May 21 21:45:34 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:34 volumio5bern volumio[5335]: info: [1747856734171] ControllerTidal::resume May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:45:34 volumio5bern volumio[5335]: info: ControllerMpd::resume May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand play May 21 21:45:34 volumio5bern volumio[5335]: info: May 21 21:45:34 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:34 volumio5bern volumio[5335]: info: sendMpdCommand play took 2 milliseconds May 21 21:45:34 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:34 volumio5bern volumio[5335]: info: May 21 21:45:34 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:34 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:34 volumio5bern volumio[5335]: info: May 21 21:45:34 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:34 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:34 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:34 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:34 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:34 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:34 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:34 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:34 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:34 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30521,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"785 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:34 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:34 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:34 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:34 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30544,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"785 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:34 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:34 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:34 volumio5bern volumio[5335]: info: ------------------------------ 7ms May 21 21:45:34 volumio5bern volumio[5335]: info: ------------------------------ 7ms May 21 21:45:34 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 7 milliseconds May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:34 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:34 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:34 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30567,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"785 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:34 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:34 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:34 volumio5bern volumio[5335]: info: ------------------------------ 11ms May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPause May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::pause May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::servicePause May 21 21:45:34 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePause May 21 21:45:34 volumio5bern volumio[5335]: info: [1747856734515] ControllerTidal::pause May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:45:34 volumio5bern volumio[5335]: info: ControllerMpd::pause May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand pause May 21 21:45:34 volumio5bern volumio[5335]: info: May 21 21:45:34 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:34 volumio5bern volumio[5335]: info: sendMpdCommand pause took 2 milliseconds May 21 21:45:34 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:34 volumio5bern volumio[5335]: info: May 21 21:45:34 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:34 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:34 volumio5bern volumio[5335]: info: May 21 21:45:34 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:34 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:34 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:34 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:34 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:34 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:45:34 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:45:34 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:34 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:34 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:34 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:34 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":31688,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"759 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:34 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:34 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:34 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:34 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":31688,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"759 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:34 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:34 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:34 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:34 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":31688,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"759 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:34 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:34 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:34 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:45:34 volumio5bern volumio[5335]: info: ------------------------------ 7ms May 21 21:45:34 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:45:35 volumio5bern volumio[5335]: verbose: New Socket.io Connection to 172.22.16.140 from 172.22.16.188 UA: Mozilla/5.0 (Linux; Android 15; Pixel 9 Pro XL Build/BP1A.250505.005.A1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.61 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 21 21:45:35 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 21:45:35 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetVisibleSources May 21 21:45:35 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 21:45:35 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:45:35 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 21 21:45:35 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 21 21:45:35 volumio5bern volumio[5335]: info: Received Get System Info May 21 21:45:35 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:45:35 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:45:35 volumio5bern volumio[5335]: info: Discovery: Getting this device information May 21 21:45:35 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:45:35 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:45:35 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:45:35 volumio5bern volumio[5335]: info: Listing playlists May 21 21:45:35 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 21 21:45:38 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 21 21:45:38 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 21 21:45:41 volumio5bern volumio[5335]: info: Enabling plugin ampswitch May 21 21:45:41 volumio5bern volumio[5335]: info: Loading plugin "ampswitch"... May 21 21:45:41 volumio5bern volumio[5335]: info: Applying required configuration parameters for plugin ampswitch May 21 21:45:41 volumio5bern volumio[5335]: info: PLUGIN START: ampswitch May 21 21:45:41 volumio5bern volumio[5335]: info: Done. May 21 21:45:41 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:45:41 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: na May 21 21:45:41 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:45:41 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: na May 21 21:45:41 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:45:41 volumio5bern volumio[5335]: info: [ASDebug] Togle GPIO: OFF May 21 21:45:48 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPlay May 21 21:45:48 volumio5bern volumio[5335]: info: CoreStateMachine::play index undefined May 21 21:45:48 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 21:45:48 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:48 volumio5bern volumio[5335]: info: CoreStateMachine::startPlaybackTimer May 21 21:45:48 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:48 volumio5bern volumio[5335]: info: [1747856748894] ControllerTidal::resume May 21 21:45:48 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:45:48 volumio5bern volumio[5335]: info: ControllerMpd::resume May 21 21:45:48 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand play May 21 21:45:48 volumio5bern volumio[5335]: info: May 21 21:45:48 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:48 volumio5bern volumio[5335]: info: sendMpdCommand play took 2 milliseconds May 21 21:45:48 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:48 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:48 volumio5bern volumio[5335]: info: May 21 21:45:48 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:48 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:48 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:48 volumio5bern volumio[5335]: info: May 21 21:45:48 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:48 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:48 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:48 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:48 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:45:48 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:48 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:48 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:48 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:48 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:48 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:45:48 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:48 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:48 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:48 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:48 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:48 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:48 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:48 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:48 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":31780,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"888 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:48 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:48 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:48 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:48 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:48 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:48 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:48 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:48 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:48 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:48 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":31802,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"845 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:48 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:48 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:48 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:48 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:48 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:48 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:48 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:48 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:48 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:48 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:45:48 volumio5bern volumio[5335]: info: ------------------------------ 7ms May 21 21:45:48 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 21:45:48 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:48 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:48 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:48 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:48 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":31825,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"845 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:48 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:48 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:48 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:48 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:48 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:48 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:48 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:48 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:48 volumio5bern volumio[5335]: info: ------------------------------ 11ms May 21 21:45:48 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: pause May 21 21:45:48 volumio5bern volumio[5335]: info: [ASDebug] Togle GPIO: ON May 21 21:45:48 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:45:48 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:45:48 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:45:48 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:45:48 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:45:48 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:45:48 volumio5bern volumio[5335]: info: Discovery: Getting this device information May 21 21:45:48 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:45:48 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:45:50 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPause May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::pause May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::servicePause May 21 21:45:50 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:50 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePause May 21 21:45:50 volumio5bern volumio[5335]: info: [1747856750049] ControllerTidal::pause May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:45:50 volumio5bern volumio[5335]: info: ControllerMpd::pause May 21 21:45:50 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand pause May 21 21:45:50 volumio5bern volumio[5335]: info: May 21 21:45:50 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:50 volumio5bern volumio[5335]: info: sendMpdCommand pause took 2 milliseconds May 21 21:45:50 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:50 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:50 volumio5bern volumio[5335]: info: May 21 21:45:50 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:50 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:50 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:50 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:50 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:45:50 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:50 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:50 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:50 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:50 volumio5bern volumio[5335]: info: May 21 21:45:50 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:50 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:50 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:50 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:45:50 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:45:50 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:50 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:50 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:50 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:50 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:50 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:50 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:50 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:50 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":33816,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"688 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:50 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:50 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:50 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:50 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:50 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:50 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:50 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":33816,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"688 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:50 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:50 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:50 volumio5bern volumio[5335]: info: ------------------------------ 6ms May 21 21:45:50 volumio5bern volumio[5335]: info: ------------------------------ 6ms May 21 21:45:50 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 5 milliseconds May 21 21:45:50 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:50 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:50 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:50 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:50 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":33816,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"688 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:50 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:50 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:50 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:50 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:45:50 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:45:50 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:45:50 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:45:50 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:45:50 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:45:50 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:45:50 volumio5bern volumio[5335]: info: [ASDebug] Togle GPIO: OFF May 21 21:45:50 volumio5bern volumio[5335]: verbose: New Socket.io Connection to 172.22.16.140:3000 from 172.22.16.188 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 21 21:45:51 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPlay May 21 21:45:51 volumio5bern volumio[5335]: info: CoreStateMachine::play index undefined May 21 21:45:51 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 21:45:51 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:51 volumio5bern volumio[5335]: info: CoreStateMachine::startPlaybackTimer May 21 21:45:51 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:51 volumio5bern volumio[5335]: info: [1747856751629] ControllerTidal::resume May 21 21:45:51 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:45:51 volumio5bern volumio[5335]: info: ControllerMpd::resume May 21 21:45:51 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand play May 21 21:45:51 volumio5bern volumio[5335]: info: sendMpdCommand play took 2 milliseconds May 21 21:45:51 volumio5bern volumio[5335]: info: May 21 21:45:51 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:51 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:51 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:51 volumio5bern volumio[5335]: info: May 21 21:45:51 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:51 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:51 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:51 volumio5bern volumio[5335]: info: May 21 21:45:51 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:51 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:51 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:51 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:45:51 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:51 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:51 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:51 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:51 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:51 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:51 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:51 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:45:51 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:51 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:51 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:51 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:51 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":33907,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"702 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:51 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:51 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:51 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:51 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:51 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:51 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:51 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:51 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:51 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:51 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":33907,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"702 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:51 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:51 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:51 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:51 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:51 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:51 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:51 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:51 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:51 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:51 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:51 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:51 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:51 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":33930,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"896 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:51 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:51 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:51 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:51 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:51 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:51 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:51 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:51 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:51 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:51 volumio5bern volumio[5335]: info: ------------------------------ 11ms May 21 21:45:51 volumio5bern volumio[5335]: info: ------------------------------ 12ms May 21 21:45:51 volumio5bern volumio[5335]: info: ------------------------------ 12ms May 21 21:45:51 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: pause May 21 21:45:51 volumio5bern volumio[5335]: info: [ASDebug] Togle GPIO: ON May 21 21:45:51 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:45:51 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:45:51 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:45:51 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:45:54 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPause May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::pause May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::servicePause May 21 21:45:54 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:54 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePause May 21 21:45:54 volumio5bern volumio[5335]: info: [1747856754832] ControllerTidal::pause May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:45:54 volumio5bern volumio[5335]: info: ControllerMpd::pause May 21 21:45:54 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand pause May 21 21:45:54 volumio5bern volumio[5335]: info: May 21 21:45:54 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:54 volumio5bern volumio[5335]: info: sendMpdCommand pause took 1 milliseconds May 21 21:45:54 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:54 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:54 volumio5bern volumio[5335]: info: May 21 21:45:54 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:54 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:54 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:54 volumio5bern volumio[5335]: info: May 21 21:45:54 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:54 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:54 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:54 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:54 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:45:54 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:54 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:54 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:54 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:54 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:54 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:45:54 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:45:54 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:54 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:54 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:54 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:54 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:54 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:54 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:54 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37957,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"880 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:54 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:54 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:54 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:54 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:54 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:54 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:54 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37957,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"880 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:54 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:54 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:54 volumio5bern volumio[5335]: info: ------------------------------ 6ms May 21 21:45:54 volumio5bern volumio[5335]: info: ------------------------------ 6ms May 21 21:45:54 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 5 milliseconds May 21 21:45:54 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:54 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:54 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:54 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:54 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":37957,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"880 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:54 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:54 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:54 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:45:54 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:45:54 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:45:54 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:45:54 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:45:54 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:45:54 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:45:54 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:45:54 volumio5bern volumio[5335]: info: [ASDebug] Togle GPIO: OFF May 21 21:45:55 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPlay May 21 21:45:55 volumio5bern volumio[5335]: info: CoreStateMachine::play index undefined May 21 21:45:55 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 21:45:55 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:55 volumio5bern volumio[5335]: info: CoreStateMachine::startPlaybackTimer May 21 21:45:55 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:55 volumio5bern volumio[5335]: info: [1747856755818] ControllerTidal::resume May 21 21:45:55 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:45:55 volumio5bern volumio[5335]: info: ControllerMpd::resume May 21 21:45:55 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand play May 21 21:45:55 volumio5bern volumio[5335]: info: May 21 21:45:55 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:55 volumio5bern volumio[5335]: info: sendMpdCommand play took 2 milliseconds May 21 21:45:55 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:55 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:55 volumio5bern volumio[5335]: info: May 21 21:45:55 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:55 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:55 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:55 volumio5bern volumio[5335]: info: May 21 21:45:55 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:45:55 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:45:55 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:45:55 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:55 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:55 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:45:55 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:55 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:55 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:55 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:55 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:45:55 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:45:55 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:45:55 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:45:55 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:45:55 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:55 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:55 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:45:55 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:55 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:55 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:55 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":38094,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"733 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:55 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:55 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:55 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:45:55 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:55 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:55 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:55 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:55 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:55 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:55 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":38117,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"682 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:55 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:55 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:55 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:55 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:55 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:55 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:45:55 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:55 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:55 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:55 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:45:55 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:45:55 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:45:55 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":38117,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"682 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:45:55 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:45:55 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:45:55 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:45:55 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:45:55 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:55 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:55 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:45:55 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:45:55 volumio5bern volumio[5335]: info: ------------------------------ 17ms May 21 21:45:55 volumio5bern volumio[5335]: info: ------------------------------ 16ms May 21 21:45:55 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: pause May 21 21:45:55 volumio5bern volumio[5335]: info: [ASDebug] Togle GPIO: ON May 21 21:45:55 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:45:55 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:45:55 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:45:55 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:45:59 volumio5bern volumio[5335]: verbose: New Socket.io Connection to 172.22.16.140:3000 from 172.22.16.188 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 21 21:46:00 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPause May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::pause May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::servicePause May 21 21:46:00 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:00 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePause May 21 21:46:00 volumio5bern volumio[5335]: info: [1747856760978] ControllerTidal::pause May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:46:00 volumio5bern volumio[5335]: info: ControllerMpd::pause May 21 21:46:00 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand pause May 21 21:46:00 volumio5bern volumio[5335]: info: May 21 21:46:00 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:00 volumio5bern volumio[5335]: info: sendMpdCommand pause took 2 milliseconds May 21 21:46:00 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:00 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:00 volumio5bern volumio[5335]: info: May 21 21:46:00 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:00 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:00 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:00 volumio5bern volumio[5335]: info: May 21 21:46:00 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:00 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:00 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:00 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:46:00 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:46:00 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:00 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:00 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:00 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:00 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:46:00 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:46:00 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:46:00 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:00 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:00 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:00 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:00 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:00 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:00 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:00 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":44089,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:00 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:00 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:46:00 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:00 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:00 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:00 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:00 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":44089,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:00 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:00 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:00 volumio5bern volumio[5335]: info: ------------------------------ 7ms May 21 21:46:00 volumio5bern volumio[5335]: info: ------------------------------ 7ms May 21 21:46:00 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 21:46:00 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:00 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:00 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:00 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:00 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":44089,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:00 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:00 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:00 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:00 volumio5bern volumio[5335]: info: ------------------------------ 10ms May 21 21:46:00 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:46:00 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:46:00 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:46:00 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:46:00 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:46:00 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:46:01 volumio5bern volumio[5335]: info: [ASDebug] Togle GPIO: OFF May 21 21:46:03 volumio5bern volumio[5335]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 21:46:03 volumio5bern volumio[5335]: info: [ASDebug] Setting UI defaults May 21 21:46:03 volumio5bern volumio[5335]: info: [ASDebug] Port: 19 May 21 21:46:03 volumio5bern volumio[5335]: info: [ASDebug] Inverted: false May 21 21:46:03 volumio5bern volumio[5335]: info: [ASDebug] Latched: false May 21 21:46:03 volumio5bern volumio[5335]: info: [ASDebug] On pulse width: 500 May 21 21:46:03 volumio5bern volumio[5335]: info: [ASDebug] Off pulse width: 500 May 21 21:46:06 volumio5bern volumio[5335]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] May 21 21:46:06 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions May 21 21:46:06 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Port: 19 May 21 21:46:06 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Inverted: false May 21 21:46:06 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Delay: 10 May 21 21:46:06 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Latched: false May 21 21:46:06 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: On Pulse width: 500 May 21 21:46:06 volumio5bern volumio[5335]: info: [ASDebug] Saving Settings: Off Pulse width: 500 May 21 21:46:08 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPlay May 21 21:46:08 volumio5bern volumio[5335]: info: CoreStateMachine::play index undefined May 21 21:46:08 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 21:46:08 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:08 volumio5bern volumio[5335]: info: CoreStateMachine::startPlaybackTimer May 21 21:46:08 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:08 volumio5bern volumio[5335]: info: [1747856768560] ControllerTidal::resume May 21 21:46:08 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:46:08 volumio5bern volumio[5335]: info: ControllerMpd::resume May 21 21:46:08 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand play May 21 21:46:08 volumio5bern volumio[5335]: info: sendMpdCommand play took 2 milliseconds May 21 21:46:08 volumio5bern volumio[5335]: info: May 21 21:46:08 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:08 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:08 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:08 volumio5bern volumio[5335]: info: May 21 21:46:08 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:08 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:08 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:08 volumio5bern volumio[5335]: info: May 21 21:46:08 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:08 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:08 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:08 volumio5bern volumio[5335]: info: sendMpdCommand status took 2 milliseconds May 21 21:46:08 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:08 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:08 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:08 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:08 volumio5bern volumio[5335]: info: sendMpdCommand status took 7 milliseconds May 21 21:46:08 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:08 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:08 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 7 milliseconds May 21 21:46:08 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 8 milliseconds May 21 21:46:08 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:08 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:08 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:08 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:08 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:08 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44180,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"704 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:08 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:08 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:46:08 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:46:08 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:08 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:46:08 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:08 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:08 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:08 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:08 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":44363,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"846 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:08 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:08 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:46:08 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:46:08 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:46:08 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:08 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:46:08 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:08 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:08 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:08 volumio5bern volumio[5335]: info: ------------------------------ 15ms May 21 21:46:08 volumio5bern volumio[5335]: info: ------------------------------ 15ms May 21 21:46:08 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 21:46:08 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:08 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:08 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:08 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:08 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45096,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1082 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:08 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:08 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:46:08 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:46:08 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:46:08 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:08 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:46:08 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:08 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:08 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:08 volumio5bern volumio[5335]: info: ------------------------------ 17ms May 21 21:46:08 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: pause May 21 21:46:08 volumio5bern volumio[5335]: info: [ASDebug] Togle GPIO: ON May 21 21:46:08 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:46:08 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:46:08 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:46:08 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:46:09 volumio5bern volumio[5335]: verbose: New Socket.io Connection to 172.22.16.140 from 172.22.16.188 UA: Mozilla/5.0 (Linux; Android 15; Pixel 9 Pro XL Build/BP1A.250505.005.A1; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.61 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 21 21:46:09 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 21:46:09 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetVisibleSources May 21 21:46:09 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 21:46:09 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:46:09 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 21 21:46:09 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 21 21:46:09 volumio5bern volumio[5335]: info: Received Get System Info May 21 21:46:09 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:46:09 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:46:09 volumio5bern volumio[5335]: info: Discovery: Getting this device information May 21 21:46:09 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:46:09 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:46:09 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:46:09 volumio5bern volumio[5335]: info: Listing playlists May 21 21:46:10 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 21 21:46:10 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPause May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::pause May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::servicePause May 21 21:46:10 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:10 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePause May 21 21:46:10 volumio5bern volumio[5335]: info: [1747856770390] ControllerTidal::pause May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:46:10 volumio5bern volumio[5335]: info: ControllerMpd::pause May 21 21:46:10 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand pause May 21 21:46:10 volumio5bern volumio[5335]: info: May 21 21:46:10 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:10 volumio5bern volumio[5335]: info: sendMpdCommand pause took 2 milliseconds May 21 21:46:10 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:10 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:10 volumio5bern volumio[5335]: info: May 21 21:46:10 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:10 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:10 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:10 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:46:10 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:46:10 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:10 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:10 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:10 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:10 volumio5bern volumio[5335]: info: May 21 21:46:10 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:10 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:10 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:10 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:46:10 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:46:10 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:46:10 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:10 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:10 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:10 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:10 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:10 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:10 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:10 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":46857,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"872 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:10 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:10 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:46:10 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:10 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:10 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:10 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:10 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":46857,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"872 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:10 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:10 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:10 volumio5bern volumio[5335]: info: ------------------------------ 5ms May 21 21:46:10 volumio5bern volumio[5335]: info: ------------------------------ 6ms May 21 21:46:10 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 5 milliseconds May 21 21:46:10 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:10 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:10 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:10 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:10 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":46857,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"872 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:10 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:10 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:10 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:10 volumio5bern volumio[5335]: info: ------------------------------ 7ms May 21 21:46:10 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:46:10 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:46:10 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:46:10 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:46:10 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:46:10 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:46:10 volumio5bern volumio[5335]: info: [ASDebug] Togle GPIO: OFF May 21 21:46:12 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPlay May 21 21:46:12 volumio5bern volumio[5335]: info: CoreStateMachine::play index undefined May 21 21:46:12 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 21:46:12 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:12 volumio5bern volumio[5335]: info: CoreStateMachine::startPlaybackTimer May 21 21:46:12 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:12 volumio5bern volumio[5335]: info: [1747856772364] ControllerTidal::resume May 21 21:46:12 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:46:12 volumio5bern volumio[5335]: info: ControllerMpd::resume May 21 21:46:12 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand play May 21 21:46:12 volumio5bern volumio[5335]: info: sendMpdCommand play took 3 milliseconds May 21 21:46:12 volumio5bern volumio[5335]: info: May 21 21:46:12 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:12 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:12 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:12 volumio5bern volumio[5335]: info: May 21 21:46:12 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:12 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:12 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:12 volumio5bern volumio[5335]: info: May 21 21:46:12 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:12 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:12 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:12 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:46:12 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:46:12 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:12 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:12 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:12 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:12 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:46:12 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:46:12 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 1 milliseconds May 21 21:46:12 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:12 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:12 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:12 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:12 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:12 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:12 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:12 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47132,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"798 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:12 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:12 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:46:12 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:46:12 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:12 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:46:12 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:12 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:12 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:12 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:12 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47155,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"798 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:12 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:12 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:46:12 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:46:12 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:46:12 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:12 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:46:12 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:12 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:12 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:12 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:46:12 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:46:12 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 6 milliseconds May 21 21:46:12 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:12 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:12 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:12 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:12 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47178,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"798 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:12 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:12 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:46:12 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:46:12 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:46:12 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:12 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:12 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:12 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:12 volumio5bern volumio[5335]: info: ------------------------------ 11ms May 21 21:46:12 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: pause May 21 21:46:12 volumio5bern volumio[5335]: info: [ASDebug] Togle GPIO: ON May 21 21:46:12 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:46:12 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:46:12 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:46:12 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:46:14 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPause May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::pause May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::servicePause May 21 21:46:14 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:14 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePause May 21 21:46:14 volumio5bern volumio[5335]: info: [1747856774082] ControllerTidal::pause May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:46:14 volumio5bern volumio[5335]: info: ControllerMpd::pause May 21 21:46:14 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand pause May 21 21:46:14 volumio5bern volumio[5335]: info: May 21 21:46:14 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:14 volumio5bern volumio[5335]: info: sendMpdCommand pause took 2 milliseconds May 21 21:46:14 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:14 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:14 volumio5bern volumio[5335]: info: May 21 21:46:14 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:14 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:14 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:14 volumio5bern volumio[5335]: info: May 21 21:46:14 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:14 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:14 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:14 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:46:14 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:46:14 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:14 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:14 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:14 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:14 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:46:14 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:46:14 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:46:14 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:14 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:14 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:14 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:14 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:14 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:14 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:14 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":49489,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"742 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:14 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:14 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:46:14 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:14 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:14 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:14 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:14 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":49489,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"742 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:14 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:14 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:14 volumio5bern volumio[5335]: info: ------------------------------ 6ms May 21 21:46:14 volumio5bern volumio[5335]: info: ------------------------------ 6ms May 21 21:46:14 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 5 milliseconds May 21 21:46:14 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:14 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:14 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:14 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:14 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":49489,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"742 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:14 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:14 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:14 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:14 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:46:14 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:46:14 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:46:14 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:46:14 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:46:14 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:46:14 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:46:14 volumio5bern volumio[5335]: info: [ASDebug] Togle GPIO: OFF May 21 21:46:15 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:46:15 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:46:15 volumio5bern volumio[5335]: info: Discovery: Getting this device information May 21 21:46:15 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:46:15 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:46:17 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPlay May 21 21:46:17 volumio5bern volumio[5335]: info: CoreStateMachine::play index undefined May 21 21:46:17 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService undefined May 21 21:46:17 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:17 volumio5bern volumio[5335]: info: CoreStateMachine::startPlaybackTimer May 21 21:46:17 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:17 volumio5bern volumio[5335]: info: [1747856777404] ControllerTidal::resume May 21 21:46:17 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:46:17 volumio5bern volumio[5335]: info: ControllerMpd::resume May 21 21:46:17 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand play May 21 21:46:17 volumio5bern volumio[5335]: info: sendMpdCommand play took 2 milliseconds May 21 21:46:17 volumio5bern volumio[5335]: info: May 21 21:46:17 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:17 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:17 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:17 volumio5bern volumio[5335]: info: May 21 21:46:17 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:17 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:17 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:17 volumio5bern volumio[5335]: info: May 21 21:46:17 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:17 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:17 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:17 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:46:17 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:17 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:17 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:46:17 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:46:17 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:46:17 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:17 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:17 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:17 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:17 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:17 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:17 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:17 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:17 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49580,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"930 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:17 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:17 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:46:17 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:46:17 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:17 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:46:17 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:17 volumio5bern volumio[5335]: info: ------------------------------ 4ms May 21 21:46:17 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 3 milliseconds May 21 21:46:17 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 3 milliseconds May 21 21:46:17 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:17 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:17 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:17 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:17 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:17 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49626,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"853 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:17 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:17 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:46:17 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:46:17 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:46:17 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:17 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:46:17 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:17 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:17 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:17 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:17 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:17 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:17 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"play","position":0,"seek":49649,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"853 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:17 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:17 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService play May 21 21:46:17 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus play May 21 21:46:17 volumio5bern volumio[5335]: info: Received an update from plugin. extracting info from payload May 21 21:46:17 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:17 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:17 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:17 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:17 volumio5bern volumio[5335]: info: ------------------------------ 13ms May 21 21:46:17 volumio5bern volumio[5335]: info: ------------------------------ 13ms May 21 21:46:17 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: pause May 21 21:46:17 volumio5bern volumio[5335]: info: [ASDebug] Togle GPIO: ON May 21 21:46:17 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:46:17 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:46:17 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:46:17 volumio5bern volumio[5335]: info: [ASDebug] CurState: play PrevState: play May 21 21:46:19 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 21 21:46:19 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 21 21:46:20 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 21:46:20 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 21:46:20 volumio5bern volumio[5335]: info: Discovery: Getting this device information May 21 21:46:20 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioGetState May 21 21:46:20 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 21:46:23 volumio5bern volumio[5335]: info: Enabling plugin gpio-buttons May 21 21:46:23 volumio5bern volumio[5335]: info: Loading plugin "gpio-buttons"... May 21 21:46:23 volumio5bern volumio[5335]: info: GPIO-Buttons initialized May 21 21:46:23 volumio5bern volumio[5335]: info: PLUGIN START: gpio-buttons May 21 21:46:23 volumio5bern volumio[5335]: info: GPIO-Buttons: Reading config and creating triggers... May 21 21:46:23 volumio5bern volumio[5335]: info: GPIO-Buttons: playPause on pin 563 May 21 21:46:23 volumio5bern volumio[5335]: info: GPIO-Buttons started May 21 21:46:23 volumio5bern volumio[5335]: info: Done. May 21 21:46:23 volumio5bern volumio[5335]: verbose: New Socket.io Connection to 172.22.16.140:3000 from 172.22.16.188 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 21 21:46:24 volumio5bern volumio[5335]: info: GPIO-Buttons: Destroying trigger 0 May 21 21:46:24 volumio5bern volumio[5335]: info: GPIO-Buttons stopped May 21 21:46:24 volumio5bern volumio[5335]: info: Disabling plugin gpio-buttons May 21 21:46:24 volumio5bern volumio[5335]: info: Done. May 21 21:46:25 volumio5bern volumio[5335]: info: Enabling plugin gpio-buttons May 21 21:46:25 volumio5bern volumio[5335]: info: Loading plugin "gpio-buttons"... May 21 21:46:25 volumio5bern volumio[5335]: info: GPIO-Buttons initialized May 21 21:46:25 volumio5bern volumio[5335]: info: PLUGIN START: gpio-buttons May 21 21:46:25 volumio5bern volumio[5335]: info: GPIO-Buttons: Reading config and creating triggers... May 21 21:46:25 volumio5bern volumio[5335]: info: GPIO-Buttons: playPause on pin 563 May 21 21:46:26 volumio5bern volumio[5335]: info: Error: Error: ENOENT: no such file or directory, open '/sys/class/gpio/gpio563/edge' May 21 21:46:26 volumio5bern volumio[5335]: info: Enabling plugin gpio-buttons May 21 21:46:26 volumio5bern volumio[5335]: info: Loading plugin "gpio-buttons"... May 21 21:46:26 volumio5bern volumio[5335]: info: GPIO-Buttons initialized May 21 21:46:26 volumio5bern volumio[5335]: info: PLUGIN START: gpio-buttons May 21 21:46:26 volumio5bern volumio[5335]: info: GPIO-Buttons: Reading config and creating triggers... May 21 21:46:26 volumio5bern volumio[5335]: info: GPIO-Buttons: playPause on pin 563 May 21 21:46:26 volumio5bern volumio[5335]: info: GPIO-Buttons started May 21 21:46:26 volumio5bern volumio[5335]: info: Done. May 21 21:46:30 volumio5bern volumio[5335]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 21:46:30 volumio5bern volumio[5335]: info: GPIO-Buttons: Getting UI config May 21 21:46:30 volumio5bern volumio[5335]: verbose: New Socket.io Connection to 172.22.16.140:3000 from 172.22.16.188 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 21 21:46:37 volumio5bern volumio[5335]: info: CALLMETHOD: system_hardware gpio-buttons saveConfig [object Object] May 21 21:46:37 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: gpio-buttons , saveConfig May 21 21:46:37 volumio5bern volumio[5335]: info: GPIO-Buttons: Destroying trigger 0 May 21 21:46:37 volumio5bern volumio[5335]: info: GPIO-Buttons: Reading config and creating triggers... May 21 21:46:37 volumio5bern volumio[5335]: info: GPIO-Buttons: playPause on pin 570 May 21 21:46:38 volumio5bern volumio[5335]: error: Failed callmethod call: Error: ENOENT: no such file or directory, open '/sys/class/gpio/gpio570/edge' May 21 21:46:39 volumio5bern volumio[5335]: info: CALLMETHOD: system_hardware gpio-buttons saveConfig [object Object] May 21 21:46:39 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: gpio-buttons , saveConfig May 21 21:46:39 volumio5bern volumio[5335]: info: GPIO-Buttons: Reading config and creating triggers... May 21 21:46:39 volumio5bern volumio[5335]: info: GPIO-Buttons: playPause on pin 570 May 21 21:46:41 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPause May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::pause May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::servicePause May 21 21:46:41 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:41 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePause May 21 21:46:41 volumio5bern volumio[5335]: info: [1747856801847] ControllerTidal::pause May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::setConsumeUpdateService mpd May 21 21:46:41 volumio5bern volumio[5335]: info: ControllerMpd::pause May 21 21:46:41 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand pause May 21 21:46:41 volumio5bern volumio[5335]: info: May 21 21:46:41 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:41 volumio5bern volumio[5335]: info: sendMpdCommand pause took 2 milliseconds May 21 21:46:41 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:41 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:41 volumio5bern volumio[5335]: info: May 21 21:46:41 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:41 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:41 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:41 volumio5bern volumio[5335]: info: May 21 21:46:41 volumio5bern volumio[5335]: ---------------------------- MPD announces state update: player May 21 21:46:41 volumio5bern volumio[5335]: info: ControllerMpd::getState May 21 21:46:41 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand status May 21 21:46:41 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:46:41 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:41 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:41 volumio5bern volumio[5335]: info: sendMpdCommand status took 1 milliseconds May 21 21:46:41 volumio5bern volumio[5335]: info: sendMpdCommand status took 0 milliseconds May 21 21:46:41 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 0 milliseconds May 21 21:46:41 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:41 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:41 volumio5bern volumio[5335]: verbose: ControllerMpd::parseState May 21 21:46:41 volumio5bern volumio[5335]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 21 21:46:41 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:41 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:41 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:41 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:41 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":74862,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"888 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:41 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:41 volumio5bern volumio[5335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 21:46:41 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:41 volumio5bern volumio[5335]: info: ------------------------------ 4ms May 21 21:46:41 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 3 milliseconds May 21 21:46:41 volumio5bern volumio[5335]: info: sendMpdCommand playlistinfo took 2 milliseconds May 21 21:46:41 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:41 volumio5bern volumio[5335]: verbose: ControllerMpd::parseTrackInfo May 21 21:46:41 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:41 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:41 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:41 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":74862,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"888 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:41 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:41 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:41 volumio5bern volumio[5335]: info: ControllerMpd::pushState May 21 21:46:41 volumio5bern volumio[5335]: info: CoreCommandRouter::servicePushState May 21 21:46:41 volumio5bern volumio[5335]: info: CorePlayQueue::getTrack 2 May 21 21:46:41 volumio5bern volumio[5335]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":74862,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"888 Kbps","isStreaming":false,"title":"88192118&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88192118&soundQuality=HI_RES","trackType":"tidal"} May 21 21:46:41 volumio5bern volumio[5335]: verbose: CURRENT POSITION 2 May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::syncState stateService pause May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::syncState currentStatus pause May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::pushState May 21 21:46:41 volumio5bern volumio[5335]: info: CoreCommandRouter::volumioPushState May 21 21:46:41 volumio5bern volumio[5335]: info: CoreStateMachine::stPlaybackTimer May 21 21:46:41 volumio5bern volumio[5335]: info: ------------------------------ 8ms May 21 21:46:41 volumio5bern volumio[5335]: info: ------------------------------ 7ms May 21 21:46:41 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:46:41 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:46:41 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:46:41 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:46:41 volumio5bern volumio[5335]: info: [ASDebug] CurState: pause PrevState: play May 21 21:46:41 volumio5bern volumio[5335]: info: [ASDebug] InitTimeout - Amp off in: 10 ms May 21 21:46:41 volumio5bern volumio[5335]: info: [ASDebug] Togle GPIO: OFF May 21 21:46:41 volumio5bern volumio[5335]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 21:46:41 volumio5bern volumio[5335]: Error: ENODEV: no such device, write May 21 21:46:41 volumio5bern volumio[5335]: at Object.writeSync (fs.js:711:3) May 21 21:46:41 volumio5bern volumio[5335]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) May 21 21:46:41 volumio5bern volumio[5335]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) May 21 21:46:41 volumio5bern volumio[5335]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) May 21 21:46:41 volumio5bern volumio[5335]: at listOnTimeout (internal/timers.js:554:17) May 21 21:46:41 volumio5bern volumio[5335]: at processTimers (internal/timers.js:497:7) { May 21 21:46:41 volumio5bern volumio[5335]: errno: -19, May 21 21:46:41 volumio5bern volumio[5335]: syscall: 'write', May 21 21:46:41 volumio5bern volumio[5335]: code: 'ENODEV' May 21 21:46:41 volumio5bern volumio[5335]: } May 21 21:46:41 volumio5bern volumio[5335]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 21:46:42 volumio5bern sudo[7029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 21:45 May 21 21:46:42 volumio5bern sudo[7029]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"