-- Logs begin at Sun 2026-02-15 17:55:41 CET, end at Tue 2026-02-17 16:40:46 CET. -- Feb 17 16:39:14 primo-plus-ql volumiologrotate[620]: ls: cannot access '/var/log/samba/log.wb-PRIMO': No such file or directory Feb 17 16:39:14 primo-plus-ql volumiologrotate[620]: ls: cannot access 'PLUS': No such file or directory Feb 17 16:39:14 primo-plus-ql volumiologrotate[620]: ls: cannot access 'QL': No such file or directory Feb 17 16:39:24 primo-plus-ql go-librespot[1811]: time="2026-02-17T16:39:24+01:00" level=trace msg="sent dealer ping" Feb 17 16:39:24 primo-plus-ql go-librespot[1811]: time="2026-02-17T16:39:24+01:00" level=trace msg="received dealer pong" Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: Feb 17 16:39:49 primo-plus-ql volumio[1117]: ---------------------------- MPD announces system playlist update Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: Ignoring MPD Status Update Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: Feb 17 16:39:49 primo-plus-ql volumio[1117]: ---------------------------- MPD announces state update: player Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: ControllerMpd::getState Feb 17 16:39:49 primo-plus-ql volumio[1117]: verbose: ControllerMpd::sendMpdCommand status Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: Feb 17 16:39:49 primo-plus-ql volumio[1117]: ---------------------------- MPD announces system playlist update Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: Ignoring MPD Status Update Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: Feb 17 16:39:49 primo-plus-ql volumio[1117]: ---------------------------- MPD announces state update: player Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: ControllerMpd::getState Feb 17 16:39:49 primo-plus-ql volumio[1117]: verbose: ControllerMpd::sendMpdCommand status Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: Feb 17 16:39:49 primo-plus-ql volumio[1117]: ---------------------------- MPD announces system playlist update Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: Ignoring MPD Status Update Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: Feb 17 16:39:49 primo-plus-ql volumio[1117]: ---------------------------- MPD announces state update: player Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: ControllerMpd::getState Feb 17 16:39:49 primo-plus-ql volumio[1117]: verbose: ControllerMpd::sendMpdCommand status Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: ------------------------------ 8ms Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: sendMpdCommand status took 7 milliseconds Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: ------------------------------ 6ms Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: sendMpdCommand status took 6 milliseconds Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: ------------------------------ 5ms Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: sendMpdCommand status took 4 milliseconds Feb 17 16:39:49 primo-plus-ql volumio[1117]: verbose: ControllerMpd::parseState Feb 17 16:39:49 primo-plus-ql volumio[1117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 16:39:49 primo-plus-ql volumio[1117]: verbose: ControllerMpd::parseState Feb 17 16:39:49 primo-plus-ql volumio[1117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 16:39:49 primo-plus-ql volumio[1117]: verbose: ControllerMpd::parseState Feb 17 16:39:49 primo-plus-ql volumio[1117]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 17 16:39:49 primo-plus-ql volumio[1117]: verbose: ControllerMpd::parseTrackInfo Feb 17 16:39:49 primo-plus-ql volumio[1117]: verbose: ControllerMpd::parseTrackInfo Feb 17 16:39:49 primo-plus-ql volumio[1117]: verbose: ControllerMpd::parseTrackInfo Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: ControllerMpd::pushState Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: CoreCommandRouter::servicePushState Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: CorePlayQueue::getTrack 0 Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: ControllerMpd::pushState Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: CoreCommandRouter::servicePushState Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: CorePlayQueue::getTrack 0 Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: ControllerMpd::pushState Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: CoreCommandRouter::servicePushState Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: CorePlayQueue::getTrack 0 Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: ------------------------------ 19ms Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: ------------------------------ 16ms Feb 17 16:39:49 primo-plus-ql volumio[1117]: info: ------------------------------ 14ms Feb 17 16:39:54 primo-plus-ql go-librespot[1811]: time="2026-02-17T16:39:54+01:00" level=trace msg="sent dealer ping" Feb 17 16:39:54 primo-plus-ql go-librespot[1811]: time="2026-02-17T16:39:54+01:00" level=trace msg="received dealer pong" Feb 17 16:40:10 primo-plus-ql volumio[1117]: info: CoreStateMachine::pushState Feb 17 16:40:10 primo-plus-ql volumio[1117]: info: CorePlayQueue::getTrack 0 Feb 17 16:40:10 primo-plus-ql volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 16:40:10 primo-plus-ql volumio[1117]: info: CoreCommandRouter::volumioPushState Feb 17 16:40:10 primo-plus-ql volumio[1117]: error: Error in serial, unable to parse message: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Feb 17 16:40:10 primo-plus-ql volumio5-onboarding[1639]: time=2026-02-17T16:40:10.444+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.45:55620,192.168.1.45:55733 @ 0x23a20c0" state=STATUS_PLAYING positionMs=170899 volume=18 Feb 17 16:40:10 primo-plus-ql volumio5-onboarding[1639]: time=2026-02-17T16:40:10.445+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.45:55620,192.168.1.45:55733 @ 0x23a20c0" id=http://streams.80s80s.de/ndw/mp3-192/volumio title="Nena - ? (Fragezeichen)" Feb 17 16:40:10 primo-plus-ql volumio[1117]: info: Signalling Playback active due to playback status change Feb 17 16:40:10 primo-plus-ql volumio[1117]: SPOTIFY: RECEIVED VOLUMIO VOLUME 18 Feb 17 16:40:10 primo-plus-ql volumio[1117]: SPOTIFY: SPOTIFY VOLUME 5 Feb 17 16:40:10 primo-plus-ql volumio[1117]: SPOTIFY: VOLUMIO VOLUME 18 Feb 17 16:40:10 primo-plus-ql volumio[1117]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 17 16:40:10 primo-plus-ql volumio[1117]: info: Setting Spotify Volume from Volumio: 18 Feb 17 16:40:10 primo-plus-ql volumio[1117]: info: Updating RAAT Signal Path Feb 17 16:40:10 primo-plus-ql volumio[1117]: info: CoreStateMachine::pushState Feb 17 16:40:10 primo-plus-ql volumio[1117]: info: CorePlayQueue::getTrack 0 Feb 17 16:40:10 primo-plus-ql volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 16:40:10 primo-plus-ql volumio[1117]: info: CoreCommandRouter::volumioPushState Feb 17 16:40:10 primo-plus-ql volumio[1117]: error: Error in serial, unable to parse message: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Feb 17 16:40:10 primo-plus-ql volumio5-onboarding[1639]: time=2026-02-17T16:40:10.761+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.45:55620,192.168.1.45:55733 @ 0x23a20c0" state=STATUS_PLAYING positionMs=171152 volume=17 Feb 17 16:40:10 primo-plus-ql volumio5-onboarding[1639]: time=2026-02-17T16:40:10.761+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.45:55620,192.168.1.45:55733 @ 0x23a20c0" id=http://streams.80s80s.de/ndw/mp3-192/volumio title="Nena - ? (Fragezeichen)" Feb 17 16:40:10 primo-plus-ql volumio[1117]: info: Signalling Playback active due to playback status change Feb 17 16:40:10 primo-plus-ql volumio[1117]: SPOTIFY: RECEIVED VOLUMIO VOLUME 17 Feb 17 16:40:10 primo-plus-ql volumio[1117]: SPOTIFY: SPOTIFY VOLUME 18 Feb 17 16:40:10 primo-plus-ql volumio[1117]: SPOTIFY: VOLUMIO VOLUME 17 Feb 17 16:40:10 primo-plus-ql volumio[1117]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 17 16:40:10 primo-plus-ql volumio[1117]: info: Updating RAAT Signal Path Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: CoreStateMachine::pushState Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: CorePlayQueue::getTrack 0 Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: CoreCommandRouter::volumioPushState Feb 17 16:40:11 primo-plus-ql volumio[1117]: error: Error in serial, unable to parse message: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Feb 17 16:40:11 primo-plus-ql volumio5-onboarding[1639]: time=2026-02-17T16:40:11.081+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.45:55620,192.168.1.45:55733 @ 0x23a20c0" state=STATUS_PLAYING positionMs=171652 volume=16 Feb 17 16:40:11 primo-plus-ql volumio5-onboarding[1639]: time=2026-02-17T16:40:11.082+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.45:55620,192.168.1.45:55733 @ 0x23a20c0" id=http://streams.80s80s.de/ndw/mp3-192/volumio title="Nena - ? (Fragezeichen)" Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: Signalling Playback active due to playback status change Feb 17 16:40:11 primo-plus-ql volumio[1117]: SPOTIFY: RECEIVED VOLUMIO VOLUME 16 Feb 17 16:40:11 primo-plus-ql volumio[1117]: SPOTIFY: SPOTIFY VOLUME 18 Feb 17 16:40:11 primo-plus-ql volumio[1117]: SPOTIFY: VOLUMIO VOLUME 16 Feb 17 16:40:11 primo-plus-ql volumio[1117]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: Setting Spotify Volume from Volumio: 16 Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: Updating RAAT Signal Path Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: CoreStateMachine::pushState Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: CorePlayQueue::getTrack 0 Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: CoreCommandRouter::volumioPushState Feb 17 16:40:11 primo-plus-ql volumio[1117]: error: Error in serial, unable to parse message: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Feb 17 16:40:11 primo-plus-ql volumio5-onboarding[1639]: time=2026-02-17T16:40:11.651+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.45:55620,192.168.1.45:55733 @ 0x23a20c0" state=STATUS_PLAYING positionMs=172152 volume=15 Feb 17 16:40:11 primo-plus-ql volumio5-onboarding[1639]: time=2026-02-17T16:40:11.652+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.45:55620,192.168.1.45:55733 @ 0x23a20c0" id=http://streams.80s80s.de/ndw/mp3-192/volumio title="Nena - ? (Fragezeichen)" Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: Signalling Playback active due to playback status change Feb 17 16:40:11 primo-plus-ql volumio[1117]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Feb 17 16:40:11 primo-plus-ql volumio[1117]: SPOTIFY: SPOTIFY VOLUME 16 Feb 17 16:40:11 primo-plus-ql volumio[1117]: SPOTIFY: VOLUMIO VOLUME 15 Feb 17 16:40:11 primo-plus-ql volumio[1117]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: Updating RAAT Signal Path Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: CoreStateMachine::pushState Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: CorePlayQueue::getTrack 0 Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 16:40:11 primo-plus-ql volumio[1117]: info: CoreCommandRouter::volumioPushState Feb 17 16:40:11 primo-plus-ql volumio[1117]: error: Error in serial, unable to parse message: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Feb 17 16:40:11 primo-plus-ql volumio5-onboarding[1639]: time=2026-02-17T16:40:11.995+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.45:55620,192.168.1.45:55733 @ 0x23a20c0" state=STATUS_PLAYING positionMs=172403 volume=14 Feb 17 16:40:11 primo-plus-ql volumio5-onboarding[1639]: time=2026-02-17T16:40:11.996+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.45:55620,192.168.1.45:55733 @ 0x23a20c0" id=http://streams.80s80s.de/ndw/mp3-192/volumio title="Nena - ? (Fragezeichen)" Feb 17 16:40:12 primo-plus-ql volumio[1117]: info: Signalling Playback active due to playback status change Feb 17 16:40:12 primo-plus-ql volumio[1117]: SPOTIFY: RECEIVED VOLUMIO VOLUME 14 Feb 17 16:40:12 primo-plus-ql volumio[1117]: SPOTIFY: SPOTIFY VOLUME 16 Feb 17 16:40:12 primo-plus-ql volumio[1117]: SPOTIFY: VOLUMIO VOLUME 14 Feb 17 16:40:12 primo-plus-ql volumio[1117]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 17 16:40:12 primo-plus-ql volumio[1117]: info: Setting Spotify Volume from Volumio: 14 Feb 17 16:40:12 primo-plus-ql volumio[1117]: info: Updating RAAT Signal Path Feb 17 16:40:12 primo-plus-ql volumio[1117]: info: CoreStateMachine::pushState Feb 17 16:40:12 primo-plus-ql volumio[1117]: info: CorePlayQueue::getTrack 0 Feb 17 16:40:12 primo-plus-ql volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 16:40:12 primo-plus-ql volumio[1117]: info: CoreCommandRouter::volumioPushState Feb 17 16:40:12 primo-plus-ql volumio[1117]: error: Error in serial, unable to parse message: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Feb 17 16:40:12 primo-plus-ql volumio5-onboarding[1639]: time=2026-02-17T16:40:12.568+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.45:55620,192.168.1.45:55733 @ 0x23a20c0" state=STATUS_PLAYING positionMs=173153 volume=13 Feb 17 16:40:12 primo-plus-ql volumio5-onboarding[1639]: time=2026-02-17T16:40:12.568+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.45:55620,192.168.1.45:55733 @ 0x23a20c0" id=http://streams.80s80s.de/ndw/mp3-192/volumio title="Nena - ? (Fragezeichen)" Feb 17 16:40:12 primo-plus-ql volumio[1117]: info: Signalling Playback active due to playback status change Feb 17 16:40:12 primo-plus-ql volumio[1117]: SPOTIFY: RECEIVED VOLUMIO VOLUME 13 Feb 17 16:40:12 primo-plus-ql volumio[1117]: SPOTIFY: SPOTIFY VOLUME 14 Feb 17 16:40:12 primo-plus-ql volumio[1117]: SPOTIFY: VOLUMIO VOLUME 13 Feb 17 16:40:12 primo-plus-ql volumio[1117]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 17 16:40:12 primo-plus-ql volumio[1117]: info: Executing endpoint metavolumio Feb 17 16:40:12 primo-plus-ql volumio[1117]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 16:40:12 primo-plus-ql volumio[1117]: info: Updating RAAT Signal Path Feb 17 16:40:12 primo-plus-ql volumio[1117]: info: Executing endpoint metavolumio Feb 17 16:40:12 primo-plus-ql volumio[1117]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Feb 17 16:40:13 primo-plus-ql volumio[1117]: info: CoreStateMachine::pushState Feb 17 16:40:13 primo-plus-ql volumio[1117]: info: CorePlayQueue::getTrack 0 Feb 17 16:40:13 primo-plus-ql volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 16:40:13 primo-plus-ql volumio[1117]: info: CoreCommandRouter::volumioPushState Feb 17 16:40:13 primo-plus-ql volumio[1117]: error: Error in serial, unable to parse message: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Feb 17 16:40:13 primo-plus-ql volumio5-onboarding[1639]: time=2026-02-17T16:40:13.393+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.45:55620,192.168.1.45:55733 @ 0x23a20c0" state=STATUS_PLAYING positionMs=173904 volume=12 Feb 17 16:40:13 primo-plus-ql volumio5-onboarding[1639]: time=2026-02-17T16:40:13.394+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.45:55620,192.168.1.45:55733 @ 0x23a20c0" id=http://streams.80s80s.de/ndw/mp3-192/volumio title="Nena - ? (Fragezeichen)" Feb 17 16:40:13 primo-plus-ql volumio[1117]: info: Signalling Playback active due to playback status change Feb 17 16:40:13 primo-plus-ql volumio[1117]: SPOTIFY: RECEIVED VOLUMIO VOLUME 12 Feb 17 16:40:13 primo-plus-ql volumio[1117]: SPOTIFY: SPOTIFY VOLUME 14 Feb 17 16:40:13 primo-plus-ql volumio[1117]: SPOTIFY: VOLUMIO VOLUME 12 Feb 17 16:40:13 primo-plus-ql volumio[1117]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 17 16:40:13 primo-plus-ql volumio[1117]: info: Setting Spotify Volume from Volumio: 12 Feb 17 16:40:13 primo-plus-ql volumio[1117]: info: Updating RAAT Signal Path Feb 17 16:40:14 primo-plus-ql volumio[1117]: SPOTIFY: SETTING SPOTIFY VOLUME 12 Feb 17 16:40:14 primo-plus-ql volumio[1117]: info: Sending Spotify command with payload to local API: /player/volume Feb 17 16:40:14 primo-plus-ql go-librespot[1811]: time="2026-02-17T16:40:14+01:00" level=debug msg="update volume requested to 7864/65535" Feb 17 16:40:15 primo-plus-ql go-librespot[1811]: time="2026-02-17T16:40:15+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Feb 17 16:40:15 primo-plus-ql go-librespot[1811]: time="2026-02-17T16:40:15+01:00" level=trace msg="emitting websocket event: volume" Feb 17 16:40:15 primo-plus-ql volumio[1117]: SPOTIFY: received: {"type":"volume","data":{"value":12,"max":100}} Feb 17 16:40:15 primo-plus-ql volumio[1117]: SPOTIFY: RECEIVED SPOTIFY VOLUME 12 Feb 17 16:40:15 primo-plus-ql volumiologrotate[620]: ls: cannot access '/var/log/samba/log.wb-PRIMO': No such file or directory Feb 17 16:40:15 primo-plus-ql volumiologrotate[620]: ls: cannot access 'PLUS': No such file or directory Feb 17 16:40:15 primo-plus-ql volumiologrotate[620]: ls: cannot access 'QL': No such file or directory Feb 17 16:40:24 primo-plus-ql go-librespot[1811]: time="2026-02-17T16:40:24+01:00" level=trace msg="sent dealer ping" Feb 17 16:40:24 primo-plus-ql go-librespot[1811]: time="2026-02-17T16:40:24+01:00" level=trace msg="received dealer pong" Feb 17 16:40:41 primo-plus-ql go-librespot[1811]: time="2026-02-17T16:40:41+01:00" level=trace msg="received accesspoint ping" Feb 17 16:40:41 primo-plus-ql go-librespot[1811]: time="2026-02-17T16:40:41+01:00" level=trace msg="received accesspoint pong ack" Feb 17 16:40:45 primo-plus-ql volumio[1117]: info: [1771342845387] [80s80s] Pushing the next song state Nena - ? (Fragezeichen) and getting next track. Feb 17 16:40:45 primo-plus-ql volumio[1117]: info: CorePlayQueue::getTrack 0 Feb 17 16:40:45 primo-plus-ql volumio[1117]: info: CoreCommandRouter::servicePushState Feb 17 16:40:45 primo-plus-ql volumio[1117]: info: CorePlayQueue::getTrack 0 Feb 17 16:40:45 primo-plus-ql volumio[1117]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music128/v4/e8/3b/95/e83b9556-58fa-13c2-a508-b8c1c7228e10/source/600x600bb.jpg","name":"Nena - ? (Fragezeichen)","title":"? (Fragezeichen)","artist":"80s80s NDW","album":"","streaming":true,"disableUiControls":true,"duration":"206","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Feb 17 16:40:45 primo-plus-ql volumio[1117]: verbose: CURRENT POSITION 0 Feb 17 16:40:45 primo-plus-ql volumio[1117]: info: CoreStateMachine::syncState stateService play Feb 17 16:40:45 primo-plus-ql volumio[1117]: info: CoreStateMachine::syncState currentStatus play Feb 17 16:40:45 primo-plus-ql volumio[1117]: info: Received an update from plugin. extracting info from payload Feb 17 16:40:45 primo-plus-ql volumio[1117]: info: CoreStateMachine::pushState Feb 17 16:40:45 primo-plus-ql volumio[1117]: info: CorePlayQueue::getTrack 0 Feb 17 16:40:45 primo-plus-ql volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 16:40:45 primo-plus-ql volumio[1117]: info: CoreCommandRouter::volumioPushState Feb 17 16:40:45 primo-plus-ql volumio[1117]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 16:40:45 primo-plus-ql volumio[1117]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Feb 17 16:40:45 primo-plus-ql volumio[1117]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Feb 17 16:40:45 primo-plus-ql volumio[1117]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:182:10) Feb 17 16:40:45 primo-plus-ql volumio[1117]: at /volumio/app/index.js:326:81 Feb 17 16:40:45 primo-plus-ql volumio[1117]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Feb 17 16:40:45 primo-plus-ql volumio[1117]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Feb 17 16:40:45 primo-plus-ql volumio[1117]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Feb 17 16:40:45 primo-plus-ql volumio[1117]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Feb 17 16:40:45 primo-plus-ql volumio[1117]: at CoreStateMachine.syncState (/volumio/app/statemachine.js:764:12) Feb 17 16:40:45 primo-plus-ql volumio[1117]: at CoreCommandRouter.servicePushState (/volumio/app/index.js:414:28) Feb 17 16:40:45 primo-plus-ql volumio[1117]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:584:24) Feb 17 16:40:45 primo-plus-ql volumio[1117]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Feb 17 16:40:45 primo-plus-ql volumio[1117]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Feb 17 16:40:45 primo-plus-ql volumio[1117]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Feb 17 16:40:45 primo-plus-ql volumio[1117]: at processImmediate (internal/timers.js:461:21) { Feb 17 16:40:45 primo-plus-ql volumio[1117]: code: 'NumberExpected' Feb 17 16:40:45 primo-plus-ql volumio[1117]: } Feb 17 16:40:45 primo-plus-ql volumio[1117]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 16:40:46 primo-plus-ql sudo[15274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-17 16:39 Feb 17 16:40:46 primo-plus-ql sudo[15274]: 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="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="primoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 04:01:29 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="cm4" VOLUMIO_DEVICENAME="CM4" VOLUMIO_VENDOR_MODEL="Volumio Primo Plus" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo Plus" VOLUMIO_HASH="048051fc95e30092a9f7a83497492604"