Jan 13 22:10:18 volumio go-librespot[2003]: time="2026-01-13T22:10:18+01:00" level=trace msg="sent dealer ping" Jan 13 22:10:18 volumio go-librespot[2003]: time="2026-01-13T22:10:18+01:00" level=trace msg="received dealer pong" Jan 13 22:10:48 volumio go-librespot[2003]: time="2026-01-13T22:10:48+01:00" level=trace msg="sent dealer ping" Jan 13 22:10:48 volumio go-librespot[2003]: time="2026-01-13T22:10:48+01:00" level=trace msg="received dealer pong" Jan 13 22:11:18 volumio go-librespot[2003]: time="2026-01-13T22:11:18+01:00" level=trace msg="sent dealer ping" Jan 13 22:11:18 volumio go-librespot[2003]: time="2026-01-13T22:11:18+01:00" level=trace msg="received dealer pong" Jan 13 22:11:21 volumio go-librespot[2003]: time="2026-01-13T22:11:21+01:00" level=trace msg="received accesspoint ping" Jan 13 22:11:21 volumio go-librespot[2003]: time="2026-01-13T22:11:21+01:00" level=trace msg="received accesspoint pong ack" Jan 13 22:11:30 volumio volumio[1359]: info: Jan 13 22:11:30 volumio volumio[1359]: ---------------------------- MPD announces system playlist update Jan 13 22:11:30 volumio volumio[1359]: info: Ignoring MPD Status Update Jan 13 22:11:30 volumio volumio[1359]: info: Jan 13 22:11:30 volumio volumio[1359]: ---------------------------- MPD announces state update: player Jan 13 22:11:30 volumio volumio[1359]: info: ControllerMpd::getState Jan 13 22:11:30 volumio volumio[1359]: verbose: ControllerMpd::sendMpdCommand status Jan 13 22:11:30 volumio volumio[1359]: info: ------------------------------ 2ms Jan 13 22:11:30 volumio volumio[1359]: info: sendMpdCommand status took 1 milliseconds Jan 13 22:11:30 volumio volumio[1359]: verbose: ControllerMpd::parseState Jan 13 22:11:30 volumio volumio[1359]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 13 22:11:30 volumio volumio[1359]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 13 22:11:30 volumio volumio[1359]: verbose: ControllerMpd::parseTrackInfo Jan 13 22:11:30 volumio volumio[1359]: info: ControllerMpd::pushState Jan 13 22:11:30 volumio volumio[1359]: info: CoreCommandRouter::servicePushState Jan 13 22:11:30 volumio volumio[1359]: info: CorePlayQueue::getTrack 0 Jan 13 22:11:30 volumio volumio[1359]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Jan 13 22:11:30 volumio volumio[1359]: info: ------------------------------ 4ms Jan 13 22:11:48 volumio go-librespot[2003]: time="2026-01-13T22:11:48+01:00" level=trace msg="sent dealer ping" Jan 13 22:11:48 volumio go-librespot[2003]: time="2026-01-13T22:11:48+01:00" level=trace msg="received dealer pong" Jan 13 22:11:54 volumio volumio[1359]: info: [1768338714547] [80s80s] Pushing the next song state Michael Jackson - Dirty Diana and getting next track. Jan 13 22:11:54 volumio volumio[1359]: info: CorePlayQueue::getTrack 0 Jan 13 22:11:54 volumio volumio[1359]: info: CoreCommandRouter::servicePushState Jan 13 22:11:54 volumio volumio[1359]: info: CorePlayQueue::getTrack 0 Jan 13 22:11:54 volumio volumio[1359]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is5-ssl.mzstatic.com/image/thumb/Music125/v4/9f/ac/1c/9fac1c50-8472-8c43-3448-59205cd580a7/source/600x600bb.jpg","name":"Michael Jackson - Dirty Diana","title":"Dirty Diana","artist":"80s80s Michael Jackson","album":"","streaming":true,"disableUiControls":true,"duration":"255","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Jan 13 22:11:54 volumio volumio[1359]: verbose: CURRENT POSITION 0 Jan 13 22:11:54 volumio volumio[1359]: info: CoreStateMachine::syncState stateService play Jan 13 22:11:54 volumio volumio[1359]: info: CoreStateMachine::syncState currentStatus play Jan 13 22:11:54 volumio volumio[1359]: info: Received an update from plugin. extracting info from payload Jan 13 22:11:54 volumio volumio[1359]: info: CoreStateMachine::pushState Jan 13 22:11:54 volumio volumio[1359]: info: CorePlayQueue::getTrack 0 Jan 13 22:11:54 volumio volumio[1359]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 22:11:54 volumio volumio[1359]: info: CoreCommandRouter::volumioPushState Jan 13 22:11:54 volumio volumio[1359]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 22:11:54 volumio volumio[1359]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Jan 13 22:11:54 volumio volumio[1359]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Jan 13 22:11:54 volumio volumio[1359]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:182:10) Jan 13 22:11:54 volumio volumio[1359]: at /volumio/app/index.js:326:81 Jan 13 22:11:54 volumio volumio[1359]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Jan 13 22:11:54 volumio volumio[1359]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Jan 13 22:11:54 volumio volumio[1359]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Jan 13 22:11:54 volumio volumio[1359]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Jan 13 22:11:54 volumio volumio[1359]: at CoreStateMachine.syncState (/volumio/app/statemachine.js:764:12) Jan 13 22:11:54 volumio volumio[1359]: at CoreCommandRouter.servicePushState (/volumio/app/index.js:414:28) Jan 13 22:11:54 volumio volumio[1359]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:584:24) Jan 13 22:11:54 volumio volumio[1359]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Jan 13 22:11:54 volumio volumio[1359]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Jan 13 22:11:54 volumio volumio[1359]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Jan 13 22:11:54 volumio volumio[1359]: at process.processImmediate (node:internal/timers:478:21) { Jan 13 22:11:54 volumio volumio[1359]: code: 'NumberExpected' Jan 13 22:11:54 volumio volumio[1359]: } Jan 13 22:11:54 volumio volumio[1359]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 22:11:54 volumio sudo[12451]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-13 22:10' Jan 13 22:11:54 volumio sudo[12451]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"