Dec 26 20:23:03 volumiopc go-librespot[47091]: time="2025-12-26T20:23:03+02:00" level=trace msg="sent dealer ping" Dec 26 20:23:03 volumiopc go-librespot[47091]: time="2025-12-26T20:23:03+02:00" level=trace msg="received dealer pong" Dec 26 20:23:33 volumiopc go-librespot[47091]: time="2025-12-26T20:23:33+02:00" level=trace msg="sent dealer ping" Dec 26 20:23:33 volumiopc go-librespot[47091]: time="2025-12-26T20:23:33+02:00" level=trace msg="received dealer pong" Dec 26 20:24:03 volumiopc go-librespot[47091]: time="2025-12-26T20:24:03+02:00" level=trace msg="sent dealer ping" Dec 26 20:24:03 volumiopc go-librespot[47091]: time="2025-12-26T20:24:03+02:00" level=trace msg="received dealer pong" Dec 26 20:24:16 volumiopc volumio[1109]: info: Dec 26 20:24:16 volumiopc volumio[1109]: ---------------------------- MPD announces system playlist update Dec 26 20:24:16 volumiopc volumio[1109]: info: Ignoring MPD Status Update Dec 26 20:24:16 volumiopc volumio[1109]: info: Dec 26 20:24:16 volumiopc volumio[1109]: ---------------------------- MPD announces state update: player Dec 26 20:24:16 volumiopc volumio[1109]: info: ControllerMpd::getState Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Dec 26 20:24:16 volumiopc volumio[1109]: info: Dec 26 20:24:16 volumiopc volumio[1109]: ---------------------------- MPD announces system playlist update Dec 26 20:24:16 volumiopc volumio[1109]: info: Ignoring MPD Status Update Dec 26 20:24:16 volumiopc volumio[1109]: info: Dec 26 20:24:16 volumiopc volumio[1109]: ---------------------------- MPD announces state update: player Dec 26 20:24:16 volumiopc volumio[1109]: info: ControllerMpd::getState Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Dec 26 20:24:16 volumiopc volumio[1109]: info: Dec 26 20:24:16 volumiopc volumio[1109]: ---------------------------- MPD announces system playlist update Dec 26 20:24:16 volumiopc volumio[1109]: info: Ignoring MPD Status Update Dec 26 20:24:16 volumiopc volumio[1109]: info: Dec 26 20:24:16 volumiopc volumio[1109]: ---------------------------- MPD announces state update: player Dec 26 20:24:16 volumiopc volumio[1109]: info: ControllerMpd::getState Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Dec 26 20:24:16 volumiopc volumio[1109]: info: Dec 26 20:24:16 volumiopc volumio[1109]: ---------------------------- MPD announces system playlist update Dec 26 20:24:16 volumiopc volumio[1109]: info: Ignoring MPD Status Update Dec 26 20:24:16 volumiopc volumio[1109]: info: Dec 26 20:24:16 volumiopc volumio[1109]: ---------------------------- MPD announces state update: player Dec 26 20:24:16 volumiopc volumio[1109]: info: ControllerMpd::getState Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Dec 26 20:24:16 volumiopc volumio[1109]: info: Dec 26 20:24:16 volumiopc volumio[1109]: ---------------------------- MPD announces system playlist update Dec 26 20:24:16 volumiopc volumio[1109]: info: Ignoring MPD Status Update Dec 26 20:24:16 volumiopc volumio[1109]: info: Dec 26 20:24:16 volumiopc volumio[1109]: ---------------------------- MPD announces state update: player Dec 26 20:24:16 volumiopc volumio[1109]: info: ControllerMpd::getState Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Dec 26 20:24:16 volumiopc volumio[1109]: info: Dec 26 20:24:16 volumiopc volumio[1109]: ---------------------------- MPD announces system playlist update Dec 26 20:24:16 volumiopc volumio[1109]: info: Ignoring MPD Status Update Dec 26 20:24:16 volumiopc volumio[1109]: info: Dec 26 20:24:16 volumiopc volumio[1109]: ---------------------------- MPD announces state update: player Dec 26 20:24:16 volumiopc volumio[1109]: info: ControllerMpd::getState Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Dec 26 20:24:16 volumiopc volumio[1109]: info: ------------------------------ 3ms Dec 26 20:24:16 volumiopc volumio[1109]: info: sendMpdCommand status took 3 milliseconds Dec 26 20:24:16 volumiopc volumio[1109]: info: ------------------------------ 3ms Dec 26 20:24:16 volumiopc volumio[1109]: info: sendMpdCommand status took 2 milliseconds Dec 26 20:24:16 volumiopc volumio[1109]: info: ------------------------------ 3ms Dec 26 20:24:16 volumiopc volumio[1109]: info: sendMpdCommand status took 3 milliseconds Dec 26 20:24:16 volumiopc volumio[1109]: info: ------------------------------ 2ms Dec 26 20:24:16 volumiopc volumio[1109]: info: sendMpdCommand status took 2 milliseconds Dec 26 20:24:16 volumiopc volumio[1109]: info: ------------------------------ 2ms Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::parseState Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::parseState Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::parseState Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::parseState Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 20:24:16 volumiopc volumio[1109]: info: sendMpdCommand status took 3 milliseconds Dec 26 20:24:16 volumiopc volumio[1109]: info: ------------------------------ 3ms Dec 26 20:24:16 volumiopc volumio[1109]: info: sendMpdCommand status took 3 milliseconds Dec 26 20:24:16 volumiopc volumio[1109]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 26 20:24:16 volumiopc volumio[1109]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 26 20:24:16 volumiopc volumio[1109]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 26 20:24:16 volumiopc volumio[1109]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::parseState Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::parseState Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::parseTrackInfo Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::parseTrackInfo Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::parseTrackInfo Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::parseTrackInfo Dec 26 20:24:16 volumiopc volumio[1109]: info: ControllerMpd::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::servicePushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CorePlayQueue::getTrack 0 Dec 26 20:24:16 volumiopc volumio[1109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12535227,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO ENERGY - HIT MUSIC ONLY","artist":"RADIO ENERGY","album":null,"uri":"http://play.global.audio/nrj128","trackType":""} Dec 26 20:24:16 volumiopc volumio[1109]: verbose: CURRENT POSITION 0 Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::syncState stateService play Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::syncState currentStatus play Dec 26 20:24:16 volumiopc volumio[1109]: info: Received an update from plugin. extracting info from payload Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:16 volumiopc volumio[1109]: info: ControllerMpd::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::servicePushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CorePlayQueue::getTrack 0 Dec 26 20:24:16 volumiopc volumio[1109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12535227,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO ENERGY - HIT MUSIC ONLY","artist":"RADIO ENERGY","album":null,"uri":"http://play.global.audio/nrj128","trackType":""} Dec 26 20:24:16 volumiopc volumio[1109]: verbose: CURRENT POSITION 0 Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::syncState stateService play Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::syncState currentStatus play Dec 26 20:24:16 volumiopc volumio[1109]: info: Received an update from plugin. extracting info from payload Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:16 volumiopc volumio[1109]: info: ControllerMpd::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::servicePushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CorePlayQueue::getTrack 0 Dec 26 20:24:16 volumiopc volumio[1109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12535227,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO ENERGY - HIT MUSIC ONLY","artist":"RADIO ENERGY","album":null,"uri":"http://play.global.audio/nrj128","trackType":""} Dec 26 20:24:16 volumiopc volumio[1109]: verbose: CURRENT POSITION 0 Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::syncState stateService play Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::syncState currentStatus play Dec 26 20:24:16 volumiopc volumio[1109]: info: Received an update from plugin. extracting info from payload Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:16 volumiopc volumio[1109]: info: ControllerMpd::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::servicePushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CorePlayQueue::getTrack 0 Dec 26 20:24:16 volumiopc volumio[1109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12535227,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO ENERGY - HIT MUSIC ONLY","artist":"RADIO ENERGY","album":null,"uri":"http://play.global.audio/nrj128","trackType":""} Dec 26 20:24:16 volumiopc volumio[1109]: verbose: CURRENT POSITION 0 Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::syncState stateService play Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::syncState currentStatus play Dec 26 20:24:16 volumiopc volumio[1109]: info: Received an update from plugin. extracting info from payload Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:16 volumiopc volumio[1109]: info: ------------------------------ 28ms Dec 26 20:24:16 volumiopc volumio[1109]: info: ------------------------------ 27ms Dec 26 20:24:16 volumiopc volumio[1109]: info: ------------------------------ 27ms Dec 26 20:24:16 volumiopc volumio[1109]: info: ------------------------------ 27ms Dec 26 20:24:16 volumiopc volumio[1109]: info: sendMpdCommand playlistinfo took 24 milliseconds Dec 26 20:24:16 volumiopc volumio[1109]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::parseTrackInfo Dec 26 20:24:16 volumiopc volumio[1109]: verbose: ControllerMpd::parseTrackInfo Dec 26 20:24:16 volumiopc volumio[1109]: info: ControllerMpd::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::servicePushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CorePlayQueue::getTrack 0 Dec 26 20:24:16 volumiopc volumio[1109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12535227,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO ENERGY - HIT MUSIC ONLY","artist":"RADIO ENERGY","album":null,"uri":"http://play.global.audio/nrj128","trackType":""} Dec 26 20:24:16 volumiopc volumio[1109]: verbose: CURRENT POSITION 0 Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::syncState stateService play Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::syncState currentStatus play Dec 26 20:24:16 volumiopc volumio[1109]: info: Received an update from plugin. extracting info from payload Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:16 volumiopc volumio[1109]: info: ControllerMpd::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::servicePushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CorePlayQueue::getTrack 0 Dec 26 20:24:16 volumiopc volumio[1109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12535227,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"RADIO ENERGY - HIT MUSIC ONLY","artist":"RADIO ENERGY","album":null,"uri":"http://play.global.audio/nrj128","trackType":""} Dec 26 20:24:16 volumiopc volumio[1109]: verbose: CURRENT POSITION 0 Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::syncState stateService play Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::syncState currentStatus play Dec 26 20:24:16 volumiopc volumio[1109]: info: Received an update from plugin. extracting info from payload Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:16 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:16 volumiopc volumio[1109]: info: ------------------------------ 43ms Dec 26 20:24:16 volumiopc volumio[1109]: info: ------------------------------ 42ms Dec 26 20:24:16 volumiopc volumio[1109]: info: [LastFM] Split composite title into artist: RADIO ENERGY and title: HIT MUSIC ONLY Dec 26 20:24:16 volumiopc volumio[1109]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"HIT MUSIC ONLY","url":"https://www.last.fm/music/Radio+Energy/_/HIT+MUSIC+ONLY","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"36","playcount":"1505","artist":{"name":"Radio Energy","url":"https://www.last.fm/music/Radio+Energy"},"userplaycount":"0","userloved":"0","toptags":""}} Dec 26 20:24:16 volumiopc volumio[1109]: info: [LastFM] Updated missing track duration: 0 Dec 26 20:24:22 volumiopc volumio[1109]: info: Dec 26 20:24:22 volumiopc volumio[1109]: ---------------------------- MPD announces system playlist update Dec 26 20:24:22 volumiopc volumio[1109]: info: Ignoring MPD Status Update Dec 26 20:24:22 volumiopc volumio[1109]: info: Dec 26 20:24:22 volumiopc volumio[1109]: ---------------------------- MPD announces state update: player Dec 26 20:24:22 volumiopc volumio[1109]: info: ControllerMpd::getState Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Dec 26 20:24:22 volumiopc volumio[1109]: info: Dec 26 20:24:22 volumiopc volumio[1109]: ---------------------------- MPD announces system playlist update Dec 26 20:24:22 volumiopc volumio[1109]: info: Ignoring MPD Status Update Dec 26 20:24:22 volumiopc volumio[1109]: info: Dec 26 20:24:22 volumiopc volumio[1109]: ---------------------------- MPD announces state update: player Dec 26 20:24:22 volumiopc volumio[1109]: info: ControllerMpd::getState Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Dec 26 20:24:22 volumiopc volumio[1109]: info: Dec 26 20:24:22 volumiopc volumio[1109]: ---------------------------- MPD announces system playlist update Dec 26 20:24:22 volumiopc volumio[1109]: info: Ignoring MPD Status Update Dec 26 20:24:22 volumiopc volumio[1109]: info: Dec 26 20:24:22 volumiopc volumio[1109]: ---------------------------- MPD announces state update: player Dec 26 20:24:22 volumiopc volumio[1109]: info: ControllerMpd::getState Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Dec 26 20:24:22 volumiopc volumio[1109]: info: Dec 26 20:24:22 volumiopc volumio[1109]: ---------------------------- MPD announces system playlist update Dec 26 20:24:22 volumiopc volumio[1109]: info: Ignoring MPD Status Update Dec 26 20:24:22 volumiopc volumio[1109]: info: Dec 26 20:24:22 volumiopc volumio[1109]: ---------------------------- MPD announces state update: player Dec 26 20:24:22 volumiopc volumio[1109]: info: ControllerMpd::getState Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Dec 26 20:24:22 volumiopc volumio[1109]: info: Dec 26 20:24:22 volumiopc volumio[1109]: ---------------------------- MPD announces system playlist update Dec 26 20:24:22 volumiopc volumio[1109]: info: Ignoring MPD Status Update Dec 26 20:24:22 volumiopc volumio[1109]: info: Dec 26 20:24:22 volumiopc volumio[1109]: ---------------------------- MPD announces state update: player Dec 26 20:24:22 volumiopc volumio[1109]: info: ControllerMpd::getState Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Dec 26 20:24:22 volumiopc volumio[1109]: info: Dec 26 20:24:22 volumiopc volumio[1109]: ---------------------------- MPD announces system playlist update Dec 26 20:24:22 volumiopc volumio[1109]: info: Ignoring MPD Status Update Dec 26 20:24:22 volumiopc volumio[1109]: info: Dec 26 20:24:22 volumiopc volumio[1109]: ---------------------------- MPD announces state update: player Dec 26 20:24:22 volumiopc volumio[1109]: info: ControllerMpd::getState Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand status Dec 26 20:24:22 volumiopc volumio[1109]: info: ------------------------------ 6ms Dec 26 20:24:22 volumiopc volumio[1109]: info: sendMpdCommand status took 5 milliseconds Dec 26 20:24:22 volumiopc volumio[1109]: info: ------------------------------ 6ms Dec 26 20:24:22 volumiopc volumio[1109]: info: sendMpdCommand status took 5 milliseconds Dec 26 20:24:22 volumiopc volumio[1109]: info: ------------------------------ 5ms Dec 26 20:24:22 volumiopc volumio[1109]: info: sendMpdCommand status took 4 milliseconds Dec 26 20:24:22 volumiopc volumio[1109]: info: ------------------------------ 4ms Dec 26 20:24:22 volumiopc volumio[1109]: info: sendMpdCommand status took 3 milliseconds Dec 26 20:24:22 volumiopc volumio[1109]: info: ------------------------------ 3ms Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::parseState Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::parseState Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::parseState Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::parseState Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 20:24:22 volumiopc volumio[1109]: info: sendMpdCommand status took 5 milliseconds Dec 26 20:24:22 volumiopc volumio[1109]: info: ------------------------------ 4ms Dec 26 20:24:22 volumiopc volumio[1109]: info: sendMpdCommand status took 3 milliseconds Dec 26 20:24:22 volumiopc volumio[1109]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 26 20:24:22 volumiopc volumio[1109]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 26 20:24:22 volumiopc volumio[1109]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 26 20:24:22 volumiopc volumio[1109]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::parseState Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::parseState Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::parseTrackInfo Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::parseTrackInfo Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::parseTrackInfo Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::parseTrackInfo Dec 26 20:24:22 volumiopc volumio[1109]: info: ControllerMpd::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::servicePushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CorePlayQueue::getTrack 0 Dec 26 20:24:22 volumiopc volumio[1109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12541270,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ATB - 9 P.M. (Till I Come)","artist":"RADIO ENERGY","album":null,"uri":"http://play.global.audio/nrj128","trackType":""} Dec 26 20:24:22 volumiopc volumio[1109]: verbose: CURRENT POSITION 0 Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::syncState stateService play Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::syncState currentStatus play Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::syncState currentStatus play Dec 26 20:24:22 volumiopc volumio[1109]: info: Received an update from plugin. extracting info from payload Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:22 volumiopc volumio[1109]: info: ControllerMpd::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::servicePushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CorePlayQueue::getTrack 0 Dec 26 20:24:22 volumiopc volumio[1109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12541270,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ATB - 9 P.M. (Till I Come)","artist":"RADIO ENERGY","album":null,"uri":"http://play.global.audio/nrj128","trackType":""} Dec 26 20:24:22 volumiopc volumio[1109]: verbose: CURRENT POSITION 0 Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::syncState stateService play Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::syncState currentStatus play Dec 26 20:24:22 volumiopc volumio[1109]: info: Received an update from plugin. extracting info from payload Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:22 volumiopc volumio[1109]: info: ControllerMpd::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::servicePushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CorePlayQueue::getTrack 0 Dec 26 20:24:22 volumiopc volumio[1109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12541270,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ATB - 9 P.M. (Till I Come)","artist":"RADIO ENERGY","album":null,"uri":"http://play.global.audio/nrj128","trackType":""} Dec 26 20:24:22 volumiopc volumio[1109]: verbose: CURRENT POSITION 0 Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::syncState stateService play Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::syncState currentStatus play Dec 26 20:24:22 volumiopc volumio[1109]: info: Received an update from plugin. extracting info from payload Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:22 volumiopc volumio[1109]: info: ControllerMpd::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::servicePushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CorePlayQueue::getTrack 0 Dec 26 20:24:22 volumiopc volumio[1109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12541270,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ATB - 9 P.M. (Till I Come)","artist":"RADIO ENERGY","album":null,"uri":"http://play.global.audio/nrj128","trackType":""} Dec 26 20:24:22 volumiopc volumio[1109]: verbose: CURRENT POSITION 0 Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::syncState stateService play Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::syncState currentStatus play Dec 26 20:24:22 volumiopc volumio[1109]: info: Received an update from plugin. extracting info from payload Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:22 volumiopc volumio[1109]: info: ------------------------------ 23ms Dec 26 20:24:22 volumiopc volumio[1109]: info: ------------------------------ 22ms Dec 26 20:24:22 volumiopc volumio[1109]: info: ------------------------------ 22ms Dec 26 20:24:22 volumiopc volumio[1109]: info: ------------------------------ 22ms Dec 26 20:24:22 volumiopc volumio[1109]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 26 20:24:22 volumiopc volumio[1109]: info: sendMpdCommand playlistinfo took 21 milliseconds Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::parseTrackInfo Dec 26 20:24:22 volumiopc volumio[1109]: verbose: ControllerMpd::parseTrackInfo Dec 26 20:24:22 volumiopc volumio[1109]: info: ControllerMpd::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::servicePushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CorePlayQueue::getTrack 0 Dec 26 20:24:22 volumiopc volumio[1109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12541270,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ATB - 9 P.M. (Till I Come)","artist":"RADIO ENERGY","album":null,"uri":"http://play.global.audio/nrj128","trackType":""} Dec 26 20:24:22 volumiopc volumio[1109]: verbose: CURRENT POSITION 0 Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::syncState stateService play Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::syncState currentStatus play Dec 26 20:24:22 volumiopc volumio[1109]: info: Received an update from plugin. extracting info from payload Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:22 volumiopc volumio[1109]: info: ControllerMpd::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::servicePushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CorePlayQueue::getTrack 0 Dec 26 20:24:22 volumiopc volumio[1109]: verbose: STATE SERVICE {"status":"play","position":0,"seek":12541270,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ATB - 9 P.M. (Till I Come)","artist":"RADIO ENERGY","album":null,"uri":"http://play.global.audio/nrj128","trackType":""} Dec 26 20:24:22 volumiopc volumio[1109]: verbose: CURRENT POSITION 0 Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::syncState stateService play Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::syncState currentStatus play Dec 26 20:24:22 volumiopc volumio[1109]: info: Received an update from plugin. extracting info from payload Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreStateMachine::pushState Dec 26 20:24:22 volumiopc volumio[1109]: info: CoreCommandRouter::volumioPushState Dec 26 20:24:22 volumiopc volumio[1109]: info: ------------------------------ 41ms Dec 26 20:24:22 volumiopc volumio[1109]: info: ------------------------------ 41ms Dec 26 20:24:22 volumiopc volumio[1109]: info: [LastFM] Split composite title into artist: ATB and title: 9 P.M. (Till I Come) Dec 26 20:24:31 volumiopc volumio[1109]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 20:24:31 volumiopc volumio[1109]: TypeError: Cannot read properties of undefined (reading '@') Dec 26 20:24:31 volumiopc volumio[1109]: at Object.callback (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:342:14) Dec 26 20:24:31 volumiopc volumio[1109]: at /data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:60:13 Dec 26 20:24:31 volumiopc volumio[1109]: at Parser. (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:322:18) Dec 26 20:24:31 volumiopc volumio[1109]: at Parser.emit (node:events:514:28) Dec 26 20:24:31 volumiopc volumio[1109]: at SAXParser.onerror (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:133:26) Dec 26 20:24:31 volumiopc volumio[1109]: at emit (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:631:35) Dec 26 20:24:31 volumiopc volumio[1109]: at error (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:660:5) Dec 26 20:24:31 volumiopc volumio[1109]: at strictFail (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:684:7) Dec 26 20:24:31 volumiopc volumio[1109]: at closeTag (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:878:9) Dec 26 20:24:31 volumiopc volumio[1109]: at SAXParser.write (/data/plugins/user_interface/lastfm/node_modules/sax/lib/sax.js:1460:13) Dec 26 20:24:31 volumiopc volumio[1109]: at exports.Parser.Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:337:31) Dec 26 20:24:31 volumiopc volumio[1109]: at Parser.parseString (/data/plugins/user_interface/lastfm/node_modules/xml2js/lib/parser.js:5:59) Dec 26 20:24:31 volumiopc volumio[1109]: at IncomingMessage. (/data/plugins/user_interface/lastfm/node_modules/simple-lastfm/lib/index.js:58:14) Dec 26 20:24:31 volumiopc volumio[1109]: at IncomingMessage.emit (node:events:526:35) Dec 26 20:24:31 volumiopc volumio[1109]: at endReadableNT (node:internal/streams/readable:1376:12) Dec 26 20:24:31 volumiopc volumio[1109]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) Dec 26 20:24:31 volumiopc volumio[1109]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 20:24:32 volumiopc sudo[64982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 20:23' Dec 26 20:24:32 volumiopc sudo[64982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:38:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="00e933ebe3692ad8aea9910d2289bf23"