-- Logs begin at Sat 2025-07-26 11:23:19 CEST, end at Wed 2025-09-03 14:01:16 CEST. -- Sep 03 14:00:43 rivoplus volumio[3193]: info: Sep 03 14:00:43 rivoplus volumio[3193]: ---------------------------- MPD announces system playlist update Sep 03 14:00:43 rivoplus volumio[3193]: info: Ignoring MPD Status Update Sep 03 14:00:43 rivoplus volumio[3193]: info: Sep 03 14:00:43 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Sep 03 14:00:43 rivoplus volumio[3193]: info: ControllerMpd::getState Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:00:43 rivoplus volumio[3193]: info: Sep 03 14:00:43 rivoplus volumio[3193]: ---------------------------- MPD announces system playlist update Sep 03 14:00:43 rivoplus volumio[3193]: info: Ignoring MPD Status Update Sep 03 14:00:43 rivoplus volumio[3193]: info: Sep 03 14:00:43 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Sep 03 14:00:43 rivoplus volumio[3193]: info: ControllerMpd::getState Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:00:43 rivoplus volumio[3193]: info: Sep 03 14:00:43 rivoplus volumio[3193]: ---------------------------- MPD announces system playlist update Sep 03 14:00:43 rivoplus volumio[3193]: info: Ignoring MPD Status Update Sep 03 14:00:43 rivoplus volumio[3193]: info: Sep 03 14:00:43 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Sep 03 14:00:43 rivoplus volumio[3193]: info: ControllerMpd::getState Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:00:43 rivoplus volumio[3193]: info: Sep 03 14:00:43 rivoplus volumio[3193]: ---------------------------- MPD announces system playlist update Sep 03 14:00:43 rivoplus volumio[3193]: info: Ignoring MPD Status Update Sep 03 14:00:43 rivoplus volumio[3193]: info: Sep 03 14:00:43 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Sep 03 14:00:43 rivoplus volumio[3193]: info: ControllerMpd::getState Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:00:43 rivoplus volumio[3193]: info: ------------------------------ 48ms Sep 03 14:00:43 rivoplus volumio[3193]: info: sendMpdCommand status took 30 milliseconds Sep 03 14:00:43 rivoplus volumio[3193]: info: ------------------------------ 26ms Sep 03 14:00:43 rivoplus volumio[3193]: info: sendMpdCommand status took 21 milliseconds Sep 03 14:00:43 rivoplus volumio[3193]: info: ------------------------------ 21ms Sep 03 14:00:43 rivoplus volumio[3193]: info: sendMpdCommand status took 18 milliseconds Sep 03 14:00:43 rivoplus volumio[3193]: info: ------------------------------ 16ms Sep 03 14:00:43 rivoplus volumio[3193]: info: sendMpdCommand status took 13 milliseconds Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:00:43 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 03 14:00:43 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 03 14:00:43 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 03 14:00:43 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 6 milliseconds Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:00:43 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:00:43 rivoplus volumio[3193]: info: ControllerMpd::pushState Sep 03 14:00:43 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Sep 03 14:00:43 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 0 Sep 03 14:00:43 rivoplus volumio[3193]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 14:00:43 rivoplus volumio[3193]: info: ControllerMpd::pushState Sep 03 14:00:43 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Sep 03 14:00:43 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 0 Sep 03 14:00:43 rivoplus volumio[3193]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 14:00:43 rivoplus volumio[3193]: info: ControllerMpd::pushState Sep 03 14:00:43 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Sep 03 14:00:43 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 0 Sep 03 14:00:43 rivoplus volumio[3193]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 14:00:43 rivoplus volumio[3193]: info: ControllerMpd::pushState Sep 03 14:00:43 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Sep 03 14:00:43 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 0 Sep 03 14:00:43 rivoplus volumio[3193]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 14:00:43 rivoplus volumio[3193]: info: ------------------------------ 74ms Sep 03 14:00:43 rivoplus volumio[3193]: info: ------------------------------ 61ms Sep 03 14:00:43 rivoplus volumio[3193]: info: ------------------------------ 56ms Sep 03 14:00:43 rivoplus volumio[3193]: info: ------------------------------ 51ms Sep 03 14:00:58 rivoplus volumio[3193]: info: Sep 03 14:00:58 rivoplus volumio[3193]: ---------------------------- MPD announces system playlist update Sep 03 14:00:58 rivoplus volumio[3193]: info: Ignoring MPD Status Update Sep 03 14:00:58 rivoplus volumio[3193]: info: Sep 03 14:00:58 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Sep 03 14:00:58 rivoplus volumio[3193]: info: ControllerMpd::getState Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:00:58 rivoplus volumio[3193]: info: ------------------------------ 7ms Sep 03 14:00:58 rivoplus volumio[3193]: info: sendMpdCommand status took 4 milliseconds Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:00:58 rivoplus volumio[3193]: info: Sep 03 14:00:58 rivoplus volumio[3193]: ---------------------------- MPD announces system playlist update Sep 03 14:00:58 rivoplus volumio[3193]: info: Ignoring MPD Status Update Sep 03 14:00:58 rivoplus volumio[3193]: info: Sep 03 14:00:58 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Sep 03 14:00:58 rivoplus volumio[3193]: info: ControllerMpd::getState Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:00:58 rivoplus volumio[3193]: info: Sep 03 14:00:58 rivoplus volumio[3193]: ---------------------------- MPD announces system playlist update Sep 03 14:00:58 rivoplus volumio[3193]: info: Ignoring MPD Status Update Sep 03 14:00:58 rivoplus volumio[3193]: info: Sep 03 14:00:58 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Sep 03 14:00:58 rivoplus volumio[3193]: info: ControllerMpd::getState Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:00:58 rivoplus volumio[3193]: info: Sep 03 14:00:58 rivoplus volumio[3193]: ---------------------------- MPD announces system playlist update Sep 03 14:00:58 rivoplus volumio[3193]: info: Ignoring MPD Status Update Sep 03 14:00:58 rivoplus volumio[3193]: info: Sep 03 14:00:58 rivoplus volumio[3193]: ---------------------------- MPD announces state update: player Sep 03 14:00:58 rivoplus volumio[3193]: info: ControllerMpd::getState Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand status Sep 03 14:00:58 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 18 milliseconds Sep 03 14:00:58 rivoplus volumio[3193]: info: ------------------------------ 17ms Sep 03 14:00:58 rivoplus volumio[3193]: info: sendMpdCommand status took 14 milliseconds Sep 03 14:00:58 rivoplus volumio[3193]: info: ------------------------------ 14ms Sep 03 14:00:58 rivoplus volumio[3193]: info: sendMpdCommand status took 11 milliseconds Sep 03 14:00:58 rivoplus volumio[3193]: info: ------------------------------ 10ms Sep 03 14:00:58 rivoplus volumio[3193]: info: sendMpdCommand status took 7 milliseconds Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::parseState Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 03 14:00:58 rivoplus volumio[3193]: info: ControllerMpd::pushState Sep 03 14:00:58 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Sep 03 14:00:58 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 0 Sep 03 14:00:58 rivoplus volumio[3193]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 14:00:58 rivoplus volumio[3193]: info: ------------------------------ 38ms Sep 03 14:00:58 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 03 14:00:58 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 03 14:00:58 rivoplus volumio[3193]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:00:58 rivoplus volumio[3193]: verbose: ControllerMpd::parseTrackInfo Sep 03 14:00:58 rivoplus volumio[3193]: info: ControllerMpd::pushState Sep 03 14:00:58 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Sep 03 14:00:58 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 0 Sep 03 14:00:58 rivoplus volumio[3193]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 14:00:58 rivoplus volumio[3193]: info: ControllerMpd::pushState Sep 03 14:00:58 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Sep 03 14:00:58 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 0 Sep 03 14:00:58 rivoplus volumio[3193]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 14:00:58 rivoplus volumio[3193]: info: ControllerMpd::pushState Sep 03 14:00:58 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Sep 03 14:00:58 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 0 Sep 03 14:00:58 rivoplus volumio[3193]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Sep 03 14:00:58 rivoplus volumio[3193]: info: ------------------------------ 40ms Sep 03 14:00:58 rivoplus volumio[3193]: info: ------------------------------ 36ms Sep 03 14:00:58 rivoplus volumio[3193]: info: ------------------------------ 32ms Sep 03 14:01:15 rivoplus volumio[3193]: info: [1756900875696] [80s80s] Pushing the next song state Coolio - 1,2,3,4 (Sumpin' New) and getting next track. Sep 03 14:01:15 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 0 Sep 03 14:01:15 rivoplus volumio[3193]: info: CoreCommandRouter::servicePushState Sep 03 14:01:15 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 0 Sep 03 14:01:15 rivoplus volumio[3193]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is2-ssl.mzstatic.com/image/thumb/Music114/v4/1e/79/91/1e799126-2473-2612-71a9-ca28005766a9/source/600x600bb.jpg","name":"Coolio - 1,2,3,4 (Sumpin' New)","title":"1,2,3,4 (Sumpin' New)","artist":"90s90s HipHop & Rap","album":"","streaming":true,"disableUiControls":true,"duration":"195","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Sep 03 14:01:15 rivoplus volumio[3193]: verbose: CURRENT POSITION 0 Sep 03 14:01:15 rivoplus volumio[3193]: info: CoreStateMachine::syncState stateService play Sep 03 14:01:15 rivoplus volumio[3193]: info: CoreStateMachine::syncState currentStatus play Sep 03 14:01:15 rivoplus volumio[3193]: info: Received an update from plugin. extracting info from payload Sep 03 14:01:15 rivoplus volumio[3193]: info: CoreStateMachine::pushState Sep 03 14:01:15 rivoplus volumio[3193]: info: CorePlayQueue::getTrack 0 Sep 03 14:01:15 rivoplus volumio[3193]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 03 14:01:15 rivoplus volumio[3193]: info: CoreCommandRouter::volumioPushState Sep 03 14:01:15 rivoplus volumio[3193]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:01:15 rivoplus volumio[3193]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Sep 03 14:01:15 rivoplus volumio[3193]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Sep 03 14:01:15 rivoplus volumio[3193]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:183:10) Sep 03 14:01:15 rivoplus volumio[3193]: at /volumio/app/index.js:326:81 Sep 03 14:01:15 rivoplus volumio[3193]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Sep 03 14:01:15 rivoplus volumio[3193]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Sep 03 14:01:15 rivoplus volumio[3193]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Sep 03 14:01:15 rivoplus volumio[3193]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:532:22) Sep 03 14:01:15 rivoplus volumio[3193]: at CoreStateMachine.syncState (/volumio/app/statemachine.js:762:12) Sep 03 14:01:15 rivoplus volumio[3193]: at CoreCommandRouter.servicePushState (/volumio/app/index.js:414:28) Sep 03 14:01:15 rivoplus volumio[3193]: at Controller80s80s.pushSongState (/data/plugins/music_service/80s80s/index.js:584:24) Sep 03 14:01:15 rivoplus volumio[3193]: at Controller80s80s.playNextTrack (/data/plugins/music_service/80s80s/index.js:665:34) Sep 03 14:01:15 rivoplus volumio[3193]: at NanoTimer.setTimeout (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:343:9) Sep 03 14:01:15 rivoplus volumio[3193]: at Immediate. (/data/plugins/music_service/80s80s/node_modules/nanotimer/lib/nanotimer.js:327:66) Sep 03 14:01:15 rivoplus volumio[3193]: at processImmediate (internal/timers.js:464:21) { Sep 03 14:01:15 rivoplus volumio[3193]: code: 'NumberExpected' Sep 03 14:01:15 rivoplus volumio[3193]: } Sep 03 14:01:15 rivoplus volumio[3193]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 03 14:01:16 rivoplus sudo[5464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-03 14:00 Sep 03 14:01:16 rivoplus sudo[5464]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:23:19 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="ca35d8983c8e34dfa9cca80071cf9105"