-- 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"