-- Logs begin at Mon 2024-04-08 22:29:52 JST, end at Mon 2024-04-08 22:31:16 JST. -- Apr 08 22:30:01 rivo sudo[5070]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:01 rivo wireless.js[4646]: Start wireless flow Apr 08 22:30:01 rivo wireless.js[4646]: Stopped hotspot (if there).. Apr 08 22:30:01 rivo sudo[5076]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 08 22:30:01 rivo sudo[5076]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:01 rivo sudo[5076]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:01 rivo sudo[5079]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 08 22:30:01 rivo sudo[5079]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:01 rivo kernel: dhd_stop: Enter ffffffc04d8d4000 Apr 08 22:30:01 rivo kernel: CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized Apr 08 22:30:01 rivo kernel: CFGP2P-ERROR) wl_cfgp2p_deinit_priv : In Apr 08 22:30:01 rivo kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 1 -> mode 0 Apr 08 22:30:01 rivo kernel: wl_android_wifi_off in 1 Apr 08 22:30:01 rivo kernel: wl_android_wifi_off in 2: g_wifi_on=1, on_failure=1 Apr 08 22:30:01 rivo kernel: dhd_dbg_detach_pkt_monitor, 2204 Apr 08 22:30:01 rivo kernel: dhd_bus_devreset: == Power OFF == Apr 08 22:30:01 rivo kernel: bcmsdh_oob_intr_unregister: Enter Apr 08 22:30:01 rivo kernel: dhd_txglom_enable: enable 0 Apr 08 22:30:01 rivo kernel: dhd_conf_set_txglom_params: txglom_mode=copy Apr 08 22:30:01 rivo kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0 Apr 08 22:30:01 rivo kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Apr 08 22:30:01 rivo kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Apr 08 22:30:01 rivo kernel: dhd_bus_devreset: WLAN OFF DONE Apr 08 22:30:01 rivo kernel: wifi_platform_set_power = 0 Apr 08 22:30:01 rivo kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Apr 08 22:30:01 rivo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Apr 08 22:30:01 rivo dhcpcd[4656]: wlan0: carrier lost Apr 08 22:30:01 rivo kernel: wl_android_wifi_off out Apr 08 22:30:01 rivo kernel: wl_ext_iapsta_dettach_netdev: Enter Apr 08 22:30:01 rivo kernel: dhd_stop: Exit Apr 08 22:30:01 rivo dhcpcd[4656]: wlan0: carrier lost Apr 08 22:30:01 rivo sudo[5079]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:01 rivo wireless.js[4646]: DHCP IP Apr 08 22:30:01 rivo wireless.js[4646]: Start ap Apr 08 22:30:01 rivo wpa_supplicant[5083]: Successfully initialized wpa_supplicant Apr 08 22:30:01 rivo kernel: dhd_open: Enter ffffffc04d8d4000 Apr 08 22:30:01 rivo kernel: dhd_open : no mutex held. set lock Apr 08 22:30:01 rivo kernel: Dongle Host Driver, version 1.579.77.41.10 (r) Apr 08 22:30:01 rivo kernel: wl_ext_iapsta_attach_netdev: ifidx=0, bssidx=0 Apr 08 22:30:01 rivo kernel: wl_android_wifi_on in 1 Apr 08 22:30:01 rivo kernel: wl_android_wifi_on in 2: g_wifi_on=0 Apr 08 22:30:01 rivo kernel: wifi_platform_set_power = 1 Apr 08 22:30:01 rivo kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Apr 08 22:30:01 rivo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Apr 08 22:30:01 rivo kernel: meson6-dwmac ff3f0000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Apr 08 22:30:01 rivo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Enable! 482 Apr 08 22:30:02 rivo kernel: sdio_reset_comm(): Apr 08 22:30:02 rivo kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Apr 08 22:30:02 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Apr 08 22:30:02 rivo kernel: meson-mmc: actual_clock :0, HHI_nand: 0x80 Apr 08 22:30:02 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Apr 08 22:30:02 rivo kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Apr 08 22:30:02 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Apr 08 22:30:02 rivo kernel: sdio_read_cis: 5 callbacks suppressed Apr 08 22:30:02 rivo kernel: sdio: queuing unknown CIS tuple 0x80 (2 bytes) Apr 08 22:30:02 rivo kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Apr 08 22:30:02 rivo kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Apr 08 22:30:02 rivo kernel: sdio: queuing unknown CIS tuple 0x80 (7 bytes) Apr 08 22:30:02 rivo kernel: sdio: queuing unknown CIS tuple 0x81 (9 bytes) Apr 08 22:30:02 rivo kernel: meson-aml-mmc ffe03000.sdio: divider requested rate 200000000 != actual rate 199999997: ret=0 Apr 08 22:30:02 rivo kernel: meson-mmc: actual_clock :199999997, HHI_nand: 0x80 Apr 08 22:30:02 rivo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x10000245 Apr 08 22:30:02 rivo kernel: meson-mmc: Data 1 aligned delay is 0 Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: clk 199999997 tuning start Apr 08 22:30:02 rivo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: [prev_map] fixed_adj_win_map:30 Apr 08 22:30:02 rivo kernel: meson-mmc: [0]=0 Apr 08 22:30:02 rivo kernel: meson-mmc: [1]=1 Apr 08 22:30:02 rivo kernel: meson-mmc: [2]=1 Apr 08 22:30:02 rivo kernel: meson-mmc: [3]=1 Apr 08 22:30:02 rivo kernel: meson-mmc: [4]=1 Apr 08 22:30:02 rivo kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Apr 08 22:30:02 rivo kernel: meson-mmc: step:4, delay1:0x4104104, delay2:0x4004104 Apr 08 22:30:02 rivo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Apr 08 22:30:02 rivo kernel: meson-mmc: step:8, delay1:0x8208208, delay2:0x8008208 Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Apr 08 22:30:02 rivo kernel: meson-mmc: step:12, delay1:0xc30c30c, delay2:0xc00c30c Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Apr 08 22:30:02 rivo kernel: meson-mmc: [>>>>>>>>bitmap_xor_dst] fixed_adj_win_map:2 Apr 08 22:30:02 rivo kernel: meson-mmc: [0]=0 Apr 08 22:30:02 rivo kernel: meson-mmc: [1]=1 Apr 08 22:30:02 rivo kernel: meson-mmc: [2]=0 Apr 08 22:30:02 rivo kernel: meson-mmc: [3]=0 Apr 08 22:30:02 rivo kernel: meson-mmc: [4]=0 Apr 08 22:30:02 rivo kernel: meson-mmc: [_find_fixed_adj_valid_win] fir_adj:1 Apr 08 22:30:02 rivo kernel: meson-mmc: [>>>>>>>>prev_map_range] fixed_adj_win_map:495 Apr 08 22:30:02 rivo kernel: meson-mmc: [1]=1 Apr 08 22:30:02 rivo kernel: meson-mmc: [2]=1 Apr 08 22:30:02 rivo kernel: meson-mmc: [3]=1 Apr 08 22:30:02 rivo kernel: meson-mmc: [4]=1 Apr 08 22:30:02 rivo kernel: meson-mmc: [0]=0 Apr 08 22:30:02 rivo kernel: meson-mmc: left:3, right:0, mid:4, size:4 Apr 08 22:30:02 rivo kernel: meson-mmc: step:0, delay1:0x0, delay2:0x0 Apr 08 22:30:02 rivo kernel: meson-mmc: sdio: sd_emmc_regs->gclock=0x10000245,sd_emmc_regs->gadjust=0x32000 Apr 08 22:30:02 rivo kernel: meson-mmc: delay1:0x0, delay2:0x0 Apr 08 22:30:02 rivo kernel: sdioh_start: set sd_f2_blocksize 256 Apr 08 22:30:02 rivo kernel: dhd_bus_devreset: == WLAN ON == Apr 08 22:30:02 rivo kernel: F1 signature read @0x18000000=0x17294359 Apr 08 22:30:02 rivo kernel: F1 signature OK, socitype:0x1 chip:0x4359 rev:0x9 pkg:0x2 Apr 08 22:30:02 rivo kernel: DHD: dongle ram size is set to 917504(orig 917504) at 0x160000 Apr 08 22:30:02 rivo kernel: dhd_bus_set_default_min_res_mask: Unhandled chip id Apr 08 22:30:02 rivo kernel: dhd_os_open_image: /lib/firmware/brcm/config_bcm4359c0_ag.txt (185 bytes) open success Apr 08 22:30:02 rivo kernel: dhd_conf_read_sdio_params: dhd_slpauto = 0 Apr 08 22:30:02 rivo kernel: dhd_conf_read_others: ccode = ALL Apr 08 22:30:02 rivo kernel: dhd_conf_read_others: regrev = 38 Apr 08 22:30:02 rivo kernel: dhd_conf_read_nv_by_chip: nv_by_chip_count=5 Apr 08 22:30:02 rivo kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=0, name=nvram_ap6212.txt Apr 08 22:30:02 rivo kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=1, name=nvram_ap6212a.txt Apr 08 22:30:02 rivo kernel: dhd_conf_read_nv_by_chip: chip=0x4345, chiprev=6, name=nvram_ap6255.txt Apr 08 22:30:02 rivo kernel: dhd_conf_read_nv_by_chip: chip=0x4354, chiprev=2, name=nvram_ap6356.txt Apr 08 22:30:02 rivo kernel: dhd_conf_read_nv_by_chip: chip=0x4359, chiprev=9, name=nvram_ap6359sa.txt Apr 08 22:30:02 rivo kernel: Final fw_path=/lib/firmware/brcm/fw_bcm4359c0_ag.bin Apr 08 22:30:02 rivo kernel: Final nv_path=/lib/firmware/brcm/nvram_ap6359sa.txt Apr 08 22:30:02 rivo kernel: Final clm_path=/lib/firmware/brcm/clm.blob Apr 08 22:30:02 rivo kernel: Final conf_path=/lib/firmware/brcm/config_bcm4359c0_ag.txt Apr 08 22:30:02 rivo kernel: dhd_os_open_image: /lib/firmware/brcm/fw_bcm4359c0_ag.bin (595758 bytes) open success Apr 08 22:30:02 rivo kernel: dhd_os_open_image: /lib/firmware/brcm/nvram_ap6359sa.txt (5861 bytes) open success Apr 08 22:30:02 rivo kernel: [43B blob data] Apr 08 22:30:02 rivo kernel: dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded. Apr 08 22:30:02 rivo wireless.js[4646]: trying... Apr 08 22:30:02 rivo kernel: dhd_bus_init: enable 0x06, ready 0x06 (waited 0us) Apr 08 22:30:02 rivo kernel: si_get_pmu_reg_addr: addrRET: 18000670 Apr 08 22:30:02 rivo kernel: bcmsdh_oob_intr_register: HW_OOB irq=70 flags=0x8 Apr 08 22:30:02 rivo kernel: dhd_get_memdump_info: File [/data/misc/wifi/.memdump.info] doesn't exist Apr 08 22:30:02 rivo kernel: dhd_get_memdump_info: MEMDUMP ENABLED = 2 Apr 08 22:30:02 rivo kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 0 -> mode 1 Apr 08 22:30:02 rivo kernel: dhd_apply_default_clm: Ignore clm file /lib/firmware/brcm/clm.blob Apr 08 22:30:02 rivo kernel: Firmware up: op_mode=0x0405, MAC=b8:13:32:e1:a6:06 Apr 08 22:30:02 rivo kernel: Driver: 1.579.77.41.10 (r) Firmware: wl0: Jan 19 2018 12:14:30 version 9.87.51.11.8 (a85e25e@shgit) (r) FWID 01-cb5aa0a5 CLM: 9.7.5 Apr 08 22:30:02 rivo kernel: dhd_txglom_enable: enable 1 Apr 08 22:30:02 rivo kernel: dhd_conf_set_txglom_params: txglom_mode=copy Apr 08 22:30:02 rivo kernel: dhd_conf_set_txglom_params: txglomsize=36, deferred_tx_len=0 Apr 08 22:30:02 rivo kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Apr 08 22:30:02 rivo kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Apr 08 22:30:02 rivo kernel: dhd_conf_get_disable_proptx: fw_proptx=0, disable_proptx=1 Apr 08 22:30:02 rivo kernel: dhd_pno_init: Support Android Location Service Apr 08 22:30:02 rivo kernel: rtt_do_get_ioctl: failed to send getbuf proxd iovar (CMD ID : 1), status=-23 Apr 08 22:30:02 rivo kernel: dhd_rtt_init : FTM is not supported Apr 08 22:30:02 rivo kernel: dhd_preinit_ioctls: SensorHub diabled 0 Apr 08 22:30:02 rivo kernel: dhd_preinit_ioctls failed to set ShubHub disable Apr 08 22:30:02 rivo kernel: failed to set WNM capabilities Apr 08 22:30:02 rivo kernel: dhd_conf_set_country: set country ALL, revision 38 Apr 08 22:30:02 rivo kernel: CONFIG-ERROR) dhd_conf_set_bufiovar: country setting failed -2 Apr 08 22:30:02 rivo kernel: Country code: US (US/0) Apr 08 22:30:02 rivo kernel: CONFIG-ERROR) dhd_conf_set_intiovar: txbf setting failed -23 Apr 08 22:30:02 rivo kernel: wl_android_wifi_on: Success Apr 08 22:30:02 rivo dhcpcd[4656]: wlan0: carrier acquired Apr 08 22:30:02 rivo dhcpcd[4656]: wlan0: carrier acquired Apr 08 22:30:02 rivo kernel: dhd_open : the lock is released. Apr 08 22:30:02 rivo kernel: dhd_open: Exit ret=0 Apr 08 22:30:02 rivo sh[4286]: eth0: carrier acquired Apr 08 22:30:02 rivo dhcpcd[4449]: eth0: carrier acquired Apr 08 22:30:02 rivo volumio-remote-updater[4748]: [2024-04-08 22:30:02] [info] asio async_connect error: system:111 (Connection refused) Apr 08 22:30:02 rivo volumio-remote-updater[4748]: [2024-04-08 22:30:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 08 22:30:02 rivo volumio-remote-updater[4748]: [2024-04-08 22:30:02] [error] handle_connect error: Underlying Transport Error Apr 08 22:30:02 rivo sudo[5099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 08 22:30:02 rivo sh[4286]: eth0: IAID 00:24:21:01 Apr 08 22:30:02 rivo dhcpcd[4656]: wlan0: IAID 32:e1:a6:06 Apr 08 22:30:02 rivo dhcpcd[4656]: wlan0: carrier lost Apr 08 22:30:02 rivo dhcpcd[4656]: wlan0: IAID 32:e1:a6:06 Apr 08 22:30:02 rivo dhcpcd[4656]: wlan0: carrier lost Apr 08 22:30:02 rivo sudo[5099]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:02 rivo dhcpcd[4449]: eth0: IAID 00:24:21:01 Apr 08 22:30:02 rivo sudo[5088]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 08 22:30:02 rivo sudo[5088]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:02 rivo sudo[5099]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:02 rivo dhcpcd[5105]: sending commands to master dhcpcd process Apr 08 22:30:02 rivo sudo[5088]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:02 rivo kernel: P2P interface registered Apr 08 22:30:02 rivo kernel: wl_cfgp2p_add_p2p_disc_if: wdev: ffffffc04ecb5800, wdev->net: (null) Apr 08 22:30:02 rivo dhcpcd[4656]: eth0: carrier acquired Apr 08 22:30:02 rivo dhcpcd[4656]: eth0: carrier acquired Apr 08 22:30:02 rivo dhcpcd[4656]: eth0: IAID 00:24:21:01 Apr 08 22:30:02 rivo dhcpcd[4656]: eth0: IAID 00:24:21:01 Apr 08 22:30:02 rivo kernel: [35B blob data] Apr 08 22:30:02 rivo kernel: P2P interface started Apr 08 22:30:02 rivo dhcpcd[4656]: control command: /sbin/dhcpcd Apr 08 22:30:02 rivo dhcpcd[4656]: control command: /sbin/dhcpcd Apr 08 22:30:03 rivo dhcpcd[4656]: eth0: soliciting a DHCP lease Apr 08 22:30:03 rivo dhcpcd[4656]: eth0: soliciting a DHCP lease Apr 08 22:30:03 rivo dhcpcd[4656]: eth0: soliciting an IPv6 router Apr 08 22:30:03 rivo dhcpcd[4656]: eth0: soliciting an IPv6 router Apr 08 22:30:03 rivo wireless.js[4646]: trying... Apr 08 22:30:03 rivo sudo[5118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 08 22:30:03 rivo sudo[5118]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:03 rivo sudo[5118]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:03 rivo sh[4286]: eth0: soliciting an IPv6 router Apr 08 22:30:03 rivo dhcpcd[4449]: eth0: soliciting an IPv6 router Apr 08 22:30:03 rivo sh[4286]: eth0: soliciting a DHCP lease Apr 08 22:30:03 rivo dhcpcd[4449]: eth0: soliciting a DHCP lease Apr 08 22:30:03 rivo systemd[1]: systemd-rfkill.service: Succeeded. Apr 08 22:30:04 rivo wireless.js[4646]: trying... Apr 08 22:30:04 rivo sudo[5121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 08 22:30:04 rivo sudo[5121]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:04 rivo sudo[5121]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:04 rivo wpa_supplicant[5116]: wlan0: Trying to associate with a2:5f:67:55:d6:54 (SSID='TP-Link_D654' freq=2452 MHz) Apr 08 22:30:04 rivo kernel: Connecting with a2:5f:67:55:d6:54 ssid "TP-Link_D654", len (12) channel=9 Apr 08 22:30:04 rivo kernel: dhd_dbg_start_pkt_monitor, 1724 Apr 08 22:30:04 rivo kernel: wl_iw_event: Link UP with a2:5f:67:55:d6:54 Apr 08 22:30:04 rivo kernel: wl_bss_connect_done succeeded with a2:5f:67:55:d6:54 Apr 08 22:30:04 rivo kernel: CFG80211-ERROR) wl_cfg80211_scan_abort : scan abort failed Apr 08 22:30:04 rivo wpa_supplicant[5116]: wlan0: Associated with a2:5f:67:55:d6:54 Apr 08 22:30:04 rivo wpa_supplicant[5116]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 08 22:30:04 rivo kernel: wl_bss_connect_done succeeded with a2:5f:67:55:d6:54 vndr_oui: 00-10-18 00-1D-0F Apr 08 22:30:04 rivo wpa_supplicant[5116]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Apr 08 22:30:04 rivo wpa_supplicant[5116]: wlan0: WPA: Key negotiation completed with a2:5f:67:55:d6:54 [PTK=CCMP GTK=CCMP] Apr 08 22:30:04 rivo wpa_supplicant[5116]: wlan0: CTRL-EVENT-CONNECTED - Connection to a2:5f:67:55:d6:54 completed [id=0 id_str=] Apr 08 22:30:04 rivo dhcpcd[4656]: wlan0: carrier acquired Apr 08 22:30:04 rivo dhcpcd[4656]: wlan0: carrier acquired Apr 08 22:30:04 rivo dhcpcd[4656]: wlan0: IAID 32:e1:a6:06 Apr 08 22:30:04 rivo dhcpcd[4656]: wlan0: IAID 32:e1:a6:06 Apr 08 22:30:05 rivo dhcpcd[4656]: wlan0: rebinding lease of 192.168.0.245 Apr 08 22:30:05 rivo dhcpcd[4656]: wlan0: rebinding lease of 192.168.0.245 Apr 08 22:30:05 rivo dhcpcd[4656]: wlan0: probing address 192.168.0.245/24 Apr 08 22:30:05 rivo dhcpcd[4656]: wlan0: probing address 192.168.0.245/24 Apr 08 22:30:05 rivo wireless.js[4646]: trying... Apr 08 22:30:05 rivo sudo[5127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 08 22:30:05 rivo sudo[5127]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:05 rivo sudo[5127]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:05 rivo dhcpcd[4656]: wlan0: soliciting an IPv6 router Apr 08 22:30:05 rivo dhcpcd[4656]: wlan0: soliciting an IPv6 router Apr 08 22:30:06 rivo wireless.js[4646]: trying... Apr 08 22:30:06 rivo sudo[5130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 08 22:30:06 rivo sudo[5130]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:06 rivo sudo[5130]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:07 rivo kernel: meson_uart ffd24000.serial: ttyS1 use xtal(24M) 24000000 change 115200 to 2000000 Apr 08 22:30:07 rivo systemd[1]: Starting Load/Save RF Kill Switch Status... Apr 08 22:30:07 rivo systemd[1]: Started Load/Save RF Kill Switch Status. Apr 08 22:30:07 rivo systemd[1]: Starting Bluetooth service... Apr 08 22:30:07 rivo bluetoothd[5151]: Bluetooth daemon 5.50 Apr 08 22:30:07 rivo bluetoothd[5151]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Apr 08 22:30:07 rivo systemd[1]: Started Bluetooth service. Apr 08 22:30:07 rivo bluetoothd[5151]: Starting SDP server Apr 08 22:30:07 rivo systemd[1]: Reached target Bluetooth. Apr 08 22:30:07 rivo bluetoothd[5151]: Excluding (cli) sap Apr 08 22:30:07 rivo dbus-daemon[4597]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=5151 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap " label="kernel") Apr 08 22:30:07 rivo bluetoothd[5151]: Bluetooth management interface 1.14 initialized Apr 08 22:30:07 rivo systemd[1]: Starting Hostname Service... Apr 08 22:30:07 rivo kernel: IPVS: Creating netns size=1928 id=2 Apr 08 22:30:07 rivo wireless.js[4646]: trying... Apr 08 22:30:07 rivo dbus-daemon[4597]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 08 22:30:07 rivo systemd[1]: Started Hostname Service. Apr 08 22:30:07 rivo sudo[5201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 08 22:30:07 rivo sudo[5201]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:07 rivo sudo[5201]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:08 rivo dhcpcd[4656]: eth0: offered 192.168.3.50 from 192.168.3.1 Apr 08 22:30:08 rivo dhcpcd[4656]: eth0: offered 192.168.3.50 from 192.168.3.1 Apr 08 22:30:08 rivo sh[4286]: eth0: offered 192.168.3.50 from 192.168.3.1 Apr 08 22:30:08 rivo dhcpcd[4449]: eth0: offered 192.168.3.50 from 192.168.3.1 Apr 08 22:30:08 rivo dhcpcd[4656]: eth0: probing for an IPv4LL address Apr 08 22:30:08 rivo dhcpcd[4656]: eth0: probing for an IPv4LL address Apr 08 22:30:08 rivo dhcpcd[4656]: eth0: probing address 192.168.3.50/24 Apr 08 22:30:08 rivo dhcpcd[4656]: eth0: probing address 192.168.3.50/24 Apr 08 22:30:08 rivo sh[4286]: eth0: probing address 192.168.3.50/24 Apr 08 22:30:08 rivo dhcpcd[4449]: eth0: probing address 192.168.3.50/24 Apr 08 22:30:08 rivo wireless.js[4646]: trying... Apr 08 22:30:08 rivo sudo[5205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 08 22:30:08 rivo sudo[5205]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:08 rivo sudo[5205]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:09 rivo wireless.js[4646]: trying... Apr 08 22:30:09 rivo sudo[5208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 08 22:30:09 rivo sudo[5208]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:09 rivo sudo[5208]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:09 rivo kernel: xhci-hcd xhci-hcd.0.auto: WARN Event TRB for slot 2 ep 2 with no TDs queued? Apr 08 22:30:10 rivo volumio-remote-updater[4748]: [2024-04-08 22:30:10] [info] asio async_connect error: system:111 (Connection refused) Apr 08 22:30:10 rivo volumio-remote-updater[4748]: [2024-04-08 22:30:10] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 08 22:30:10 rivo volumio-remote-updater[4748]: [2024-04-08 22:30:10] [error] handle_connect error: Underlying Transport Error Apr 08 22:30:10 rivo wireless.js[4646]: trying... Apr 08 22:30:10 rivo sudo[5211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 08 22:30:10 rivo sudo[5211]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:10 rivo sudo[5211]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:10 rivo dhcpcd[4656]: wlan0: leased 192.168.0.245 for 7200 seconds Apr 08 22:30:10 rivo dhcpcd[4656]: wlan0: leased 192.168.0.245 for 7200 seconds Apr 08 22:30:10 rivo avahi-daemon[4710]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.245. Apr 08 22:30:10 rivo dhcpcd[4656]: wlan0: adding route to 192.168.0.0/24 Apr 08 22:30:10 rivo dhcpcd[4656]: wlan0: adding default route via 192.168.0.1 Apr 08 22:30:10 rivo dhcpcd[4656]: wlan0: adding route to 192.168.0.0/24 Apr 08 22:30:10 rivo dhcpcd[4656]: wlan0: adding default route via 192.168.0.1 Apr 08 22:30:10 rivo avahi-daemon[4710]: New relevant interface wlan0.IPv4 for mDNS. Apr 08 22:30:10 rivo avahi-daemon[4710]: Registering new address record for 192.168.0.245 on wlan0.IPv4. Apr 08 22:30:11 rivo dhcpcd[4656]: forked to background, child pid 5249 Apr 08 22:30:11 rivo dhcpcd[4656]: forked to background, child pid 5249 Apr 08 22:30:11 rivo systemd[1]: Started DHCP Client Daemon. Apr 08 22:30:11 rivo wireless.js[4646]: trying... Apr 08 22:30:11 rivo sudo[5251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 08 22:30:11 rivo sudo[5251]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:11 rivo sudo[5251]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:11 rivo wireless.js[4646]: Connected to: ----TP-Link_D654 Apr 08 22:30:11 rivo wireless.js[4646]: ---- Apr 08 22:30:11 rivo sudo[5254]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 08 22:30:11 rivo sudo[5254]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:11 rivo sudo[5254]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:11 rivo wireless.js[4646]: ... joined AP, wlan0 IPv4 is 192.168.0.245, ipV6 is undefined Apr 08 22:30:11 rivo wireless.js[4646]: It's done! AP Apr 08 22:30:11 rivo systemd[1]: Started Wireless Services. Apr 08 22:30:11 rivo systemd[1]: Started Volumio Backend Module. Apr 08 22:30:11 rivo systemd[1]: Started Volumio Cpu Tweaker. Apr 08 22:30:11 rivo volumio-cpu-tweak[5261]: Setting RT Priority for mpd Apr 08 22:30:11 rivo volumio-cpu-tweak[5261]: chrt: cannot obtain the list of tasks: No such file or directory Apr 08 22:30:11 rivo volumio-cpu-tweak[5261]: Setting MPD Affinity Apr 08 22:30:11 rivo volumio-cpu-tweak[5261]: pid 3's current affinity mask: 1 Apr 08 22:30:11 rivo volumio-cpu-tweak[5261]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 08 22:30:11 rivo volumio-cpu-tweak[5261]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 08 22:30:11 rivo systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 08 22:30:12 rivo systemd[1]: systemd-rfkill.service: Succeeded. Apr 08 22:30:12 rivo dhcpcd[5249]: eth0: leased 192.168.3.50 for 86400 seconds Apr 08 22:30:12 rivo avahi-daemon[4710]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.3.50. Apr 08 22:30:12 rivo avahi-daemon[4710]: New relevant interface eth0.IPv4 for mDNS. Apr 08 22:30:12 rivo avahi-daemon[4710]: Registering new address record for 192.168.3.50 on eth0.IPv4. Apr 08 22:30:12 rivo dhcpcd[5249]: eth0: adding route to 192.168.3.0/24 Apr 08 22:30:12 rivo dhcpcd[5249]: eth0: adding default route via 192.168.3.1 Apr 08 22:30:12 rivo sh[4286]: eth0: leased 192.168.3.50 for 86400 seconds Apr 08 22:30:12 rivo sh[4286]: eth0: adding route to 192.168.3.0/24 Apr 08 22:30:12 rivo dhcpcd[4449]: eth0: leased 192.168.3.50 for 86400 seconds Apr 08 22:30:12 rivo dhcpcd[4449]: eth0: adding route to 192.168.3.0/24 Apr 08 22:30:12 rivo sh[4286]: eth0: adding default route via 192.168.3.1 Apr 08 22:30:12 rivo dhcpcd[4449]: eth0: adding default route via 192.168.3.1 Apr 08 22:30:12 rivo sh[4286]: forked to background, child pid 5358 Apr 08 22:30:12 rivo dhcpcd[4449]: forked to background, child pid 5358 Apr 08 22:30:13 rivo sh[4286]: eth0=eth0 Apr 08 22:30:13 rivo volumio[5260]: info: ------------------------------------------- Apr 08 22:30:13 rivo volumio[5260]: info: ----- Volumio3 ---- Apr 08 22:30:13 rivo volumio[5260]: info: ------------------------------------------- Apr 08 22:30:13 rivo volumio[5260]: info: ----- System startup ---- Apr 08 22:30:13 rivo volumio[5260]: info: ------------------------------------------- Apr 08 22:30:14 rivo volumio[5260]: info: MYVOLUMIO Environment detected Apr 08 22:30:14 rivo volumio[5260]: info: Plugin folders cleanup Apr 08 22:30:14 rivo volumio[5260]: info: Scanning into folder /volumio/app/plugins/ Apr 08 22:30:14 rivo volumio[5260]: info: Scanning category audio_interface Apr 08 22:30:14 rivo volumio[5260]: info: Scanning category miscellanea Apr 08 22:30:14 rivo volumio[5260]: info: Scanning category music_service Apr 08 22:30:14 rivo volumio[5260]: info: Scanning category plugins.json Apr 08 22:30:14 rivo volumio[5260]: info: Scanning category system_controller Apr 08 22:30:14 rivo volumio[5260]: info: Scanning category user_interface Apr 08 22:30:14 rivo volumio[5260]: info: Scanning into folder /data/plugins/ Apr 08 22:30:14 rivo volumio[5260]: info: Plugin folders cleanup completed Apr 08 22:30:14 rivo volumio[5260]: info: ------------------------------------------- Apr 08 22:30:14 rivo volumio[5260]: info: ----- Core plugins startup ---- Apr 08 22:30:14 rivo volumio[5260]: info: ------------------------------------------- Apr 08 22:30:14 rivo volumio[5260]: info: Loading plugins from folder /volumio/app/plugins/ Apr 08 22:30:14 rivo volumio[5260]: info: Adding plugin bluetooth to MyMusic Plugins Apr 08 22:30:14 rivo volumio[5260]: info: Adding plugin multiroom to MyMusic Plugins Apr 08 22:30:14 rivo volumio[5260]: info: Adding plugin upnp to MyMusic Plugins Apr 08 22:30:14 rivo volumio[5260]: info: Adding plugin metavolumio to MyMusic Plugins Apr 08 22:30:14 rivo volumio[5260]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 08 22:30:14 rivo volumio[5260]: info: Adding plugin cd_controller to MyMusic Plugins Apr 08 22:30:14 rivo volumio[5260]: info: Adding plugin raat to MyMusic Plugins Apr 08 22:30:14 rivo volumio[5260]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 08 22:30:14 rivo volumio[5260]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 08 22:30:14 rivo volumio[5260]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 08 22:30:14 rivo volumio[5260]: info: Loading plugins from folder /data/plugins/ Apr 08 22:30:14 rivo volumio[5260]: info: Loading plugin "system"... Apr 08 22:30:14 rivo volumio[5260]: info: Loading plugin "appearance"... Apr 08 22:30:16 rivo volumio[5260]: info: Loading plugin "network"... Apr 08 22:30:16 rivo volumio[5260]: info: Refreshing Cached IP Addresses Apr 08 22:30:16 rivo sudo[5398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 08 22:30:16 rivo sudo[5398]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:16 rivo sudo[5400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 08 22:30:16 rivo volumio[5260]: info: Loading plugin "services"... Apr 08 22:30:16 rivo sudo[5398]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:16 rivo sudo[5400]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:16 rivo volumio[5260]: info: Loading plugin "alsa_controller"... Apr 08 22:30:16 rivo sudo[5404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 08 22:30:16 rivo sudo[5400]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:16 rivo sudo[5404]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:16 rivo kernel: wl_escan_set_scan: ioctl_ver=2 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 22:30:16 rivo volumio[5260]: info: Loading plugin "wizard"... Apr 08 22:30:16 rivo volumio[5260]: info: Loading plugin "networkfs"... Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo volumio[5260]: info: Starting Udev Watcher for removable devices Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo volumio[5260]: error: Cannot associate FS Label, not mounting Apr 08 22:30:16 rivo volumio[5260]: error: Cannot associate FS Label, not mounting Apr 08 22:30:16 rivo volumio[5260]: info: Ignoring mount for partition: BOOT Apr 08 22:30:16 rivo volumio[5260]: info: Ignoring mount for partition: volumio Apr 08 22:30:16 rivo volumio[5260]: info: Ignoring mount for partition: volumio_data Apr 08 22:30:16 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 22:30:16 rivo volumio[5260]: info: Loading plugin "volumio_command_line_client"... Apr 08 22:30:16 rivo volumio[5260]: info: Loading plugin "upnp"... Apr 08 22:30:16 rivo volumio[5260]: info: [1712583016296] Starting Upmpd Daemon Apr 08 22:30:16 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 22:30:16 rivo volumio[5260]: info: Loading plugin "my_music"... Apr 08 22:30:16 rivo volumio[5260]: info: Loading plugin "mpd"... Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo volumio[5260]: info: Loading plugin "upnp_browser"... Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:16 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:17 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:17 rivo volumio[5260]: info: Loading plugin "bluetooth"... Apr 08 22:30:17 rivo volumio[5260]: info: [1712583017210] Starting BluetoothController Apr 08 22:30:17 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:17 rivo volumio[5260]: info: Loading plugin "alarm-clock"... Apr 08 22:30:17 rivo volumio[5260]: info: Loading plugin "manifestui"... Apr 08 22:30:17 rivo volumio[5260]: info: Loading plugin "metavolumio"... Apr 08 22:30:17 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:17 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:18 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:18 rivo volumio[5260]: info: Loading plugin "airplay_emulation"... Apr 08 22:30:18 rivo volumio[5260]: info: Starting Shairport Sync Apr 08 22:30:18 rivo volumio[5260]: info: Loading plugin "cd_controller"... Apr 08 22:30:19 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:19 rivo volumio[5260]: info: Loading plugin "last_100"... Apr 08 22:30:19 rivo volumio[5260]: info: Loading plugin "raat"... Apr 08 22:30:19 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:19 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:19 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:19 rivo volumio[5260]: info: RAAT Plugin loaded Apr 08 22:30:19 rivo volumio[5260]: info: Adding restartRAATSocket REST API Endpoint Apr 08 22:30:19 rivo volumio[5260]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Apr 08 22:30:19 rivo volumio[5260]: info: Loading plugin "streaming_services"... Apr 08 22:30:19 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:19 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 8 Apr 08 22:30:20 rivo volumio-remote-updater[4748]: [2024-04-08 22:30:20] [connect] Successful connection Apr 08 22:30:20 rivo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04d8d4000 drv_status 0x0 e_type 69 e_states 0 Apr 08 22:30:20 rivo volumio[5260]: info: Starting Streaming Service Transparent Proxy Apr 08 22:30:20 rivo sudo[5404]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:20 rivo volumio[5260]: info: Loading plugin "tidalconnect"... Apr 08 22:30:20 rivo volumio[5260]: info: Loading plugin "webradio"... Apr 08 22:30:20 rivo volumio[5260]: info: Loading plugin "i2s_dacs"... Apr 08 22:30:20 rivo volumio[5260]: info: I2S DAC not set, start Auto-detection Apr 08 22:30:20 rivo volumio[5260]: info: Loading plugin "volumiodiscovery"... Apr 08 22:30:20 rivo volumio[5260]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 08 22:30:20 rivo volumio[5260]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 22:30:20 rivo volumio[5260]: *** WARNING *** For more information see Apr 08 22:30:20 rivo node[5260]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 08 22:30:20 rivo volumio[5260]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 08 22:30:20 rivo volumio[5260]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 22:30:20 rivo volumio[5260]: *** WARNING *** For more information see Apr 08 22:30:20 rivo node[5260]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 22:30:20 rivo node[5260]: *** WARNING *** For more information see Apr 08 22:30:20 rivo node[5260]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 08 22:30:20 rivo node[5260]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 08 22:30:20 rivo node[5260]: *** WARNING *** For more information see Apr 08 22:30:20 rivo volumio[5260]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 08 22:30:20 rivo volumio[5260]: info: Discovery: Started advertising with name: Rivo Apr 08 22:30:20 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 22:30:20 rivo volumio[5260]: info: Loading plugin "multiroom"... Apr 08 22:30:21 rivo volumio[5260]: STREAMING PROXY: Starting server on port 3245 Apr 08 22:30:21 rivo volumio[5260]: Node JS runtime: 14 Apr 08 22:30:22 rivo volumio[5260]: info: Applying required configuration parameters for plugin multiroom Apr 08 22:30:22 rivo sudo[5427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 08 22:30:22 rivo sudo[5427]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:22 rivo sudo[5427]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:22 rivo volumio[5260]: info: MRS: MultiRoom plugin initialized Apr 08 22:30:22 rivo volumio[5260]: info: MRS: STOPPING SNAPCLIENT Apr 08 22:30:22 rivo volumio[5260]: info: MRS: Snap server stop Apr 08 22:30:22 rivo volumio[5260]: info: MRS: STOPPING volumioStreaming Apr 08 22:30:22 rivo sudo[5444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 08 22:30:22 rivo sudo[5445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 08 22:30:22 rivo sudo[5444]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:22 rivo sudo[5445]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:22 rivo volumio[5260]: info: Loading plugin "outputs"... Apr 08 22:30:22 rivo sudo[5450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 08 22:30:22 rivo sudo[5450]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:22 rivo volumio[5260]: info: Loading plugin "albumart"... Apr 08 22:30:22 rivo sudo[5451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 08 22:30:22 rivo sudo[5451]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:22 rivo sudo[5451]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:22 rivo volumio[5260]: info: Plugin example_plugin is not enabled Apr 08 22:30:22 rivo volumio[5260]: info: Loading plugin "hi_res_audio"... Apr 08 22:30:22 rivo sudo[5445]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:22 rivo sudo[5444]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:22 rivo sudo[5450]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:22 rivo volumio[5260]: Forking 3 albumart workers Apr 08 22:30:23 rivo volumio[5260]: Starting albumart workers Apr 08 22:30:23 rivo volumio[5260]: Starting albumart workers Apr 08 22:30:23 rivo volumio[5260]: Starting albumart workers Apr 08 22:30:23 rivo volumio[5260]: info: Applying required configuration parameters for plugin hi_res_audio Apr 08 22:30:24 rivo volumio[5260]: info: Loading plugin "inputs"... Apr 08 22:30:24 rivo volumio[5260]: info: Loading plugin "qobuz"... Apr 08 22:30:25 rivo systemd[1]: systemd-fsckd.service: Succeeded. Apr 08 22:30:25 rivo volumio[5260]: info: Loading plugin "smart_inputs"... Apr 08 22:30:26 rivo volumio[5260]: info: Loading plugin "tidal"... Apr 08 22:30:27 rivo volumio[5260]: info: Loading plugin "rivocontrol"... Apr 08 22:30:27 rivo volumio[5260]: info: Adding this device properties Apr 08 22:30:27 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Apr 08 22:30:27 rivo volumio[5260]: info: Setting Additional Device Volumio Properties: [object Object] Apr 08 22:30:27 rivo volumio[5260]: info: Loading plugin "updater_comm"... Apr 08 22:30:27 rivo volumio[5260]: info: Loading plugin "cec_controller"... Apr 08 22:30:28 rivo volumio[5260]: info: Applying required configuration parameters for plugin cec_controller Apr 08 22:30:28 rivo volumio[5260]: info: Plugin mpdemulation is not enabled Apr 08 22:30:28 rivo volumio[5260]: info: Loading plugin "rest_api"... Apr 08 22:30:28 rivo volumio[5260]: info: Loading plugin "websocket"... Apr 08 22:30:28 rivo volumio[5260]: info: Loading i18n strings for locale ja Apr 08 22:30:28 rivo volumio[5260]: Updating browse sources language Apr 08 22:30:28 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 22:30:28 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 22:30:28 rivo volumio[5260]: info: CoreCommandRouter::initPlayerControls Apr 08 22:30:28 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:28 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:28 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:28 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:28 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:28 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:28 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:28 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:28 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 22:30:28 rivo volumio[5260]: Express server listening on port 3000 Apr 08 22:30:28 rivo volumio[5260]: [Metrics] WebUI: 15s 527.03ms Apr 08 22:30:28 rivo volumio[5260]: info: CoreStateMachine::resetVolumioState Apr 08 22:30:28 rivo volumio[5260]: info: CoreStateMachine::getcurrentVolume Apr 08 22:30:28 rivo volumio[5260]: info: CoreCommandRouter::volumioRetrievevolume Apr 08 22:30:28 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:30:28 rivo sudo[5513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 08 22:30:28 rivo sudo[5513]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:28 rivo sudo[5513]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:28 rivo volumio[5260]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 08 22:30:28 rivo sudo[5515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 08 22:30:28 rivo volumio[5260]: info: Completed loading Core Plugins Apr 08 22:30:28 rivo sudo[5515]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:28 rivo volumio[5260]: info: Preparing to generate the ALSA configuration file Apr 08 22:30:28 rivo sudo[5515]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:28 rivo volumio[5260]: info: MRS: Removed streaming files Apr 08 22:30:28 rivo volumio[5260]: info: MRS: volumioStreaming STOPPED Apr 08 22:30:28 rivo volumio[5260]: info: MRS: SNAPSERVER STOPPED Apr 08 22:30:28 rivo volumio[5260]: info: MRS: SNAPCLIENT STOPPED Apr 08 22:30:28 rivo volumio[5260]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Apr 08 22:30:28 rivo volumio[5260]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 08 22:30:28 rivo volumio[5260]: info: Reading ALSA contributions from plugins. Apr 08 22:30:28 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 1 Apr 08 22:30:28 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 2 Apr 08 22:30:28 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 3 Apr 08 22:30:28 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 4 Apr 08 22:30:28 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 5 Apr 08 22:30:28 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 6 Apr 08 22:30:28 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 7 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 8 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 9 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 10 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 11 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 12 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 13 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 14 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 15 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 16 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 17 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 18 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 19 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 20 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 21 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 22 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 23 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 24 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 25 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 26 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 27 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 28 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 29 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 30 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 31 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 32 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 33 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 34 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 35 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 36 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 37 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 38 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 39 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 40 Apr 08 22:30:29 rivo volumio[5260]: info: VolumeController:: Volume=100 Mute =false Apr 08 22:30:29 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:29 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:29 rivo volumio[5260]: info: CoreStateMachine::updateTrackBlock Apr 08 22:30:29 rivo volumio[5260]: info: CorePlayQueue::getTrackBlock Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::volumioRetrievevolume Apr 08 22:30:29 rivo volumio[5260]: info: Reloading queue from file Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.0.245 from 192.168.0.153 UA: okhttp/4.9.2 Total Clients: 41 Apr 08 22:30:29 rivo volumio[5260]: verbose: New Socket.io Connection to 192.168.3.50 from 192.168.3.49 UA: okhttp/4.9.2 Total Clients: 42 Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::volumioGetState Apr 08 22:30:29 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 08 22:30:29 rivo volumio[5260]: info: CoreStateMachine::setRepeat false single undefined Apr 08 22:30:29 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:29 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:29 rivo volumio[5260]: info: CoreStateMachine::setRandom false Apr 08 22:30:29 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:29 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:29 rivo volumio[5260]: info: Discovery: adding 1f5e7998-cdc0-4efe-b025-dae76c4ee9c2 Apr 08 22:30:29 rivo volumio[5260]: info: Discovery: Found device Rivo Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::volumioGetState Apr 08 22:30:29 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:29 rivo volumio[5260]: info: MRS: Pushing multiroomSync output for this device Apr 08 22:30:29 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:29 rivo volumio[5260]: info: Adding audio output: Apr 08 22:30:29 rivo volumio[5260]: info: Adding audio output: Apr 08 22:30:29 rivo volumio[5260]: info: VolumeController:: Volume=100 Mute =false Apr 08 22:30:29 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:29 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:29 rivo sudo[5532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 08 22:30:29 rivo sudo[5532]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:29 rivo volumio[5260]: info: Discovery: this is already registered, 1f5e7998-cdc0-4efe-b025-dae76c4ee9c2 Apr 08 22:30:29 rivo volumio[5260]: info: Discovery: Found device Rivo Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::volumioGetState Apr 08 22:30:29 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:29 rivo systemd[1]: Started UPnP Renderer front-end to MPD. Apr 08 22:30:29 rivo sudo[5532]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:29 rivo volumio[5260]: info: Asound.conf file unchanged, so no further update is needed Apr 08 22:30:29 rivo volumio[5260]: info: Output device has changed, restarting MPD Apr 08 22:30:29 rivo sudo[5549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 08 22:30:29 rivo volumio[5260]: info: Output device has changed, restarting Shairport Sync Apr 08 22:30:29 rivo sudo[5549]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:30:29 rivo sudo[5549]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:29 rivo sudo[5552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 08 22:30:29 rivo sudo[5552]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:29 rivo systemd[1]: Listening on mpd.socket. Apr 08 22:30:29 rivo volumio[5260]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 22:30:29 rivo volumio[5260]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:29 rivo volumio[5260]: info: ___________ START PLUGINS ___________ Apr 08 22:30:29 rivo volumio[5260]: info: ControllerMpd::onStart: Initializing MPD Apr 08 22:30:29 rivo volumio[5260]: info: Creating MPD Configuration file Apr 08 22:30:29 rivo sudo[5562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 08 22:30:29 rivo sudo[5562]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:29 rivo sudo[5562]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 22:30:29 rivo volumio[5260]: info: [1712583029990] CoreMusicLibrary::Adding element メディアサーバー Apr 08 22:30:29 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 22:30:30 rivo sudo[5567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 08 22:30:30 rivo sudo[5567]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 08 22:30:30 rivo volumio[5260]: info: Adding Manifest REST API Endpoints Apr 08 22:30:30 rivo volumio[5260]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Apr 08 22:30:30 rivo volumio[5260]: info: Adding METAVOLUMIO REST API Endpoints Apr 08 22:30:30 rivo volumio[5260]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 08 22:30:30 rivo volumio[5260]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 08 22:30:30 rivo volumio[5260]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 08 22:30:30 rivo volumio[5260]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:30:30 rivo sudo[5570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Apr 08 22:30:30 rivo sudo[5570]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:30 rivo sudo[5570]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:30 rivo volumio[5260]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:30:30 rivo volumio[5260]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 08 22:30:30 rivo volumio[5260]: info: Preparing CD Folders Apr 08 22:30:30 rivo volumio[5260]: info: Adding CD REST API Endpoints Apr 08 22:30:30 rivo volumio[5260]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 08 22:30:30 rivo volumio[5260]: info: Starting UDEV Watcher for CD Apr 08 22:30:30 rivo volumio[5260]: info: Detecting CD presence with UDEV Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 22:30:30 rivo volumio[5260]: info: [1712583030170] CoreMusicLibrary::Adding element Last_100 Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 22:30:30 rivo volumio[5260]: info: Starting RAAT Plugin Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Apr 08 22:30:30 rivo volumio[5260]: info: Additional UI Settings Added for plugin music_service/raat Apr 08 22:30:30 rivo volumio[5260]: info: Registering DSP Elements listener and retrieving current ones Apr 08 22:30:30 rivo volumio[5260]: info: Additional DSP elements updated Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:30 rivo volumio[5260]: info: Updating RAAT Signal Path Apr 08 22:30:30 rivo volumio[5260]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 08 22:30:30 rivo volumio[5260]: info: Not Reporting Auto name since its the default one Apr 08 22:30:30 rivo volumio[5260]: info: Streaming services startup Apr 08 22:30:30 rivo volumio[5260]: info: Starting Streaming Daemon Apr 08 22:30:30 rivo sudo[5585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 08 22:30:30 rivo sudo[5585]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:30 rivo sudo[5585]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 08 22:30:30 rivo volumio[5260]: info: [1712583030347] CoreMusicLibrary::Adding element Webradio Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 08 22:30:30 rivo sudo[5593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 08 22:30:30 rivo sudo[5593]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:30 rivo volumio[5260]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:30 rivo sudo[5593]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:30 rivo volumio[5260]: error: Hi Res Audio Failed Login: Missing Login Data Apr 08 22:30:30 rivo volumio[5260]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 08 22:30:30 rivo volumio[5260]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 08 22:30:30 rivo volumio[5260]: info: Initializing Serial Communication on port /dev/ttyS3 Apr 08 22:30:30 rivo volumio[5260]: info: Adding inputs REST Endpoints Apr 08 22:30:30 rivo volumio[5260]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 08 22:30:30 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Apr 08 22:30:30 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Apr 08 22:30:30 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Apr 08 22:30:30 rivo volumio[5260]: info: Scanning Audio Inputs Apr 08 22:30:30 rivo volumio[5260]: info: Checking against Known Cards name Apr 08 22:30:30 rivo volumio[5260]: info: Checking against Known Cards name Apr 08 22:30:30 rivo volumio[5260]: info: Checking against Known Cards name Apr 08 22:30:30 rivo volumio[5260]: info: Checking against Known Cards name Apr 08 22:30:30 rivo volumio[5260]: info: Checking against Known Cards name Apr 08 22:30:30 rivo volumio[5260]: info: Adding Server instance for streaming Apr 08 22:30:30 rivo sudo[5610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Apr 08 22:30:30 rivo sudo[5610]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:30 rivo sudo[5610]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:30 rivo sudo[5613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Apr 08 22:30:30 rivo sudo[5613]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:30 rivo sudo[5613]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:30 rivo sudo[5616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Apr 08 22:30:30 rivo sudo[5616]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:30 rivo sudo[5616]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:30 rivo sudo[5619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Apr 08 22:30:30 rivo sudo[5619]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:30 rivo sudo[5619]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:30 rivo volumio[5260]: info: Apply VIM3L Onboard LEDs Settings Apr 08 22:30:30 rivo volumio[5260]: info: Turning On Onboard LEDs Apr 08 22:30:30 rivo sudo[5623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Apr 08 22:30:30 rivo sudo[5623]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:30 rivo volumio[5260]: info: Starting CEC Event listener Apr 08 22:30:30 rivo sudo[5623]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:30 rivo volumio[5260]: info: Volumio Calling Home Apr 08 22:30:30 rivo sudo[5628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Apr 08 22:30:30 rivo sudo[5628]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:30 rivo sudo[5628]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:30 rivo volumio[5260]: (node:5260) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Apr 08 22:30:30 rivo volumio[5260]: (Use `node --trace-deprecation ...` to show where the warning was created) Apr 08 22:30:30 rivo volumio[5260]: info: Stopping AccessToken refresher cron for QOBUZ Apr 08 22:30:30 rivo volumio[5260]: info: AccessToken refresher cron started for QOBUZ Apr 08 22:30:30 rivo volumio[5260]: info: Stopping AccessToken refresher cron Apr 08 22:30:30 rivo volumio[5260]: info: AccessToken refresher cron started Apr 08 22:30:30 rivo volumio[5260]: info: Adding TIDAL REST API Endpoints Apr 08 22:30:30 rivo volumio[5260]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 08 22:30:30 rivo volumio[5260]: info: Adding QOBUZ REST API Endpoints Apr 08 22:30:30 rivo volumio[5260]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 08 22:30:30 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 08 22:30:31 rivo volumio[5260]: info: Upmpdcli Daemon Started Apr 08 22:30:31 rivo volumio[5260]: info: Serial port opened successfully Apr 08 22:30:31 rivo volumio[5260]: info: Sending serial start messages Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: MP1 GPIO: Signalled system ready via GPIO Apr 08 22:30:31 rivo volumio[5260]: error: Cannot start Volumio Streaming Daemon Apr 08 22:30:31 rivo volumio[5260]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 08 22:30:31 rivo volumio[5260]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 08 22:30:31 rivo volumio[5260]: info: RAAT Albumart path created successfully Apr 08 22:30:31 rivo volumio[5260]: info: Bluetooth name changed to Rivo Apr 08 22:30:31 rivo volumio[5260]: info: MPD Permissions set Apr 08 22:30:31 rivo volumio[5260]: info: MPD Permissions set Apr 08 22:30:31 rivo volumio[5260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 43 Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Apr 08 22:30:31 rivo volumio[5260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 44 Apr 08 22:30:31 rivo sudo[5656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Apr 08 22:30:31 rivo sudo[5656]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:31 rivo sudo[5656]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:31 rivo sudo[5658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Apr 08 22:30:31 rivo sudo[5658]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::volumioGetVisibleSources Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::volumioGetState Apr 08 22:30:31 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 08 22:30:31 rivo volumio[5260]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 08 22:30:31 rivo volumio[5260]: info: Received Get System Info Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 08 22:30:31 rivo volumio[5260]: info: Discovery: Getting this device information Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::volumioGetState Apr 08 22:30:31 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 08 22:30:31 rivo systemd[1]: Started RAAT DAEMON. Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::volumioGetState Apr 08 22:30:31 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:31 rivo sudo[5658]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:31 rivo volumio[5260]: info: Listing playlists Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::volumioGetQueue Apr 08 22:30:31 rivo volumio[5260]: info: CoreStateMachine::getQueue Apr 08 22:30:31 rivo volumio[5260]: info: CorePlayQueue::getQueue Apr 08 22:30:31 rivo volumio[5260]: info: Received Get System Info Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 08 22:30:31 rivo volumio[5260]: info: Discovery: Getting this device information Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::volumioGetState Apr 08 22:30:31 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::volumioGetState Apr 08 22:30:31 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:31 rivo volumio[5260]: info: Volumio called home Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Apr 08 22:30:31 rivo volumio[5260]: info: Setting Device Volume Override Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 08 22:30:31 rivo volumio[5260]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: SMSL USB AUDIO Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 08 22:30:31 rivo volumio[5260]: info: Disabling external Volume Control Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:31 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:31 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:31 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Apr 08 22:30:31 rivo volumio[5260]: info: Setting Additional System Software info: Hardware Revision: 2.1 Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Apr 08 22:30:31 rivo volumio[5260]: info: Setting HW Firmware info: undefined Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Apr 08 22:30:31 rivo volumio[5260]: info: Setting HW Version info: 2.1 Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Apr 08 22:30:31 rivo volumio[5260]: info: Setting Additional System Software info: Hardware Revision: 2.1, Firmware Version: 0.3.3 Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Apr 08 22:30:31 rivo volumio[5260]: info: Setting HW Firmware info: 0.3.3 Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Apr 08 22:30:31 rivo volumio[5260]: info: Setting HW Version info: 2.1 Apr 08 22:30:31 rivo volumio[5260]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Apr 08 22:30:31 rivo volumio[5260]: info: MCU Signalled Headphone Mode Disabled Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:30:31 rivo volumio[5260]: info: Updating RAAT Signal Path Apr 08 22:30:31 rivo volumio[5260]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Apr 08 22:30:31 rivo volumio[5260]: info: MCU Signalled Sleep Mode Disabled Apr 08 22:30:31 rivo volumio[5260]: info: Enabling Advanced system settings configuration Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Apr 08 22:30:31 rivo volumio[5260]: info: Additional UI Settings Added for plugin music_service/inputs Apr 08 22:30:31 rivo volumio[5260]: info: MCU Signalled Auto Boot Mode On Power Disabled Apr 08 22:30:31 rivo sudo[5696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Apr 08 22:30:31 rivo sudo[5696]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:31 rivo sudo[5696]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:31 rivo volumio[5260]: info: Raat Daemon started successfully Apr 08 22:30:31 rivo volumio[5260]: info: Starting Shairport Sync Apr 08 22:30:31 rivo volumio[5260]: info: Starting Shairport Sync Apr 08 22:30:31 rivo sudo[5699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 22:30:31 rivo sudo[5699]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:31 rivo volumio[5260]: info: Starting Shairport Sync Apr 08 22:30:31 rivo sudo[5702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 22:30:31 rivo sudo[5702]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:31 rivo volumio[5260]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Apr 08 22:30:31 rivo sudo[5705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 08 22:30:31 rivo sudo[5705]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:31 rivo volumio[5260]: info: Executing endpoint restartRAATSocket Apr 08 22:30:31 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Apr 08 22:30:32 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:32 rivo volumio[5260]: info: VolumeController:: Volume=100 Mute =false Apr 08 22:30:32 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:32 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:32 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 22:30:32 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:32 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:32 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:32 rivo volumio[5260]: info: Adding Inputs via Serial API Apr 08 22:30:32 rivo volumio[5260]: info: CoreCommandRouter::volumioGetState Apr 08 22:30:32 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:32 rivo volumio[5260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 45 Apr 08 22:30:32 rivo volumio[5260]: info: MCU Signalled Playback Inactive Apr 08 22:30:32 rivo volumio[5260]: info: CoreCommandRouter::volumioGetState Apr 08 22:30:32 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:32 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:32 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:32 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 22:30:32 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:32 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:32 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:32 rivo volumio[5260]: info: CoreCommandRouter::servicePushState Apr 08 22:30:32 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:32 rivo volumio[5260]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received inputs Apr 08 22:30:32 rivo volumio[5260]: info: CoreCommandRouter::volumiosetSourceActiveno-source Apr 08 22:30:32 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 08 22:30:33 rivo sudo[5710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 08 22:30:33 rivo sudo[5710]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:33 rivo systemd[1]: Started Volumio Bluetooth Module. Apr 08 22:30:33 rivo sudo[5710]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:33 rivo sudo[5725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Apr 08 22:30:33 rivo sudo[5725]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:33 rivo volumiobt[5712]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Apr 08 22:30:33 rivo sudo[5725]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:33 rivo volumio[5260]: info: Volumio BT Module successfully started Apr 08 22:30:33 rivo volumio[5260]: info: Turning Off Onboard LEDs Apr 08 22:30:33 rivo sudo[5738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Apr 08 22:30:33 rivo sudo[5738]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:33 rivo sudo[5738]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:33 rivo sudo[5742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Apr 08 22:30:33 rivo sudo[5742]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:33 rivo sudo[5742]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:33 rivo volumio[5260]: info: Onboard LEDs initialized Apr 08 22:30:33 rivo volumio[5260]: info: TidalConnect service stoped! Apr 08 22:30:33 rivo volumio[5260]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 08 22:30:33 rivo volumio[5260]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 08 22:30:33 rivo sudo[5754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 08 22:30:33 rivo sudo[5754]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:33 rivo systemd[1]: Started Volumio Tidal Connect Service. Apr 08 22:30:33 rivo sudo[5754]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:34 rivo sudo[5778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Apr 08 22:30:34 rivo sudo[5778]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:34 rivo volumiobt[5712]: pulseaudio: no process found Apr 08 22:30:34 rivo sudo[5778]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:34 rivo volumio[5260]: info: Executing endpoint tc_getconfig Apr 08 22:30:34 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 08 22:30:34 rivo vtcs[5756]: STARTING TidalConnect services, version: 1.3.0.19 Apr 08 22:30:34 rivo dbus-daemon[4597]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.13' (uid=1000 pid=5786 comm="/usr/bin/pulseaudio --high-priority=true " label="kernel") Apr 08 22:30:34 rivo systemd[1]: Starting RealtimeKit Scheduling Policy Service... Apr 08 22:30:34 rivo kernel: IPVS: Creating netns size=1928 id=3 Apr 08 22:30:34 rivo volumio[5260]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 08 22:30:34 rivo dbus-daemon[4597]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Apr 08 22:30:34 rivo systemd[1]: Started RealtimeKit Scheduling Policy Service. Apr 08 22:30:34 rivo rtkit-daemon[5788]: Successfully called chroot. Apr 08 22:30:34 rivo rtkit-daemon[5788]: Successfully dropped privileges. Apr 08 22:30:34 rivo rtkit-daemon[5788]: Successfully limited resources. Apr 08 22:30:34 rivo rtkit-daemon[5788]: Running. Apr 08 22:30:34 rivo rtkit-daemon[5788]: Watchdog thread running. Apr 08 22:30:34 rivo rtkit-daemon[5788]: Canary thread running. Apr 08 22:30:34 rivo dbus-daemon[4597]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.14' (uid=0 pid=5788 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel") Apr 08 22:30:34 rivo vtcs[5756]: STARTED TidalConnect services. Apr 08 22:30:34 rivo systemd[1]: Starting Authorization Manager... Apr 08 22:30:34 rivo volumio[5260]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 08 22:30:34 rivo volumio[5260]: info: Executing endpoint tc_connect Apr 08 22:30:34 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 08 22:30:34 rivo volumio[5260]: info: Connecting to TidalConnect Apr 08 22:30:34 rivo volumio[5260]: info: CoreCommandRouter::servicePushState Apr 08 22:30:34 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:34 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:34 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:34 rivo polkitd[5825]: started daemon version 0.105 using authority implementation `local' version `0.105' Apr 08 22:30:34 rivo dbus-daemon[4597]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 08 22:30:34 rivo systemd[1]: Started Authorization Manager. Apr 08 22:30:34 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:34 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:34 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:34 rivo volumio[5260]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Apr 08 22:30:34 rivo volumio[5260]: info: CoreCommandRouter::servicePushState Apr 08 22:30:34 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:34 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:34 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:34 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:34 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:34 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:34 rivo volumio[5260]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Apr 08 22:30:34 rivo pulseaudio[5786]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 08 22:30:34 rivo pulseaudio[5786]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 08 22:30:34 rivo bluetoothd[5151]: Endpoint registered: sender=:1.35 path=/MediaEndpoint/A2DPSource Apr 08 22:30:34 rivo bluetoothd[5151]: Endpoint registered: sender=:1.35 path=/MediaEndpoint/A2DPSink Apr 08 22:30:34 rivo volumiobt[5712]: Applying permissions Apr 08 22:30:34 rivo volumiobt[5712]: Setting BT discoverable and pairable Apr 08 22:30:34 rivo pulseaudio[5786]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Apr 08 22:30:34 rivo volumiobt[5712]: Agent registered Apr 08 22:30:34 rivo volumiobt[5712]: [bluetooth]# -e power on Apr 08 22:30:34 rivo volumiobt[5712]: Invalid command in menu main: -e Apr 08 22:30:34 rivo volumiobt[5712]: Apr 08 22:30:34 rivo volumiobt[5712]: Use "help" for a list of available commands in a menu. Apr 08 22:30:34 rivo volumiobt[5712]: Use "menu " if you want to enter any submenu. Apr 08 22:30:34 rivo volumiobt[5712]: Use "back" if you want to return to menu main. Apr 08 22:30:34 rivo volumiobt[5712]: [bluetooth]# agent on Apr 08 22:30:34 rivo volumiobt[5712]: Agent is already registered Apr 08 22:30:34 rivo volumiobt[5712]: [bluetooth]# discoverable on Apr 08 22:30:34 rivo volumiobt[5712]: [bluetooth]# pairable on Apr 08 22:30:34 rivo volumiobt[5712]: [bluetooth]# agent NoInputNoOutput Apr 08 22:30:34 rivo volumiobt[5712]: Agent is already registered Apr 08 22:30:34 rivo volumiobt[5712]: [bluetooth]# default-agent Apr 08 22:30:34 rivo volumiobt[5712]: [bluetooth]# quit Apr 08 22:30:34 rivo volumiobt[5712]: [59B blob data] Apr 08 22:30:35 rivo volumio-remote-updater[4748]: [2024-04-08 22:30:35] [connect] Successful connection Apr 08 22:30:35 rivo volumio-remote-updater[4748]: [2024-04-08 22:30:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1712583035 101 Apr 08 22:30:35 rivo volumio[5260]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 46 Apr 08 22:30:35 rivo volumio[5260]: info: MRS: Getting audio outputs on start Apr 08 22:30:35 rivo volumio[5260]: info: MRS: Requesting all other devices output Apr 08 22:30:36 rivo volumio[5260]: info: TidalConnect service started! Apr 08 22:30:37 rivo systemd[1]: systemd-hostnamed.service: Succeeded. Apr 08 22:30:37 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 08 22:30:37 rivo volumio[5260]: info: CURURI: music-library Apr 08 22:30:37 rivo volumio[5260]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Apr 08 22:30:40 rivo volumio[5260]: info: Checking for updated MCU Firmware Apr 08 22:30:40 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 22:30:40 rivo volumio[5260]: info: Firware on device is on latest version, no need to update Apr 08 22:30:41 rivo kernel: usb 1-1.1: reset high-speed USB device number 3 using xhci-hcd Apr 08 22:30:43 rivo volumio[5260]: info: Mounting Device RIVO Apr 08 22:30:43 rivo sudo[5883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/RIVO -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 08 22:30:43 rivo sudo[5883]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:43 rivo systemd[1]: Started Helper to synchronize boot up for ifupdown. Apr 08 22:30:43 rivo kernel: FAT-fs (sda2): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Apr 08 22:30:43 rivo systemd[1]: Starting Raise network interfaces... Apr 08 22:30:43 rivo kernel: FAT-fs (sda2): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Apr 08 22:30:43 rivo sudo[5883]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:43 rivo volumio[5260]: info: Mounting Device EFI Apr 08 22:30:43 rivo sudo[5937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/EFI -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 08 22:30:43 rivo sudo[5937]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:43 rivo systemd[1]: Started Raise network interfaces. Apr 08 22:30:43 rivo systemd[1]: Reached target Network. Apr 08 22:30:43 rivo systemd[1]: Reached target Network is Online. Apr 08 22:30:43 rivo systemd[1]: Starting LSB: Brings up/down network automatically... Apr 08 22:30:43 rivo systemd[1]: Starting /etc/rc.local Compatibility... Apr 08 22:30:43 rivo systemd[1]: Starting Samba NMB Daemon... Apr 08 22:30:43 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 08 22:30:43 rivo rc.local[5972]: [i] set fix freq 1200000 Apr 08 22:30:43 rivo sudo[5699]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:43 rivo systemd[1]: Starting Network Time Service... Apr 08 22:30:43 rivo sudo[5705]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:43 rivo systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Apr 08 22:30:43 rivo sudo[5702]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:43 rivo systemd[1]: Starting Flexible IR remote input/output application support... Apr 08 22:30:44 rivo systemd[1]: Starting Music Player Daemon... Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Info: lircd: Opening log, level: Info Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Version: lircd 0.10.1 Apr 08 22:30:44 rivo systemd[1]: Starting Permit User Sessions... Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: System info: Linux rivo 4.9.241 #4 SMP PREEMPT Tue Oct 17 09:27:12 CEST 2023 aarch64 GNU/Linux Apr 08 22:30:44 rivo rc.local[5972]: /etc/rc.local: 29: /etc/rc.local: cannot create /proc/irq/42/smp_affinity: Directory nonexistent Apr 08 22:30:44 rivo systemd[1]: rc-local.service: Control process exited, code=exited, status=2/INVALIDARGUMENT Apr 08 22:30:44 rivo systemd[1]: rc-local.service: Failed with result 'exit-code'. Apr 08 22:30:44 rivo systemd[1]: Failed to start /etc/rc.local Compatibility. Apr 08 22:30:44 rivo kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Apr 08 22:30:44 rivo kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Apr 08 22:30:44 rivo sudo[5937]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:44 rivo ntpd[6014]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 08 22:30:44 rivo ntpd[6014]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Apr 08 22:30:44 rivo systemd[1]: Started Network Time Service. Apr 08 22:30:44 rivo systemd[1]: Started Permit User Sessions. Apr 08 22:30:44 rivo ntpd[6126]: proto: precision = 1.250 usec (-20) Apr 08 22:30:44 rivo ntpd[6126]: Listen and drop on 0 v6wildcard [::]:123 Apr 08 22:30:44 rivo ntpd[6126]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 08 22:30:44 rivo ntpd[6126]: Listen normally on 2 lo 127.0.0.1:123 Apr 08 22:30:44 rivo volumio[5260]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 08 22:30:44 rivo ntpd[6126]: Listen normally on 3 eth0 192.168.3.50:123 Apr 08 22:30:44 rivo ntpd[6126]: Listen normally on 4 wlan0 192.168.0.245:123 Apr 08 22:30:44 rivo ntpd[6126]: Listening on routing socket on fd #21 for interface updates Apr 08 22:30:44 rivo ntpd[6126]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 08 22:30:44 rivo ntpd[6126]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 08 22:30:44 rivo systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 08 22:30:44 rivo systemd[1]: Starting Hold until boot process finishes up... Apr 08 22:30:44 rivo systemd[1]: Received SIGRTMIN+21 from PID 3954 (plymouthd). Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Info: Initial device: auto Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Info: Initial device: auto Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: driver: devinput Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: output: /var/run/lirc/lircd Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Info: lircd: Opening log, level: Info Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: Using systemd fd Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Warning: Running as root Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: nodaemon: 1 Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: logfile: syslog Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: immediate-init: 0 Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: permission: 666 Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: driver-options: Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: listen: 0 Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: connect: (null) Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: userelease: 0 Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: effective_user: (null) Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: release_suffix: _EVUP Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: allow_simulate: 0 Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: repeat_max: 600 Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: configfile: /etc/lirc/lircd.conf Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Options: dynamic_codes: (null) Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Current driver: devinput Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Driver API version: 4 Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Driver version: 0.10.0 Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/devinput.html Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Info: Using remote: devinput-64. Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Info: lircd: Opening log, level: Info Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: Using systemd fd Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Warning: Running as root Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Info: Using remote: devinput-64. Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MISC Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MOUSE Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_SOUTH Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MISC Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TOOL_PEN Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER_HAPPY1 Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_WHEEL Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Info: Using remote: devinput-32. Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_MOUSE Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_SOUTH Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TOOL_PEN Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_TRIGGER_HAPPY1 Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-64: Multiple values for same code: BTN_WHEEL Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Info: Using remote: devinput-32. Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MISC Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MISC Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MOUSE Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_MOUSE Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_SOUTH Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_SOUTH Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TOOL_PEN Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TOOL_PEN Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER_HAPPY1 Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_WHEEL Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_TRIGGER_HAPPY1 Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: /etc/lirc/lircd.conf.d/devinput.lircd.conf: devinput-32: Multiple values for same code: BTN_WHEEL Apr 08 22:30:44 rivo systemd[1]: Received SIGRTMIN+21 from PID 3954 (plymouthd). Apr 08 22:30:44 rivo systemd[1]: plymouth-quit.service: Succeeded. Apr 08 22:30:44 rivo lircd[6028]: lircd-0.10.1[6028]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Apr 08 22:30:44 rivo lircd-0.10.1[6028]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Apr 08 22:30:44 rivo systemd[1]: Started Terminate Plymouth Boot Screen. Apr 08 22:30:44 rivo systemd[1]: Started Flexible IR remote input/output application support. Apr 08 22:30:44 rivo kernel: fb: osd_release now.index=3,open_count=1 Apr 08 22:30:44 rivo kernel: fb: osd_release now.index=2,open_count=1 Apr 08 22:30:44 rivo kernel: fb: osd_release now.index=1,open_count=1 Apr 08 22:30:44 rivo ifplugd(eth0)[6179]: ifplugd 0.28 initializing. Apr 08 22:30:44 rivo ifplugd(eth0)[6179]: Using interface eth0/02:00:00:24:21:01 with driver (version: Jan_2016) Apr 08 22:30:44 rivo systemd[1]: plymouth-start.service: Succeeded. Apr 08 22:30:44 rivo volumio[5260]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 08 22:30:44 rivo ifplugd(eth0)[6179]: Using detection mode: SIOCETHTOOL Apr 08 22:30:44 rivo ifplugd(eth0)[6179]: Initialization complete, link beat detected. Apr 08 22:30:44 rivo systemd[1]: plymouth-quit-wait.service: Succeeded. Apr 08 22:30:44 rivo systemd[1]: Started Hold until boot process finishes up. Apr 08 22:30:44 rivo volumio[5260]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 08 22:30:44 rivo volumio[5260]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 08 22:30:44 rivo systemd[1]: Started Serial Getty on ttyS0. Apr 08 22:30:44 rivo systemd[1]: Reached target Login Prompts. Apr 08 22:30:44 rivo ifplugd(eth0)[6179]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 08 22:30:44 rivo volumio[5260]: info: Shairport-Sync Started Apr 08 22:30:44 rivo volumio[5260]: Error adding Membership: Error: addMembership EINVAL Apr 08 22:30:44 rivo volumio[5260]: info: Shairport-Sync Started Apr 08 22:30:44 rivo ifplugd(eth0)[6179]: client: ifup: interface eth0 already configured Apr 08 22:30:44 rivo ifplugd(eth0)[6179]: Program executed successfully. Apr 08 22:30:44 rivo ifplugd[5971]: Network Interface Plugging Daemon...start eth0...done. Apr 08 22:30:44 rivo systemd[1]: Started LSB: Brings up/down network automatically. Apr 08 22:30:44 rivo nmbd[6115]: [2024/04/08 22:30:44.888271, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 08 22:30:44 rivo nmbd[6115]: started asyncdns process 6226 Apr 08 22:30:44 rivo nmbd[6115]: [2024/04/08 22:30:44.899202, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 08 22:30:44 rivo nmbd[6115]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 08 22:30:44 rivo kernel: nf_conntrack: default automatic helper assignment has been turned off for security reasons and CT-based firewall rule not found. Use the iptables CT target to attach helpers instead. Apr 08 22:30:44 rivo systemd[1]: Started Samba NMB Daemon. Apr 08 22:30:44 rivo systemd[1]: Starting Samba Winbind Daemon... Apr 08 22:30:44 rivo volumio[5260]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 08 22:30:45 rivo winbindd[6229]: [2024/04/08 22:30:45.286389, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 08 22:30:45 rivo winbindd[6229]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 08 22:30:45 rivo winbindd[6229]: [2024/04/08 22:30:45.300114, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 08 22:30:45 rivo winbindd[6229]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 08 22:30:45 rivo systemd[1]: Started Samba Winbind Daemon. Apr 08 22:30:45 rivo systemd[1]: Starting Samba SMB Daemon... Apr 08 22:30:45 rivo volumio[5260]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 08 22:30:45 rivo smbd[6272]: [2024/04/08 22:30:45.895994, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 08 22:30:45 rivo smbd[6272]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 08 22:30:45 rivo systemd[1]: Started Samba SMB Daemon. Apr 08 22:30:45 rivo systemd[1]: Reached target Multi-User System. Apr 08 22:30:45 rivo systemd[1]: Reached target Graphical Interface. Apr 08 22:30:45 rivo systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 08 22:30:45 rivo volumio[5260]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 08 22:30:45 rivo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 08 22:30:45 rivo systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 08 22:30:46 rivo mpd[6073]: Apr 08 22:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 08 22:30:46 rivo systemd[1]: Started Music Player Daemon. Apr 08 22:30:46 rivo systemd[1]: Startup finished in 6.696s (kernel) + 54.845s (userspace) = 1min 1.541s. Apr 08 22:30:46 rivo volumio[5260]: Upnp client error: Error: This socket has been ended by the other party Apr 08 22:30:46 rivo sudo[5552]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:46 rivo sudo[5567]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:46 rivo volumio[5260]: info: Completed starting Core Plugins Apr 08 22:30:46 rivo volumio[5260]: info: ------------------------------------------- Apr 08 22:30:46 rivo volumio[5260]: info: ----- MyVolumio plugins startup ---- Apr 08 22:30:46 rivo volumio[5260]: info: ------------------------------------------- Apr 08 22:30:46 rivo volumio[5260]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 08 22:30:47 rivo volumio[5260]: error: MPD error: The expression evaluated to a falsy value: Apr 08 22:30:47 rivo volumio[5260]: assert.ok(self.idling) Apr 08 22:30:47 rivo volumio[5260]: error: The expression evaluated to a falsy value: Apr 08 22:30:47 rivo volumio[5260]: assert.ok(self.idling) Apr 08 22:30:47 rivo volumio[5260]: error: updateQueue error: null Apr 08 22:30:47 rivo volumio[5260]: info: MPD running with PID6073 Apr 08 22:30:47 rivo volumio[5260]: ,establishing connection Apr 08 22:30:47 rivo volumio[5260]: error: updateQueue error: null Apr 08 22:30:47 rivo kernel: fb: mem_free_work, free memory: addr:800000 Apr 08 22:30:49 rivo volumio[5534]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 08 22:30:53 rivo volumio[5260]: info: CoreCommandRouter::volumioPlay Apr 08 22:30:53 rivo volumio[5260]: info: CoreStateMachine::play index undefined Apr 08 22:30:53 rivo volumio[5260]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 22:30:53 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:53 rivo volumio[5260]: info: CoreStateMachine::startPlaybackTimer Apr 08 22:30:53 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:53 rivo volumio[5260]: verbose: ControllerMpd::clearAddPlayTracks USB/RIVO/Violin Concerto, Op. 35 _ Violin Concerto N (US - Sony Classical SK 68338;68338 - 1998-0)/01. Violin Concerto in D major, Op. 35_ I. Allegro moderato.wav Apr 08 22:30:53 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 22:30:53 rivo volumio[5260]: info: sendMpdCommand stop took 3 milliseconds Apr 08 22:30:53 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand clear Apr 08 22:30:53 rivo volumio[5260]: info: sendMpdCommand clear took 1 milliseconds Apr 08 22:30:53 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand add "USB/RIVO/Violin Concerto, Op. 35 _ Violin Concerto N (US - Sony Classical SK 68338;68338 - 1998-0)/01. Violin Concerto in D major, Op. 35_ I. Allegro moderato.wav" Apr 08 22:30:53 rivo volumio[5260]: info: Apr 08 22:30:53 rivo volumio[5260]: ---------------------------- MPD announces system playlist update Apr 08 22:30:53 rivo volumio[5260]: info: Ignoring MPD Status Update Apr 08 22:30:53 rivo volumio[5260]: info: Apr 08 22:30:53 rivo volumio[5260]: ---------------------------- MPD announces system playlist update Apr 08 22:30:53 rivo volumio[5260]: info: Ignoring MPD Status Update Apr 08 22:30:53 rivo volumio[5260]: info: sendMpdCommand add "USB/RIVO/Violin Concerto, Op. 35 _ Violin Concerto N (US - Sony Classical SK 68338;68338 - 1998-0)/01. Violin Concerto in D major, Op. 35_ I. Allegro moderato.wav" took 5 milliseconds Apr 08 22:30:53 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand play Apr 08 22:30:53 rivo volumio[5260]: info: Apr 08 22:30:53 rivo volumio[5260]: ---------------------------- MPD announces system playlist update Apr 08 22:30:53 rivo volumio[5260]: info: Ignoring MPD Status Update Apr 08 22:30:53 rivo volumio[5260]: info: Apr 08 22:30:53 rivo volumio[5260]: ---------------------------- MPD announces system playlist update Apr 08 22:30:53 rivo volumio[5260]: info: Ignoring MPD Status Update Apr 08 22:30:53 rivo volumio[5260]: info: ------------------------------ 22ms Apr 08 22:30:53 rivo volumio[5260]: info: ------------------------------ 20ms Apr 08 22:30:53 rivo volumio[5260]: info: sendMpdCommand play took 18 milliseconds Apr 08 22:30:53 rivo volumio[5260]: info: ------------------------------ 16ms Apr 08 22:30:53 rivo volumio[5260]: info: ------------------------------ 7ms Apr 08 22:30:53 rivo volumio[5260]: info: Apr 08 22:30:53 rivo volumio[5260]: ---------------------------- MPD announces system playlist update Apr 08 22:30:53 rivo volumio[5260]: info: Ignoring MPD Status Update Apr 08 22:30:53 rivo volumio[5260]: info: Apr 08 22:30:53 rivo volumio[5260]: ---------------------------- MPD announces system playlist update Apr 08 22:30:53 rivo volumio[5260]: info: Ignoring MPD Status Update Apr 08 22:30:53 rivo volumio[5260]: info: ------------------------------ 5ms Apr 08 22:30:53 rivo volumio[5260]: info: ------------------------------ 4ms Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 08 22:30:55 rivo volumio[5260]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 08 22:30:56 rivo volumio[5260]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 08 22:30:56 rivo volumio[5260]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 08 22:30:56 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:30:56 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:30:56 rivo volumio[5260]: info: Starting MyVolumio Remote Streaming Endpoints Apr 08 22:30:56 rivo volumio[5260]: info: MyVolumio login type: Token Apr 08 22:30:56 rivo volumio[5260]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 08 22:30:56 rivo volumio[5260]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Apr 08 22:30:56 rivo volumio[5260]: info: CoreCommandRouter::volumioPlay Apr 08 22:30:56 rivo volumio[5260]: info: CoreStateMachine::play index undefined Apr 08 22:30:56 rivo volumio[5260]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 22:30:56 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:56 rivo volumio[5260]: info: CoreStateMachine::startPlaybackTimer Apr 08 22:30:56 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::clearAddPlayTracks USB/RIVO/Violin Concerto, Op. 35 _ Violin Concerto N (US - Sony Classical SK 68338;68338 - 1998-0)/01. Violin Concerto in D major, Op. 35_ I. Allegro moderato.wav Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces state update: player Apr 08 22:30:56 rivo volumio[5260]: info: ControllerMpd::getState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand status Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces state update: player Apr 08 22:30:56 rivo volumio[5260]: info: ControllerMpd::getState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand status Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces state update: player Apr 08 22:30:56 rivo volumio[5260]: info: ControllerMpd::getState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand status Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces state update: player Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand stop took 27 milliseconds Apr 08 22:30:56 rivo volumio[5260]: info: ControllerMpd::getState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand status Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand status took 25 milliseconds Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand status took 24 milliseconds Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand status took 22 milliseconds Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand clear Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::parseState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::parseState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::parseState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces state update: player Apr 08 22:30:56 rivo volumio[5260]: info: ControllerMpd::getState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand status Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces state update: player Apr 08 22:30:56 rivo volumio[5260]: info: ControllerMpd::getState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand status Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces system playlist update Apr 08 22:30:56 rivo volumio[5260]: info: Ignoring MPD Status Update Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand status took 23 milliseconds Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand clear took 21 milliseconds Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand status took 15 milliseconds Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand status took 14 milliseconds Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::parseState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand add "USB/RIVO/Violin Concerto, Op. 35 _ Violin Concerto N (US - Sony Classical SK 68338;68338 - 1998-0)/01. Violin Concerto in D major, Op. 35_ I. Allegro moderato.wav" Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::parseTrackInfo Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::parseTrackInfo Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::parseTrackInfo Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::parseState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::parseState Apr 08 22:30:56 rivo volumio[5260]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 22:30:56 rivo volumio[5260]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 22:30:56 rivo volumio[5260]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 22:30:56 rivo volumio[5260]: info: ControllerMpd::pushState Apr 08 22:30:56 rivo volumio[5260]: info: CoreCommandRouter::servicePushState Apr 08 22:30:56 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:56 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:56 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:56 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:56 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:56 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:56 rivo volumio[5260]: 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} Apr 08 22:30:56 rivo volumio[5260]: verbose: CURRENT POSITION 0 Apr 08 22:30:56 rivo volumio[5260]: info: CoreStateMachine::syncState stateService stop Apr 08 22:30:56 rivo volumio[5260]: info: CoreStateMachine::syncState currentStatus stop Apr 08 22:30:56 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:56 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:56 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:56 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:56 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:56 rivo volumio[5260]: info: No code Apr 08 22:30:56 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:56 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:56 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:56 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:56 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:56 rivo volumio[5260]: info: ControllerMpd::pushState Apr 08 22:30:56 rivo volumio[5260]: info: CoreCommandRouter::servicePushState Apr 08 22:30:56 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:56 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:56 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:56 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:56 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:56 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:56 rivo volumio[5260]: 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} Apr 08 22:30:56 rivo volumio[5260]: verbose: CURRENT POSITION 0 Apr 08 22:30:56 rivo volumio[5260]: info: CoreStateMachine::syncState stateService stop Apr 08 22:30:56 rivo volumio[5260]: info: CoreStateMachine::syncState currentStatus stop Apr 08 22:30:56 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:56 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:56 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:56 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:56 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:56 rivo volumio[5260]: info: No code Apr 08 22:30:56 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:56 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:56 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:56 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:56 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:56 rivo volumio[5260]: info: ------------------------------ 126ms Apr 08 22:30:56 rivo volumio[5260]: info: ------------------------------ 123ms Apr 08 22:30:56 rivo volumio[5260]: info: ------------------------------ 121ms Apr 08 22:30:56 rivo volumio[5260]: info: ------------------------------ 116ms Apr 08 22:30:56 rivo volumio[5260]: info: ------------------------------ 115ms Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces system playlist update Apr 08 22:30:56 rivo volumio[5260]: info: Ignoring MPD Status Update Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces system playlist update Apr 08 22:30:56 rivo volumio[5260]: info: Ignoring MPD Status Update Apr 08 22:30:56 rivo volumio[5260]: error: updateQueue error: null Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces system playlist update Apr 08 22:30:56 rivo volumio[5260]: info: Ignoring MPD Status Update Apr 08 22:30:56 rivo volumio[5260]: info: ------------------------------ 114ms Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand playlistinfo took 106 milliseconds Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand add "USB/RIVO/Violin Concerto, Op. 35 _ Violin Concerto N (US - Sony Classical SK 68338;68338 - 1998-0)/01. Violin Concerto in D major, Op. 35_ I. Allegro moderato.wav" took 107 milliseconds Apr 08 22:30:56 rivo volumio[5260]: info: ------------------------------ 8ms Apr 08 22:30:56 rivo volumio[5260]: info: ------------------------------ 7ms Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::parseTrackInfo Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand play Apr 08 22:30:56 rivo volumio[5260]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 08 22:30:56 rivo volumio[5260]: info: ------------------------------ 141ms Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces system playlist update Apr 08 22:30:56 rivo volumio[5260]: info: Ignoring MPD Status Update Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces system playlist update Apr 08 22:30:56 rivo volumio[5260]: info: Ignoring MPD Status Update Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces state update: player Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces state update: player Apr 08 22:30:56 rivo volumio[5260]: info: ------------------------------ 36ms Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand play took 31 milliseconds Apr 08 22:30:56 rivo volumio[5260]: info: ControllerMpd::getState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand status Apr 08 22:30:56 rivo volumio[5260]: info: ------------------------------ 30ms Apr 08 22:30:56 rivo volumio[5260]: info: ------------------------------ 29ms Apr 08 22:30:56 rivo volumio[5260]: info: ControllerMpd::getState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand status Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces state update: player Apr 08 22:30:56 rivo volumio[5260]: info: ControllerMpd::getState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand status Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces state update: player Apr 08 22:30:56 rivo volumio[5260]: info: ControllerMpd::getState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand status Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand status took 24 milliseconds Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand status took 22 milliseconds Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand status took 21 milliseconds Apr 08 22:30:56 rivo volumio[5260]: info: sendMpdCommand status took 18 milliseconds Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::parseState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::parseState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::parseState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::parseState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces state update: player Apr 08 22:30:56 rivo volumio[5260]: info: ControllerMpd::getState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand status Apr 08 22:30:56 rivo volumio[5260]: info: Apr 08 22:30:56 rivo volumio[5260]: ---------------------------- MPD announces state update: player Apr 08 22:30:56 rivo volumio[5260]: info: ControllerMpd::getState Apr 08 22:30:56 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand status Apr 08 22:30:57 rivo volumio[5260]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 08 22:30:57 rivo volumio[5260]: info: sendMpdCommand playlistinfo took 27 milliseconds Apr 08 22:30:57 rivo volumio[5260]: info: sendMpdCommand playlistinfo took 27 milliseconds Apr 08 22:30:57 rivo volumio[5260]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 08 22:30:57 rivo volumio[5260]: info: sendMpdCommand status took 24 milliseconds Apr 08 22:30:57 rivo volumio[5260]: info: sendMpdCommand status took 22 milliseconds Apr 08 22:30:57 rivo volumio[5260]: verbose: ControllerMpd::parseTrackInfo Apr 08 22:30:57 rivo volumio[5260]: verbose: ControllerMpd::parseTrackInfo Apr 08 22:30:57 rivo volumio[5260]: verbose: ControllerMpd::parseTrackInfo Apr 08 22:30:57 rivo volumio[5260]: verbose: ControllerMpd::parseTrackInfo Apr 08 22:30:57 rivo volumio[5260]: verbose: ControllerMpd::parseState Apr 08 22:30:57 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 22:30:57 rivo volumio[5260]: verbose: ControllerMpd::parseState Apr 08 22:30:57 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 08 22:30:57 rivo volumio[5260]: info: ControllerMpd::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::servicePushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":1167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Violin Concerto in D major, Op. 35: I. Allegro moderato","artist":"五嶋みどり","album":"Violin Concerto, Op. 35 / Violin Concerto No. 1, Op. 99","uri":"USB/RIVO/Violin Concerto, Op. 35 _ Violin Concerto N (US - Sony Classical SK 68338;68338 - 1998-0)/01. Violin Concerto in D major, Op. 35_ I. Allegro moderato.wav","trackType":"wav"} Apr 08 22:30:57 rivo volumio[5260]: verbose: CURRENT POSITION 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::syncState stateService play Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::syncState currentStatus stop Apr 08 22:30:57 rivo volumio[5260]: info: ControllerMpd::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::servicePushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":1167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Violin Concerto in D major, Op. 35: I. Allegro moderato","artist":"五嶋みどり","album":"Violin Concerto, Op. 35 / Violin Concerto No. 1, Op. 99","uri":"USB/RIVO/Violin Concerto, Op. 35 _ Violin Concerto N (US - Sony Classical SK 68338;68338 - 1998-0)/01. Violin Concerto in D major, Op. 35_ I. Allegro moderato.wav","trackType":"wav"} Apr 08 22:30:57 rivo volumio[5260]: verbose: CURRENT POSITION 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::syncState stateService play Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::syncState currentStatus play Apr 08 22:30:57 rivo volumio[5260]: info: Received an update from plugin. extracting info from payload Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:57 rivo volumio[5260]: info: ControllerMpd::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::servicePushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":1167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Violin Concerto in D major, Op. 35: I. Allegro moderato","artist":"五嶋みどり","album":"Violin Concerto, Op. 35 / Violin Concerto No. 1, Op. 99","uri":"USB/RIVO/Violin Concerto, Op. 35 _ Violin Concerto N (US - Sony Classical SK 68338;68338 - 1998-0)/01. Violin Concerto in D major, Op. 35_ I. Allegro moderato.wav","trackType":"wav"} Apr 08 22:30:57 rivo volumio[5260]: verbose: CURRENT POSITION 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::syncState stateService play Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::syncState currentStatus play Apr 08 22:30:57 rivo volumio[5260]: info: Received an update from plugin. extracting info from payload Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:57 rivo volumio[5260]: info: ControllerMpd::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::servicePushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":1167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Violin Concerto in D major, Op. 35: I. Allegro moderato","artist":"五嶋みどり","album":"Violin Concerto, Op. 35 / Violin Concerto No. 1, Op. 99","uri":"USB/RIVO/Violin Concerto, Op. 35 _ Violin Concerto N (US - Sony Classical SK 68338;68338 - 1998-0)/01. Violin Concerto in D major, Op. 35_ I. Allegro moderato.wav","trackType":"wav"} Apr 08 22:30:57 rivo volumio[5260]: verbose: CURRENT POSITION 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::syncState stateService play Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::syncState currentStatus play Apr 08 22:30:57 rivo volumio[5260]: info: Received an update from plugin. extracting info from payload Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:57 rivo volumio[5260]: info: ------------------------------ 123ms Apr 08 22:30:57 rivo volumio[5260]: info: ------------------------------ 129ms Apr 08 22:30:57 rivo volumio[5260]: info: ------------------------------ 125ms Apr 08 22:30:57 rivo volumio[5260]: info: ------------------------------ 124ms Apr 08 22:30:57 rivo volumio[5260]: info: sendMpdCommand playlistinfo took 107 milliseconds Apr 08 22:30:57 rivo volumio[5260]: info: sendMpdCommand playlistinfo took 107 milliseconds Apr 08 22:30:57 rivo volumio[5260]: verbose: ControllerMpd::parseTrackInfo Apr 08 22:30:57 rivo volumio[5260]: verbose: ControllerMpd::parseTrackInfo Apr 08 22:30:57 rivo volumio[5260]: info: ControllerMpd::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::servicePushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":1167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Violin Concerto in D major, Op. 35: I. Allegro moderato","artist":"五嶋みどり","album":"Violin Concerto, Op. 35 / Violin Concerto No. 1, Op. 99","uri":"USB/RIVO/Violin Concerto, Op. 35 _ Violin Concerto N (US - Sony Classical SK 68338;68338 - 1998-0)/01. Violin Concerto in D major, Op. 35_ I. Allegro moderato.wav","trackType":"wav"} Apr 08 22:30:57 rivo volumio[5260]: verbose: CURRENT POSITION 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::syncState stateService play Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::syncState currentStatus play Apr 08 22:30:57 rivo volumio[5260]: info: Received an update from plugin. extracting info from payload Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:57 rivo volumio[5260]: info: ControllerMpd::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::servicePushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":1167,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Violin Concerto in D major, Op. 35: I. Allegro moderato","artist":"五嶋みどり","album":"Violin Concerto, Op. 35 / Violin Concerto No. 1, Op. 99","uri":"USB/RIVO/Violin Concerto, Op. 35 _ Violin Concerto N (US - Sony Classical SK 68338;68338 - 1998-0)/01. Violin Concerto in D major, Op. 35_ I. Allegro moderato.wav","trackType":"wav"} Apr 08 22:30:57 rivo volumio[5260]: verbose: CURRENT POSITION 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::syncState stateService play Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::syncState currentStatus play Apr 08 22:30:57 rivo volumio[5260]: info: Received an update from plugin. extracting info from payload Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:57 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:30:57 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:30:57 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:30:57 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:30:57 rivo volumio[5260]: info: ------------------------------ 195ms Apr 08 22:30:57 rivo volumio[5260]: info: ------------------------------ 194ms Apr 08 22:30:57 rivo volumio[5260]: info: Signalling Playback active due to playback status change Apr 08 22:30:57 rivo volumio[5260]: info: MCU Signalled Playback Active Apr 08 22:30:57 rivo volumio[5260]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 08 22:30:58 rivo volumio[5260]: info: MyVolumio token set successfully Apr 08 22:30:58 rivo volumio[5260]: info: MYVOLUMIO: Adding device Apr 08 22:30:58 rivo volumio[5260]: info: MYVOLUMIO: Evaluating Server Apr 08 22:30:58 rivo volumio[5260]: info: MyVolumio status changed Apr 08 22:30:58 rivo volumio[5260]: info: Streaming services startup Apr 08 22:30:58 rivo volumio[5260]: info: Starting Streaming Daemon Apr 08 22:30:58 rivo volumio[5260]: info: Removing browser output: myVolumio user plan is not superstar Apr 08 22:30:58 rivo volumio[5260]: info: Removing audio output: Apr 08 22:30:58 rivo volumio[5260]: info: Stoppping Tunnel 1 Apr 08 22:30:58 rivo sudo[6447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 08 22:30:58 rivo sudo[6447]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:59 rivo sudo[6447]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:59 rivo sudo[6451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 08 22:30:59 rivo sudo[6451]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 08 22:30:59 rivo volumio[5260]: error: Cannot start Volumio Streaming Daemon Apr 08 22:30:59 rivo volumio[5260]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 08 22:30:59 rivo volumio[5260]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 08 22:30:59 rivo sudo[6451]: pam_unix(sudo:session): session closed for user root Apr 08 22:30:59 rivo volumio[5260]: info: Remote SSH Stopped Apr 08 22:30:59 rivo volumio[5260]: info: Setting Geolocation for MyVolumio to as1 Apr 08 22:30:59 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:30:59 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:30:59 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:31:00 rivo volumio[5260]: info: Updating MyVolumio device info Apr 08 22:31:00 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:31:00 rivo volumio[5260]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 08 22:31:00 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 08 22:31:00 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:31:00 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 08 22:31:00 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 08 22:31:00 rivo volumio-remote-updater[4748]: No test mode Apr 08 22:31:00 rivo volumio-remote-updater[4748]: No alpha test mode Apr 08 22:31:01 rivo volumio[5260]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 08 22:31:01 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 08 22:31:01 rivo volumio[5260]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 08 22:31:02 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 08 22:31:02 rivo volumio[5260]: info: CURURI: music-library Apr 08 22:31:02 rivo volumio[5260]: info: Preload queue cleared Apr 08 22:31:03 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 08 22:31:03 rivo volumio[5260]: info: CURURI: music-library/USB Apr 08 22:31:03 rivo volumio[5260]: info: Preload queue cleared Apr 08 22:31:04 rivo volumio[5260]: info: MYVOLUMIO: Adding device Apr 08 22:31:04 rivo volumio[5260]: info: MYVOLUMIO: Evaluating Server Apr 08 22:31:04 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 08 22:31:04 rivo volumio[5260]: info: CURURI: music-library/USB/RIVO Apr 08 22:31:04 rivo volumio[5260]: info: Preload queue cleared Apr 08 22:31:05 rivo volumio[5260]: info: Setting Geolocation for MyVolumio to as1 Apr 08 22:31:05 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:31:05 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:31:05 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:31:07 rivo volumio[5260]: info: Updating MyVolumio device info Apr 08 22:31:07 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:31:07 rivo volumio[5260]: error: MyVolumio Plugin failed to start in a timely fashion Apr 08 22:31:07 rivo volumio[5260]: info: BOOT COMPLETED Apr 08 22:31:07 rivo volumio[5260]: [Metrics] CommandRouter: 53s 814.29ms Apr 08 22:31:07 rivo volumio[5260]: info: CoreCommandRouter::volumiosetStartupVolume Apr 08 22:31:07 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 08 22:31:07 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 08 22:31:07 rivo volumio[5260]: info: CoreCommandRouter::Close All Modals sent Apr 08 22:31:07 rivo volumio[5260]: info: CoreCommandRouter::Close All Modals sent Apr 08 22:31:07 rivo volumio[5260]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 08 22:31:07 rivo volumio[5260]: Upnp client error: Error: This socket has been ended by the other party Apr 08 22:31:07 rivo volumio[5260]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Apr 08 22:31:07 rivo volumio[5260]: xcb_connection_has_error() returned true Apr 08 22:31:07 rivo volumio[5260]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Apr 08 22:31:07 rivo volumio[5260]: aplay: main:828: audio open error: Device or resource busy Apr 08 22:31:10 rivo kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=0x00 Apr 08 22:31:10 rivo kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 08 48 d7 0e 00 00 01 00 Apr 08 22:31:10 rivo kernel: blk_update_request: I/O error, dev sda, sector 138991374 Apr 08 22:31:10 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 08 22:31:10 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 08 22:31:10 rivo volumio[5260]: xcb_connection_has_error() returned true Apr 08 22:31:10 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 08 22:31:10 rivo volumio[5260]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 08 22:31:13 rivo volumio[5260]: Upnp client error: Error: This socket has been ended by the other party Apr 08 22:31:13 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 08 22:31:13 rivo volumio[5260]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 47 Apr 08 22:31:13 rivo volumio[5260]: info: CoreCommandRouter::volumioGetState Apr 08 22:31:13 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:31:15 rivo volumio[5260]: info: Preload queue cleared Apr 08 22:31:15 rivo volumio[5260]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 08 22:31:15 rivo volumio[5260]: info: CoreStateMachine::ClearQueue Apr 08 22:31:15 rivo volumio[5260]: info: CoreStateMachine::stop Apr 08 22:31:15 rivo volumio[5260]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 08 22:31:15 rivo volumio[5260]: info: CoreStateMachine::stPlaybackTimer Apr 08 22:31:15 rivo volumio[5260]: info: CoreStateMachine::updateTrackBlock Apr 08 22:31:15 rivo volumio[5260]: info: CorePlayQueue::getTrackBlock Apr 08 22:31:15 rivo volumio[5260]: info: CoreStateMachine::pushState Apr 08 22:31:15 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:31:15 rivo volumio[5260]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 08 22:31:15 rivo volumio[5260]: info: CoreCommandRouter::volumioPushState Apr 08 22:31:15 rivo volumio[5260]: info: MRS: Pushing multiroomSync output update for this device Apr 08 22:31:15 rivo volumio[5260]: info: MRS: Pushing multiroomSync output Apr 08 22:31:15 rivo volumio[5260]: info: CoreStateMachine::serviceStop Apr 08 22:31:15 rivo volumio[5260]: info: CorePlayQueue::getTrack 0 Apr 08 22:31:15 rivo volumio[5260]: info: CoreCommandRouter::serviceStop Apr 08 22:31:15 rivo volumio[5260]: info: ControllerMpd::stop Apr 08 22:31:15 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand stop Apr 08 22:31:15 rivo volumio[5260]: info: CorePlayQueue::clearPlayQueue Apr 08 22:31:15 rivo volumio[5260]: info: CorePlayQueue::saveQueue Apr 08 22:31:15 rivo volumio[5260]: info: CoreCommandRouter::volumioPushQueue Apr 08 22:31:15 rivo volumio[5260]: info: CoreStateMachine::addQueueItems Apr 08 22:31:15 rivo volumio[5260]: info: CorePlayQueue::addQueueItems Apr 08 22:31:15 rivo volumio[5260]: info: Preload queue cleared Apr 08 22:31:15 rivo volumio[5260]: info: Adding Item to queue: music-library/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_ Apr 08 22:31:15 rivo volumio[5260]: info: Exploding uri music-library/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_ in service mpd Apr 08 22:31:15 rivo volumio[5260]: info: sendMpdCommand stop took 79 milliseconds Apr 08 22:31:15 rivo volumio[5260]: info: Apr 08 22:31:15 rivo volumio[5260]: ---------------------------- MPD announces state update: player Apr 08 22:31:15 rivo volumio[5260]: info: ControllerMpd::getState Apr 08 22:31:15 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand status Apr 08 22:31:15 rivo volumio[5260]: info: Apr 08 22:31:15 rivo volumio[5260]: ---------------------------- MPD announces state update: player Apr 08 22:31:15 rivo volumio[5260]: info: ControllerMpd::getState Apr 08 22:31:15 rivo volumio[5260]: verbose: ControllerMpd::sendMpdCommand status Apr 08 22:31:15 rivo volumio[5260]: info: Apr 08 22:31:15 rivo volumio[5260]: ---------------------------- MPD announces state update: player Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Ludwig%20van%20Beethoven/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F01.%20Sonata%20in%20C-sharp%20minor%2C%20op.%2027%20no.%202%20_Moonlight__%20Adagio%20sostenuto.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/01. Sonata in C-sharp minor, op. 27 no. 2 _Moonlight__ Adagio sostenuto.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Ludwig%20van%20Beethoven/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F02.%20Sonata%20in%20C-sharp%20minor%2C%20op.%2027%20no.%202%20_Moonlight__%20Allegretto.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/02. Sonata in C-sharp minor, op. 27 no. 2 _Moonlight__ Allegretto.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Ludwig%20van%20Beethoven/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F03.%20Sonata%20in%20C-sharp%20minor%2C%20op.%2027%20no.%202%20_Moonlight__%20Presto%20agitato.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/03. Sonata in C-sharp minor, op. 27 no. 2 _Moonlight__ Presto agitato.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Cesar%20Franck/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F04.%20Prelude%2C%20Choral%20et%20Fugue_%20Prelude.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/04. Prelude, Choral et Fugue_ Prelude.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Cesar%20Franck/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F05.%20Prelude%2C%20Choral%20et%20Fugue_%20Choral.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/05. Prelude, Choral et Fugue_ Choral.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Cesar%20Franck/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F06.%20Prelude%2C%20Choral%20et%20Fugue_%20Fugue.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/06. Prelude, Choral et Fugue_ Fugue.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F07.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20I_%20Theme.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/07. Variations on a Theme by Paganini, op. 35, Book I_ Theme.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F08.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20I_%20Variation%20I.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/08. Variations on a Theme by Paganini, op. 35, Book I_ Variation I.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F09.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20I_%20Variation%20II.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/09. Variations on a Theme by Paganini, op. 35, Book I_ Variation II.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F10.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20I_%20Variation%20III.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/10. Variations on a Theme by Paganini, op. 35, Book I_ Variation III.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F11.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20I_%20Variation%20IV.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/11. Variations on a Theme by Paganini, op. 35, Book I_ Variation IV.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F12.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20I_%20Variation%20V.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/12. Variations on a Theme by Paganini, op. 35, Book I_ Variation V.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F13.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20I_%20Variation%20VI.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/13. Variations on a Theme by Paganini, op. 35, Book I_ Variation VI.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F14.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20I_%20Variation%20VII.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/14. Variations on a Theme by Paganini, op. 35, Book I_ Variation VII.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F15.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20I_%20Variation%20VIII.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/15. Variations on a Theme by Paganini, op. 35, Book I_ Variation VIII.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F16.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20I_%20Variation%20IX.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/16. Variations on a Theme by Paganini, op. 35, Book I_ Variation IX.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F17.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20I_%20Variation%20X.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/17. Variations on a Theme by Paganini, op. 35, Book I_ Variation X.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F18.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20I_%20Variation%20XI.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/18. Variations on a Theme by Paganini, op. 35, Book I_ Variation XI.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F19.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20I_%20Variation%20XII.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/19. Variations on a Theme by Paganini, op. 35, Book I_ Variation XII.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F20.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20I_%20Variation%20XIII.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/20. Variations on a Theme by Paganini, op. 35, Book I_ Variation XIII.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F21.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20I_%20Variation%20XIV.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/21. Variations on a Theme by Paganini, op. 35, Book I_ Variation XIV.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F22.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20II_%20Variation%20I.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/22. Variations on a Theme by Paganini, op. 35, Book II_ Variation I.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F23.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20II_%20Variation%20II.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/23. Variations on a Theme by Paganini, op. 35, Book II_ Variation II.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F24.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20II_%20Variation%20III.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/24. Variations on a Theme by Paganini, op. 35, Book II_ Variation III.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F25.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20II_%20Variation%20IV.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/25. Variations on a Theme by Paganini, op. 35, Book II_ Variation IV.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F26.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20II_%20Variation%20V.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/26. Variations on a Theme by Paganini, op. 35, Book II_ Variation V.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F27.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20II_%20Variation%20VI.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/27. Variations on a Theme by Paganini, op. 35, Book II_ Variation VI.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F28.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20II_%20Variation%20VII.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/28. Variations on a Theme by Paganini, op. 35, Book II_ Variation VII.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F29.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20II_%20Variation%20VIII.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/29. Variations on a Theme by Paganini, op. 35, Book II_ Variation VIII.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F30.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20II_%20Variation%20IX.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/30. Variations on a Theme by Paganini, op. 35, Book II_ Variation IX.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F31.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20II_%20Variation%20X.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/31. Variations on a Theme by Paganini, op. 35, Book II_ Variation X.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F32.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20II_%20Variation%20XI.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/32. Variations on a Theme by Paganini, op. 35, Book II_ Variation XI.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F33.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20II_%20Variation%20XII.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/33. Variations on a Theme by Paganini, op. 35, Book II_ Variation XII.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F34.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20II_%20Variation%20XIII.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/34. Variations on a Theme by Paganini, op. 35, Book II_ Variation XIII.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Johannes%20Brahms/%E3%83%99%E3%83%BC%E3%83%88%E3%83%BC%E3%83%B4%E3%82%A7%E3%83%B3%3A%E6%9C%88%E5%85%89%E3%82%BD%E3%83%8A%E3%82%BF%26%E3%83%96%E3%83%A9%E3%83%BC%E3%83%A0%E3%82%B9%3A%E3%83%91%E3%82%AC%E3%83%8B%E3%83%BC%E3%83%8B%E5%A4%89%E5%A5%8F%E6%9B%B2/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2F35.%20Variations%20on%20a%20Theme%20by%20Paganini%2C%20op.%2035%2C%20Book%20II_%20Variation%20XIV.wav&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/35. Variations on a Theme by Paganini, op. 35, Book II_ Variation XIV.wav Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Ludwig%20van%20Beethoven/Beethoven%3A%20Moonlight%20Sonata%20%2F%20Franck%3A%20Prelude%2C%20Choral%20et%20Fugue%20%2F%20Brahms%3A%20Paganini%20Variations/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2FBeethoven%2C%20Franck%2C%20Brahms%3B%20Evgeny%20Kissin%20-%20Beethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%20.cue&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/Beethoven, Franck, Brahms; Evgeny Kissin - Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_ .cue Apr 08 22:31:15 rivo volumio[5260]: info: ALBUMART /albumart?cacheid=471&web=Ludwig%20van%20Beethoven/Beethoven%3A%20Moonlight%20Sonata%20%2F%20Franck%3A%20Prelude%2C%20Choral%20et%20Fugue%20%2F%20Brahms%3A%20Paganini%20Variations/extralarge&path=%2Fmnt%2FUSB%2FRIVO%2FBeethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%2FBeethoven%2C%20Franck%2C%20Brahms%3B%20Evgeny%20Kissin%20-%20Beethoven_%20Moonlight%20Sonata%20_%20Franck_%20Prelude%2C%20Choral%20et%20Fugue%20_%20Brahms_%20.cue&metadata=false Apr 08 22:31:15 rivo volumio[5260]: info: URI /mnt/USB/RIVO/Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_/Beethoven, Franck, Brahms; Evgeny Kissin - Beethoven_ Moonlight Sonata _ Franck_ Prelude, Choral et Fugue _ Brahms_ .cue Apr 08 22:31:15 rivo volumio[5260]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 22:31:15 rivo volumio[5260]: Error: Unable to resolve or reject the same promise twice Apr 08 22:31:15 rivo volumio[5260]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 08 22:31:15 rivo volumio[5260]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 08 22:31:15 rivo volumio[5260]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 08 22:31:15 rivo volumio[5260]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 08 22:31:15 rivo volumio[5260]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 08 22:31:15 rivo volumio[5260]: at Socket.emit (events.js:400:28) Apr 08 22:31:15 rivo volumio[5260]: at addChunk (internal/streams/readable.js:293:12) Apr 08 22:31:15 rivo volumio[5260]: at readableAddChunk (internal/streams/readable.js:263:11) Apr 08 22:31:15 rivo volumio[5260]: at Socket.Readable.push (internal/streams/readable.js:206:10) Apr 08 22:31:15 rivo volumio[5260]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 08 22:31:15 rivo volumio[5260]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 08 22:31:16 rivo sudo[6510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-08 22:30 Apr 08 22:31:16 rivo sudo[6510]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 11:41:55 AM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="b0437c7088d6645a661a49f6d9fa596a"