-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2026-03-25 19:34:46 CET. -- Mar 25 19:33:08 primo-plus-ql ntpd[804]: 151.22.209.90 local addr 192.168.1.42 -> Mar 25 19:33:26 primo-plus-ql go-librespot[1932]: time="2026-03-25T19:33:26+01:00" level=trace msg="sent dealer ping" Mar 25 19:33:26 primo-plus-ql go-librespot[1932]: time="2026-03-25T19:33:26+01:00" level=trace msg="received dealer pong" Mar 25 19:33:48 primo-plus-ql volumio5-onboarding[1829]: time=2026-03-25T19:33:48.744+01:00 level=WARN msg="pending write exceeded maximum retries, dropping" component=ble/conn Mar 25 19:33:52 primo-plus-ql volumiologrotate[707]: ls: cannot access '/var/log/samba/log.wb-PRIMO': No such file or directory Mar 25 19:33:52 primo-plus-ql volumiologrotate[707]: ls: cannot access 'PLUS': No such file or directory Mar 25 19:33:52 primo-plus-ql volumiologrotate[707]: ls: cannot access 'QL': No such file or directory Mar 25 19:33:56 primo-plus-ql go-librespot[1932]: time="2026-03-25T19:33:56+01:00" level=trace msg="sent dealer ping" Mar 25 19:33:56 primo-plus-ql go-librespot[1932]: time="2026-03-25T19:33:56+01:00" level=trace msg="received dealer pong" Mar 25 19:33:56 primo-plus-ql go-librespot[1932]: time="2026-03-25T19:33:56+01:00" level=trace msg="received accesspoint ping" Mar 25 19:33:56 primo-plus-ql go-librespot[1932]: time="2026-03-25T19:33:56+01:00" level=trace msg="received accesspoint pong ack" Mar 25 19:34:26 primo-plus-ql go-librespot[1932]: time="2026-03-25T19:34:26+01:00" level=trace msg="sent dealer ping" Mar 25 19:34:26 primo-plus-ql go-librespot[1932]: time="2026-03-25T19:34:26+01:00" level=trace msg="received dealer pong" Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Preload queue cleared Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreStateMachine::ClearQueue Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreStateMachine::stop Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CorePlayQueue::clearPlayQueue Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CorePlayQueue::saveQueue Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreCommandRouter::volumioPushQueue Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreStateMachine::addQueueItems Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CorePlayQueue::addQueueItems Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Preload queue cleared Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Adding Item to queue: webeighties/0 Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Exploding uri webeighties/0 in service 80s80s Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: [1774463681587] [80s80s] explodeUri: webeighties/0 Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreCommandRouter::volumioPushQueue Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CorePlayQueue::saveQueue Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreStateMachine::updateTrackBlock Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrackBlock Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreCommandRouter::volumioPlay Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreStateMachine::play index 0 Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreStateMachine::stop Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreStateMachine::play index undefined Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 0 Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreStateMachine::startPlaybackTimer Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 0 Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreCommandRouter::volumioGetVisibleSources Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: [1774463681600] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: [1774463681601] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: [1774463681908] [80s80s] received new event containing 2 songs. Mar 25 19:34:41 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: sendMpdCommand stop took 2 milliseconds Mar 25 19:34:41 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Mar 25 19:34:41 primo-plus-ql volumio[1302]: ---------------------------- MPD announces system playlist update Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Ignoring MPD Status Update Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: sendMpdCommand clear took 1 milliseconds Mar 25 19:34:41 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Mar 25 19:34:41 primo-plus-ql volumio[1302]: ---------------------------- MPD announces system playlist update Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Ignoring MPD Status Update Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Mar 25 19:34:41 primo-plus-ql volumio[1302]: ---------------------------- MPD announces system playlist update Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Ignoring MPD Status Update Mar 25 19:34:41 primo-plus-ql volumio[1302]: error: updateQueue error: null Mar 25 19:34:41 primo-plus-ql volumio[1302]: error: updateQueue error: null Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: ------------------------------ 5ms Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: sendMpdCommand consume 1 took 4 milliseconds Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: ------------------------------ 3ms Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: [1774463681920] [80s80s] adding url: http://streams.80s80s.de/web/mp3-192/volumio Mar 25 19:34:41 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" Mar 25 19:34:41 primo-plus-ql volumio[1302]: error: updateQueue error: null Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: ------------------------------ 4ms Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" took 1 milliseconds Mar 25 19:34:41 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand play Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Mar 25 19:34:41 primo-plus-ql volumio[1302]: ---------------------------- MPD announces system playlist update Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Ignoring MPD Status Update Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Mar 25 19:34:41 primo-plus-ql volumio[1302]: ---------------------------- MPD announces system playlist update Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Ignoring MPD Status Update Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Mar 25 19:34:41 primo-plus-ql volumio[1302]: ---------------------------- MPD announces system playlist update Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: Ignoring MPD Status Update Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: sendMpdCommand play took 11 milliseconds Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: ------------------------------ 11ms Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: ------------------------------ 10ms Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: ------------------------------ 8ms Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: [1774463681939] [80s80s] Pushing the next song state: Kano - Another Life Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 0 Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreCommandRouter::servicePushState Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 0 Mar 25 19:34:41 primo-plus-ql volumio[1302]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music124/v4/44/de/af/44deafd0-ae91-fec8-b37d-9b3ae910a4ef/source/600x600bb.jpg","name":"Kano - Another Life","title":"Another Life","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"219","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 25 19:34:41 primo-plus-ql volumio[1302]: verbose: CURRENT POSITION 0 Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreStateMachine::syncState stateService play Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CoreStateMachine::syncState currentStatus stop Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: [1774463681943] [80s80s] PlayNextTrack API delay: 30 Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: [1774463681964] [80s80s] Setting timer to: 219000 milliseconds. Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 0 Mar 25 19:34:41 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 1 Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Mar 25 19:34:44 primo-plus-ql volumio[1302]: ---------------------------- MPD announces state update: player Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::getState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand status Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Mar 25 19:34:44 primo-plus-ql volumio[1302]: ---------------------------- MPD announces state update: player Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::getState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand status Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Mar 25 19:34:44 primo-plus-ql volumio[1302]: ---------------------------- MPD announces state update: player Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::getState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand status Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: camilladsp stopping service pid 5052... Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: camilladsp service terminated, instance 1 Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: camilladsp service started and running in background, instance 1 Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Mar 25 19:34:44 primo-plus-ql volumio[1302]: ---------------------------- MPD announces state update: player Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand status took 57 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand status took 55 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand status took 55 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::getState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand status Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Mar 25 19:34:44 primo-plus-ql volumio[1302]: ---------------------------- MPD announces state update: player Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::getState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand status Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Mar 25 19:34:44 primo-plus-ql volumio[1302]: ---------------------------- MPD announces state update: player Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::getState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand status Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Mar 25 19:34:44 primo-plus-ql volumio[1302]: ---------------------------- MPD announces system playlist update Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Ignoring MPD Status Update Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Mar 25 19:34:44 primo-plus-ql volumio[1302]: ---------------------------- MPD announces state update: player Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::getState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand status Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand status took 43 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand playlistinfo took 43 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand playlistinfo took 43 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand playlistinfo took 43 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand status took 42 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand status took 41 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ------------------------------ 38ms Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand status took 37 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseTrackInfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseTrackInfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseTrackInfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::pushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CoreCommandRouter::servicePushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 0 Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::pushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CoreCommandRouter::servicePushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 0 Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::pushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CoreCommandRouter::servicePushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 0 Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ------------------------------ 110ms Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ------------------------------ 106ms Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ------------------------------ 104ms Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Mar 25 19:34:44 primo-plus-ql volumio[1302]: ---------------------------- MPD announces system playlist update Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Ignoring MPD Status Update Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Mar 25 19:34:44 primo-plus-ql volumio[1302]: ---------------------------- MPD announces state update: player Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::getState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand status Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Mar 25 19:34:44 primo-plus-ql volumio[1302]: ---------------------------- MPD announces system playlist update Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Ignoring MPD Status Update Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Mar 25 19:34:44 primo-plus-ql volumio[1302]: ---------------------------- MPD announces state update: player Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::getState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand status Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand playlistinfo took 9 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ------------------------------ 9ms Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand status took 8 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ------------------------------ 8ms Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand status took 7 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseTrackInfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseTrackInfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseTrackInfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseTrackInfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseState Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::pushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CoreCommandRouter::servicePushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 0 Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::pushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CoreCommandRouter::servicePushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 0 Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::pushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CoreCommandRouter::servicePushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 0 Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::pushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CoreCommandRouter::servicePushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 0 Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ------------------------------ 64ms Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ------------------------------ 60ms Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ------------------------------ 59ms Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ------------------------------ 56ms Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseTrackInfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: verbose: ControllerMpd::parseTrackInfo Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::pushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CoreCommandRouter::servicePushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 0 Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ControllerMpd::pushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CoreCommandRouter::servicePushState Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 0 Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ------------------------------ 15ms Mar 25 19:34:44 primo-plus-ql volumio[1302]: info: ------------------------------ 13ms Mar 25 19:34:46 primo-plus-ql volumio[1302]: info: Preload queue cleared Mar 25 19:34:46 primo-plus-ql volumio[1302]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 19:34:46 primo-plus-ql volumio[1302]: info: CoreStateMachine::ClearQueue Mar 25 19:34:46 primo-plus-ql volumio[1302]: info: CoreStateMachine::stop Mar 25 19:34:46 primo-plus-ql volumio[1302]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 19:34:46 primo-plus-ql volumio[1302]: info: CoreStateMachine::stPlaybackTimer Mar 25 19:34:46 primo-plus-ql volumio[1302]: info: CoreStateMachine::updateTrackBlock Mar 25 19:34:46 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrackBlock Mar 25 19:34:46 primo-plus-ql volumio[1302]: info: CoreStateMachine::pushState Mar 25 19:34:46 primo-plus-ql volumio[1302]: info: CorePlayQueue::getTrack 0 Mar 25 19:34:46 primo-plus-ql volumio[1302]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 19:34:46 primo-plus-ql volumio[1302]: info: CoreCommandRouter::volumioPushState Mar 25 19:34:46 primo-plus-ql volumio[1302]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 19:34:46 primo-plus-ql volumio[1302]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Mar 25 19:34:46 primo-plus-ql volumio[1302]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Mar 25 19:34:46 primo-plus-ql volumio[1302]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:182:10) Mar 25 19:34:46 primo-plus-ql volumio[1302]: at /volumio/app/index.js:326:81 Mar 25 19:34:46 primo-plus-ql volumio[1302]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Mar 25 19:34:46 primo-plus-ql volumio[1302]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Mar 25 19:34:46 primo-plus-ql volumio[1302]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Mar 25 19:34:46 primo-plus-ql volumio[1302]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Mar 25 19:34:46 primo-plus-ql volumio[1302]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1247:12) Mar 25 19:34:46 primo-plus-ql volumio[1302]: at CoreStateMachine.clearQueue (/volumio/app/statemachine.js:277:8) Mar 25 19:34:46 primo-plus-ql volumio[1302]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:494:21) Mar 25 19:34:46 primo-plus-ql volumio[1302]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Mar 25 19:34:46 primo-plus-ql volumio[1302]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Mar 25 19:34:46 primo-plus-ql volumio[1302]: at Socket.emit (events.js:315:20) Mar 25 19:34:46 primo-plus-ql volumio[1302]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 25 19:34:46 primo-plus-ql volumio[1302]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Mar 25 19:34:46 primo-plus-ql volumio[1302]: code: 'NumberExpected' Mar 25 19:34:46 primo-plus-ql volumio[1302]: } Mar 25 19:34:46 primo-plus-ql volumio[1302]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 19:34:46 primo-plus-ql sudo[5498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-25 19:33 Mar 25 19:34:46 primo-plus-ql sudo[5498]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="primoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 01:08:28 PM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Primo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo Plus" VOLUMIO_HASH="2aa40c31709b83c0e790f52b483672b5"