-- Logs begin at Sun 2024-12-01 09:42:05 CET, end at Sun 2024-12-01 09:55:30 CET. -- Dec 01 09:54:22 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 09:54:22 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 09:54:22 rivo volumio[3211]: info: Discovery: Getting this device information Dec 01 09:54:22 rivo volumio[3211]: info: CoreCommandRouter::volumioGetState Dec 01 09:54:22 rivo volumio[3211]: info: CorePlayQueue::getTrack 0 Dec 01 09:54:22 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 09:54:35 rivo kernel: meson-mmc: card IN Dec 01 09:54:35 rivo kernel: meson-mmc: normal card in Dec 01 09:54:35 rivo kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Dec 01 09:54:35 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000033c Dec 01 09:54:36 rivo kernel: meson-mmc: actual_clock :50000000, HHI_nand: 0x80 Dec 01 09:54:36 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x10000354 Dec 01 09:54:36 rivo kernel: sd: new high speed SDXC card at address 2123 Dec 01 09:54:36 rivo kernel: sd: clock 50000000, 4-bit-bus-width Dec 01 09:54:36 rivo kernel: mmcblk1: sd:2123 APPSD 249 GiB Dec 01 09:54:36 rivo kernel: mmcblk1: p1 Dec 01 09:54:36 rivo kernel: meson-mmc: Enter aml_emmc_partition_ops Dec 01 09:54:38 rivo kernel: meson-mmc: card OUT Dec 01 09:54:38 rivo kernel: meson-mmc: card out Dec 01 09:54:39 rivo kernel: meson-mmc: card IN Dec 01 09:54:39 rivo kernel: meson-mmc: normal card in Dec 01 09:54:42 rivo kernel: meson-mmc: sd: warning... data crc, vstat:0xe1ff100c, virqc:3fff Dec 01 09:54:42 rivo kernel: meson-mmc: @ cmd 18 arg 1f23dc00 with ffffffc04e4ce9a8; stop 0, status 4 Dec 01 09:54:42 rivo kernel: meson-mmc: sd: err: wait for irq service, bus_fsm:0x8 Dec 01 09:54:42 rivo kernel: meson-mmc: meson_mmc_irq_thread_v3() 621: set 1st retry! Dec 01 09:54:42 rivo kernel: meson-mmc: sd: resp_timeout,vstat:0xa1ff2800,virqc:3fff Dec 01 09:54:42 rivo kernel: sd: card 2123 removed Dec 01 09:54:42 rivo volumio[3211]: error: Cannot associate FS Label, not mounting Dec 01 09:54:42 rivo volumio[3211]: info: Partition removed: {"syspath":"/sys/devices/platform/ffe05000.sd/mmc_host/sd/sd:2123/block/mmcblk1/mmcblk1p1","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-ffe05000.sd-part1 /dev/disk/by-id/mmc-APPSD_0x12800002-part1","DEVNAME":"/dev/mmcblk1p1","DEVPATH":"/devices/platform/ffe05000.sd/mmc_host/sd/sd:2123/block/mmcblk1/mmcblk1p1","DEVTYPE":"partition","ID_NAME":"APPSD","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-ffe05000.sd","ID_PATH_TAG":"platform-ffe05000_sd","ID_SERIAL":"0x12800002","MAJOR":"179","MINOR":"129","PARTN":"1","SEQNUM":"6971","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"774339013"} Dec 01 09:54:42 rivo volumio[3211]: error: Cannot associate FS Label, not mounting Dec 01 09:54:42 rivo kernel: meson-mmc: actual_clock :0, HHI_nand: 0x80 Dec 01 09:54:42 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x10000354 Dec 01 09:54:42 rivo kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Dec 01 09:54:42 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000033c Dec 01 09:54:42 rivo kernel: meson-mmc: actual_clock :50000000, HHI_nand: 0x80 Dec 01 09:54:42 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x10000354 Dec 01 09:54:42 rivo kernel: sd: new high speed SDXC card at address 2123 Dec 01 09:54:42 rivo kernel: sd: clock 50000000, 4-bit-bus-width Dec 01 09:54:42 rivo kernel: mmcblk1: sd:2123 APPSD 249 GiB Dec 01 09:54:42 rivo kernel: mmcblk1: p1 Dec 01 09:54:42 rivo kernel: meson-mmc: Enter aml_emmc_partition_ops Dec 01 09:54:44 rivo volumio[3211]: info: Ignoring mount for partition: 49A3-485A Dec 01 09:54:52 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 09:54:52 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 09:54:52 rivo volumio[3211]: info: Discovery: Getting this device information Dec 01 09:54:52 rivo volumio[3211]: info: CoreCommandRouter::volumioGetState Dec 01 09:54:52 rivo volumio[3211]: info: CorePlayQueue::getTrack 0 Dec 01 09:54:52 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 09:54:56 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 09:54:56 rivo volumio[3211]: info: CURURI: music-library Dec 01 09:54:56 rivo volumio[3211]: info: Preload queue cleared Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 09:55:06 rivo volumio[3211]: info: Retrieving Cloud Streaming UI Dec 01 09:55:06 rivo volumio[3211]: info: Getting Tidal Cloud Configuration Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 09:55:06 rivo volumio[3211]: info: Getting Qobuz Cloud Configuration Dec 01 09:55:06 rivo volumio[3211]: info: Asking plugin for UI Config Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 09:55:06 rivo volumio[3211]: info: Getting Spotify Cloud Configuration Dec 01 09:55:06 rivo volumio[3211]: info: Asking plugin for UI Config Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 09:55:06 rivo volumio[3211]: info: Saving Spotify Acccount Dec 01 09:55:06 rivo volumio[3211]: info: Got it Dec 01 09:55:06 rivo volumio[3211]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 01 09:55:06 rivo volumio[3211]: info: Got Tidal Cloud Configuration Dec 01 09:55:06 rivo volumio[3211]: info: Got it Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::volumioGetBrowseSources Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::volumioGetBrowseSources Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::volumioGetBrowseSources Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 09:55:06 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 01 09:55:10 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 01 09:55:10 rivo volumio[3211]: verbose: ControllerMpd::sendMpdCommand rescan Dec 01 09:55:10 rivo volumio[3211]: verbose: ControllerMpd::sendMpdCommand status Dec 01 09:55:10 rivo volumio[3211]: info: Dec 01 09:55:10 rivo volumio[3211]: ---------------------------- MPD announces state update: update Dec 01 09:55:10 rivo volumio[3211]: info: sendMpdCommand rescan took 13 milliseconds Dec 01 09:55:10 rivo volumio[3211]: info: ControllerMpd::getState Dec 01 09:55:10 rivo volumio[3211]: verbose: ControllerMpd::sendMpdCommand status Dec 01 09:55:10 rivo volumio[3211]: info: sendMpdCommand status took 7 milliseconds Dec 01 09:55:10 rivo volumio[3211]: info: sendMpdCommand status took 4 milliseconds Dec 01 09:55:10 rivo volumio[3211]: verbose: ControllerMpd::parseState Dec 01 09:55:10 rivo volumio[3211]: info: Command Router : Notfying DB Updatetrue Dec 01 09:55:10 rivo volumio[3211]: info: CoreCommandRouter::Close All Modals sent Dec 01 09:55:10 rivo volumio[3211]: verbose: ControllerMpd::parseState Dec 01 09:55:10 rivo volumio[3211]: info: ControllerMpd::pushState Dec 01 09:55:10 rivo volumio[3211]: info: CoreCommandRouter::servicePushState Dec 01 09:55:10 rivo volumio[3211]: info: CoreStateMachine::pushState Dec 01 09:55:10 rivo volumio[3211]: info: CorePlayQueue::getTrack 0 Dec 01 09:55:10 rivo volumio[3211]: info: CoreCommandRouter::volumioPushState Dec 01 09:55:10 rivo volumio[3211]: info: CorePlayQueue::getTrack 0 Dec 01 09:55:10 rivo volumio[3211]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 01 09:55:10 rivo volumio[3211]: verbose: CURRENT POSITION 0 Dec 01 09:55:10 rivo volumio[3211]: info: CoreStateMachine::syncState stateService stop Dec 01 09:55:10 rivo volumio[3211]: info: CoreStateMachine::syncState currentStatus stop Dec 01 09:55:10 rivo volumio[3211]: info: CoreStateMachine::pushState Dec 01 09:55:10 rivo volumio[3211]: info: CorePlayQueue::getTrack 0 Dec 01 09:55:10 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 09:55:10 rivo volumio[3211]: info: CoreCommandRouter::volumioPushState Dec 01 09:55:10 rivo volumio[3211]: info: No code Dec 01 09:55:10 rivo volumio[3211]: info: CoreStateMachine::pushState Dec 01 09:55:10 rivo volumio[3211]: info: CorePlayQueue::getTrack 0 Dec 01 09:55:10 rivo volumio[3211]: info: CoreCommandRouter::volumioPushState Dec 01 09:55:10 rivo volumio[3211]: info: ------------------------------ 112ms Dec 01 09:55:10 rivo volumio[3211]: info: Updating RAAT Signal Path Dec 01 09:55:10 rivo volumio[3211]: info: Updating RAAT Signal Path Dec 01 09:55:10 rivo volumio[3211]: info: Updating RAAT Signal Path Dec 01 09:55:10 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 09:55:12 rivo volumio[3211]: info: MPD Database updated - AlbumList cache refreshed Dec 01 09:55:12 rivo volumio[3211]: info: Dec 01 09:55:12 rivo volumio[3211]: ---------------------------- MPD announces state update: database Dec 01 09:55:12 rivo volumio[3211]: verbose: ControllerMpd::sendMpdCommand status Dec 01 09:55:12 rivo volumio[3211]: info: Dec 01 09:55:12 rivo volumio[3211]: ---------------------------- MPD announces state update: update Dec 01 09:55:12 rivo volumio[3211]: info: ControllerMpd::getState Dec 01 09:55:12 rivo volumio[3211]: verbose: ControllerMpd::sendMpdCommand status Dec 01 09:55:12 rivo volumio[3211]: info: ControllerMpd::getState Dec 01 09:55:12 rivo volumio[3211]: verbose: ControllerMpd::sendMpdCommand status Dec 01 09:55:12 rivo volumio[3211]: info: sendMpdCommand status took 51 milliseconds Dec 01 09:55:12 rivo volumio[3211]: info: sendMpdCommand status took 50 milliseconds Dec 01 09:55:12 rivo volumio[3211]: info: sendMpdCommand status took 49 milliseconds Dec 01 09:55:12 rivo volumio[3211]: verbose: ControllerMpd::parseState Dec 01 09:55:12 rivo volumio[3211]: info: Command Router : Notfying DB Updatefalse Dec 01 09:55:12 rivo volumio[3211]: info: CoreCommandRouter::Close All Modals sent Dec 01 09:55:12 rivo volumio[3211]: verbose: ControllerMpd::parseState Dec 01 09:55:12 rivo volumio[3211]: info: ControllerMpd::pushState Dec 01 09:55:12 rivo volumio[3211]: info: CoreCommandRouter::servicePushState Dec 01 09:55:12 rivo volumio[3211]: info: CoreStateMachine::pushState Dec 01 09:55:12 rivo volumio[3211]: info: CorePlayQueue::getTrack 0 Dec 01 09:55:12 rivo volumio[3211]: info: CoreCommandRouter::volumioPushState Dec 01 09:55:12 rivo volumio[3211]: info: CorePlayQueue::getTrack 0 Dec 01 09:55:12 rivo volumio[3211]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 01 09:55:12 rivo volumio[3211]: verbose: CURRENT POSITION 0 Dec 01 09:55:12 rivo volumio[3211]: info: CoreStateMachine::syncState stateService stop Dec 01 09:55:12 rivo volumio[3211]: info: CoreStateMachine::syncState currentStatus stop Dec 01 09:55:12 rivo volumio[3211]: info: CoreStateMachine::pushState Dec 01 09:55:12 rivo volumio[3211]: info: CorePlayQueue::getTrack 0 Dec 01 09:55:12 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 09:55:12 rivo volumio[3211]: info: CoreCommandRouter::volumioPushState Dec 01 09:55:12 rivo volumio[3211]: info: No code Dec 01 09:55:12 rivo volumio[3211]: info: CoreStateMachine::pushState Dec 01 09:55:12 rivo volumio[3211]: info: CorePlayQueue::getTrack 0 Dec 01 09:55:12 rivo volumio[3211]: info: CoreCommandRouter::volumioPushState Dec 01 09:55:12 rivo volumio[3211]: info: ControllerMpd::pushState Dec 01 09:55:12 rivo volumio[3211]: info: CoreCommandRouter::servicePushState Dec 01 09:55:12 rivo volumio[3211]: info: CoreStateMachine::pushState Dec 01 09:55:12 rivo volumio[3211]: info: CorePlayQueue::getTrack 0 Dec 01 09:55:12 rivo volumio[3211]: info: CoreCommandRouter::volumioPushState Dec 01 09:55:12 rivo volumio[3211]: info: CorePlayQueue::getTrack 0 Dec 01 09:55:12 rivo volumio[3211]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 01 09:55:12 rivo volumio[3211]: verbose: CURRENT POSITION 0 Dec 01 09:55:12 rivo volumio[3211]: info: CoreStateMachine::syncState stateService stop Dec 01 09:55:12 rivo volumio[3211]: info: CoreStateMachine::syncState currentStatus stop Dec 01 09:55:12 rivo volumio[3211]: info: CoreStateMachine::pushState Dec 01 09:55:12 rivo volumio[3211]: info: CorePlayQueue::getTrack 0 Dec 01 09:55:12 rivo volumio[3211]: info: CoreCommandRouter::volumioPushState Dec 01 09:55:12 rivo volumio[3211]: info: No code Dec 01 09:55:12 rivo volumio[3211]: info: CoreStateMachine::pushState Dec 01 09:55:12 rivo volumio[3211]: info: CorePlayQueue::getTrack 0 Dec 01 09:55:12 rivo volumio[3211]: info: CoreCommandRouter::volumioPushState Dec 01 09:55:12 rivo volumio[3211]: info: ------------------------------ 149ms Dec 01 09:55:12 rivo volumio[3211]: info: ------------------------------ 151ms Dec 01 09:55:12 rivo volumio[3211]: info: Updating RAAT Signal Path Dec 01 09:55:12 rivo volumio[3211]: info: Updating RAAT Signal Path Dec 01 09:55:12 rivo volumio[3211]: info: Updating RAAT Signal Path Dec 01 09:55:12 rivo volumio[3211]: info: Updating RAAT Signal Path Dec 01 09:55:12 rivo volumio[3211]: info: Updating RAAT Signal Path Dec 01 09:55:12 rivo volumio[3211]: info: Updating RAAT Signal Path Dec 01 09:55:14 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 09:55:18 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 09:55:22 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 09:55:22 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 09:55:22 rivo volumio[3211]: info: Discovery: Getting this device information Dec 01 09:55:22 rivo volumio[3211]: info: CoreCommandRouter::volumioGetState Dec 01 09:55:22 rivo volumio[3211]: info: CorePlayQueue::getTrack 0 Dec 01 09:55:22 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 09:55:22 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 09:55:26 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 01 09:55:28 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Dec 01 09:55:28 rivo volumio[3211]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Dec 01 09:55:29 rivo volumio[3211]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 09:55:29 rivo volumio[3211]: Error: getaddrinfo -3007 Dec 01 09:55:29 rivo volumio[3211]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Dec 01 09:55:29 rivo volumio[3211]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Dec 01 09:55:29 rivo volumio[3211]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Dec 01 09:55:29 rivo volumio[3211]: code: -3007, Dec 01 09:55:29 rivo volumio[3211]: errno: -3007, Dec 01 09:55:29 rivo volumio[3211]: syscall: 'getaddrinfo' Dec 01 09:55:29 rivo volumio[3211]: } Dec 01 09:55:29 rivo volumio[3211]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 09:55:30 rivo sudo[7576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 09:54 Dec 01 09:55:30 rivo sudo[7576]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 05:04:03 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b3e66dc85ea2c87aca7db72b97d00dff"