-- Logs begin at Thu 2019-02-14 02:11:58 PST, end at Fri 2024-05-03 04:48:24 PDT. -- May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: 'clock-freq-min-max' property was deprecated. May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: No vmmc regulator found May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: No vqmmc regulator found May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: GPIO lookup for consumer wp May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: using device tree for GPIO lookup May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/dwmmc@ff0d0000[0]' May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/dwmmc@ff0d0000[0]' May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: using lookup tables for GPIO lookup May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: lookup for GPIO wp failed May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: parsed 'maskrom_gpio' property of node '/dwmmc@ff0f0000[0]' - status (0) May 03 04:47:42 primo kernel: dwmmc_rockchip ff0f0000.dwmmc: set maskrom gpio to enable emmc May 03 04:47:42 primo kernel: dwmmc_rockchip ff0f0000.dwmmc: IDMAC supports 32-bit address mode. May 03 04:47:42 primo kernel: dwmmc_rockchip ff0f0000.dwmmc: Using internal DMA controller. May 03 04:47:42 primo kernel: dwmmc_rockchip ff0f0000.dwmmc: Version ID is 270a May 03 04:47:42 primo kernel: dwmmc_rockchip ff0f0000.dwmmc: DW MMC controller at irq 31,32 bit host data width,256 deep fifo May 03 04:47:42 primo kernel: scsi 0:0:0:0: Direct-Access G-TECH ArmorATD 2005 PQ: 0 ANSI: 6 May 03 04:47:42 primo kernel: sd 0:0:0:0: [sda] Very big device. Trying to use READ CAPACITY(16). May 03 04:47:42 primo kernel: sd 0:0:0:0: [sda] 9767475200 512-byte logical blocks: (5.00 TB/4.55 TiB) May 03 04:47:42 primo kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks May 03 04:47:42 primo kernel: sd 0:0:0:0: [sda] Write Protect is off May 03 04:47:42 primo kernel: sd 0:0:0:0: [sda] Mode Sense: 3f 00 10 00 May 03 04:47:42 primo kernel: sd 0:0:0:0: [sda] No Caching mode page found May 03 04:47:42 primo kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through May 03 04:47:42 primo kernel: scsi 0:0:0:1: Enclosure G-TECH SES Device 2005 PQ: 0 ANSI: 6 May 03 04:47:42 primo kernel: sd 0:0:0:0: [sda] Very big device. Trying to use READ CAPACITY(16). May 03 04:47:42 primo kernel: dwmmc_rockchip ff0f0000.dwmmc: 'clock-freq-min-max' property was deprecated. May 03 04:47:42 primo kernel: dwmmc_rockchip ff0f0000.dwmmc: No vmmc regulator found May 03 04:47:42 primo kernel: dwmmc_rockchip ff0f0000.dwmmc: No vqmmc regulator found May 03 04:47:42 primo kernel: dwmmc_rockchip ff0f0000.dwmmc: GPIO lookup for consumer wp May 03 04:47:42 primo kernel: dwmmc_rockchip ff0f0000.dwmmc: using device tree for GPIO lookup May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/dwmmc@ff0f0000[0]' May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/dwmmc@ff0f0000[0]' May 03 04:47:42 primo kernel: dwmmc_rockchip ff0f0000.dwmmc: using lookup tables for GPIO lookup May 03 04:47:42 primo kernel: dwmmc_rockchip ff0f0000.dwmmc: lookup for GPIO wp failed May 03 04:47:42 primo kernel: mmc_host mmc1: Bus speed (slot 0) = 400000Hz (slot req 400000Hz, actual 400000HZ div = 0) May 03 04:47:42 primo kernel: dwmmc_rockchip ff0f0000.dwmmc: 1 slots initialized May 03 04:47:42 primo kernel: sdhci-pltfm: SDHCI platform and OF driver helper May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: parsed 'gpios' property of node '/gpio-leds/pwr-led[0]' - status (0) May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: parsed 'gpios' property of node '/gpio-leds/act-led[0]' - status (0) May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: parsed 'gpios' property of node '/gpio-leds/led1-led[0]' - status (0) May 03 04:47:42 primo kernel: ledtrig-cpu: registered to indicate activity on CPUs May 03 04:47:42 primo kernel: hidraw: raw HID events driver (C) Jiri Kosina May 03 04:47:42 primo kernel: input: Generic USB Audio as /devices/platform/ff500000.usb/usb3/3-1/3-1:1.255/0003:0BDA:481A.0001/input/input0 May 03 04:47:42 primo kernel: sda: sda1 sda2 May 03 04:47:42 primo kernel: mmc1: MAN_BKOPS_EN bit is not set May 03 04:47:42 primo kernel: mmc_host mmc1: Bus speed (slot 0) = 148500000Hz (slot req 150000000Hz, actual 148500000HZ div = 0) May 03 04:47:42 primo kernel: sd 0:0:0:0: [sda] Very big device. Trying to use READ CAPACITY(16). May 03 04:47:42 primo kernel: sd 0:0:0:0: [sda] Attached SCSI disk May 03 04:47:42 primo kernel: hid-generic 0003:0BDA:481A.0001: input,hiddev0,hidraw0: USB HID v1.11 Device [Generic USB Audio] on usb-ff500000.usb-1/input255 May 03 04:47:42 primo kernel: usbcore: registered new interface driver usbhid May 03 04:47:42 primo kernel: usbhid: USB HID core driver May 03 04:47:42 primo kernel: dtbocfg_module_init May 03 04:47:42 primo kernel: dtbocfg_module_init: OK May 03 04:47:42 primo kernel: ashmem: initialized May 03 04:47:42 primo kernel: find panel: asus,tc358762 May 03 04:47:42 primo kernel: ff960000.dsi.0 supply power not found, using dummy regulator May 03 04:47:42 primo kernel: bridge-tc358762-dsi ff960000.dsi.0: GPIO lookup for consumer enable May 03 04:47:42 primo kernel: bridge-tc358762-dsi ff960000.dsi.0: using device tree for GPIO lookup May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'enable-gpios' property of node '/dsi@ff960000/mipi-panel[0]' May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'enable-gpio' property of node '/dsi@ff960000/mipi-panel[0]' May 03 04:47:42 primo kernel: bridge-tc358762-dsi ff960000.dsi.0: using lookup tables for GPIO lookup May 03 04:47:42 primo kernel: bridge-tc358762-dsi ff960000.dsi.0: lookup for GPIO enable failed May 03 04:47:42 primo kernel: rockchip-vop ff940000.vop: invalid resource May 03 04:47:42 primo kernel: rockchip-vop ff940000.vop: failed to get vop cabc lut registers May 03 04:47:42 primo kernel: rockchip-drm display-subsystem: bound ff940000.vop (ops 0xc0c75e64) May 03 04:47:42 primo kernel: rockchip-vop ff930000.vop: invalid resource May 03 04:47:42 primo kernel: rockchip-vop ff930000.vop: failed to get vop cabc lut registers May 03 04:47:42 primo kernel: rockchip-drm display-subsystem: bound ff930000.vop (ops 0xc0c75e64) May 03 04:47:42 primo kernel: panel doesn't be connected May 03 04:47:42 primo kernel: rockchip-drm display-subsystem: bound ff960000.dsi (ops 0xc0ccb26c) May 03 04:47:42 primo kernel: dwhdmi-rockchip ff980000.hdmi: hdmi_i2s_audio_disable=true May 03 04:47:42 primo kernel: i2c i2c-6: of_i2c: modalias failure on /hdmi@ff980000/ports May 03 04:47:42 primo kernel: dwhdmi-rockchip ff980000.hdmi: registered DesignWare HDMI I2C bus driver May 03 04:47:42 primo kernel: dwhdmi-rockchip ff980000.hdmi: Detected HDMI TX controller v2.00a with HDCP (DWC MHL PHY) May 03 04:47:42 primo kernel: dw-hdmi-i2s-audio dw-hdmi-i2s-audio.3.auto: disable hdmi i2s audio May 03 04:47:42 primo kernel: Registered IR keymap rc-cec May 03 04:47:42 primo kernel: input: RC for dw_hdmi as /devices/platform/ff980000.hdmi/rc/rc0/input1 May 03 04:47:42 primo kernel: rc0: RC for dw_hdmi as /devices/platform/ff980000.hdmi/rc/rc0 May 03 04:47:42 primo kernel: rockchip-drm display-subsystem: bound ff980000.hdmi (ops 0xc0c72c2c) May 03 04:47:42 primo kernel: [drm:drm_vblank_init] Supports vblank timestamp caching Rev 2 (21.10.2013). May 03 04:47:42 primo kernel: [drm:drm_vblank_init] No driver support for vblank timestamp query. May 03 04:47:42 primo kernel: rockchip-drm display-subsystem: failed to parse display resources May 03 04:47:42 primo kernel: rockchip-drm display-subsystem: No connectors reported connected with modes May 03 04:47:42 primo kernel: [drm:drm_fb_helper_single_fb_probe] Cannot find any crtc or sizes - going 1024x768 May 03 04:47:42 primo kernel: Console: switching to colour frame buffer device 128x48 May 03 04:47:42 primo kernel: rockchip-drm display-subsystem: fb0: frame buffer device May 03 04:47:42 primo kernel: [board_info] create Board_info_proc_file sucessed! May 03 04:47:42 primo kernel: project_id_2:0x0, project_id_1:0x0, project_id_0:0x0 May 03 04:47:42 primo kernel: ram_id_2:0x0, ram_id_1:0x1, ram_id_0:0x0 May 03 04:47:42 primo kernel: pcb_id_2:0x0, pcb_id_1:0x1, pcb_id_0:0x0 May 03 04:47:42 primo kernel: dwmmc_rockchip ff0f0000.dwmmc: Successfully tuned phase to 205 May 03 04:47:42 primo kernel: mmc1: new HS200 MMC card at address 0001 May 03 04:47:42 primo kernel: mmcblk1: mmc1:0001 HAG4a2 14.7 GiB May 03 04:47:42 primo kernel: mmcblk1boot0: mmc1:0001 HAG4a2 partition 1 4.00 MiB May 03 04:47:42 primo kernel: snd-soc-es90x8q2m-dac sound-ext-card: ASoC: CODEC DAI es90x8q2m-dac-dai not registered May 03 04:47:42 primo kernel: snd-soc-es90x8q2m-dac sound-ext-card: snd_soc_register_card() failed: -517 May 03 04:47:42 primo kernel: u32 classifier May 03 04:47:42 primo kernel: Netfilter messages via NETLINK v0.30. May 03 04:47:42 primo kernel: nfnl_acct: registering with nfnetlink. May 03 04:47:42 primo kernel: nf_conntrack version 0.5.0 (16384 buckets, 65536 max) May 03 04:47:42 primo kernel: ctnetlink v0.93: registering with nfnetlink. May 03 04:47:42 primo kernel: xt_time: kernel timezone is -0000 May 03 04:47:42 primo kernel: ip_set: protocol 6 May 03 04:47:42 primo kernel: IPVS: Registered protocols (TCP, UDP, SCTP, AH, ESP) May 03 04:47:42 primo kernel: IPVS: Connection hash table configured (size=4096, memory=32Kbytes) May 03 04:47:42 primo kernel: IPVS: Creating netns size=1496 id=0 May 03 04:47:42 primo kernel: IPVS: ipvs loaded. May 03 04:47:42 primo kernel: IPVS: [rr] scheduler registered. May 03 04:47:42 primo kernel: IPVS: [wrr] scheduler registered. May 03 04:47:42 primo kernel: IPVS: [lc] scheduler registered. May 03 04:47:42 primo kernel: IPVS: [wlc] scheduler registered. May 03 04:47:42 primo kernel: IPVS: [lblc] scheduler registered. May 03 04:47:42 primo kernel: IPVS: [lblcr] scheduler registered. May 03 04:47:42 primo kernel: IPVS: [dh] scheduler registered. May 03 04:47:42 primo kernel: IPVS: [sh] scheduler registered. May 03 04:47:42 primo kernel: IPVS: [sed] scheduler registered. May 03 04:47:42 primo kernel: IPVS: [nq] scheduler registered. May 03 04:47:42 primo kernel: IPVS: ftp: loaded support on port[0] = 21 May 03 04:47:42 primo kernel: IPVS: [sip] pe registered. May 03 04:47:42 primo kernel: ip_tables: (C) 2000-2006 Netfilter Core Team May 03 04:47:42 primo kernel: ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully May 03 04:47:42 primo kernel: arp_tables: (C) 2002 David S. Miller May 03 04:47:42 primo kernel: Initializing XFRM netlink socket May 03 04:47:42 primo kernel: NET: Registered protocol family 10 May 03 04:47:42 primo kernel: ip6_tables: (C) 2000-2006 Netfilter Core Team May 03 04:47:42 primo kernel: sit: IPv6 over IPv4 tunneling driver May 03 04:47:42 primo kernel: NET: Registered protocol family 17 May 03 04:47:42 primo kernel: NET: Registered protocol family 15 May 03 04:47:42 primo kernel: bridge: automatic filtering via arp/ip/ip6tables has been deprecated. Update your scripts to load br_netfilter if you need this. May 03 04:47:42 primo kernel: Bridge firewalling registered May 03 04:47:42 primo kernel: Ebtables v2.0 registered May 03 04:47:42 primo kernel: Bluetooth: RFCOMM TTY layer initialized May 03 04:47:42 primo kernel: Bluetooth: RFCOMM socket layer initialized May 03 04:47:42 primo kernel: Bluetooth: RFCOMM ver 1.11 May 03 04:47:42 primo kernel: Bluetooth: HIDP (Human Interface Emulation) ver 1.2 May 03 04:47:42 primo kernel: Bluetooth: HIDP socket layer initialized May 03 04:47:42 primo kernel: 8021q: 802.1Q VLAN Support v1.8 May 03 04:47:42 primo kernel: lib80211: common routines for IEEE802.11 drivers May 03 04:47:42 primo kernel: lib80211_crypt: registered algorithm 'NULL' May 03 04:47:42 primo kernel: lib80211_crypt: registered algorithm 'WEP' May 03 04:47:42 primo kernel: lib80211_crypt: registered algorithm 'CCMP' May 03 04:47:42 primo kernel: lib80211_crypt: registered algorithm 'TKIP' May 03 04:47:42 primo kernel: [WLAN_RFKILL]: Enter rfkill_wlan_init May 03 04:47:42 primo kernel: [WLAN_RFKILL]: Enter rfkill_wlan_probe May 03 04:47:42 primo kernel: [WLAN_RFKILL]: wlan_platdata_parse_dt: wifi_chip_type = ap6212 May 03 04:47:42 primo kernel: [WLAN_RFKILL]: wlan_platdata_parse_dt: enable wifi power control. May 03 04:47:42 primo kernel: [WLAN_RFKILL]: wlan_platdata_parse_dt: wifi power controled by gpio. May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'WIFI,poweren_gpio' property of node '/wireless-wlan[0]' May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'WIFI,reset_gpio' property of node '/wireless-wlan[0]' May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: parsed 'WIFI,host_wake_irq' property of node '/wireless-wlan[0]' - status (0) May 03 04:47:42 primo kernel: [WLAN_RFKILL]: wlan_platdata_parse_dt: get property: WIFI,host_wake_irq = 150, flags = 0. May 03 04:47:42 primo kernel: [WLAN_RFKILL]: rfkill_wlan_probe: init gpio May 03 04:47:42 primo kernel: [WLAN_RFKILL]: Exit rfkill_wlan_probe May 03 04:47:42 primo kernel: [BT_RFKILL]: Enter rfkill_rk_init May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: parsed 'uart_rts_gpios' property of node '/wireless-bluetooth[0]' - status (0) May 03 04:47:42 primo kernel: [BT_RFKILL]: bluetooth_platdata_parse_dt: get property: uart_rts_gpios = 139. May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'BT,power_gpio' property of node '/wireless-bluetooth[0]' May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: parsed 'BT,reset_gpio' property of node '/wireless-bluetooth[0]' - status (0) May 03 04:47:42 primo kernel: [BT_RFKILL]: bluetooth_platdata_parse_dt: get property: BT,reset_gpio = 149. May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: parsed 'BT,wake_gpio' property of node '/wireless-bluetooth[0]' - status (0) May 03 04:47:42 primo kernel: [BT_RFKILL]: bluetooth_platdata_parse_dt: get property: BT,wake_gpio = 146. May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: parsed 'BT,wake_host_irq' property of node '/wireless-bluetooth[0]' - status (0) May 03 04:47:42 primo kernel: [BT_RFKILL]: bluetooth_platdata_parse_dt: get property: BT,wake_host_irq = 151. May 03 04:47:42 primo kernel: [BT_RFKILL]: bluetooth_platdata_parse_dt: clk_get failed!!!. May 03 04:47:42 primo kernel: [BT_RFKILL]: Request irq for bt wakeup host May 03 04:47:42 primo kernel: [BT_RFKILL]: BT_WAKE_HOST IRQ fired May 03 04:47:42 primo kernel: [BT_RFKILL]: ** disable irq May 03 04:47:42 primo kernel: [BT_RFKILL]: bt_default device registered. May 03 04:47:42 primo kernel: Key type dns_resolver registered May 03 04:47:42 primo kernel: cif_isp10_v4l2_drv_probe: probing... May 03 04:47:42 primo kernel: cif_isp10_pltfrm_dev_init(1221) ERR: could not get default pinstate May 03 04:47:42 primo kernel: cif_isp10_pltfrm_dev_init WARN: could not get pins_sleep pinstate May 03 04:47:42 primo kernel: cif_isp10_pltfrm_dev_init WARN: could not get pins_inactive pinstate May 03 04:47:42 primo kernel: rk_iommu ff914000.iommu: Unbalanced pm_runtime_enable! May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: parsed 'rockchip,pd-gpio' property of node '/i2c@ff660000/camera-module@36[0]' - status (0) May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'rockchip,pwr-gpio' property of node '/i2c@ff660000/camera-module@36[0]' May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'rockchip,flash-gpio' property of node '/i2c@ff660000/camera-module@36[0]' May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'rockchip,torch-gpio' property of node '/i2c@ff660000/camera-module@36[0]' May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'rockchip,rst-gpio' property of node '/i2c@ff660000/camera-module@36[0]' May 03 04:47:42 primo kernel: ov7750.ov_camera_module_write_config(182) ERR: no active sensor configuration May 03 04:47:42 primo kernel: ov7750.ov_camera_module_write_config(233) ERR: failed with error -14 May 03 04:47:42 primo kernel: ov7750.pltfrm_camera_module_read_reg(996) ERR: i2c read from offset 0x0000300a failed with error -6 May 03 04:47:42 primo kernel: ov7750.pltfrm_camera_module_read_reg(996) ERR: i2c read from offset 0x0000300b failed with error -6 May 03 04:47:42 primo kernel: ov7750.ov7750_check_camera_id(571) ERR: register read failed, camera module powered off? May 03 04:47:42 primo kernel: ov7750.ov7750_check_camera_id(589) ERR: failed with error (-6) May 03 04:47:42 primo kernel: ov7750.ov_camera_module_attach(256) ERR: failed with error -6 May 03 04:47:42 primo kernel: cif_isp10_img_src_v4l2_i2c_subdev_to_img_src(59) ERR: failed with error -6 May 03 04:47:42 primo kernel: cif_isp10_img_src_to_img_src(70) ERR: to_img_src failed! May 03 04:47:42 primo kernel: cif_isp10_img_src_to_img_src(78) ERR: failed with error -14 May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: parsed 'rockchip,pd-gpio' property of node '/i2c@ff660000/camera-module@10[0]' - status (0) May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'rockchip,pwr-gpio' property of node '/i2c@ff660000/camera-module@10[0]' May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'rockchip,flash-gpio' property of node '/i2c@ff660000/camera-module@10[0]' May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'rockchip,torch-gpio' property of node '/i2c@ff660000/camera-module@10[0]' May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'rockchip,rst-gpio' property of node '/i2c@ff660000/camera-module@10[0]' May 03 04:47:42 primo kernel: imx219.pltfrm_camera_module_read_reg(996) ERR: i2c read from offset 0x00000000 failed with error -6 May 03 04:47:42 primo kernel: imx219.pltfrm_camera_module_read_reg(996) ERR: i2c read from offset 0x00000001 failed with error -6 May 03 04:47:42 primo kernel: imx219.imx219_check_camera_id(759) ERR: register read failed, camera module powered off? May 03 04:47:42 primo kernel: imx219.imx219_check_camera_id(776) ERR: failed with error (-6) May 03 04:47:42 primo kernel: imx219.imx_camera_module_attach(236) ERR: failed with error -6 May 03 04:47:42 primo kernel: cif_isp10_img_src_v4l2_i2c_subdev_to_img_src(59) ERR: failed with error -6 May 03 04:47:42 primo kernel: cif_isp10_img_src_to_img_src(70) ERR: to_img_src failed! May 03 04:47:42 primo kernel: cif_isp10_img_src_to_img_src(78) ERR: failed with error -14 May 03 04:47:42 primo kernel: cif_isp10_img_srcs_init(1097) ERR: failed with error -14 May 03 04:47:42 primo kernel: cif_isp10_create(5626) ERR: cif_isp10_img_srcs_init failed May 03 04:47:42 primo kernel: cif_isp10_create(5662) ERR: failed with error -14 May 03 04:47:42 primo kernel: ThumbEE CPU extension supported. May 03 04:47:42 primo kernel: Registering SWP/SWPB emulation handler May 03 04:47:42 primo kernel: registered taskstats version 1 May 03 04:47:42 primo kernel: Loading compiled-in X.509 certificates May 03 04:47:42 primo kernel: W : [File] : drivers/gpu/arm/midgard_for_linux/platform/rk/mali_kbase_config_rk.c; [Line] : 107; [Func] : kbase_platform_rk_init(); power-off-delay-ms not available. May 03 04:47:42 primo kernel: mali ffa30000.gpu: GPU identified as 0x0750 r0p0 status 1 May 03 04:47:42 primo kernel: I : [File] : drivers/gpu/arm/midgard_for_linux/backend/gpu/mali_kbase_devfreq.c; [Line] : 371; [Func] : kbase_devfreq_init(); success initing power_model_simple. May 03 04:47:42 primo kernel: mali ffa30000.gpu: Probed as mali0 May 03 04:47:42 primo kernel: rk_gmac-dwmac ff290000.ethernet: clock input or output? (input). May 03 04:47:42 primo kernel: rk_gmac-dwmac ff290000.ethernet: TX delay(0x30). May 03 04:47:42 primo kernel: rk_gmac-dwmac ff290000.ethernet: RX delay(0x10). May 03 04:47:42 primo kernel: rk_gmac-dwmac ff290000.ethernet: clock input from PHY May 03 04:47:42 primo kernel: rk_gmac-dwmac ff290000.ethernet: init for RGMII May 03 04:47:42 primo kernel: stmmac - user ID: 0x10, Synopsys ID: 0x35 May 03 04:47:42 primo kernel: Ring mode enabled May 03 04:47:42 primo kernel: DMA HW capability register supported May 03 04:47:42 primo kernel: Normal descriptors May 03 04:47:42 primo kernel: RX Checksum Offload Engine supported (type 2) May 03 04:47:42 primo kernel: TX Checksum insertion supported May 03 04:47:42 primo kernel: Wake-Up On Lan supported May 03 04:47:42 primo kernel: Enable RX Mitigation via HW Watchdog Timer May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: parsed 'snps,reset-gpio' property of node '/ethernet@ff290000[0]' - status (0) May 03 04:47:42 primo kernel: devfreq ffa30000.gpu: Couldn't update frequency transition information. May 03 04:47:42 primo kernel: libphy: stmmac: probed May 03 04:47:42 primo kernel: eth%d: PHY ID 001cc915 at 0 IRQ POLL (stmmac-0:00) active May 03 04:47:42 primo kernel: eth%d: PHY ID 001cc915 at 1 IRQ POLL (stmmac-0:01) May 03 04:47:42 primo kernel: rockchip-thermal ff280000.tsadc: Missing rockchip,grf property May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'maskrom_gpio' property of node '/dwmmc@ff0d0000[0]' May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: IDMAC supports 32-bit address mode. May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: Using internal DMA controller. May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: Version ID is 270a May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: DW MMC controller at irq 30,32 bit host data width,256 deep fifo May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: 'clock-freq-min-max' property was deprecated. May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: No vmmc regulator found May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: No vqmmc regulator found May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: GPIO lookup for consumer wp May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: using device tree for GPIO lookup May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'wp-gpios' property of node '/dwmmc@ff0d0000[0]' May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: can't parse 'wp-gpio' property of node '/dwmmc@ff0d0000[0]' May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: using lookup tables for GPIO lookup May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: lookup for GPIO wp failed May 03 04:47:42 primo kernel: platform sdio-pwrseq: GPIO lookup for consumer reset May 03 04:47:42 primo kernel: platform sdio-pwrseq: using device tree for GPIO lookup May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: parsed 'reset-gpios' property of node '/sdio-pwrseq[0]' - status (0) May 03 04:47:42 primo kernel: platform sdio-pwrseq: GPIO lookup for consumer reset May 03 04:47:42 primo kernel: platform sdio-pwrseq: using device tree for GPIO lookup May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: parsed 'reset-gpios' property of node '/sdio-pwrseq[1]' - status (0) May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: allocated mmc-pwrseq May 03 04:47:42 primo kernel: mmc_host mmc2: Bus speed (slot 0) = 400000Hz (slot req 400000Hz, actual 400000HZ div = 0) May 03 04:47:42 primo kernel: dwmmc_rockchip ff0d0000.dwmmc: 1 slots initialized May 03 04:47:42 primo kernel: snd-soc-es90x8q2m-dac sound-ext-card: es90x8q2m-dac-dai <-> ff890000.i2s mapping ok May 03 04:47:42 primo kernel: of_get_named_gpiod_flags: parsed 'gpios' property of node '/gpio-keys/button@0[0]' - status (0) May 03 04:47:42 primo kernel: gpio-5 (GPIO Key Power): gpiod_set_debounce: missing set() or set_debounce() operations May 03 04:47:42 primo kernel: input: gpio-keys as /devices/platform/gpio-keys/input/input2 May 03 04:47:42 primo kernel: rk808-rtc rk808-rtc: setting system clock to 2013-01-18 08:50:18 UTC (1358499018) May 03 04:47:42 primo kernel: device-tree: Duplicate name in testcase-data, renamed to "duplicate-name#1" May 03 04:47:42 primo kernel: ### dt-test ### start of unittest - you will see error messages May 03 04:47:42 primo kernel: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 May 03 04:47:42 primo kernel: /testcase-data/phandle-tests/consumer-a: could not get #phandle-cells-missing for /testcase-data/phandle-tests/provider1 May 03 04:47:42 primo kernel: /testcase-data/phandle-tests/consumer-a: could not find phandle May 03 04:47:42 primo kernel: /testcase-data/phandle-tests/consumer-a: could not find phandle May 03 04:47:42 primo kernel: /testcase-data/phandle-tests/consumer-a: arguments longer than property May 03 04:47:42 primo kernel: /testcase-data/phandle-tests/consumer-a: arguments longer than property May 03 04:47:42 primo kernel: irq: no irq domain found for /testcase-data/interrupts/intc0 ! May 03 04:47:42 primo kernel: overlay_is_topmost: #5 clashes #6 @/testcase-data/overlay-node/test-bus/test-unittest8 May 03 04:47:42 primo kernel: overlay_removal_is_ok: overlay #5 is not topmost May 03 04:47:42 primo kernel: of_overlay_destroy: removal check failed for overlay #5 May 03 04:47:42 primo kernel: ### dt-test ### end of unittest - 148 passed, 0 failed May 03 04:47:42 primo kernel: mmc_host mmc2: Bus speed (slot 0) = 49500000Hz (slot req 50000000Hz, actual 49500000HZ div = 0) May 03 04:47:42 primo kernel: mmc2: new high speed SDIO card at address 0001 May 03 04:47:42 primo kernel: vcc_sd: disabling May 03 04:47:42 primo kernel: vcc_flash: disabling May 03 04:47:42 primo kernel: vdd_logic: disabling May 03 04:47:42 primo kernel: ALSA device list: May 03 04:47:42 primo kernel: #0: ES90x8Q2M DAC May 03 04:47:42 primo kernel: ttyS3 - failed to request DMA May 03 04:47:42 primo kernel: mmcblk1boot1: mmc1:0001 HAG4a2 partition 2 4.00 MiB May 03 04:47:42 primo kernel: mmcblk1rpmb: mmc1:0001 HAG4a2 partition 3 4.00 MiB May 03 04:47:42 primo kernel: Freeing unused kernel memory: 1024K (c1000000 - c1100000) May 03 04:47:42 primo kernel: mmcblk1: p1 p2 p3 May 03 04:47:42 primo kernel: vendor storage:20160801 ret = 0 May 03 04:47:42 primo initramfs: Booting Volumio for Rockchip May 03 04:47:42 primo initramfs: This script mounts rootfs RO with an overlay RW layer. May 03 04:47:42 primo initramfs: IMGPART=/dev/mmcblk1p2 May 03 04:47:42 primo initramfs: IMGFILE=/volumio_current.sqsh May 03 04:47:42 primo initramfs: DATAPART=/dev/mmcblk1p3 May 03 04:47:42 primo initramfs: BOOTPART=/dev/mmcblk1p1 May 03 04:47:42 primo initramfs: BOOTCONFIG=/boot//extlinux/extlinux.conf May 03 04:47:42 primo initramfs: BOOTDELAY=5 May 03 04:47:42 primo kernel: EXT4-fs (mmcblk1p2): recovery complete May 03 04:47:42 primo kernel: EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null) May 03 04:47:42 primo initramfs: Checking for USB updates if you did not boot from USB... May 03 04:47:42 primo kernel: UDF-fs: warning (device sda1): udf_fill_super: No partition found (2) May 03 04:47:42 primo kernel: FAT-fs (mmcblk1p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. May 03 04:47:42 primo kernel: FAT-fs (mmcblk1p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. May 03 04:47:42 primo kernel: EXT4-fs (mmcblk1p3): warning: mounting fs with errors, running e2fsck is recommended May 03 04:47:42 primo kernel: EXT4-fs (mmcblk1p3): recovery complete May 03 04:47:42 primo kernel: EXT4-fs (mmcblk1p3): mounted filesystem with ordered data mode. Opts: (null) May 03 04:47:42 primo initramfs: With Option: May 03 04:47:42 primo initramfs: found fstab entry with UUID=F86D-3F4E for /boot May 03 04:47:42 primo initramfs: VOLUMIO_VERSION="3.661" May 03 04:47:42 primo initramfs: Finish initramfs, continue booting Volumio May 03 04:47:42 primo systemd[1]: System time before build time, advancing clock. May 03 04:47:42 primo kernel: cgroup: cgroup2: unknown option "nsdelegate" May 03 04:47:42 primo systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) May 03 04:47:42 primo systemd[1]: Detected architecture arm. May 03 04:47:42 primo systemd[1]: Set hostname to . May 03 04:47:42 primo systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling. May 03 04:47:42 primo systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) May 03 04:47:42 primo systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 03 04:47:42 primo systemd[1]: Condition check resulted in Journal Audit Socket being skipped. May 03 04:47:42 primo systemd[1]: Listening on Journal Socket. May 03 04:47:42 primo systemd[1]: Mounting Kernel Debug File System... May 03 04:47:42 primo systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped. May 03 04:47:42 primo systemd[1]: Condition check resulted in Huge Pages File System being skipped. May 03 04:47:42 primo systemd[1]: Starting Create list of required static device nodes for the current kernel... May 03 04:47:42 primo systemd[1]: Starting Remount Root and Kernel File Systems... May 03 04:47:42 primo systemd[1]: Started Forward Password Requests to Wall Directory Watch. May 03 04:47:42 primo systemd[1]: Started dynamicswap service. May 03 04:47:42 primo systemd[1]: Reached target Swap. May 03 04:47:42 primo systemd[1]: Listening on udev Kernel Socket. May 03 04:47:42 primo systemd[1]: Created slice system-systemd\x2dfsck.slice. May 03 04:47:42 primo systemd[1]: Condition check resulted in Arbitrary Executable File Formats File System Automount Point being skipped. May 03 04:47:42 primo systemd[1]: Listening on RPCbind Server Activation Socket. May 03 04:47:42 primo systemd[1]: Mounting RPC Pipe File System... May 03 04:47:42 primo systemd[1]: Listening on Journal Socket (/dev/log). May 03 04:47:42 primo systemd[1]: Created slice User and Session Slice. May 03 04:47:42 primo systemd[1]: Reached target Slices. May 03 04:47:42 primo systemd[1]: Listening on fsck to fsckd communication Socket. May 03 04:47:42 primo systemd[1]: Listening on udev Control Socket. May 03 04:47:42 primo systemd[1]: Starting Journal Service... May 03 04:47:42 primo systemd[1]: Starting Load Kernel Modules... May 03 04:47:42 primo systemd[1]: Reached target System Time Synchronized. May 03 04:47:42 primo systemd[1]: Listening on initctl Compatibility Named Pipe. May 03 04:47:42 primo systemd[1]: Mounting POSIX Message Queue File System... May 03 04:47:42 primo systemd[1]: Created slice system-serial\x2dgetty.slice. May 03 04:47:42 primo systemd[1]: Starting udev Coldplug all Devices... May 03 04:47:42 primo systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. May 03 04:47:42 primo systemd[1]: Starting Restore / save the current clock... May 03 04:47:42 primo systemd[1]: Mounted Kernel Debug File System. May 03 04:47:42 primo systemd[1]: Started Create list of required static device nodes for the current kernel. May 03 04:47:42 primo systemd[1]: Started Remount Root and Kernel File Systems. May 03 04:47:42 primo systemd[1]: dynamicswap.service: Succeeded. May 03 04:47:42 primo systemd[1]: Mounted RPC Pipe File System. May 03 04:47:42 primo systemd[1]: Started Load Kernel Modules. May 03 04:47:42 primo systemd[1]: Mounted POSIX Message Queue File System. May 03 04:47:42 primo systemd[1]: Started Restore / save the current clock. May 03 04:47:42 primo systemd-journald[285]: Journal started May 03 04:47:42 primo systemd-journald[285]: Runtime journal (/run/log/journal/8cf606ad6f1733c6bd524e3461a9f9b5) is 7.5M, max 30.0M, 22.5M free. May 03 04:47:42 primo systemd-modules-load[286]: libkmod: ERROR ../libkmod/libkmod-config.c:656 kmod_config_parse: /etc/modprobe.d/alsa-base.conf line 1: ignoring bad line starting with '#' May 03 04:47:42 primo systemd-modules-load[286]: libkmod: ERROR ../libkmod/libkmod-config.c:656 kmod_config_parse: /etc/modprobe.d/alsa-base.conf line 2: ignoring bad line starting with '#' May 03 04:47:42 primo fake-hwclock[289]: Fri May 3 11:47:42 UTC 2024 May 03 04:47:42 primo systemd[1]: Started Journal Service. May 03 04:47:42 primo systemd[1]: Starting Apply Kernel Variables... May 03 04:47:42 primo systemd[1]: Mounting FUSE Control File System... May 03 04:47:42 primo systemd[1]: Mounting Kernel Configuration File System... May 03 04:47:42 primo systemd[1]: Starting Create System Users... May 03 04:47:42 primo systemd[1]: Starting Load/Save Random Seed... May 03 04:47:42 primo systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 03 04:47:42 primo systemd[1]: Mounted Kernel Configuration File System. May 03 04:47:42 primo systemd[1]: Mounted FUSE Control File System. May 03 04:47:42 primo systemd[1]: Started Apply Kernel Variables. May 03 04:47:42 primo systemd[1]: Started Load/Save Random Seed. May 03 04:47:42 primo systemd[1]: Started Create System Users. May 03 04:47:42 primo systemd[1]: Starting Create Static Device Nodes in /dev... May 03 04:47:42 primo systemd[1]: Started Create Static Device Nodes in /dev. May 03 04:47:42 primo systemd[1]: Starting udev Kernel Device Manager... May 03 04:47:42 primo systemd[1]: Reached target Local File Systems (Pre). May 03 04:47:42 primo systemd[1]: Mounting /var/spool/cups... May 03 04:47:42 primo systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 03 04:47:42 primo systemd[1]: Mounting /var/log... May 03 04:47:42 primo systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. May 03 04:47:42 primo systemd[1]: Mounting /tmp... May 03 04:47:42 primo systemd[1]: Mounted /var/spool/cups. May 03 04:47:42 primo systemd[1]: Mounting /var/spool/cups/tmp... May 03 04:47:42 primo systemd[1]: Mounted /var/log. May 03 04:47:42 primo systemd[1]: Starting Flush Journal to Persistent Storage... May 03 04:47:42 primo systemd-udevd[303]: libkmod: ERROR ../libkmod/libkmod-config.c:656 kmod_config_parse: /etc/modprobe.d/alsa-base.conf line 1: ignoring bad line starting with '#' May 03 04:47:42 primo systemd-udevd[303]: libkmod: ERROR ../libkmod/libkmod-config.c:656 kmod_config_parse: /etc/modprobe.d/alsa-base.conf line 2: ignoring bad line starting with '#' May 03 04:47:42 primo systemd[1]: Started udev Coldplug all Devices. May 03 04:47:42 primo systemd[1]: Mounted /tmp. May 03 04:47:42 primo systemd[1]: Mounted /var/spool/cups/tmp. May 03 04:47:42 primo systemd[1]: Starting Helper to synchronize boot up for ifupdown... May 03 04:47:42 primo systemd[1]: Started udev Kernel Device Manager. May 03 04:47:42 primo systemd[1]: Starting Show Plymouth Boot Screen... May 03 04:47:42 primo systemd-journald[285]: Runtime journal (/run/log/journal/8cf606ad6f1733c6bd524e3461a9f9b5) is 7.5M, max 30.0M, 22.5M free. May 03 04:47:42 primo systemd[1]: Started Flush Journal to Persistent Storage. May 03 04:47:42 primo systemd[1]: Received SIGRTMIN+20 from PID 328 (plymouthd). May 03 04:47:42 primo systemd[1]: Started Show Plymouth Boot Screen. May 03 04:47:42 primo systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. May 03 04:47:42 primo systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 03 04:47:42 primo systemd[1]: Reached target Local Encrypted Volumes. May 03 04:47:42 primo systemd[1]: Reached target Paths. May 03 04:47:42 primo systemd-udevd[325]: Using default interface naming scheme 'v240'. May 03 04:47:42 primo systemd-udevd[325]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 03 04:47:42 primo systemd-udevd[325]: link_config: could not get ethtool features for eth0 May 03 04:47:42 primo systemd-udevd[325]: Could not set offload features of eth0: Device or resource busy May 03 04:47:42 primo systemd[1]: Found device /dev/ttyS3. May 03 04:47:42 primo systemd-udevd[324]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 03 04:47:42 primo systemd-udevd[319]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 03 04:47:42 primo systemd-udevd[312]: Using default interface naming scheme 'v240'. May 03 04:47:42 primo systemd-udevd[312]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. May 03 04:47:42 primo systemd-udevd[322]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 03 04:47:42 primo systemd[1]: Found device /dev/disk/by-uuid/F86D-3F4E. May 03 04:47:42 primo kernel: cfg80211: World regulatory domain updated: May 03 04:47:42 primo kernel: cfg80211: DFS Master region: unset May 03 04:47:42 primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) May 03 04:47:42 primo kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) May 03 04:47:42 primo kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz, 92000 KHz AUTO), (N/A, 2000 mBm), (N/A) May 03 04:47:42 primo kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A) May 03 04:47:42 primo kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (N/A) May 03 04:47:42 primo kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2000 mBm), (0 s) May 03 04:47:42 primo kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm), (0 s) May 03 04:47:42 primo kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A) May 03 04:47:42 primo kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A) May 03 04:47:42 primo systemd-udevd[321]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 03 04:47:43 primo kernel: cfg80211: Regulatory domain changed to country: US May 03 04:47:43 primo kernel: cfg80211: DFS Master region: FCC May 03 04:47:43 primo kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) May 03 04:47:43 primo kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 3000 mBm), (N/A) May 03 04:47:43 primo kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (N/A) May 03 04:47:43 primo kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz, 160000 KHz AUTO), (N/A, 2300 mBm), (0 s) May 03 04:47:43 primo kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2300 mBm), (0 s) May 03 04:47:43 primo kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 3000 mBm), (N/A) May 03 04:47:43 primo kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 4000 mBm), (N/A) May 03 04:47:43 primo systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. May 03 04:47:43 primo systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. May 03 04:47:43 primo systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. May 03 04:47:43 primo systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. May 03 04:47:43 primo systemd[1]: Condition check resulted in Arbitrary Executable File Formats File System Automount Point being skipped. May 03 04:47:43 primo systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. May 03 04:47:43 primo systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. May 03 04:47:43 primo systemd[1]: Condition check resulted in Huge Pages File System being skipped. May 03 04:47:43 primo systemd[1]: Starting File System Check on /dev/disk/by-uuid/F86D-3F4E... May 03 04:47:43 primo systemd[1]: Starting Load/Save RF Kill Switch Status... May 03 04:47:43 primo systemd[1]: Started File System Check Daemon to report status. May 03 04:47:43 primo systemd[1]: Started Load/Save RF Kill Switch Status. May 03 04:47:43 primo systemd-fsck[427]: fsck.fat 4.1 (2017-01-24) May 03 04:47:43 primo systemd-fsck[427]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. May 03 04:47:43 primo systemd-fsck[427]: Automatically removing dirty bit. May 03 04:47:43 primo systemd-fsck[427]: Performing changes. May 03 04:47:43 primo systemd-fsck[427]: /dev/mmcblk1p1: 88 files, 10098/30651 clusters May 03 04:47:43 primo systemd[1]: Started File System Check on /dev/disk/by-uuid/F86D-3F4E. May 03 04:47:43 primo systemd[1]: Mounting /boot... May 03 04:47:43 primo systemd[1]: Mounted /boot. May 03 04:47:43 primo systemd[1]: Reached target Local File Systems. May 03 04:47:43 primo systemd[1]: Started ifup for eth0. May 03 04:47:43 primo systemd[1]: Started ifup for wlan0. May 03 04:47:43 primo systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 03 04:47:43 primo systemd[1]: Starting Create Volatile Files and Directories... May 03 04:47:43 primo systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. May 03 04:47:43 primo systemd[1]: Starting Preprocess NFS configuration... May 03 04:47:43 primo systemd[1]: Received SIGRTMIN+20 from PID 328 (plymouthd). May 03 04:47:43 primo systemd[1]: plymouth-read-write.service: Succeeded. May 03 04:47:43 primo systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 03 04:47:43 primo systemd[1]: nfs-config.service: Succeeded. May 03 04:47:43 primo systemd[1]: Started Preprocess NFS configuration. May 03 04:47:43 primo systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. May 03 04:47:43 primo systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. May 03 04:47:43 primo systemd[1]: Reached target NFS client services. May 03 04:47:43 primo systemd[1]: Started Create Volatile Files and Directories. May 03 04:47:43 primo systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 03 04:47:43 primo systemd[1]: Condition check resulted in Network Time Synchronization being skipped. May 03 04:47:43 primo systemd[1]: Starting RPC bind portmap service... May 03 04:47:43 primo systemd[1]: Started Entropy daemon using the HAVEGE algorithm. May 03 04:47:43 primo kernel: IPVS: Creating netns size=1496 id=1 May 03 04:47:43 primo kernel: IPVS: ftp: loaded support on port[0] = 21 May 03 04:47:43 primo systemd[1]: Started Update UTMP about System Boot/Shutdown. May 03 04:47:43 primo systemd[1]: Reached target System Initialization. May 03 04:47:43 primo systemd[1]: Listening on triggerhappy.socket. May 03 04:47:43 primo systemd[1]: man-db.timer: Not using persistent file timestamp Sat 2024-05-11 08:09:26 PDT as it is in the future. May 03 04:47:43 primo systemd[1]: Started Daily man-db regeneration. May 03 04:47:43 primo systemd[1]: Listening on D-Bus System Message Bus Socket. May 03 04:47:43 primo systemd[1]: Started Volumio Splash. May 03 04:47:43 primo systemd[1]: apt-daily.timer: Not using persistent file timestamp Sat 2024-05-11 08:09:26 PDT as it is in the future. May 03 04:47:43 primo systemd[1]: Started Daily apt download activities. May 03 04:47:43 primo systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Sat 2024-05-11 08:09:26 PDT as it is in the future. May 03 04:47:43 primo systemd[1]: Started Daily apt upgrade and clean activities. May 03 04:47:43 primo systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 03 04:47:43 primo systemd[1]: Reached target Sockets. May 03 04:47:43 primo systemd[1]: Reached target Basic System. May 03 04:47:43 primo systemd[480]: volumiosplash.service: Failed to execute command: No such file or directory May 03 04:47:43 primo systemd[480]: volumiosplash.service: Failed at step EXEC spawning /bin/volumiosplash.sh: No such file or directory May 03 04:47:43 primo systemd[1]: Starting triggerhappy global hotkey daemon... May 03 04:47:43 primo systemd[1]: Starting Login Service... May 03 04:47:43 primo systemd[1]: Started volumio-remote-updater.service. May 03 04:47:43 primo systemd[1]: Started Tinkerboard BT INIT. May 03 04:47:43 primo systemd[1]: Started D-Bus System Message Bus. May 03 04:47:43 primo systemd[1]: Starting WPA supplicant... May 03 04:47:43 primo systemd[1]: Started Volumio Iptables Module. May 03 04:47:43 primo thd[482]: Unable to parse trigger line: May 03 04:47:43 primo thd[482]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle May 03 04:47:43 primo thd[482]: Unable to parse trigger line: May 03 04:47:43 primo thd[482]: Found socket passed from systemd May 03 04:47:43 primo systemd[1]: Started Volumio Log Rotation Service. May 03 04:47:43 primo systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 03 04:47:43 primo systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. May 03 04:47:43 primo systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. May 03 04:47:43 primo systemd[1]: Starting DHCP Client Daemon... May 03 04:47:43 primo systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. May 03 04:47:43 primo systemd[1]: Started Manage Sound Card State (restore and store). May 03 04:47:43 primo systemd[1]: Starting Save/Restore Sound Card State... May 03 04:47:43 primo kernel: ttyS0 - failed to request DMA May 03 04:47:43 primo systemd[1]: Starting Wireless Services... May 03 04:47:43 primo systemd[1]: Started UPnP Renderer front-end to MPD. May 03 04:47:43 primo alsactl[497]: alsactl 1.1.8 daemon started May 03 04:47:43 primo systemd[1]: Started Daily Cleanup of Temporary Directories. May 03 04:47:43 primo systemd[1]: Reached target Timers. May 03 04:47:43 primo systemd[1]: Started triggerhappy global hotkey daemon. May 03 04:47:43 primo systemd[1]: volumiosplash.service: Main process exited, code=exited, status=203/EXEC May 03 04:47:43 primo systemd[1]: volumiosplash.service: Failed with result 'exit-code'. May 03 04:47:43 primo systemd-logind[483]: New seat seat0. May 03 04:47:43 primo systemd-logind[483]: Watching system buttons on /dev/input/event1 (RC for dw_hdmi) May 03 04:47:43 primo systemd-logind[483]: Watching system buttons on /dev/input/event2 (gpio-keys) May 03 04:47:43 primo avahi-daemon[494]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). May 03 04:47:43 primo avahi-daemon[494]: Successfully dropped root privileges. May 03 04:47:43 primo avahi-daemon[494]: avahi-daemon 0.7 starting up. May 03 04:47:43 primo systemd[1]: Started Login Service. May 03 04:47:43 primo systemd[1]: Started Save/Restore Sound Card State. May 03 04:47:43 primo systemd[1]: Reached target Sound Card. May 03 04:47:43 primo systemd[1]: Started WPA supplicant. May 03 04:47:43 primo wpa_supplicant[489]: Successfully initialized wpa_supplicant May 03 04:47:43 primo systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 03 04:47:43 primo systemd[1]: iptables.service: Succeeded. May 03 04:47:43 primo avahi-daemon[494]: Successfully called chroot(). May 03 04:47:43 primo avahi-daemon[494]: Successfully dropped remaining capabilities. May 03 04:47:43 primo avahi-daemon[494]: Loading service file /services/volumio.service. May 03 04:47:43 primo volumio-remote-updater[485]: Error: No active session May 03 04:47:43 primo volumio[500]: Could not open config: /tmp/upmpdcli.conf May 03 04:47:43 primo systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE May 03 04:47:43 primo systemd[1]: upmpdcli.service: Failed with result 'exit-code'. May 03 04:47:44 primo kernel: rtk_btcoex: Open BTCOEX May 03 04:47:44 primo kernel: rtk_btcoex: create_udpsocket: connect_port: 30001 May 03 04:47:44 primo kernel: rtk_btcoex: send msg INVITE_REQ with len:11 May 03 04:47:44 primo kernel: rtk_btcoex: BTCOEX hci_rev 0x1e3e May 03 04:47:44 primo kernel: rtk_btcoex: BTCOEX lmp_subver 0xe40e May 03 04:47:44 primo systemd[1]: Starting Bluetooth service... May 03 04:47:44 primo bluetoothd[530]: Bluetooth daemon 5.50 May 03 04:47:44 primo bluetoothd[530]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf May 03 04:47:44 primo systemd[1]: Started Bluetooth service. May 03 04:47:44 primo systemd[1]: Reached target Bluetooth. May 03 04:47:44 primo bluetoothd[530]: Starting SDP server May 03 04:47:44 primo bluetoothd[530]: Excluding (cli) sap May 03 04:47:44 primo bluetoothd[530]: kernel lacks bnep-protocol support May 03 04:47:44 primo bluetoothd[530]: System does not support network plugin May 03 04:47:44 primo bluetoothd[530]: Bluetooth management interface 1.10 initialized May 03 04:47:44 primo dbus-daemon[487]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=530 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") May 03 04:47:44 primo systemd[1]: Starting Hostname Service... May 03 04:47:44 primo systemd[1]: Started RPC bind portmap service. May 03 04:47:44 primo systemd[1]: Reached target Remote File Systems (Pre). May 03 04:47:44 primo systemd[1]: Reached target Remote File Systems. May 03 04:47:44 primo systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 03 04:47:44 primo systemd[1]: Reached target RPC Port Mapper. May 03 04:47:44 primo kernel: usb 1-1.2: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. May 03 04:47:44 primo kernel: usb 1-1.2: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 May 03 04:47:44 primo kernel: usb 1-1.2: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. May 03 04:47:44 primo kernel: usb 1-1.2: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 May 03 04:47:44 primo kernel: usbcore: registered new interface driver snd-usb-audio May 03 04:47:44 primo sh[434]: wlan0=wlan0 May 03 04:47:44 primo avahi-daemon[494]: Network interface enumeration completed. May 03 04:47:44 primo volumio-remote-updater[485]: [2024-05-03 04:47:44] [info] asio async_connect error: system:111 (Connection refused) May 03 04:47:44 primo volumio-remote-updater[485]: [2024-05-03 04:47:44] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 03 04:47:44 primo volumio-remote-updater[485]: [2024-05-03 04:47:44] [error] handle_connect error: Underlying Transport Error May 03 04:47:44 primo avahi-daemon[494]: Server startup complete. Host name is primo.local. Local service cookie is 1399342532. May 03 04:47:44 primo avahi-daemon[494]: Service "primo" (/services/volumio.service) successfully established. May 03 04:47:44 primo sh[433]: eth0: waiting for carrier May 03 04:47:44 primo dhcpcd[471]: eth0: waiting for carrier May 03 04:47:44 primo sh[433]: eth0: carrier acquired May 03 04:47:44 primo dhcpcd[471]: eth0: carrier acquired May 03 04:47:44 primo kernel: IPVS: Creating netns size=1496 id=2 May 03 04:47:44 primo kernel: IPVS: ftp: loaded support on port[0] = 21 May 03 04:47:44 primo sh[433]: DUID 00:01:00:01:29:3d:6c:2d:04:92:26:c5:97:66 May 03 04:47:44 primo dhcpcd[471]: DUID 00:01:00:01:29:3d:6c:2d:04:92:26:c5:97:66 May 03 04:47:44 primo sh[433]: eth0: IAID 26:c5:97:66 May 03 04:47:44 primo dhcpcd[471]: eth0: IAID 26:c5:97:66 May 03 04:47:44 primo sh[433]: eth0: adding address fe80::692:26ff:fec5:9766 May 03 04:47:44 primo dhcpcd[471]: eth0: adding address fe80::692:26ff:fec5:9766 May 03 04:47:44 primo sh[433]: ipv6_addaddr1: Permission denied May 03 04:47:44 primo dhcpcd[471]: ipv6_addaddr1: Permission denied May 03 04:47:44 primo systemd-udevd[326]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 03 04:47:44 primo systemd-logind[483]: Watching system buttons on /dev/input/event0 (Generic USB Audio) May 03 04:47:44 primo wireless.js[499]: Cleaning previous... May 03 04:47:44 primo systemd-udevd[313]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. May 03 04:47:44 primo systemd-udevd[323]: Process '/bin/bash -c '/usr/local/bin/volumio scanaudioinputs'' failed with exit code 7. May 03 04:47:44 primo dhcpcd[496]: no interfaces have a carrier May 03 04:47:44 primo dhcpcd[496]: no interfaces have a carrier May 03 04:47:44 primo dhcpcd[496]: forked to background, child pid 623 May 03 04:47:44 primo dhcpcd[496]: forked to background, child pid 623 May 03 04:47:44 primo dhcpcd[623]: eth0: waiting for carrier May 03 04:47:44 primo dhcpcd[623]: wlan0: waiting for carrier May 03 04:47:44 primo systemd[1]: Started DHCP Client Daemon. May 03 04:47:44 primo dhcpcd[623]: eth0: carrier acquired May 03 04:47:44 primo loadcpufreq[546]: Loading cpufreq kernel modules...done (none). May 03 04:47:44 primo dhcpcd[623]: DUID 00:01:00:01:29:3d:6c:2d:04:92:26:c5:97:66 May 03 04:47:44 primo dhcpcd[623]: eth0: IAID 26:c5:97:66 May 03 04:47:44 primo dhcpcd[623]: eth0: adding address fe80::692:26ff:fec5:9766 May 03 04:47:44 primo dhcpcd[623]: ipv6_addaddr1: Permission denied May 03 04:47:44 primo systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 03 04:47:44 primo systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 03 04:47:44 primo cpufrequtils[627]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. May 03 04:47:44 primo systemd[1]: Started LSB: set CPUFreq kernel parameters. May 03 04:47:44 primo sudo[631]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 03 04:47:44 primo sudo[631]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:44 primo sudo[631]: pam_unix(sudo:session): session closed for user root May 03 04:47:44 primo sudo[650]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 03 04:47:44 primo sudo[650]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:44 primo sudo[650]: pam_unix(sudo:session): session closed for user root May 03 04:47:44 primo dbus-daemon[487]: [system] Successfully activated service 'org.freedesktop.hostname1' May 03 04:47:44 primo systemd[1]: Started Hostname Service. May 03 04:47:45 primo wireless.js[499]: Stopped aP May 03 04:47:45 primo wireless.js[499]: Wireless Networking DISABLED, not starting wireless flow May 03 04:47:45 primo iwconfig[664]: Error for wireless request "Set Power Management" (8B2C) : May 03 04:47:45 primo iwconfig[664]: SET failed on device wlan0 ; Operation not permitted. May 03 04:47:45 primo systemd[1]: wireless.service: Succeeded. May 03 04:47:45 primo systemd[1]: Started Wireless Services. May 03 04:47:45 primo systemd[1]: Started Volumio Backend Module. May 03 04:47:45 primo systemd[1]: Started Volumio Cpu Tweaker. May 03 04:47:45 primo haveged[472]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.3.0 CTV); collect: 128K May 03 04:47:45 primo haveged[472]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15012/57848 May 03 04:47:45 primo haveged[472]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.9979 May 03 04:47:45 primo haveged[472]: haveged: fills: 0, generated: 0 May 03 04:47:45 primo volumio-cpu-tweak[666]: Setting RT Priority for mpd May 03 04:47:45 primo volumio-cpu-tweak[666]: pid 35's current scheduling policy: SCHED_OTHER May 03 04:47:45 primo volumio-cpu-tweak[666]: pid 35's current scheduling priority: 0 May 03 04:47:45 primo volumio-cpu-tweak[666]: Setting MPD Affinity May 03 04:47:45 primo volumio-cpu-tweak[666]: pid 3's current affinity mask: 1 May 03 04:47:45 primo volumio-cpu-tweak[666]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 03 04:47:45 primo volumio-cpu-tweak[666]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 03 04:47:45 primo systemd[1]: volumio_cpu_tweak.service: Succeeded. May 03 04:47:45 primo sh[433]: eth0: soliciting an IPv6 router May 03 04:47:45 primo dhcpcd[471]: eth0: soliciting an IPv6 router May 03 04:47:45 primo sh[433]: eth0: rebinding lease of 192.168.142.132 May 03 04:47:45 primo dhcpcd[471]: eth0: rebinding lease of 192.168.142.132 May 03 04:47:45 primo dhcpcd[623]: eth0: rebinding lease of 192.168.142.132 May 03 04:47:45 primo dhcpcd[623]: eth0: soliciting an IPv6 router May 03 04:47:46 primo volumio[665]: info: ------------------------------------------- May 03 04:47:46 primo volumio[665]: info: ----- Volumio3 ---- May 03 04:47:46 primo volumio[665]: info: ------------------------------------------- May 03 04:47:46 primo volumio[665]: info: ----- System startup ---- May 03 04:47:46 primo volumio[665]: info: ------------------------------------------- May 03 04:47:46 primo sh[433]: eth0: carrier lost May 03 04:47:46 primo dhcpcd[623]: eth0: carrier lost May 03 04:47:46 primo dhcpcd[471]: eth0: carrier lost May 03 04:47:46 primo sh[433]: cat: /run/dhcpcd/resolv.conf.eth0.link: No such file or directory May 03 04:47:47 primo volumio[665]: info: MYVOLUMIO Environment detected May 03 04:47:47 primo volumio[665]: info: Plugin folders cleanup May 03 04:47:47 primo volumio[665]: info: Scanning into folder /volumio/app/plugins/ May 03 04:47:47 primo volumio[665]: info: Scanning category audio_interface May 03 04:47:47 primo volumio[665]: info: Scanning category miscellanea May 03 04:47:47 primo volumio[665]: info: Scanning category music_service May 03 04:47:47 primo volumio[665]: info: Scanning category plugins.json May 03 04:47:47 primo volumio[665]: info: Scanning category system_controller May 03 04:47:47 primo volumio[665]: info: Scanning category user_interface May 03 04:47:47 primo volumio[665]: info: Scanning into folder /data/plugins/ May 03 04:47:47 primo volumio[665]: info: Scanning category music_service May 03 04:47:47 primo volumio[665]: info: Scanning category system_hardware May 03 04:47:47 primo volumio[665]: info: Scanning category user_interface May 03 04:47:47 primo volumio[665]: info: Plugin folders cleanup completed May 03 04:47:47 primo volumio[665]: info: ------------------------------------------- May 03 04:47:47 primo volumio[665]: info: ----- Core plugins startup ---- May 03 04:47:47 primo volumio[665]: info: ------------------------------------------- May 03 04:47:47 primo volumio[665]: info: Loading plugins from folder /volumio/app/plugins/ May 03 04:47:47 primo volumio[665]: info: Adding plugin upnp to MyMusic Plugins May 03 04:47:47 primo volumio[665]: info: Adding plugin airplay_emulation to MyMusic Plugins May 03 04:47:47 primo volumio[665]: info: Adding plugin upnp_browser to MyMusic Plugins May 03 04:47:47 primo volumio[665]: info: Loading plugins from folder /data/plugins/ May 03 04:47:47 primo volumio[665]: info: Loading plugin "system"... May 03 04:47:47 primo volumio[665]: info: Loading plugin "appearance"... May 03 04:47:47 primo kernel: setLedConfiguration: #### before setting led, Reg26 = 0xd1 , Reg28 = 0x9770 May 03 04:47:47 primo kernel: setLedConfiguration: #### after setting led, Reg26 = 0x91 , Reg28 = 0x9040 May 03 04:47:47 primo kernel: rk_gmac-dwmac ff290000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx May 03 04:47:47 primo sh[433]: eth0: carrier acquired May 03 04:47:47 primo dhcpcd[471]: eth0: carrier acquired May 03 04:47:47 primo dhcpcd[623]: eth0: carrier acquired May 03 04:47:47 primo sh[433]: eth0: IAID 26:c5:97:66 May 03 04:47:47 primo dhcpcd[471]: eth0: IAID 26:c5:97:66 May 03 04:47:47 primo dhcpcd[623]: eth0: IAID 26:c5:97:66 May 03 04:47:48 primo sh[433]: eth0: rebinding lease of 192.168.142.132 May 03 04:47:48 primo dhcpcd[471]: eth0: rebinding lease of 192.168.142.132 May 03 04:47:48 primo dhcpcd[623]: eth0: soliciting an IPv6 router May 03 04:47:48 primo volumio[665]: info: Loading plugin "network"... May 03 04:47:48 primo volumio[665]: info: Refreshing Cached IP Addresses May 03 04:47:48 primo volumio[665]: info: Loading plugin "services"... May 03 04:47:48 primo volumio[665]: info: Loading plugin "alsa_controller"... May 03 04:47:48 primo sudo[728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 03 04:47:48 primo sudo[730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 03 04:47:48 primo sudo[728]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:48 primo sudo[730]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:48 primo sudo[728]: pam_unix(sudo:session): session closed for user root May 03 04:47:48 primo sudo[730]: pam_unix(sudo:session): session closed for user root May 03 04:47:48 primo volumio[665]: warn: Unable to locate the audio output device Schiit Bifrost 2 Unison USB. Please configure a valid output device. May 03 04:47:48 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 03 04:47:48 primo volumio[665]: info: Loading plugin "wizard"... May 03 04:47:48 primo volumio[665]: info: Loading plugin "networkfs"... May 03 04:47:48 primo volumio[665]: info: Starting Udev Watcher for removable devices May 03 04:47:48 primo sudo[754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=@((4518KKing)),ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.142.13/mediaqnap /mnt/NAS/MediaNAS May 03 04:47:48 primo sudo[756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=@((4518KKing)),ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.142.13/mediaqnap/media/music /mnt/NAS/MediaNAS May 03 04:47:48 primo sudo[754]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:48 primo sudo[756]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:48 primo volumio[665]: info: Ignoring mount for partition: BOOT May 03 04:47:48 primo volumio[665]: info: Ignoring mount for partition: volumio May 03 04:47:48 primo volumio[665]: info: Ignoring mount for partition: volumio_data May 03 04:47:48 primo volumio[665]: error: Cannot associate FS Label, not mounting May 03 04:47:48 primo volumio[665]: error: Cannot associate FS Label, not mounting May 03 04:47:48 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 03 04:47:48 primo volumio[665]: info: Loading plugin "volumio_command_line_client"... May 03 04:47:48 primo sudo[754]: pam_unix(sudo:session): session closed for user root May 03 04:47:48 primo kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 03 04:47:48 primo kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 03 04:47:48 primo kernel: CIFS VFS: cifs_mount failed w/return code = -101 May 03 04:47:48 primo kernel: CIFS VFS: cifs_mount failed w/return code = -101 May 03 04:47:48 primo sudo[756]: pam_unix(sudo:session): session closed for user root May 03 04:47:48 primo volumio[665]: info: Plugin upnp is not enabled May 03 04:47:48 primo volumio[665]: info: Loading plugin "my_music"... May 03 04:47:48 primo sh[433]: eth0: soliciting an IPv6 router May 03 04:47:48 primo dhcpcd[471]: eth0: soliciting an IPv6 router May 03 04:47:48 primo volumio[665]: info: Loading plugin "mpd"... May 03 04:47:48 primo dhcpcd[623]: eth0: rebinding lease of 192.168.142.132 May 03 04:47:48 primo volumio[665]: info: Plugin upnp_browser is not enabled May 03 04:47:48 primo volumio[665]: info: Loading plugin "alarm-clock"... May 03 04:47:48 primo volumio[665]: info: Loading plugin "airplay_emulation"... May 03 04:47:48 primo volumio[665]: info: Starting Shairport Sync May 03 04:47:48 primo volumio[665]: info: Loading plugin "last_100"... May 03 04:47:48 primo volumio[665]: info: Loading plugin "webradio"... May 03 04:47:49 primo volumio[665]: info: Loading plugin "i2s_dacs"... May 03 04:47:49 primo volumio[665]: info: I2S DAC not set, start Auto-detection May 03 04:47:49 primo volumio[665]: info: Loading plugin "volumiodiscovery"... May 03 04:47:49 primo volumio[665]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 03 04:47:49 primo volumio[665]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 04:47:49 primo volumio[665]: *** WARNING *** For more information see May 03 04:47:49 primo volumio[665]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 03 04:47:49 primo volumio[665]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 04:47:49 primo node[665]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 03 04:47:49 primo volumio[665]: *** WARNING *** For more information see May 03 04:47:49 primo node[665]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 04:47:49 primo node[665]: *** WARNING *** For more information see May 03 04:47:49 primo node[665]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 03 04:47:49 primo node[665]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 04:47:49 primo node[665]: *** WARNING *** For more information see May 03 04:47:49 primo volumio[665]: info: Applying required configuration parameters for plugin volumiodiscovery May 03 04:47:49 primo volumio[665]: info: Discovery: Started advertising with name: primo May 03 04:47:49 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 03 04:47:49 primo volumio[665]: info: Loading plugin "bandcamp"... May 03 04:47:49 primo systemd[1]: systemd-rfkill.service: Succeeded. May 03 04:47:49 primo volumio[665]: info: Loading plugin "mixcloud"... May 03 04:47:49 primo volumio[665]: info: Loading plugin "soundcloud"... May 03 04:47:49 primo volumio[665]: info: Loading plugin "ytcr"... May 03 04:47:49 primo volumio-remote-updater[485]: [2024-05-03 04:47:49] [connect] Successful connection May 03 04:47:50 primo volumio[665]: info: Loading plugin "outputs"... May 03 04:47:50 primo volumio[665]: info: Loading plugin "albumart"... May 03 04:47:50 primo volumio[665]: info: Plugin example_plugin is not enabled May 03 04:47:50 primo volumio[665]: info: Loading plugin "inputs"... May 03 04:47:50 primo volumio[665]: info: Loading plugin "updater_comm"... May 03 04:47:50 primo volumio[665]: info: Plugin mpdemulation is not enabled May 03 04:47:50 primo volumio[665]: info: Loading plugin "rest_api"... May 03 04:47:50 primo volumio[665]: info: Loading plugin "websocket"... May 03 04:47:50 primo volumio[665]: info: Loading plugin "80s80s"... May 03 04:47:50 primo sh[433]: eth0: carrier lost May 03 04:47:50 primo dhcpcd[471]: eth0: carrier lost May 03 04:47:50 primo dhcpcd[623]: eth0: carrier lost May 03 04:47:50 primo kernel: rk_gmac-dwmac ff290000.ethernet eth0: Link is Down May 03 04:47:50 primo sh[433]: cat: /run/dhcpcd/resolv.conf.eth0.dhcp: No such file or directory May 03 04:47:50 primo volumio[665]: Forking 3 albumart workers May 03 04:47:50 primo volumio[665]: info: Applying required configuration parameters for plugin 80s80s May 03 04:47:50 primo volumio[665]: info: [1714736870929] [80s80s] API delay: 30 May 03 04:47:50 primo volumio[665]: info: Loading plugin "RoonBridge"... May 03 04:47:51 primo volumio[665]: info: Applying required configuration parameters for plugin RoonBridge May 03 04:47:51 primo volumio[665]: info: Loading plugin "podcast"... May 03 04:47:51 primo volumio[665]: info: ControllerPodcast::constructor May 03 04:47:51 primo volumio[665]: Starting albumart workers May 03 04:47:51 primo volumio[665]: info: Loading plugin "spop"... May 03 04:47:51 primo volumio[665]: Starting albumart workers May 03 04:47:51 primo volumio[665]: Starting albumart workers May 03 04:47:51 primo volumio[665]: info: Loading plugin "volspotconnect2"... May 03 04:47:52 primo volumio[665]: info: Plugin music_services_shield is not enabled May 03 04:47:52 primo volumio[665]: info: Loading plugin "Systeminfo"... May 03 04:47:52 primo volumio[665]: info: Loading plugin "lastfm"... May 03 04:47:52 primo kernel: setLedConfiguration: #### before setting led, Reg26 = 0x91 , Reg28 = 0x9040 May 03 04:47:52 primo kernel: setLedConfiguration: #### after setting led, Reg26 = 0x91 , Reg28 = 0x9040 May 03 04:47:52 primo kernel: rk_gmac-dwmac ff290000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx May 03 04:47:52 primo sh[433]: eth0: carrier acquired May 03 04:47:52 primo dhcpcd[471]: eth0: carrier acquired May 03 04:47:52 primo dhcpcd[623]: eth0: carrier acquired May 03 04:47:52 primo sh[433]: eth0: IAID 26:c5:97:66 May 03 04:47:52 primo dhcpcd[471]: eth0: IAID 26:c5:97:66 May 03 04:47:52 primo dhcpcd[623]: eth0: IAID 26:c5:97:66 May 03 04:47:52 primo volumio[665]: info: Loading i18n strings for locale en May 03 04:47:52 primo volumio[665]: Updating browse sources language May 03 04:47:52 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:47:52 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 04:47:52 primo volumio[665]: info: CoreCommandRouter::initPlayerControls May 03 04:47:52 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:47:52 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:47:52 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:47:52 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:47:52 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:47:52 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:47:52 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:47:52 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:47:52 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 04:47:52 primo volumio[665]: Express server listening on port 3000 May 03 04:47:52 primo volumio[665]: [Metrics] WebUI: 6s 885.04ms May 03 04:47:52 primo sh[433]: eth0: soliciting an IPv6 router May 03 04:47:52 primo dhcpcd[471]: eth0: soliciting an IPv6 router May 03 04:47:52 primo volumio[665]: info: CoreStateMachine::resetVolumioState May 03 04:47:52 primo volumio[665]: info: CoreStateMachine::getcurrentVolume May 03 04:47:52 primo volumio[665]: info: CoreCommandRouter::volumioRetrievevolume May 03 04:47:52 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 04:47:52 primo volumio[665]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 03 04:47:52 primo volumio[665]: info: Completed loading Core Plugins May 03 04:47:52 primo volumio[665]: info: Preparing to generate the ALSA configuration file May 03 04:47:53 primo volumio[665]: info: CoreStateMachine::pushState May 03 04:47:53 primo volumio[665]: info: CorePlayQueue::getTrack 0 May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::volumioPushState May 03 04:47:53 primo volumio[665]: info: CoreStateMachine::updateTrackBlock May 03 04:47:53 primo volumio[665]: info: CorePlayQueue::getTrackBlock May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::volumioRetrievevolume May 03 04:47:53 primo volumio[665]: info: Cannot mount NAS MediaNAS at system boot, trial number 1 ,retrying in 5 seconds May 03 04:47:53 primo volumio[665]: info: Cannot mount NAS MediaNAS at system boot, trial number 1 ,retrying in 5 seconds May 03 04:47:53 primo volumio[665]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 03 04:47:53 primo volumio[665]: info: Reading ALSA contributions from plugins. May 03 04:47:53 primo volumio-remote-updater[485]: [2024-05-03 04:47:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714736869 101 May 03 04:47:53 primo volumio[665]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 May 03 04:47:53 primo dhcpcd[623]: eth0: rebinding lease of 192.168.142.132 May 03 04:47:53 primo volumio[665]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 May 03 04:47:53 primo volumio[665]: info: CoreStateMachine::pushState May 03 04:47:53 primo volumio[665]: info: CorePlayQueue::getTrack 0 May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::volumioPushState May 03 04:47:53 primo volumio[665]: info: Reloading queue from file May 03 04:47:53 primo volumio[665]: info: CoreStateMachine::setRepeat null single undefined May 03 04:47:53 primo volumio[665]: info: CoreStateMachine::pushState May 03 04:47:53 primo volumio[665]: info: CorePlayQueue::getTrack 0 May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::volumioPushState May 03 04:47:53 primo volumio[665]: info: CoreStateMachine::setRandom null May 03 04:47:53 primo volumio[665]: info: CoreStateMachine::pushState May 03 04:47:53 primo volumio[665]: info: CorePlayQueue::getTrack 0 May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::volumioPushState May 03 04:47:53 primo volumio[665]: info: Setting Device type: Tinkerboard May 03 04:47:53 primo volumio[665]: info: Asound.conf file unchanged, so no further update is needed May 03 04:47:53 primo volumio[665]: info: Output device has changed, restarting MPD May 03 04:47:53 primo volumio[665]: info: Output device has changed, restarting Shairport Sync May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 04:47:53 primo sudo[891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 03 04:47:53 primo sudo[893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 03 04:47:53 primo sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:53 primo sudo[891]: pam_unix(sudo:session): session closed for user root May 03 04:47:53 primo sudo[893]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:53 primo volumio[665]: info: ___________ START PLUGINS ___________ May 03 04:47:53 primo volumio[665]: info: ControllerMpd::onStart: Initializing MPD May 03 04:47:53 primo volumio[665]: info: Creating MPD Configuration file May 03 04:47:53 primo systemd[1]: Listening on mpd.socket. May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 04:47:53 primo sudo[912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 03 04:47:53 primo sudo[913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 03 04:47:53 primo sudo[913]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:53 primo sudo[912]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 04:47:53 primo sudo[912]: pam_unix(sudo:session): session closed for user root May 03 04:47:53 primo dhcpcd[623]: eth0: soliciting an IPv6 router May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:47:53 primo volumio[665]: info: [1714736873407] CoreMusicLibrary::Adding element Last_100 May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:47:53 primo volumio[665]: info: [1714736873410] CoreMusicLibrary::Adding element Webradio May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:47:53 primo volumio[665]: info: [1714736873421] CoreMusicLibrary::Adding element Bandcamp Discover May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:47:53 primo volumio[665]: Cannot find translation for source Bandcamp Discover May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:47:53 primo volumio[665]: info: [1714736873427] CoreMusicLibrary::Adding element Mixcloud May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:47:53 primo volumio[665]: Cannot find translation for source Bandcamp Discover May 03 04:47:53 primo volumio[665]: Cannot find translation for source Mixcloud May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:47:53 primo volumio[665]: info: [1714736873432] CoreMusicLibrary::Adding element SoundCloud May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:47:53 primo volumio[665]: Cannot find translation for source Bandcamp Discover May 03 04:47:53 primo volumio[665]: Cannot find translation for source Mixcloud May 03 04:47:53 primo volumio[665]: Cannot find translation for source SoundCloud May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:47:53 primo volumio[665]: info: [1714736873442] CoreMusicLibrary::Adding element 80s80s Radio May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:47:53 primo volumio[665]: Cannot find translation for source Bandcamp Discover May 03 04:47:53 primo volumio[665]: Cannot find translation for source Mixcloud May 03 04:47:53 primo volumio[665]: Cannot find translation for source SoundCloud May 03 04:47:53 primo volumio[665]: Cannot find translation for source 80s80s Radio May 03 04:47:53 primo sh[433]: eth0: rebinding lease of 192.168.142.132 May 03 04:47:53 primo dhcpcd[471]: eth0: rebinding lease of 192.168.142.132 May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:47:53 primo volumio[665]: info: [1714736873459] CoreMusicLibrary::Adding element Podcast May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:47:53 primo volumio[665]: Cannot find translation for source Bandcamp Discover May 03 04:47:53 primo volumio[665]: Cannot find translation for source Mixcloud May 03 04:47:53 primo volumio[665]: Cannot find translation for source SoundCloud May 03 04:47:53 primo volumio[665]: Cannot find translation for source 80s80s Radio May 03 04:47:53 primo volumio[665]: Cannot find translation for source Podcast May 03 04:47:53 primo sudo[934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service May 03 04:47:53 primo volumio[665]: [SpotifyConnect] Creating VLS config file May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:47:53 primo volumio[665]: [SpotifyConnect] Starting metadata listener May 03 04:47:53 primo sudo[934]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:53 primo sudo[938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:47:53 primo volumio[665]: info: [1714736873498] CoreMusicLibrary::Adding element LastFM May 03 04:47:53 primo volumio[665]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:47:53 primo volumio[665]: Cannot find translation for source Bandcamp Discover May 03 04:47:53 primo volumio[665]: Cannot find translation for source Mixcloud May 03 04:47:53 primo volumio[665]: Cannot find translation for source SoundCloud May 03 04:47:53 primo volumio[665]: Cannot find translation for source 80s80s Radio May 03 04:47:53 primo volumio[665]: Cannot find translation for source Podcast May 03 04:47:53 primo volumio[665]: Cannot find translation for source LastFM May 03 04:47:53 primo volumio[665]: info: [LastFM] scrobbler initiated! May 03 04:47:53 primo volumio[665]: info: [LastFM] extended logging: false May 03 04:47:53 primo volumio[665]: info: [LastFM] try scrobble stream/radio plays: true May 03 04:47:53 primo sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:53 primo systemd[1]: Started RoonBridge. May 03 04:47:53 primo volumio[665]: info: [LastFM] Left init routine May 03 04:47:53 primo volumio[665]: info: [LastFM] Socket already connected: true May 03 04:47:53 primo volumio[665]: info: Volumio Calling Home May 03 04:47:53 primo sudo[934]: pam_unix(sudo:session): session closed for user root May 03 04:47:53 primo sudo[946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 03 04:47:53 primo systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. May 03 04:47:53 primo systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. May 03 04:47:53 primo sudo[946]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:53 primo systemd[1]: Started Spotify Daemon. May 03 04:47:53 primo sudo[938]: pam_unix(sudo:session): session closed for user root May 03 04:47:53 primo systemd[1]: Started Volspotconnect2 Daemon. May 03 04:47:53 primo sudo[946]: pam_unix(sudo:session): session closed for user root May 03 04:47:53 primo volumio[963]: 2024-05-03 04:47:53 [SPTF] 11:47:53.640 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 03 04:47:53 primo volumio[963]: 2024-05-03 04:47:53 [SPTF] 11:47:53.642 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 03 04:47:53 primo volumio[665]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 04:47:53 primo volumio[963]: 2024-05-03 04:47:53 [ERR ] Can't bind socket: Cannot assign requested address May 03 04:47:53 primo systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 03 04:47:53 primo systemd[1]: spop.service: Failed with result 'signal'. May 03 04:47:53 primo volumio[665]: Error: getaddrinfo EBUSY ws.audioscrobbler.com May 03 04:47:53 primo volumio[665]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { May 03 04:47:53 primo volumio[665]: errno: -16, May 03 04:47:53 primo volumio[665]: code: 'EBUSY', May 03 04:47:53 primo volumio[665]: syscall: 'getaddrinfo', May 03 04:47:53 primo volumio[665]: hostname: 'ws.audioscrobbler.com' May 03 04:47:53 primo volumio[665]: } May 03 04:47:53 primo volumio[665]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 04:47:53 primo volumio[973]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 May 03 04:47:53 primo volumio[973]: Reading Config from "volspotify.toml" May 03 04:47:53 primo volumio[973]: [Vollibrespot] : Using Alsa backend with device: volumio May 03 04:47:53 primo volumio[973]: thread 'main' panicked at 'Discovery error!: Os { code: 19, kind: Other, message: "No such device" }', src/main.rs:223:76 May 03 04:47:53 primo volumio[973]: stack backtrace: May 03 04:47:53 primo volumio[973]: 0: 0x7fade040 - std::backtrace_rs::backtrace::libunwind::trace::h4e6fc6cdc5d76862 May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5 May 03 04:47:53 primo volumio[973]: 1: 0x7fade040 - std::backtrace_rs::backtrace::trace_unsynchronized::h1c665adaaf480d86 May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 May 03 04:47:53 primo volumio[973]: 2: 0x7fade040 - std::sys_common::backtrace::_print_fmt::h39ff02745027e048 May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:67:5 May 03 04:47:53 primo volumio[973]: 3: 0x7fade040 - ::fmt::hc83ae421f857c6ae May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:46:22 May 03 04:47:53 primo volumio[973]: 4: 0x7faff6a4 - core::fmt::write::h59f33d28fc1a9cf5 May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/fmt/mod.rs:1092:17 May 03 04:47:53 primo volumio[973]: 5: 0x7fad6fec - std::io::Write::write_fmt::h96774110acd52d88 May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/io/mod.rs:1572:15 May 03 04:47:53 primo volumio[973]: 6: 0x7fae074c - std::sys_common::backtrace::_print::h28b9431057012c44 May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:49:5 May 03 04:47:53 primo volumio[973]: 7: 0x7fae074c - std::sys_common::backtrace::print::h28173b7ebfe4a4ac May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:36:9 May 03 04:47:53 primo volumio[973]: 8: 0x7fae074c - std::panicking::default_hook::{{closure}}::h12f82aa3def34336 May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:208:50 May 03 04:47:53 primo volumio[973]: 9: 0x7fae012c - std::panicking::default_hook::hee29440c29c1cc2e May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:225:9 May 03 04:47:53 primo volumio[973]: 10: 0x7fae0dac - std::panicking::rust_panic_with_hook::he7d3b6e3eee77e72 May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:591:17 May 03 04:47:53 primo volumio[973]: 11: 0x7fae0928 - std::panicking::begin_panic_handler::{{closure}}::h7faba6b3632756a6 May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:497:13 May 03 04:47:53 primo volumio[973]: 12: 0x7fade5bc - std::sys_common::backtrace::__rust_end_short_backtrace::hdde0f982a7a71ce9 May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:141:18 May 03 04:47:53 primo volumio[973]: 13: 0x7fae0870 - rust_begin_unwind May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:493:5 May 03 04:47:53 primo volumio[973]: 14: 0x7f5ba21c - core::panicking::panic_fmt::h97b6949f75a63095 May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/panicking.rs:92:14 May 03 04:47:53 primo volumio[973]: 15: 0x7f5ba120 - core::option::expect_none_failed::h4525992415ba2383 May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/option.rs:1329:5 May 03 04:47:53 primo volumio[973]: 16: 0x7f5e0918 - vollibrespot::main::hf11cedd7d8a3be51 May 03 04:47:53 primo volumio[973]: 17: 0x7f613500 - std::sys_common::backtrace::__rust_begin_short_backtrace::ha48b0377a890cb6d May 03 04:47:53 primo volumio[973]: 18: 0x7f613720 - std::rt::lang_start::{{closure}}::h120c4b14d2445a8e May 03 04:47:53 primo volumio[973]: 19: 0x7fae1198 - core::ops::function::impls:: for &F>::call_once::hb7f0eab2b8615017 May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/ops/function.rs:259:13 May 03 04:47:53 primo volumio[973]: 20: 0x7fae1198 - std::panicking::try::do_call::h2c625414401a1a99 May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:379:40 May 03 04:47:53 primo volumio[973]: 21: 0x7fae1198 - std::panicking::try::h09167d835e285a07 May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:343:19 May 03 04:47:53 primo volumio[973]: 22: 0x7fae1198 - std::panic::catch_unwind::hdff19ee37d8421eb May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panic.rs:431:14 May 03 04:47:53 primo volumio[973]: 23: 0x7fae1198 - std::rt::lang_start_internal::h835544f25e1ef31c May 03 04:47:53 primo volumio[973]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/rt.rs:51:25 May 03 04:47:53 primo volumio[973]: 24: 0x7f5e1a08 - main May 03 04:47:53 primo volumio[973]: 25: 0xb6cb1524 - __libc_start_main May 03 04:47:53 primo systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=101/n/a May 03 04:47:53 primo systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. May 03 04:47:54 primo sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-03 04:46 May 03 04:47:54 primo sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:54 primo sudo[992]: pam_unix(sudo:session): session closed for user root May 03 04:47:54 primo volumio-remote-updater[485]: [2024-05-03 04:47:54] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 03 04:47:54 primo volumio-remote-updater[485]: [2024-05-03 04:47:54] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 03 04:47:54 primo systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 03 04:47:54 primo sudo[893]: pam_unix(sudo:session): session closed for user root May 03 04:47:54 primo sudo[913]: pam_unix(sudo:session): session closed for user root May 03 04:47:54 primo systemd[1]: volumio.service: Failed with result 'exit-code'. May 03 04:47:54 primo systemd[1]: Started dynamicswap service. May 03 04:47:54 primo systemd[1]: dynamicswap.service: Succeeded. May 03 04:47:54 primo RoonBridge[948]: 00:00:00.007 Warn: get lock file path: /tmp/.rnbgem0- May 03 04:47:54 primo systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 03 04:47:54 primo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. May 03 04:47:54 primo systemd[1]: Started dynamicswap service. May 03 04:47:54 primo systemd[1]: Stopped Volumio Backend Module. May 03 04:47:54 primo systemd[1]: Started Volumio Backend Module. May 03 04:47:54 primo systemd[1]: dynamicswap.service: Succeeded. May 03 04:47:54 primo RoonBridge[948]: 00:00:00.321 Trace: [childprocess] using unix child process May 03 04:47:54 primo RoonBridge[948]: Initializing May 03 04:47:54 primo RoonBridge[948]: 00:00:00.452 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 03 04:47:54 primo RoonBridge[948]: 00:00:00.482 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer May 03 04:47:54 primo RoonBridge[948]: Not Running (.o) May 03 04:47:55 primo RoonBridge[948]: Running May 03 04:47:55 primo RoonBridge[948]: Unhandled Exception: May 03 04:47:55 primo RoonBridge[948]: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:47:55 primo RoonBridge[948]: Parameter name: path2 May 03 04:47:55 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:47:55 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:47:55 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:47:55 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:47:55 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:47:55 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:47:55 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:47:55 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:47:55 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:47:55 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:47:55 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:47:55 primo RoonBridge[948]: [ERROR] FATAL UNHANDLED EXCEPTION: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:47:55 primo RoonBridge[948]: Parameter name: path2 May 03 04:47:55 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:47:55 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:47:55 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:47:55 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:47:55 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:47:55 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:47:55 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:47:55 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:47:55 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:47:55 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:47:55 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:47:55 primo RoonBridge[948]: Not Running (o.) May 03 04:47:55 primo volumio[1019]: info: ------------------------------------------- May 03 04:47:55 primo volumio[1019]: info: ----- Volumio3 ---- May 03 04:47:55 primo volumio[1019]: info: ------------------------------------------- May 03 04:47:55 primo volumio[1019]: info: ----- System startup ---- May 03 04:47:55 primo volumio[1019]: info: ------------------------------------------- May 03 04:47:56 primo systemd[1]: volspotconnect2.service: Service RestartSec=2s expired, scheduling restart. May 03 04:47:56 primo systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 1. May 03 04:47:56 primo systemd[1]: Stopped Volspotconnect2 Daemon. May 03 04:47:56 primo systemd[1]: Started Volspotconnect2 Daemon. May 03 04:47:56 primo volumio[1063]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 May 03 04:47:56 primo volumio[1063]: Reading Config from "volspotify.toml" May 03 04:47:56 primo volumio[1063]: [Vollibrespot] : Using Alsa backend with device: volumio May 03 04:47:56 primo volumio[1063]: thread 'main' panicked at 'Discovery error!: Os { code: 19, kind: Other, message: "No such device" }', src/main.rs:223:76 May 03 04:47:56 primo volumio[1063]: stack backtrace: May 03 04:47:56 primo volumio[1063]: 0: 0x7fad4040 - std::backtrace_rs::backtrace::libunwind::trace::h4e6fc6cdc5d76862 May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5 May 03 04:47:56 primo volumio[1063]: 1: 0x7fad4040 - std::backtrace_rs::backtrace::trace_unsynchronized::h1c665adaaf480d86 May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 May 03 04:47:56 primo volumio[1063]: 2: 0x7fad4040 - std::sys_common::backtrace::_print_fmt::h39ff02745027e048 May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:67:5 May 03 04:47:56 primo volumio[1063]: 3: 0x7fad4040 - ::fmt::hc83ae421f857c6ae May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:46:22 May 03 04:47:56 primo volumio[1063]: 4: 0x7faf56a4 - core::fmt::write::h59f33d28fc1a9cf5 May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/fmt/mod.rs:1092:17 May 03 04:47:56 primo volumio[1063]: 5: 0x7faccfec - std::io::Write::write_fmt::h96774110acd52d88 May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/io/mod.rs:1572:15 May 03 04:47:56 primo volumio[1063]: 6: 0x7fad674c - std::sys_common::backtrace::_print::h28b9431057012c44 May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:49:5 May 03 04:47:56 primo volumio[1063]: 7: 0x7fad674c - std::sys_common::backtrace::print::h28173b7ebfe4a4ac May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:36:9 May 03 04:47:56 primo volumio[1063]: 8: 0x7fad674c - std::panicking::default_hook::{{closure}}::h12f82aa3def34336 May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:208:50 May 03 04:47:56 primo volumio[1063]: 9: 0x7fad612c - std::panicking::default_hook::hee29440c29c1cc2e May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:225:9 May 03 04:47:56 primo volumio[1063]: 10: 0x7fad6dac - std::panicking::rust_panic_with_hook::he7d3b6e3eee77e72 May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:591:17 May 03 04:47:56 primo volumio[1063]: 11: 0x7fad6928 - std::panicking::begin_panic_handler::{{closure}}::h7faba6b3632756a6 May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:497:13 May 03 04:47:56 primo volumio[1063]: 12: 0x7fad45bc - std::sys_common::backtrace::__rust_end_short_backtrace::hdde0f982a7a71ce9 May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:141:18 May 03 04:47:56 primo volumio[1063]: 13: 0x7fad6870 - rust_begin_unwind May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:493:5 May 03 04:47:56 primo volumio[1063]: 14: 0x7f5b021c - core::panicking::panic_fmt::h97b6949f75a63095 May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/panicking.rs:92:14 May 03 04:47:56 primo volumio[1063]: 15: 0x7f5b0120 - core::option::expect_none_failed::h4525992415ba2383 May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/option.rs:1329:5 May 03 04:47:56 primo volumio[1063]: 16: 0x7f5d6918 - vollibrespot::main::hf11cedd7d8a3be51 May 03 04:47:56 primo volumio[1063]: 17: 0x7f609500 - std::sys_common::backtrace::__rust_begin_short_backtrace::ha48b0377a890cb6d May 03 04:47:56 primo volumio[1063]: 18: 0x7f609720 - std::rt::lang_start::{{closure}}::h120c4b14d2445a8e May 03 04:47:56 primo volumio[1063]: 19: 0x7fad7198 - core::ops::function::impls:: for &F>::call_once::hb7f0eab2b8615017 May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/ops/function.rs:259:13 May 03 04:47:56 primo volumio[1063]: 20: 0x7fad7198 - std::panicking::try::do_call::h2c625414401a1a99 May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:379:40 May 03 04:47:56 primo volumio[1063]: 21: 0x7fad7198 - std::panicking::try::h09167d835e285a07 May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:343:19 May 03 04:47:56 primo volumio[1063]: 22: 0x7fad7198 - std::panic::catch_unwind::hdff19ee37d8421eb May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panic.rs:431:14 May 03 04:47:56 primo volumio[1063]: 23: 0x7fad7198 - std::rt::lang_start_internal::h835544f25e1ef31c May 03 04:47:56 primo volumio[1063]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/rt.rs:51:25 May 03 04:47:56 primo volumio[1063]: 24: 0x7f5d7a08 - main May 03 04:47:56 primo volumio[1063]: 25: 0xb6d24524 - __libc_start_main May 03 04:47:56 primo systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=101/n/a May 03 04:47:56 primo systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. May 03 04:47:56 primo volumio[1019]: info: MYVOLUMIO Environment detected May 03 04:47:56 primo volumio[1019]: info: Plugin folders cleanup May 03 04:47:56 primo volumio[1019]: info: Scanning into folder /volumio/app/plugins/ May 03 04:47:56 primo volumio[1019]: info: Scanning category audio_interface May 03 04:47:56 primo volumio[1019]: info: Scanning category miscellanea May 03 04:47:56 primo volumio[1019]: info: Scanning category music_service May 03 04:47:56 primo volumio[1019]: info: Scanning category plugins.json May 03 04:47:56 primo volumio[1019]: info: Scanning category system_controller May 03 04:47:56 primo volumio[1019]: info: Scanning category user_interface May 03 04:47:56 primo volumio[1019]: info: Scanning into folder /data/plugins/ May 03 04:47:56 primo volumio[1019]: info: Scanning category music_service May 03 04:47:56 primo volumio[1019]: info: Scanning category system_hardware May 03 04:47:56 primo volumio[1019]: info: Scanning category user_interface May 03 04:47:56 primo volumio[1019]: info: Plugin folders cleanup completed May 03 04:47:56 primo volumio[1019]: info: ------------------------------------------- May 03 04:47:56 primo volumio[1019]: info: ----- Core plugins startup ---- May 03 04:47:56 primo volumio[1019]: info: ------------------------------------------- May 03 04:47:56 primo volumio[1019]: info: Loading plugins from folder /volumio/app/plugins/ May 03 04:47:56 primo volumio[1019]: info: Adding plugin upnp to MyMusic Plugins May 03 04:47:56 primo volumio[1019]: info: Adding plugin airplay_emulation to MyMusic Plugins May 03 04:47:56 primo volumio[1019]: info: Adding plugin upnp_browser to MyMusic Plugins May 03 04:47:56 primo volumio[1019]: info: Loading plugins from folder /data/plugins/ May 03 04:47:56 primo volumio[1019]: info: Loading plugin "system"... May 03 04:47:56 primo volumio[1019]: info: Loading plugin "appearance"... May 03 04:47:57 primo volumio[1019]: info: Loading plugin "network"... May 03 04:47:57 primo volumio[1019]: info: Refreshing Cached IP Addresses May 03 04:47:57 primo volumio[1019]: info: Loading plugin "services"... May 03 04:47:57 primo volumio[1019]: info: Loading plugin "alsa_controller"... May 03 04:47:57 primo sudo[1076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 03 04:47:57 primo sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 03 04:47:57 primo sudo[1076]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:57 primo sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:57 primo sudo[1076]: pam_unix(sudo:session): session closed for user root May 03 04:47:57 primo sudo[1078]: pam_unix(sudo:session): session closed for user root May 03 04:47:57 primo volumio[1019]: warn: Unable to locate the audio output device Schiit Bifrost 2 Unison USB. Please configure a valid output device. May 03 04:47:57 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 03 04:47:57 primo volumio[1019]: info: Loading plugin "wizard"... May 03 04:47:57 primo volumio[1019]: info: Loading plugin "networkfs"... May 03 04:47:57 primo volumio[1019]: info: Starting Udev Watcher for removable devices May 03 04:47:57 primo sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=@((4518KKing)),ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.142.13/mediaqnap /mnt/NAS/MediaNAS May 03 04:47:57 primo sudo[1104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=@((4518KKing)),ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.142.13/mediaqnap/media/music /mnt/NAS/MediaNAS May 03 04:47:57 primo sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:57 primo sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:47:57 primo volumio[1019]: info: Ignoring mount for partition: BOOT May 03 04:47:57 primo volumio[1019]: info: Ignoring mount for partition: volumio May 03 04:47:57 primo volumio[1019]: info: Ignoring mount for partition: volumio_data May 03 04:47:57 primo volumio[1019]: error: Cannot associate FS Label, not mounting May 03 04:47:57 primo volumio[1019]: error: Cannot associate FS Label, not mounting May 03 04:47:57 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 03 04:47:57 primo volumio[1019]: info: Loading plugin "volumio_command_line_client"... May 03 04:47:57 primo volumio[1019]: info: Plugin upnp is not enabled May 03 04:47:57 primo volumio[1019]: info: Loading plugin "my_music"... May 03 04:47:57 primo volumio[1019]: info: Loading plugin "mpd"... May 03 04:47:57 primo sudo[1104]: pam_unix(sudo:session): session closed for user root May 03 04:47:57 primo kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 03 04:47:57 primo kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 03 04:47:57 primo kernel: CIFS VFS: cifs_mount failed w/return code = -101 May 03 04:47:57 primo kernel: CIFS VFS: cifs_mount failed w/return code = -101 May 03 04:47:57 primo sudo[1102]: pam_unix(sudo:session): session closed for user root May 03 04:47:57 primo volumio[1019]: info: Plugin upnp_browser is not enabled May 03 04:47:57 primo volumio[1019]: info: Loading plugin "alarm-clock"... May 03 04:47:57 primo RoonBridge[948]: 00:00:03.251 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 03 04:47:57 primo RoonBridge[948]: Running May 03 04:47:57 primo volumio[1019]: info: Loading plugin "airplay_emulation"... May 03 04:47:57 primo volumio[1019]: info: Starting Shairport Sync May 03 04:47:57 primo volumio[1019]: info: Loading plugin "last_100"... May 03 04:47:57 primo volumio[1019]: info: Loading plugin "webradio"... May 03 04:47:57 primo volumio[1019]: info: Loading plugin "i2s_dacs"... May 03 04:47:57 primo volumio[1019]: info: I2S DAC not set, start Auto-detection May 03 04:47:57 primo volumio[1019]: info: Loading plugin "volumiodiscovery"... May 03 04:47:57 primo volumio[1019]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 03 04:47:57 primo volumio[1019]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 04:47:57 primo volumio[1019]: *** WARNING *** For more information see May 03 04:47:57 primo volumio[1019]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 03 04:47:57 primo volumio[1019]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 04:47:57 primo node[1019]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 03 04:47:57 primo volumio[1019]: *** WARNING *** For more information see May 03 04:47:57 primo node[1019]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 04:47:57 primo node[1019]: *** WARNING *** For more information see May 03 04:47:57 primo node[1019]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 03 04:47:57 primo node[1019]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 04:47:57 primo node[1019]: *** WARNING *** For more information see May 03 04:47:57 primo volumio[1019]: info: Applying required configuration parameters for plugin volumiodiscovery May 03 04:47:57 primo volumio[1019]: info: Discovery: Started advertising with name: primo May 03 04:47:57 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 03 04:47:57 primo volumio[1019]: info: Loading plugin "bandcamp"... May 03 04:47:57 primo volumio[1019]: info: Loading plugin "mixcloud"... May 03 04:47:58 primo dhcpcd[623]: eth0: probing for an IPv4LL address May 03 04:47:58 primo volumio[1019]: info: Loading plugin "soundcloud"... May 03 04:47:58 primo dhcpcd[623]: eth0: DHCP lease expired May 03 04:47:58 primo dhcpcd[623]: eth0: soliciting a DHCP lease May 03 04:47:58 primo volumio[1019]: info: Loading plugin "ytcr"... May 03 04:47:58 primo RoonBridge[948]: Unhandled Exception: May 03 04:47:58 primo RoonBridge[948]: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:47:58 primo RoonBridge[948]: Parameter name: path2 May 03 04:47:58 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:47:58 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:47:58 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:47:58 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:47:58 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:47:58 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:47:58 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:47:58 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:47:58 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:47:58 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:47:58 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:47:58 primo RoonBridge[948]: [ERROR] FATAL UNHANDLED EXCEPTION: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:47:58 primo RoonBridge[948]: Parameter name: path2 May 03 04:47:58 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:47:58 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:47:58 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:47:58 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:47:58 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:47:58 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:47:58 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:47:58 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:47:58 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:47:58 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:47:58 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:47:58 primo RoonBridge[948]: Not Running (o.) May 03 04:47:58 primo systemd[1]: volspotconnect2.service: Service RestartSec=2s expired, scheduling restart. May 03 04:47:58 primo systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 2. May 03 04:47:58 primo systemd[1]: Stopped Volspotconnect2 Daemon. May 03 04:47:58 primo systemd[1]: Started Volspotconnect2 Daemon. May 03 04:47:58 primo volumio[1151]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 May 03 04:47:58 primo volumio[1151]: Reading Config from "volspotify.toml" May 03 04:47:58 primo volumio[1151]: [Vollibrespot] : Using Alsa backend with device: volumio May 03 04:47:58 primo sh[433]: eth0: probing for an IPv4LL address May 03 04:47:58 primo dhcpcd[471]: eth0: probing for an IPv4LL address May 03 04:47:58 primo volumio[1151]: thread 'main' panicked at 'Discovery error!: Os { code: 19, kind: Other, message: "No such device" }', src/main.rs:223:76 May 03 04:47:58 primo volumio[1151]: stack backtrace: May 03 04:47:58 primo volumio[1151]: 0: 0x7fb83040 - std::backtrace_rs::backtrace::libunwind::trace::h4e6fc6cdc5d76862 May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5 May 03 04:47:58 primo volumio[1151]: 1: 0x7fb83040 - std::backtrace_rs::backtrace::trace_unsynchronized::h1c665adaaf480d86 May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 May 03 04:47:58 primo volumio[1151]: 2: 0x7fb83040 - std::sys_common::backtrace::_print_fmt::h39ff02745027e048 May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:67:5 May 03 04:47:58 primo volumio[1151]: 3: 0x7fb83040 - ::fmt::hc83ae421f857c6ae May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:46:22 May 03 04:47:58 primo sh[433]: eth0: DHCP lease expired May 03 04:47:58 primo dhcpcd[471]: eth0: DHCP lease expired May 03 04:47:58 primo volumio[1151]: 4: 0x7fba46a4 - core::fmt::write::h59f33d28fc1a9cf5 May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/fmt/mod.rs:1092:17 May 03 04:47:58 primo volumio[1151]: 5: 0x7fb7bfec - std::io::Write::write_fmt::h96774110acd52d88 May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/io/mod.rs:1572:15 May 03 04:47:58 primo volumio[1151]: 6: 0x7fb8574c - std::sys_common::backtrace::_print::h28b9431057012c44 May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:49:5 May 03 04:47:58 primo volumio[1151]: 7: 0x7fb8574c - std::sys_common::backtrace::print::h28173b7ebfe4a4ac May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:36:9 May 03 04:47:58 primo volumio[1151]: 8: 0x7fb8574c - std::panicking::default_hook::{{closure}}::h12f82aa3def34336 May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:208:50 May 03 04:47:58 primo volumio[1151]: 9: 0x7fb8512c - std::panicking::default_hook::hee29440c29c1cc2e May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:225:9 May 03 04:47:58 primo volumio[1151]: 10: 0x7fb85dac - std::panicking::rust_panic_with_hook::he7d3b6e3eee77e72 May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:591:17 May 03 04:47:58 primo volumio[1151]: 11: 0x7fb85928 - std::panicking::begin_panic_handler::{{closure}}::h7faba6b3632756a6 May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:497:13 May 03 04:47:58 primo volumio[1151]: 12: 0x7fb835bc - std::sys_common::backtrace::__rust_end_short_backtrace::hdde0f982a7a71ce9 May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:141:18 May 03 04:47:58 primo volumio[1151]: 13: 0x7fb85870 - rust_begin_unwind May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:493:5 May 03 04:47:58 primo volumio[1151]: 14: 0x7f65f21c - core::panicking::panic_fmt::h97b6949f75a63095 May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/panicking.rs:92:14 May 03 04:47:58 primo volumio[1151]: 15: 0x7f65f120 - core::option::expect_none_failed::h4525992415ba2383 May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/option.rs:1329:5 May 03 04:47:58 primo volumio[1151]: 16: 0x7f685918 - vollibrespot::main::hf11cedd7d8a3be51 May 03 04:47:58 primo volumio[1151]: 17: 0x7f6b8500 - std::sys_common::backtrace::__rust_begin_short_backtrace::ha48b0377a890cb6d May 03 04:47:58 primo volumio[1151]: 18: 0x7f6b8720 - std::rt::lang_start::{{closure}}::h120c4b14d2445a8e May 03 04:47:58 primo volumio[1151]: 19: 0x7fb86198 - core::ops::function::impls:: for &F>::call_once::hb7f0eab2b8615017 May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/ops/function.rs:259:13 May 03 04:47:58 primo volumio[1151]: 20: 0x7fb86198 - std::panicking::try::do_call::h2c625414401a1a99 May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:379:40 May 03 04:47:58 primo volumio[1151]: 21: 0x7fb86198 - std::panicking::try::h09167d835e285a07 May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:343:19 May 03 04:47:58 primo volumio[1151]: 22: 0x7fb86198 - std::panic::catch_unwind::hdff19ee37d8421eb May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panic.rs:431:14 May 03 04:47:58 primo volumio[1151]: 23: 0x7fb86198 - std::rt::lang_start_internal::h835544f25e1ef31c May 03 04:47:58 primo volumio[1151]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/rt.rs:51:25 May 03 04:47:58 primo volumio[1151]: 24: 0x7f686a08 - main May 03 04:47:58 primo volumio[1151]: 25: 0xb6d45524 - __libc_start_main May 03 04:47:58 primo systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=101/n/a May 03 04:47:58 primo systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. May 03 04:47:58 primo sh[433]: eth0: soliciting a DHCP lease May 03 04:47:58 primo dhcpcd[471]: eth0: soliciting a DHCP lease May 03 04:47:59 primo volumio[1019]: info: Loading plugin "outputs"... May 03 04:47:59 primo volumio[1019]: info: Loading plugin "albumart"... May 03 04:47:59 primo volumio[1019]: info: Plugin example_plugin is not enabled May 03 04:47:59 primo volumio[1019]: info: Loading plugin "inputs"... May 03 04:47:59 primo volumio[1019]: info: Loading plugin "updater_comm"... May 03 04:47:59 primo volumio[1019]: info: Plugin mpdemulation is not enabled May 03 04:47:59 primo volumio[1019]: info: Loading plugin "rest_api"... May 03 04:47:59 primo volumio[1019]: info: Loading plugin "websocket"... May 03 04:47:59 primo volumio[1019]: info: Loading plugin "80s80s"... May 03 04:47:59 primo volumio-remote-updater[485]: [2024-05-03 04:47:59] [connect] Successful connection May 03 04:47:59 primo volumio[1019]: Forking 3 albumart workers May 03 04:47:59 primo volumio[1019]: info: Applying required configuration parameters for plugin 80s80s May 03 04:47:59 primo volumio[1019]: info: [1714736879313] [80s80s] API delay: 30 May 03 04:47:59 primo volumio[1019]: info: Loading plugin "RoonBridge"... May 03 04:47:59 primo volumio[1019]: info: Applying required configuration parameters for plugin RoonBridge May 03 04:47:59 primo volumio[1019]: info: Loading plugin "podcast"... May 03 04:47:59 primo volumio[1019]: info: ControllerPodcast::constructor May 03 04:47:59 primo volumio[1019]: info: Loading plugin "spop"... May 03 04:47:59 primo volumio[1019]: Starting albumart workers May 03 04:47:59 primo volumio[1019]: Starting albumart workers May 03 04:47:59 primo volumio[1019]: Starting albumart workers May 03 04:48:00 primo volumio[1019]: info: Loading plugin "volspotconnect2"... May 03 04:48:00 primo RoonBridge[948]: 00:00:05.960 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 03 04:48:00 primo RoonBridge[948]: Running May 03 04:48:00 primo volumio[1019]: info: Plugin music_services_shield is not enabled May 03 04:48:00 primo volumio[1019]: info: Loading plugin "Systeminfo"... May 03 04:48:00 primo volumio[1019]: info: Loading plugin "lastfm"... May 03 04:48:00 primo systemd[1]: volspotconnect2.service: Service RestartSec=2s expired, scheduling restart. May 03 04:48:00 primo systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 3. May 03 04:48:00 primo systemd[1]: Stopped Volspotconnect2 Daemon. May 03 04:48:00 primo systemd[1]: Started Volspotconnect2 Daemon. May 03 04:48:00 primo volumio[1219]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 May 03 04:48:00 primo volumio[1219]: Reading Config from "volspotify.toml" May 03 04:48:00 primo volumio[1219]: [Vollibrespot] : Using Alsa backend with device: volumio May 03 04:48:00 primo volumio[1219]: thread 'main' panicked at 'Discovery error!: Os { code: 19, kind: Other, message: "No such device" }', src/main.rs:223:76 May 03 04:48:00 primo volumio[1219]: stack backtrace: May 03 04:48:00 primo volumio[1219]: 0: 0x7fb02040 - std::backtrace_rs::backtrace::libunwind::trace::h4e6fc6cdc5d76862 May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/libunwind.rs:90:5 May 03 04:48:00 primo volumio[1219]: 1: 0x7fb02040 - std::backtrace_rs::backtrace::trace_unsynchronized::h1c665adaaf480d86 May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/../../backtrace/src/backtrace/mod.rs:66:5 May 03 04:48:00 primo volumio[1219]: 2: 0x7fb02040 - std::sys_common::backtrace::_print_fmt::h39ff02745027e048 May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:67:5 May 03 04:48:00 primo volumio[1219]: 3: 0x7fb02040 - ::fmt::hc83ae421f857c6ae May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:46:22 May 03 04:48:00 primo volumio[1219]: 4: 0x7fb236a4 - core::fmt::write::h59f33d28fc1a9cf5 May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/fmt/mod.rs:1092:17 May 03 04:48:00 primo volumio[1219]: 5: 0x7fafafec - std::io::Write::write_fmt::h96774110acd52d88 May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/io/mod.rs:1572:15 May 03 04:48:00 primo volumio[1219]: 6: 0x7fb0474c - std::sys_common::backtrace::_print::h28b9431057012c44 May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:49:5 May 03 04:48:00 primo volumio[1219]: 7: 0x7fb0474c - std::sys_common::backtrace::print::h28173b7ebfe4a4ac May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:36:9 May 03 04:48:00 primo volumio[1219]: 8: 0x7fb0474c - std::panicking::default_hook::{{closure}}::h12f82aa3def34336 May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:208:50 May 03 04:48:00 primo volumio[1219]: 9: 0x7fb0412c - std::panicking::default_hook::hee29440c29c1cc2e May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:225:9 May 03 04:48:00 primo volumio[1219]: 10: 0x7fb04dac - std::panicking::rust_panic_with_hook::he7d3b6e3eee77e72 May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:591:17 May 03 04:48:00 primo volumio[1219]: 11: 0x7fb04928 - std::panicking::begin_panic_handler::{{closure}}::h7faba6b3632756a6 May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:497:13 May 03 04:48:00 primo volumio[1219]: 12: 0x7fb025bc - std::sys_common::backtrace::__rust_end_short_backtrace::hdde0f982a7a71ce9 May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/sys_common/backtrace.rs:141:18 May 03 04:48:00 primo volumio[1219]: 13: 0x7fb04870 - rust_begin_unwind May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:493:5 May 03 04:48:00 primo volumio[1219]: 14: 0x7f5de21c - core::panicking::panic_fmt::h97b6949f75a63095 May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/panicking.rs:92:14 May 03 04:48:00 primo volumio[1219]: 15: 0x7f5de120 - core::option::expect_none_failed::h4525992415ba2383 May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/option.rs:1329:5 May 03 04:48:00 primo volumio[1219]: 16: 0x7f604918 - vollibrespot::main::hf11cedd7d8a3be51 May 03 04:48:00 primo volumio[1219]: 17: 0x7f637500 - std::sys_common::backtrace::__rust_begin_short_backtrace::ha48b0377a890cb6d May 03 04:48:00 primo volumio[1219]: 18: 0x7f637720 - std::rt::lang_start::{{closure}}::h120c4b14d2445a8e May 03 04:48:00 primo volumio[1219]: 19: 0x7fb05198 - core::ops::function::impls:: for &F>::call_once::hb7f0eab2b8615017 May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/core/src/ops/function.rs:259:13 May 03 04:48:00 primo volumio[1219]: 20: 0x7fb05198 - std::panicking::try::do_call::h2c625414401a1a99 May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:379:40 May 03 04:48:00 primo volumio[1219]: 21: 0x7fb05198 - std::panicking::try::h09167d835e285a07 May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panicking.rs:343:19 May 03 04:48:00 primo volumio[1219]: 22: 0x7fb05198 - std::panic::catch_unwind::hdff19ee37d8421eb May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/panic.rs:431:14 May 03 04:48:00 primo volumio[1219]: 23: 0x7fb05198 - std::rt::lang_start_internal::h835544f25e1ef31c May 03 04:48:00 primo volumio[1219]: at /rustc/9bc8c42bb2f19e745a63f3445f1ac248fb015e53/library/std/src/rt.rs:51:25 May 03 04:48:00 primo volumio[1219]: 24: 0x7f605a08 - main May 03 04:48:00 primo volumio[1219]: 25: 0xb6cc3524 - __libc_start_main May 03 04:48:00 primo volumio[1019]: info: Loading i18n strings for locale en May 03 04:48:00 primo systemd[1]: volspotconnect2.service: Main process exited, code=exited, status=101/n/a May 03 04:48:00 primo systemd[1]: volspotconnect2.service: Failed with result 'exit-code'. May 03 04:48:00 primo volumio[1019]: Updating browse sources language May 03 04:48:00 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:00 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 04:48:00 primo volumio[1019]: info: CoreCommandRouter::initPlayerControls May 03 04:48:00 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:00 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:00 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:00 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:00 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:00 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:00 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:00 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:00 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 04:48:00 primo volumio[1019]: Express server listening on port 3000 May 03 04:48:00 primo volumio[1019]: [Metrics] WebUI: 5s 530.81ms May 03 04:48:00 primo volumio[1019]: info: CoreStateMachine::resetVolumioState May 03 04:48:00 primo volumio[1019]: info: CoreStateMachine::getcurrentVolume May 03 04:48:00 primo volumio[1019]: info: CoreCommandRouter::volumioRetrievevolume May 03 04:48:00 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 04:48:01 primo volumio[1019]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 03 04:48:01 primo volumio[1019]: info: Completed loading Core Plugins May 03 04:48:01 primo volumio[1019]: info: Preparing to generate the ALSA configuration file May 03 04:48:01 primo RoonBridge[948]: Unhandled Exception: May 03 04:48:01 primo RoonBridge[948]: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:01 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:01 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:01 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:01 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:01 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:01 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:01 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:01 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:01 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:01 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:01 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:01 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:01 primo RoonBridge[948]: [ERROR] FATAL UNHANDLED EXCEPTION: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:01 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:01 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:01 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:01 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:01 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:01 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:01 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:01 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:01 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:01 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:01 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:01 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:01 primo RoonBridge[948]: Not Running (o.) May 03 04:48:01 primo volumio[1019]: info: CoreStateMachine::pushState May 03 04:48:01 primo volumio[1019]: info: CorePlayQueue::getTrack 0 May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::volumioPushState May 03 04:48:01 primo volumio[1019]: info: CoreStateMachine::updateTrackBlock May 03 04:48:01 primo volumio[1019]: info: CorePlayQueue::getTrackBlock May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::volumioRetrievevolume May 03 04:48:01 primo volumio[1019]: info: Cannot mount NAS MediaNAS at system boot, trial number 1 ,retrying in 5 seconds May 03 04:48:01 primo volumio[1019]: info: Cannot mount NAS MediaNAS at system boot, trial number 1 ,retrying in 5 seconds May 03 04:48:01 primo volumio[1019]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 03 04:48:01 primo volumio[1019]: info: Reading ALSA contributions from plugins. May 03 04:48:01 primo volumio-remote-updater[485]: [2024-05-03 04:48:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714736879 101 May 03 04:48:01 primo volumio[1019]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 May 03 04:48:01 primo volumio[1019]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 May 03 04:48:01 primo volumio[1019]: info: CoreStateMachine::pushState May 03 04:48:01 primo volumio[1019]: info: CorePlayQueue::getTrack 0 May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::volumioPushState May 03 04:48:01 primo volumio[1019]: info: Reloading queue from file May 03 04:48:01 primo volumio[1019]: info: CoreStateMachine::setRepeat null single undefined May 03 04:48:01 primo volumio[1019]: info: CoreStateMachine::pushState May 03 04:48:01 primo volumio[1019]: info: CorePlayQueue::getTrack 0 May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::volumioPushState May 03 04:48:01 primo volumio[1019]: info: CoreStateMachine::setRandom null May 03 04:48:01 primo volumio[1019]: info: CoreStateMachine::pushState May 03 04:48:01 primo volumio[1019]: info: CorePlayQueue::getTrack 0 May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::volumioPushState May 03 04:48:01 primo volumio[1019]: info: Setting Device type: Tinkerboard May 03 04:48:01 primo volumio[1019]: info: Asound.conf file unchanged, so no further update is needed May 03 04:48:01 primo volumio[1019]: info: Output device has changed, restarting MPD May 03 04:48:01 primo volumio[1019]: info: Output device has changed, restarting Shairport Sync May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 04:48:01 primo sudo[1243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 03 04:48:01 primo sudo[1245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 03 04:48:01 primo sudo[1243]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:01 primo sudo[1243]: pam_unix(sudo:session): session closed for user root May 03 04:48:01 primo sudo[1245]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:01 primo volumio[1019]: info: ___________ START PLUGINS ___________ May 03 04:48:01 primo volumio[1019]: info: ControllerMpd::onStart: Initializing MPD May 03 04:48:01 primo volumio[1019]: info: Creating MPD Configuration file May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 04:48:01 primo sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 03 04:48:01 primo sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:01 primo sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 03 04:48:01 primo sudo[1263]: pam_unix(sudo:session): session closed for user root May 03 04:48:01 primo sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:01 primo volumio[1019]: info: [1714736881438] CoreMusicLibrary::Adding element Last_100 May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:01 primo volumio[1019]: info: [1714736881441] CoreMusicLibrary::Adding element Webradio May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:01 primo volumio[1019]: info: [1714736881450] CoreMusicLibrary::Adding element Bandcamp Discover May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:01 primo volumio[1019]: Cannot find translation for source Bandcamp Discover May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:01 primo volumio[1019]: info: [1714736881456] CoreMusicLibrary::Adding element Mixcloud May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:01 primo volumio[1019]: Cannot find translation for source Bandcamp Discover May 03 04:48:01 primo volumio[1019]: Cannot find translation for source Mixcloud May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:01 primo volumio[1019]: info: [1714736881461] CoreMusicLibrary::Adding element SoundCloud May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:01 primo volumio[1019]: Cannot find translation for source Bandcamp Discover May 03 04:48:01 primo volumio[1019]: Cannot find translation for source Mixcloud May 03 04:48:01 primo volumio[1019]: Cannot find translation for source SoundCloud May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:01 primo volumio[1019]: info: [1714736881470] CoreMusicLibrary::Adding element 80s80s Radio May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:01 primo volumio[1019]: Cannot find translation for source Bandcamp Discover May 03 04:48:01 primo volumio[1019]: Cannot find translation for source Mixcloud May 03 04:48:01 primo volumio[1019]: Cannot find translation for source SoundCloud May 03 04:48:01 primo volumio[1019]: Cannot find translation for source 80s80s Radio May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:01 primo volumio[1019]: info: [1714736881486] CoreMusicLibrary::Adding element Podcast May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:01 primo volumio[1019]: Cannot find translation for source Bandcamp Discover May 03 04:48:01 primo volumio[1019]: Cannot find translation for source Mixcloud May 03 04:48:01 primo volumio[1019]: Cannot find translation for source SoundCloud May 03 04:48:01 primo volumio[1019]: Cannot find translation for source 80s80s Radio May 03 04:48:01 primo volumio[1019]: Cannot find translation for source Podcast May 03 04:48:01 primo sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service May 03 04:48:01 primo volumio[1019]: [SpotifyConnect] Creating VLS config file May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:01 primo sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:01 primo volumio[1019]: [SpotifyConnect] Starting metadata listener May 03 04:48:01 primo sudo[1290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:01 primo volumio[1019]: info: [1714736881524] CoreMusicLibrary::Adding element LastFM May 03 04:48:01 primo volumio[1019]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:01 primo volumio[1019]: Cannot find translation for source Bandcamp Discover May 03 04:48:01 primo volumio[1019]: Cannot find translation for source Mixcloud May 03 04:48:01 primo volumio[1019]: Cannot find translation for source SoundCloud May 03 04:48:01 primo volumio[1019]: Cannot find translation for source 80s80s Radio May 03 04:48:01 primo volumio[1019]: Cannot find translation for source Podcast May 03 04:48:01 primo volumio[1019]: Cannot find translation for source LastFM May 03 04:48:01 primo sudo[1286]: pam_unix(sudo:session): session closed for user root May 03 04:48:01 primo volumio[1019]: info: [LastFM] scrobbler initiated! May 03 04:48:01 primo volumio[1019]: info: [LastFM] extended logging: false May 03 04:48:01 primo volumio[1019]: info: [LastFM] try scrobble stream/radio plays: true May 03 04:48:01 primo volumio[1019]: info: [LastFM] Left init routine May 03 04:48:01 primo volumio[1019]: info: [LastFM] Socket already connected: true May 03 04:48:01 primo sudo[1290]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:01 primo volumio[1019]: info: Volumio Calling Home May 03 04:48:01 primo sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 03 04:48:01 primo sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:01 primo systemd[1]: Started Spotify Daemon. May 03 04:48:01 primo sudo[1290]: pam_unix(sudo:session): session closed for user root May 03 04:48:01 primo volumio[1308]: 2024-05-03 04:48:01 [SPTF] 11:48:01.593 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 03 04:48:01 primo volumio[1308]: 2024-05-03 04:48:01 [ERR ] Can't bind socket: Cannot assign requested address May 03 04:48:01 primo systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 03 04:48:01 primo systemd[1]: spop.service: Failed with result 'signal'. May 03 04:48:01 primo volumio[1019]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 04:48:01 primo volumio[1019]: Error: getaddrinfo EBUSY ws.audioscrobbler.com May 03 04:48:01 primo volumio[1019]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { May 03 04:48:01 primo volumio[1019]: errno: -16, May 03 04:48:01 primo volumio[1019]: code: 'EBUSY', May 03 04:48:01 primo volumio[1019]: syscall: 'getaddrinfo', May 03 04:48:01 primo volumio[1019]: hostname: 'ws.audioscrobbler.com' May 03 04:48:01 primo volumio[1019]: } May 03 04:48:01 primo volumio[1019]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 04:48:01 primo sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-03 04:47 May 03 04:48:01 primo sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:02 primo sudo[1328]: pam_unix(sudo:session): session closed for user root May 03 04:48:02 primo volumio-remote-updater[485]: [2024-05-03 04:48:02] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 03 04:48:02 primo volumio-remote-updater[485]: [2024-05-03 04:48:02] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 03 04:48:02 primo systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 03 04:48:02 primo sudo[1297]: pam_unix(sudo:session): session closed for user root May 03 04:48:02 primo sudo[1245]: pam_unix(sudo:session): session closed for user root May 03 04:48:02 primo sudo[1266]: pam_unix(sudo:session): session closed for user root May 03 04:48:02 primo systemd[1]: volumio.service: Failed with result 'exit-code'. May 03 04:48:02 primo systemd[1]: Started dynamicswap service. May 03 04:48:02 primo systemd[1]: dynamicswap.service: Succeeded. May 03 04:48:02 primo systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 03 04:48:02 primo systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. May 03 04:48:02 primo systemd[1]: Stopped Volumio Backend Module. May 03 04:48:02 primo systemd[1]: Started Volumio Backend Module. May 03 04:48:02 primo systemd[1]: Started dynamicswap service. May 03 04:48:02 primo systemd[1]: dynamicswap.service: Succeeded. May 03 04:48:02 primo dhcpcd[623]: eth0: using IPv4LL address 169.254.150.38 May 03 04:48:02 primo dhcpcd[623]: eth0: adding route to 169.254.0.0/16 May 03 04:48:02 primo dhcpcd[623]: eth0: adding default route May 03 04:48:02 primo avahi-daemon[494]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.150.38. May 03 04:48:02 primo avahi-daemon[494]: New relevant interface eth0.IPv4 for mDNS. May 03 04:48:02 primo avahi-daemon[494]: Registering new address record for 169.254.150.38 on eth0.IPv4. May 03 04:48:03 primo RoonBridge[948]: 00:00:08.714 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 03 04:48:03 primo RoonBridge[948]: Running May 03 04:48:03 primo systemd[1]: volspotconnect2.service: Service RestartSec=2s expired, scheduling restart. May 03 04:48:03 primo systemd[1]: volspotconnect2.service: Scheduled restart job, restart counter is at 4. May 03 04:48:03 primo systemd[1]: Stopped Volspotconnect2 Daemon. May 03 04:48:03 primo systemd[1]: Started Volspotconnect2 Daemon. May 03 04:48:03 primo volumio[1385]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 May 03 04:48:03 primo volumio[1385]: Reading Config from "volspotify.toml" May 03 04:48:03 primo volumio[1385]: [Vollibrespot] : Using Alsa backend with device: volumio May 03 04:48:03 primo volumio[1385]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } May 03 04:48:03 primo sh[433]: eth0: using IPv4LL address 169.254.150.38 May 03 04:48:03 primo sh[433]: eth0: adding route to 169.254.0.0/16 May 03 04:48:03 primo sh[433]: eth0: adding default route May 03 04:48:03 primo dhcpcd[471]: eth0: using IPv4LL address 169.254.150.38 May 03 04:48:03 primo dhcpcd[471]: eth0: adding route to 169.254.0.0/16 May 03 04:48:03 primo dhcpcd[471]: eth0: adding default route May 03 04:48:03 primo sh[433]: forked to background, child pid 1404 May 03 04:48:03 primo dhcpcd[471]: forked to background, child pid 1404 May 03 04:48:03 primo sh[433]: eth0=eth0 May 03 04:48:03 primo volumio[1357]: info: ------------------------------------------- May 03 04:48:03 primo volumio[1357]: info: ----- Volumio3 ---- May 03 04:48:03 primo volumio[1357]: info: ------------------------------------------- May 03 04:48:03 primo volumio[1357]: info: ----- System startup ---- May 03 04:48:03 primo volumio[1357]: info: ------------------------------------------- May 03 04:48:03 primo RoonBridge[948]: Unhandled Exception: May 03 04:48:03 primo RoonBridge[948]: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:03 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:03 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:03 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:03 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:03 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:03 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:03 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:03 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:03 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:03 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:03 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:03 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:03 primo RoonBridge[948]: [ERROR] FATAL UNHANDLED EXCEPTION: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:03 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:03 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:03 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:03 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:03 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:03 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:03 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:03 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:03 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:03 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:03 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:03 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:03 primo RoonBridge[948]: Not Running (o.) May 03 04:48:04 primo volumio[1357]: info: MYVOLUMIO Environment detected May 03 04:48:04 primo volumio[1357]: info: Plugin folders cleanup May 03 04:48:04 primo volumio[1357]: info: Scanning into folder /volumio/app/plugins/ May 03 04:48:04 primo volumio[1357]: info: Scanning category audio_interface May 03 04:48:04 primo volumio[1357]: info: Scanning category miscellanea May 03 04:48:04 primo volumio[1357]: info: Scanning category music_service May 03 04:48:04 primo volumio[1357]: info: Scanning category plugins.json May 03 04:48:04 primo volumio[1357]: info: Scanning category system_controller May 03 04:48:04 primo volumio[1357]: info: Scanning category user_interface May 03 04:48:04 primo volumio[1357]: info: Scanning into folder /data/plugins/ May 03 04:48:04 primo volumio[1357]: info: Scanning category music_service May 03 04:48:04 primo volumio[1357]: info: Scanning category system_hardware May 03 04:48:04 primo volumio[1357]: info: Scanning category user_interface May 03 04:48:04 primo volumio[1357]: info: Plugin folders cleanup completed May 03 04:48:04 primo volumio[1357]: info: ------------------------------------------- May 03 04:48:04 primo volumio[1357]: info: ----- Core plugins startup ---- May 03 04:48:04 primo volumio[1357]: info: ------------------------------------------- May 03 04:48:04 primo volumio[1357]: info: Loading plugins from folder /volumio/app/plugins/ May 03 04:48:04 primo volumio[1357]: info: Adding plugin upnp to MyMusic Plugins May 03 04:48:04 primo volumio[1357]: info: Adding plugin airplay_emulation to MyMusic Plugins May 03 04:48:04 primo volumio[1357]: info: Adding plugin upnp_browser to MyMusic Plugins May 03 04:48:04 primo volumio[1357]: info: Loading plugins from folder /data/plugins/ May 03 04:48:04 primo volumio[1357]: info: Loading plugin "system"... May 03 04:48:04 primo volumio[1357]: info: Loading plugin "appearance"... May 03 04:48:04 primo volumio[1357]: info: Loading plugin "network"... May 03 04:48:04 primo volumio[1357]: info: Refreshing Cached IP Addresses May 03 04:48:04 primo volumio[1357]: info: Loading plugin "services"... May 03 04:48:04 primo volumio[1357]: info: Loading plugin "alsa_controller"... May 03 04:48:04 primo sudo[1434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 03 04:48:04 primo sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 03 04:48:04 primo sudo[1434]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:04 primo sudo[1434]: pam_unix(sudo:session): session closed for user root May 03 04:48:04 primo sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:04 primo sudo[1436]: pam_unix(sudo:session): session closed for user root May 03 04:48:04 primo volumio[1357]: warn: Unable to locate the audio output device Schiit Bifrost 2 Unison USB. Please configure a valid output device. May 03 04:48:04 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 03 04:48:04 primo volumio[1357]: info: Loading plugin "wizard"... May 03 04:48:04 primo volumio[1357]: info: Loading plugin "networkfs"... May 03 04:48:04 primo volumio[1357]: info: Starting Udev Watcher for removable devices May 03 04:48:04 primo sudo[1460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=@((4518KKing)),ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.142.13/mediaqnap /mnt/NAS/MediaNAS May 03 04:48:04 primo sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=admin,password=@((4518KKing)),ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.142.13/mediaqnap/media/music /mnt/NAS/MediaNAS May 03 04:48:04 primo sudo[1460]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:04 primo sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:04 primo volumio[1357]: info: Ignoring mount for partition: BOOT May 03 04:48:04 primo volumio[1357]: info: Ignoring mount for partition: volumio May 03 04:48:04 primo volumio[1357]: info: Ignoring mount for partition: volumio_data May 03 04:48:04 primo volumio[1357]: error: Cannot associate FS Label, not mounting May 03 04:48:04 primo volumio[1357]: error: Cannot associate FS Label, not mounting May 03 04:48:04 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 03 04:48:04 primo volumio[1357]: info: Loading plugin "volumio_command_line_client"... May 03 04:48:04 primo volumio[1357]: info: Plugin upnp is not enabled May 03 04:48:04 primo volumio[1357]: info: Loading plugin "my_music"... May 03 04:48:04 primo volumio[1357]: info: Loading plugin "mpd"... May 03 04:48:05 primo volumio[1357]: info: Plugin upnp_browser is not enabled May 03 04:48:05 primo volumio[1357]: info: Loading plugin "alarm-clock"... May 03 04:48:05 primo volumio[1357]: info: Loading plugin "airplay_emulation"... May 03 04:48:05 primo volumio[1357]: info: Starting Shairport Sync May 03 04:48:05 primo volumio[1357]: info: Loading plugin "last_100"... May 03 04:48:05 primo volumio[1357]: info: Loading plugin "webradio"... May 03 04:48:05 primo volumio[1357]: info: Loading plugin "i2s_dacs"... May 03 04:48:05 primo volumio[1357]: info: I2S DAC not set, start Auto-detection May 03 04:48:05 primo volumio[1357]: info: Loading plugin "volumiodiscovery"... May 03 04:48:05 primo volumio[1357]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 03 04:48:05 primo volumio[1357]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 04:48:05 primo volumio[1357]: *** WARNING *** For more information see May 03 04:48:05 primo volumio[1357]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 03 04:48:05 primo node[1357]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 03 04:48:05 primo volumio[1357]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 04:48:05 primo volumio[1357]: *** WARNING *** For more information see May 03 04:48:05 primo node[1357]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 04:48:05 primo node[1357]: *** WARNING *** For more information see May 03 04:48:05 primo node[1357]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 03 04:48:05 primo node[1357]: *** WARNING *** Please fix your application to use the native API of Avahi! May 03 04:48:05 primo node[1357]: *** WARNING *** For more information see May 03 04:48:05 primo volumio[1357]: info: Applying required configuration parameters for plugin volumiodiscovery May 03 04:48:05 primo volumio[1357]: info: Discovery: Started advertising with name: primo May 03 04:48:05 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 03 04:48:05 primo volumio[1357]: info: Loading plugin "bandcamp"... May 03 04:48:05 primo volumio[1357]: info: Loading plugin "mixcloud"... May 03 04:48:05 primo volumio[1357]: info: Loading plugin "soundcloud"... May 03 04:48:05 primo volumio[1357]: info: Loading plugin "ytcr"... May 03 04:48:05 primo RoonBridge[948]: 00:00:11.470 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 03 04:48:05 primo RoonBridge[948]: Running May 03 04:48:06 primo volumio[1357]: info: Loading plugin "outputs"... May 03 04:48:06 primo volumio[1357]: info: Loading plugin "albumart"... May 03 04:48:06 primo volumio[1357]: info: Plugin example_plugin is not enabled May 03 04:48:06 primo volumio[1357]: info: Loading plugin "inputs"... May 03 04:48:06 primo volumio[1357]: info: Loading plugin "updater_comm"... May 03 04:48:06 primo volumio[1357]: info: Plugin mpdemulation is not enabled May 03 04:48:06 primo volumio[1357]: info: Loading plugin "rest_api"... May 03 04:48:06 primo volumio[1357]: info: Loading plugin "websocket"... May 03 04:48:06 primo volumio[1357]: info: Loading plugin "80s80s"... May 03 04:48:06 primo RoonBridge[948]: Unhandled Exception: May 03 04:48:06 primo RoonBridge[948]: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:06 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:06 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:06 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:06 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:06 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:06 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:06 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:06 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:06 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:06 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:06 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:06 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:06 primo RoonBridge[948]: [ERROR] FATAL UNHANDLED EXCEPTION: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:06 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:06 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:06 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:06 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:06 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:06 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:06 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:06 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:06 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:06 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:06 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:06 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:06 primo RoonBridge[948]: Not Running (o.) May 03 04:48:06 primo volumio[1357]: Forking 3 albumart workers May 03 04:48:06 primo volumio[1357]: info: Applying required configuration parameters for plugin 80s80s May 03 04:48:06 primo volumio[1357]: info: [1714736886683] [80s80s] API delay: 30 May 03 04:48:06 primo volumio[1357]: info: Loading plugin "RoonBridge"... May 03 04:48:06 primo volumio[1357]: info: Applying required configuration parameters for plugin RoonBridge May 03 04:48:06 primo volumio[1357]: info: Loading plugin "podcast"... May 03 04:48:06 primo volumio[1357]: info: ControllerPodcast::constructor May 03 04:48:07 primo volumio[1357]: info: Loading plugin "spop"... May 03 04:48:07 primo volumio-remote-updater[485]: [2024-05-03 04:48:07] [connect] Successful connection May 03 04:48:07 primo volumio[1357]: Starting albumart workers May 03 04:48:07 primo volumio[1357]: Starting albumart workers May 03 04:48:07 primo volumio[1357]: Starting albumart workers May 03 04:48:07 primo volumio[1357]: info: Loading plugin "volspotconnect2"... May 03 04:48:07 primo volumio[1357]: info: Plugin music_services_shield is not enabled May 03 04:48:07 primo volumio[1357]: info: Loading plugin "Systeminfo"... May 03 04:48:07 primo volumio[1357]: info: Loading plugin "lastfm"... May 03 04:48:08 primo volumio[1357]: info: Loading i18n strings for locale en May 03 04:48:08 primo volumio[1357]: Updating browse sources language May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::initPlayerControls May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 03 04:48:08 primo volumio[1357]: Express server listening on port 3000 May 03 04:48:08 primo volumio[1357]: [Metrics] WebUI: 4s 939.25ms May 03 04:48:08 primo volumio[1357]: info: CoreStateMachine::resetVolumioState May 03 04:48:08 primo volumio[1357]: info: CoreStateMachine::getcurrentVolume May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::volumioRetrievevolume May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 04:48:08 primo volumio[1357]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 03 04:48:08 primo volumio[1357]: info: Completed loading Core Plugins May 03 04:48:08 primo volumio[1357]: info: Preparing to generate the ALSA configuration file May 03 04:48:08 primo volumio[1357]: info: CoreStateMachine::pushState May 03 04:48:08 primo volumio[1357]: info: CorePlayQueue::getTrack 0 May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::volumioPushState May 03 04:48:08 primo volumio[1357]: info: CoreStateMachine::updateTrackBlock May 03 04:48:08 primo volumio[1357]: info: CorePlayQueue::getTrackBlock May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::volumioRetrievevolume May 03 04:48:08 primo volumio[1357]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 03 04:48:08 primo volumio[1357]: info: Reading ALSA contributions from plugins. May 03 04:48:08 primo volumio-remote-updater[485]: [2024-05-03 04:48:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714736887 101 May 03 04:48:08 primo volumio[1357]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 May 03 04:48:08 primo volumio[1357]: info: CoreStateMachine::pushState May 03 04:48:08 primo volumio[1357]: info: CorePlayQueue::getTrack 0 May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::volumioPushState May 03 04:48:08 primo volumio[1357]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 May 03 04:48:08 primo volumio[1357]: info: Reloading queue from file May 03 04:48:08 primo volumio[1357]: info: CoreStateMachine::setRepeat null single undefined May 03 04:48:08 primo volumio[1357]: info: CoreStateMachine::pushState May 03 04:48:08 primo volumio[1357]: info: CorePlayQueue::getTrack 0 May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::volumioPushState May 03 04:48:08 primo volumio[1357]: info: CoreStateMachine::setRandom null May 03 04:48:08 primo volumio[1357]: info: CoreStateMachine::pushState May 03 04:48:08 primo volumio[1357]: info: CorePlayQueue::getTrack 0 May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::volumioPushState May 03 04:48:08 primo volumio[1357]: info: Setting Device type: Tinkerboard May 03 04:48:08 primo volumio[1357]: info: Asound.conf file unchanged, so no further update is needed May 03 04:48:08 primo volumio[1357]: info: Output device has changed, restarting MPD May 03 04:48:08 primo volumio[1357]: info: Output device has changed, restarting Shairport Sync May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 04:48:08 primo sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 03 04:48:08 primo sudo[1572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 03 04:48:08 primo sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:08 primo sudo[1570]: pam_unix(sudo:session): session closed for user root May 03 04:48:08 primo sudo[1572]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:08 primo RoonBridge[948]: 00:00:14.211 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 03 04:48:08 primo volumio[1357]: info: ___________ START PLUGINS ___________ May 03 04:48:08 primo volumio[1357]: info: ControllerMpd::onStart: Initializing MPD May 03 04:48:08 primo volumio[1357]: info: Creating MPD Configuration file May 03 04:48:08 primo RoonBridge[948]: Running May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 04:48:08 primo sudo[1596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 03 04:48:08 primo sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:08 primo sudo[1599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 03 04:48:08 primo sudo[1596]: pam_unix(sudo:session): session closed for user root May 03 04:48:08 primo sudo[1599]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:08 primo volumio[1357]: info: [1714736888674] CoreMusicLibrary::Adding element Last_100 May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:08 primo volumio[1357]: info: [1714736888679] CoreMusicLibrary::Adding element Webradio May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:08 primo volumio[1357]: info: [1714736888691] CoreMusicLibrary::Adding element Bandcamp Discover May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:08 primo volumio[1357]: Cannot find translation for source Bandcamp Discover May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:08 primo volumio[1357]: info: [1714736888698] CoreMusicLibrary::Adding element Mixcloud May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:08 primo volumio[1357]: Cannot find translation for source Bandcamp Discover May 03 04:48:08 primo volumio[1357]: Cannot find translation for source Mixcloud May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:08 primo volumio[1357]: info: [1714736888706] CoreMusicLibrary::Adding element SoundCloud May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:08 primo volumio[1357]: Cannot find translation for source Bandcamp Discover May 03 04:48:08 primo volumio[1357]: Cannot find translation for source Mixcloud May 03 04:48:08 primo volumio[1357]: Cannot find translation for source SoundCloud May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:08 primo volumio[1357]: info: [1714736888719] CoreMusicLibrary::Adding element 80s80s Radio May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:08 primo volumio[1357]: Cannot find translation for source Bandcamp Discover May 03 04:48:08 primo volumio[1357]: Cannot find translation for source Mixcloud May 03 04:48:08 primo volumio[1357]: Cannot find translation for source SoundCloud May 03 04:48:08 primo volumio[1357]: Cannot find translation for source 80s80s Radio May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:08 primo volumio[1357]: info: [1714736888737] CoreMusicLibrary::Adding element Podcast May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:08 primo volumio[1357]: Cannot find translation for source Bandcamp Discover May 03 04:48:08 primo volumio[1357]: Cannot find translation for source Mixcloud May 03 04:48:08 primo volumio[1357]: Cannot find translation for source SoundCloud May 03 04:48:08 primo volumio[1357]: Cannot find translation for source 80s80s Radio May 03 04:48:08 primo volumio[1357]: Cannot find translation for source Podcast May 03 04:48:08 primo sudo[1621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service May 03 04:48:08 primo volumio[1357]: [SpotifyConnect] Creating VLS config file May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:08 primo sudo[1621]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:08 primo volumio[1357]: [SpotifyConnect] Starting metadata listener May 03 04:48:08 primo sudo[1626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 03 04:48:08 primo sudo[1621]: pam_unix(sudo:session): session closed for user root May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:08 primo volumio[1357]: info: [1714736888784] CoreMusicLibrary::Adding element LastFM May 03 04:48:08 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:08 primo volumio[1357]: Cannot find translation for source Bandcamp Discover May 03 04:48:08 primo volumio[1357]: Cannot find translation for source Mixcloud May 03 04:48:08 primo volumio[1357]: Cannot find translation for source SoundCloud May 03 04:48:08 primo volumio[1357]: Cannot find translation for source 80s80s Radio May 03 04:48:08 primo volumio[1357]: Cannot find translation for source Podcast May 03 04:48:08 primo volumio[1357]: Cannot find translation for source LastFM May 03 04:48:08 primo sudo[1626]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:08 primo volumio[1357]: info: [LastFM] scrobbler initiated! May 03 04:48:08 primo volumio[1357]: info: [LastFM] extended logging: false May 03 04:48:08 primo volumio[1357]: info: [LastFM] try scrobble stream/radio plays: true May 03 04:48:08 primo volumio[1357]: info: [LastFM] Left init routine May 03 04:48:08 primo volumio[1357]: info: [LastFM] Socket already connected: true May 03 04:48:08 primo volumio[1357]: info: Volumio Calling Home May 03 04:48:08 primo systemd[1]: Started Spotify Daemon. May 03 04:48:08 primo sudo[1635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 03 04:48:08 primo sudo[1626]: pam_unix(sudo:session): session closed for user root May 03 04:48:08 primo volumio[1640]: 2024-05-03 04:48:08 [SPTF] 11:48:08.846 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 03 04:48:08 primo sudo[1635]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:08 primo volumio[1640]: 2024-05-03 04:48:08 [SPTF] 11:48:08.861 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 03 04:48:08 primo sudo[1635]: pam_unix(sudo:session): session closed for user root May 03 04:48:08 primo volumio[1357]: [SpotifyConnect] Vollibrespot Daemon service started! May 03 04:48:08 primo volumio[1357]: [Metrics] SpotifyConnect: 0s 198.38ms May 03 04:48:08 primo volumio[1357]: info: SpopD Daemon Started May 03 04:48:08 primo volumio[1357]: info: MPD Permissions set May 03 04:48:08 primo volumio[1357]: info: MPD Permissions set May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 03 04:48:09 primo kernel: nf_conntrack: automatic helper assignment is deprecated and it will be removed soon. Use the iptables CT target to attach helpers instead. May 03 04:48:09 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 03 04:48:09 primo volumio[1357]: info: Starting Shairport Sync May 03 04:48:09 primo volumio[1357]: info: Starting Shairport Sync May 03 04:48:09 primo volumio[1357]: info: Starting Shairport Sync May 03 04:48:09 primo sudo[1671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 03 04:48:09 primo sudo[1671]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:09 primo sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 03 04:48:09 primo sudo[1677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 03 04:48:09 primo sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:09 primo sudo[1677]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:09 primo dhcpcd[1404]: eth0: offered 192.168.142.132 from 192.168.142.1 May 03 04:48:09 primo dhcpcd[1404]: eth0: probing address 192.168.142.132/24 May 03 04:48:09 primo RoonBridge[948]: Unhandled Exception: May 03 04:48:09 primo RoonBridge[948]: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:09 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:09 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:09 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:09 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:09 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:09 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:09 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:09 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:09 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:09 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:09 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:09 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:09 primo RoonBridge[948]: [ERROR] FATAL UNHANDLED EXCEPTION: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:09 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:09 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:09 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:09 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:09 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:09 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:09 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:09 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:09 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:09 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:09 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:09 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:09 primo RoonBridge[948]: Not Running (o.) May 03 04:48:09 primo dhcpcd[623]: eth0: offered 192.168.142.132 from 192.168.142.1 May 03 04:48:09 primo dhcpcd[623]: eth0: probing address 192.168.142.132/24 May 03 04:48:11 primo RoonBridge[948]: 00:00:16.947 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 03 04:48:11 primo RoonBridge[948]: Running May 03 04:48:11 primo RoonBridge[948]: Unhandled Exception: May 03 04:48:11 primo RoonBridge[948]: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:11 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:11 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:11 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:11 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:11 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:11 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:11 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:11 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:11 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:11 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:11 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:11 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:11 primo RoonBridge[948]: [ERROR] FATAL UNHANDLED EXCEPTION: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:11 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:11 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:11 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:11 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:11 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:11 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:11 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:11 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:11 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:11 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:11 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:11 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:11 primo RoonBridge[948]: Not Running (o.) May 03 04:48:11 primo volumio[1357]: verbose: New Socket.io Connection to 169.254.150.38:3000 from 192.168.142.169 UA: node-XMLHttpRequest Total Clients: 3 May 03 04:48:11 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket May 03 04:48:12 primo volumio[1357]: info: CoreCommandRouter::volumioGetState May 03 04:48:12 primo volumio[1357]: info: CorePlayQueue::getTrack 0 May 03 04:48:12 primo volumio[1357]: error: Cannot associate FS Label, not mounting May 03 04:48:12 primo volumio[1357]: info: Mounting Device ArmorHD May 03 04:48:12 primo sudo[1703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/ArmorHD -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 03 04:48:12 primo systemd[1]: Started Helper to synchronize boot up for ifupdown. May 03 04:48:12 primo sudo[1703]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:12 primo systemd[1]: Starting Raise network interfaces... May 03 04:48:12 primo systemd[1]: Started Raise network interfaces. May 03 04:48:12 primo systemd[1]: Reached target Network. May 03 04:48:12 primo systemd[1]: Starting Permit User Sessions... May 03 04:48:12 primo systemd[1]: Starting Music Player Daemon... May 03 04:48:12 primo systemd[1]: Starting OpenBSD Secure Shell server... May 03 04:48:12 primo systemd[1]: Starting Network Time Service... May 03 04:48:12 primo systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. May 03 04:48:12 primo systemd[1]: Reached target Network is Online. May 03 04:48:12 primo systemd[1]: Starting LSB: Brings up/down network automatically... May 03 04:48:12 primo systemd[1]: Starting Samba NMB Daemon... May 03 04:48:12 primo systemd[1]: Starting /etc/rc.local Compatibility... May 03 04:48:12 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 03 04:48:12 primo sudo[1671]: pam_unix(sudo:session): session closed for user root May 03 04:48:12 primo sudo[1677]: pam_unix(sudo:session): session closed for user root May 03 04:48:12 primo systemd[1]: Started Permit User Sessions. May 03 04:48:12 primo sudo[1675]: pam_unix(sudo:session): session closed for user root May 03 04:48:12 primo sshd[1787]: Server listening on 0.0.0.0 port 22. May 03 04:48:12 primo sshd[1787]: Server listening on :: port 22. May 03 04:48:12 primo systemd[1]: Started OpenBSD Secure Shell server. May 03 04:48:12 primo ntpd[1769]: ntpd 4.2.8p12@1.3728-o (1): Starting May 03 04:48:12 primo ntpd[1769]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 03 04:48:12 primo systemd[1]: Started Network Time Service. May 03 04:48:12 primo ntpd[1800]: proto: precision = 0.583 usec (-21) May 03 04:48:12 primo ntpd[1800]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 03 04:48:12 primo ntpd[1800]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 03 04:48:12 primo ntpd[1800]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 493 days ago May 03 04:48:12 primo ntpd[1800]: Listen and drop on 0 v6wildcard [::]:123 May 03 04:48:12 primo ntpd[1800]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 03 04:48:12 primo ntpd[1800]: Listen normally on 2 lo 127.0.0.1:123 May 03 04:48:12 primo ntpd[1800]: Listen normally on 3 eth0 169.254.150.38:123 May 03 04:48:12 primo ntpd[1800]: Listening on routing socket on fd #20 for interface updates May 03 04:48:12 primo ntpd[1800]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 03 04:48:12 primo ntpd[1800]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 03 04:48:12 primo sudo[1795]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 0x48 0x48 May 03 04:48:12 primo ifplugd(eth0)[1814]: ifplugd 0.28 initializing. May 03 04:48:12 primo ifplugd(eth0)[1814]: Using interface eth0/04:92:26:C5:97:66 with driver (version: March_2013) May 03 04:48:12 primo ifplugd(eth0)[1814]: Using detection mode: SIOCETHTOOL May 03 04:48:12 primo ifplugd(eth0)[1814]: Initialization complete, link beat detected. May 03 04:48:12 primo sudo[1795]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:12 primo sudo[1795]: pam_unix(sudo:session): session closed for user root May 03 04:48:12 primo systemd[1]: Started /etc/rc.local Compatibility. May 03 04:48:12 primo systemd[1]: Starting Hold until boot process finishes up... May 03 04:48:12 primo systemd[1]: Starting Terminate Plymouth Boot Screen... May 03 04:48:12 primo systemd[1]: Received SIGRTMIN+21 from PID 328 (plymouthd). May 03 04:48:12 primo kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 03 04:48:12 primo kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 03 04:48:12 primo kernel: CIFS VFS: cifs_mount failed w/return code = -115 May 03 04:48:12 primo kernel: CIFS VFS: cifs_mount failed w/return code = -115 May 03 04:48:12 primo sudo[1462]: pam_unix(sudo:session): session closed for user root May 03 04:48:12 primo sudo[1460]: pam_unix(sudo:session): session closed for user root May 03 04:48:12 primo systemd[1]: Received SIGRTMIN+21 from PID 328 (plymouthd). May 03 04:48:12 primo systemd[1]: plymouth-quit-wait.service: Succeeded. May 03 04:48:12 primo systemd[1]: Started Hold until boot process finishes up. May 03 04:48:12 primo ifplugd(eth0)[1814]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 03 04:48:12 primo systemd[1]: plymouth-start.service: Succeeded. May 03 04:48:12 primo systemd[1]: plymouth-quit.service: Succeeded. May 03 04:48:12 primo systemd[1]: Started Terminate Plymouth Boot Screen. May 03 04:48:12 primo systemd[1]: Started Serial Getty on ttyS3. May 03 04:48:12 primo systemd[1]: Reached target Login Prompts. May 03 04:48:12 primo ifplugd(eth0)[1814]: client: ifup: interface eth0 already configured May 03 04:48:12 primo ifplugd(eth0)[1814]: Program executed successfully. May 03 04:48:12 primo ifplugd[1770]: Network Interface Plugging Daemon...start eth0...done. May 03 04:48:12 primo systemd[1]: Started LSB: Brings up/down network automatically. May 03 04:48:13 primo nmbd[1782]: [2024/05/03 04:48:13.125824, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) May 03 04:48:13 primo nmbd[1782]: started asyncdns process 1840 May 03 04:48:13 primo nmbd[1782]: [2024/05/03 04:48:13.131030, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 03 04:48:13 primo systemd[1]: Started Samba NMB Daemon. May 03 04:48:13 primo nmbd[1782]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 03 04:48:13 primo systemd[1]: Starting Samba Winbind Daemon... May 03 04:48:13 primo systemd[1]: systemd-fsckd.service: Succeeded. May 03 04:48:13 primo winbindd[1841]: [2024/05/03 04:48:13.365313, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) May 03 04:48:13 primo winbindd[1841]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 03 04:48:13 primo winbindd[1841]: [2024/05/03 04:48:13.374246, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 03 04:48:13 primo winbindd[1841]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 03 04:48:13 primo systemd[1]: Started Samba Winbind Daemon. May 03 04:48:13 primo systemd[1]: Starting Samba SMB Daemon... May 03 04:48:13 primo smbd[1846]: [2024/05/03 04:48:13.668719, 0] ../lib/util/become_daemon.c:138(daemon_ready) May 03 04:48:13 primo smbd[1846]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 03 04:48:13 primo systemd[1]: Started Samba SMB Daemon. May 03 04:48:13 primo systemd[1]: Reached target Multi-User System. May 03 04:48:13 primo systemd[1]: Reached target Graphical Interface. May 03 04:48:13 primo systemd[1]: Starting Update UTMP about System Runlevel Changes... May 03 04:48:13 primo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 03 04:48:13 primo systemd[1]: Started Update UTMP about System Runlevel Changes. May 03 04:48:13 primo RoonBridge[948]: 00:00:19.481 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 03 04:48:13 primo RoonBridge[948]: Running May 03 04:48:13 primo volumio[1640]: 2024-05-03 04:48:13 [SPTF] 11:48:13.865 E [ap:1694] AP Socket Error: Timeout reached (20000) May 03 04:48:13 primo volumio[1640]: 2024-05-03 04:48:13 [SPTF] 11:48:13.865 E [ap:3953] Connection error: 117 May 03 04:48:13 primo volumio[1640]: 2024-05-03 04:48:13 [SPTF] 11:48:13.865 I [ap:1752] Connecting to AP ap.spotify.com:443 May 03 04:48:14 primo mpd[1767]: May 03 04:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 03 04:48:14 primo RoonBridge[948]: Unhandled Exception: May 03 04:48:14 primo RoonBridge[948]: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:14 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:14 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:14 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:14 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:14 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:14 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:14 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:14 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:14 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:14 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:14 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:14 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:14 primo RoonBridge[948]: [ERROR] FATAL UNHANDLED EXCEPTION: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:14 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:14 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:14 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:14 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:14 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:14 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:14 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:14 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:14 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:14 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:14 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:14 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:14 primo RoonBridge[948]: Not Running (o.) May 03 04:48:14 primo systemd[1]: Started Music Player Daemon. May 03 04:48:14 primo systemd[1]: Startup finished in 15.833s (kernel) + 33.290s (userspace) = 49.124s. May 03 04:48:14 primo sudo[1572]: pam_unix(sudo:session): session closed for user root May 03 04:48:14 primo sudo[1599]: pam_unix(sudo:session): session closed for user root May 03 04:48:14 primo dhcpcd[623]: eth0: leased 192.168.142.132 for 86400 seconds May 03 04:48:14 primo dhcpcd[623]: eth0: adding route to 192.168.142.0/24 May 03 04:48:14 primo dhcpcd[623]: eth0: changing default route via 192.168.142.1 May 03 04:48:14 primo avahi-daemon[494]: Registering new address record for 192.168.142.132 on eth0.IPv4. May 03 04:48:14 primo dhcpcd[1404]: eth0: deleted default route May 03 04:48:14 primo ntpd[1800]: ntpd exiting on signal 15 (Terminated) May 03 04:48:14 primo systemd[1]: Stopping Network Time Service... May 03 04:48:14 primo systemd[1]: ntp.service: Succeeded. May 03 04:48:14 primo systemd[1]: Stopped Network Time Service. May 03 04:48:14 primo systemd[1]: Starting Network Time Service... May 03 04:48:14 primo ntpd[1906]: ntpd 4.2.8p12@1.3728-o (1): Starting May 03 04:48:14 primo ntpd[1906]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 03 04:48:14 primo systemd[1]: Started Network Time Service. May 03 04:48:15 primo avahi-daemon[494]: Withdrawing address record for 169.254.150.38 on eth0. May 03 04:48:15 primo avahi-daemon[494]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.150.38. May 03 04:48:15 primo ntpd[1912]: proto: precision = 0.583 usec (-21) May 03 04:48:15 primo avahi-daemon[494]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.142.132. May 03 04:48:15 primo dhcpcd[623]: eth0: deleting route to 169.254.0.0/16 May 03 04:48:15 primo ntpd[1912]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 03 04:48:15 primo ntpd[1912]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 03 04:48:15 primo ntpd[1912]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 493 days ago May 03 04:48:15 primo ntpd[1912]: Listen and drop on 0 v6wildcard [::]:123 May 03 04:48:15 primo ntpd[1912]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 03 04:48:15 primo ntpd[1912]: Listen normally on 2 lo 127.0.0.1:123 May 03 04:48:15 primo ntpd[1912]: Listen normally on 3 eth0 192.168.142.132:123 May 03 04:48:15 primo ntpd[1912]: Listening on routing socket on fd #20 for interface updates May 03 04:48:15 primo ntpd[1912]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 03 04:48:15 primo ntpd[1912]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 03 04:48:15 primo systemd[1]: systemd-hostnamed.service: Succeeded. May 03 04:48:15 primo nmbd[1782]: [2024/05/03 04:48:15.145218, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:15 primo nmbd[1782]: Packet send failed to 169.254.255.255(137) ERRNO=Invalid argument May 03 04:48:15 primo nmbd[1782]: [2024/05/03 04:48:15.145369, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 03 04:48:15 primo nmbd[1782]: retransmit_or_expire_response_records: Failed to resend packet id 7099 to IP 169.254.255.255 on subnet 169.254.150.38 May 03 04:48:15 primo nmbd[1782]: [2024/05/03 04:48:15.145426, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:15 primo nmbd[1782]: Packet send failed to 169.254.255.255(137) ERRNO=Invalid argument May 03 04:48:15 primo nmbd[1782]: [2024/05/03 04:48:15.145472, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 03 04:48:15 primo nmbd[1782]: retransmit_or_expire_response_records: Failed to resend packet id 7100 to IP 169.254.255.255 on subnet 169.254.150.38 May 03 04:48:15 primo nmbd[1782]: [2024/05/03 04:48:15.145545, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:15 primo nmbd[1782]: Packet send failed to 169.254.255.255(137) ERRNO=Invalid argument May 03 04:48:15 primo nmbd[1782]: [2024/05/03 04:48:15.145594, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 03 04:48:15 primo nmbd[1782]: retransmit_or_expire_response_records: Failed to resend packet id 7101 to IP 169.254.255.255 on subnet 169.254.150.38 May 03 04:48:15 primo nmbd[1782]: [2024/05/03 04:48:15.145647, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:15 primo nmbd[1782]: Packet send failed to 169.254.255.255(137) ERRNO=Invalid argument May 03 04:48:15 primo nmbd[1782]: [2024/05/03 04:48:15.145697, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 03 04:48:15 primo nmbd[1782]: retransmit_or_expire_response_records: Failed to resend packet id 7102 to IP 169.254.255.255 on subnet 169.254.150.38 May 03 04:48:15 primo nmbd[1782]: [2024/05/03 04:48:15.145747, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:15 primo nmbd[1782]: Packet send failed to 169.254.255.255(137) ERRNO=Invalid argument May 03 04:48:15 primo nmbd[1782]: [2024/05/03 04:48:15.145796, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 03 04:48:15 primo nmbd[1782]: retransmit_or_expire_response_records: Failed to resend packet id 7103 to IP 169.254.255.255 on subnet 169.254.150.38 May 03 04:48:15 primo dhcpcd[1404]: eth0: leased 192.168.142.132 for 86400 seconds May 03 04:48:15 primo dhcpcd[1404]: eth0: adding route to 192.168.142.0/24 May 03 04:48:15 primo dhcpcd[1404]: eth0: adding default route via 192.168.142.1 May 03 04:48:15 primo ntpd[1912]: ntpd exiting on signal 15 (Terminated) May 03 04:48:15 primo systemd[1]: Stopping Network Time Service... May 03 04:48:15 primo systemd[1]: ntp.service: Succeeded. May 03 04:48:15 primo systemd[1]: Stopped Network Time Service. May 03 04:48:15 primo systemd[1]: Starting Network Time Service... May 03 04:48:15 primo ntpd[1960]: ntpd 4.2.8p12@1.3728-o (1): Starting May 03 04:48:15 primo ntpd[1960]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 03 04:48:15 primo systemd[1]: Started Network Time Service. May 03 04:48:15 primo ntpd[1966]: proto: precision = 0.583 usec (-21) May 03 04:48:15 primo ntpd[1966]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 03 04:48:15 primo ntpd[1966]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 03 04:48:15 primo ntpd[1966]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 493 days ago May 03 04:48:15 primo ntpd[1966]: Listen and drop on 0 v6wildcard [::]:123 May 03 04:48:15 primo ntpd[1966]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 03 04:48:15 primo dhcpcd[1404]: eth0: deleting route to 169.254.0.0/16 May 03 04:48:15 primo ntpd[1966]: Listen normally on 2 lo 127.0.0.1:123 May 03 04:48:15 primo ntpd[1966]: Listen normally on 3 eth0 192.168.142.132:123 May 03 04:48:15 primo ntpd[1966]: Listening on routing socket on fd #20 for interface updates May 03 04:48:15 primo ntpd[1966]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 03 04:48:15 primo ntpd[1966]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized May 03 04:48:16 primo nmbd[1782]: [2024/05/03 04:48:16.147035, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:16 primo nmbd[1782]: Packet send failed to 169.254.255.255(137) ERRNO=Invalid argument May 03 04:48:16 primo nmbd[1782]: [2024/05/03 04:48:16.147167, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 03 04:48:16 primo nmbd[1782]: retransmit_or_expire_response_records: Failed to resend packet id 7099 to IP 169.254.255.255 on subnet 169.254.150.38 May 03 04:48:16 primo nmbd[1782]: [2024/05/03 04:48:16.147227, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:16 primo nmbd[1782]: Packet send failed to 169.254.255.255(137) ERRNO=Invalid argument May 03 04:48:16 primo nmbd[1782]: [2024/05/03 04:48:16.147278, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 03 04:48:16 primo nmbd[1782]: retransmit_or_expire_response_records: Failed to resend packet id 7100 to IP 169.254.255.255 on subnet 169.254.150.38 May 03 04:48:16 primo nmbd[1782]: [2024/05/03 04:48:16.147329, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:16 primo nmbd[1782]: Packet send failed to 169.254.255.255(137) ERRNO=Invalid argument May 03 04:48:16 primo nmbd[1782]: [2024/05/03 04:48:16.147379, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 03 04:48:16 primo nmbd[1782]: retransmit_or_expire_response_records: Failed to resend packet id 7101 to IP 169.254.255.255 on subnet 169.254.150.38 May 03 04:48:16 primo nmbd[1782]: [2024/05/03 04:48:16.147432, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:16 primo nmbd[1782]: Packet send failed to 169.254.255.255(137) ERRNO=Invalid argument May 03 04:48:16 primo nmbd[1782]: [2024/05/03 04:48:16.147479, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 03 04:48:16 primo nmbd[1782]: retransmit_or_expire_response_records: Failed to resend packet id 7102 to IP 169.254.255.255 on subnet 169.254.150.38 May 03 04:48:16 primo nmbd[1782]: [2024/05/03 04:48:16.147528, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:16 primo nmbd[1782]: Packet send failed to 169.254.255.255(137) ERRNO=Invalid argument May 03 04:48:16 primo nmbd[1782]: [2024/05/03 04:48:16.147576, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 03 04:48:16 primo nmbd[1782]: retransmit_or_expire_response_records: Failed to resend packet id 7103 to IP 169.254.255.255 on subnet 169.254.150.38 May 03 04:48:16 primo ntpd[1966]: Soliciting pool server 152.70.159.102 May 03 04:48:16 primo RoonBridge[948]: 00:00:22.096 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 03 04:48:16 primo RoonBridge[948]: Running May 03 04:48:16 primo RoonBridge[948]: Unhandled Exception: May 03 04:48:16 primo RoonBridge[948]: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:16 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:16 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:16 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:16 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:16 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:16 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:16 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:16 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:16 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:16 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:16 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:16 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:16 primo RoonBridge[948]: [ERROR] FATAL UNHANDLED EXCEPTION: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:16 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:16 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:16 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:16 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:16 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:16 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:16 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:16 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:16 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:16 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:16 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:16 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:16 primo RoonBridge[948]: Not Running (o.) May 03 04:48:17 primo nmbd[1782]: [2024/05/03 04:48:17.148800, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:17 primo nmbd[1782]: Packet send failed to 169.254.255.255(137) ERRNO=Invalid argument May 03 04:48:17 primo nmbd[1782]: [2024/05/03 04:48:17.148945, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 03 04:48:17 primo nmbd[1782]: retransmit_or_expire_response_records: Failed to resend packet id 7099 to IP 169.254.255.255 on subnet 169.254.150.38 May 03 04:48:17 primo nmbd[1782]: [2024/05/03 04:48:17.149007, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:17 primo nmbd[1782]: Packet send failed to 169.254.255.255(137) ERRNO=Invalid argument May 03 04:48:17 primo nmbd[1782]: [2024/05/03 04:48:17.149058, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 03 04:48:17 primo nmbd[1782]: retransmit_or_expire_response_records: Failed to resend packet id 7100 to IP 169.254.255.255 on subnet 169.254.150.38 May 03 04:48:17 primo nmbd[1782]: [2024/05/03 04:48:17.149109, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:17 primo nmbd[1782]: Packet send failed to 169.254.255.255(137) ERRNO=Invalid argument May 03 04:48:17 primo nmbd[1782]: [2024/05/03 04:48:17.149159, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 03 04:48:17 primo nmbd[1782]: retransmit_or_expire_response_records: Failed to resend packet id 7101 to IP 169.254.255.255 on subnet 169.254.150.38 May 03 04:48:17 primo nmbd[1782]: [2024/05/03 04:48:17.149209, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:17 primo nmbd[1782]: Packet send failed to 169.254.255.255(137) ERRNO=Invalid argument May 03 04:48:17 primo nmbd[1782]: [2024/05/03 04:48:17.149256, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 03 04:48:17 primo nmbd[1782]: retransmit_or_expire_response_records: Failed to resend packet id 7102 to IP 169.254.255.255 on subnet 169.254.150.38 May 03 04:48:17 primo nmbd[1782]: [2024/05/03 04:48:17.149304, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:17 primo nmbd[1782]: Packet send failed to 169.254.255.255(137) ERRNO=Invalid argument May 03 04:48:17 primo nmbd[1782]: [2024/05/03 04:48:17.149350, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) May 03 04:48:17 primo nmbd[1782]: retransmit_or_expire_response_records: Failed to resend packet id 7103 to IP 169.254.255.255 on subnet 169.254.150.38 May 03 04:48:17 primo ntpd[1966]: Soliciting pool server 104.167.241.253 May 03 04:48:17 primo ntpd[1966]: Soliciting pool server 205.233.73.201 May 03 04:48:18 primo nmbd[1782]: [2024/05/03 04:48:18.150637, 0] ../source3/libsmb/nmblib.c:917(send_udp) May 03 04:48:18 primo nmbd[1782]: Packet send failed to 169.254.255.255(138) ERRNO=Invalid argument May 03 04:48:18 primo ntpd[1966]: Soliciting pool server 208.67.75.242 May 03 04:48:18 primo ntpd[1966]: Soliciting pool server 108.59.2.24 May 03 04:48:18 primo ntpd[1966]: Soliciting pool server 204.2.134.163 May 03 04:48:18 primo volumio[1640]: 2024-05-03 04:48:18 [SPTF] 11:48:18.871 E [ap:1694] AP Socket Error: Timeout reached (20000) May 03 04:48:18 primo volumio[1640]: 2024-05-03 04:48:18 [SPTF] 11:48:18.871 E [ap:3953] Connection error: 117 May 03 04:48:18 primo volumio[1640]: 2024-05-03 04:48:18 [SPTF] 11:48:18.871 I [ap:1752] Connecting to AP ap.spotify.com:80 May 03 04:48:18 primo RoonBridge[948]: 00:00:24.634 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 03 04:48:18 primo RoonBridge[948]: Running May 03 04:48:19 primo ntpd[1966]: Soliciting pool server 207.246.65.226 May 03 04:48:19 primo ntpd[1966]: Soliciting pool server 23.150.41.123 May 03 04:48:19 primo ntpd[1966]: Soliciting pool server 104.156.246.53 May 03 04:48:19 primo ntpd[1966]: Soliciting pool server 137.190.2.4 May 03 04:48:19 primo RoonBridge[948]: Unhandled Exception: May 03 04:48:19 primo RoonBridge[948]: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:19 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:19 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:19 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:19 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:19 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:19 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:19 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:19 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:19 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:19 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:19 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:19 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:19 primo RoonBridge[948]: [ERROR] FATAL UNHANDLED EXCEPTION: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:19 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:19 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:19 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:19 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:19 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:19 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:19 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:19 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:19 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:19 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:19 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:19 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:19 primo RoonBridge[948]: Not Running (o.) May 03 04:48:20 primo ntpd[1966]: Soliciting pool server 66.205.249.28 May 03 04:48:20 primo ntpd[1966]: Soliciting pool server 73.193.62.54 May 03 04:48:20 primo ntpd[1966]: Soliciting pool server 5.78.89.3 May 03 04:48:20 primo ntpd[1966]: Soliciting pool server 162.159.200.1 May 03 04:48:21 primo ntfs-3g[1997]: Version 2017.3.23AR.3 integrated FUSE 28 May 03 04:48:21 primo ntfs-3g[1997]: Mounted /dev/sda2 (Read-Write, label "ArmorHD", NTFS 3.1) May 03 04:48:21 primo ntfs-3g[1997]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 May 03 04:48:21 primo ntfs-3g[1997]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda2,blkdev,blksize=4096 May 03 04:48:21 primo ntfs-3g[1997]: Global ownership and permissions enforced, configuration type 7 May 03 04:48:21 primo sudo[1703]: pam_unix(sudo:session): session closed for user root May 03 04:48:21 primo volumio[1357]: info: Connecting to daemon May 03 04:48:21 primo ntpd[1966]: Soliciting pool server 69.10.223.134 May 03 04:48:21 primo ntpd[1966]: Soliciting pool server 15.204.87.223 May 03 04:48:21 primo ntpd[1966]: Soliciting pool server 69.10.223.132 May 03 04:48:21 primo volumio[1357]: info: Cannot mount NAS MediaNAS at system boot, trial number 1 ,retrying in 5 seconds May 03 04:48:21 primo volumio[1357]: info: Cannot mount NAS MediaNAS at system boot, trial number 1 ,retrying in 5 seconds May 03 04:48:21 primo volumio[1357]: info: Shairport-Sync Started May 03 04:48:21 primo volumio[1357]: Error adding Membership: Error: addMembership EINVAL May 03 04:48:21 primo volumio[1357]: info: Shairport-Sync Started May 03 04:48:21 primo volumio[1357]: info: Shairport-Sync Started May 03 04:48:21 primo ntpd[1966]: Soliciting pool server 69.10.223.133 May 03 04:48:21 primo volumio[1357]: error: MPD error: The expression evaluated to a falsy value: May 03 04:48:21 primo volumio[1357]: assert.ok(self.idling) May 03 04:48:21 primo volumio[1357]: error: The expression evaluated to a falsy value: May 03 04:48:21 primo volumio[1357]: assert.ok(self.idling) May 03 04:48:21 primo volumio[1357]: error: updateQueue error: null May 03 04:48:21 primo volumio[1357]: info: MPD running with PID1767 May 03 04:48:21 primo volumio[1357]: ,establishing connection May 03 04:48:21 primo volumio[1357]: error: updateQueue error: null May 03 04:48:21 primo volumio[1357]: [yt-cast-receiver] Listening on port 8098 May 03 04:48:21 primo RoonBridge[948]: 00:00:27.326 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 03 04:48:21 primo RoonBridge[948]: Running May 03 04:48:22 primo RoonBridge[948]: Unhandled Exception: May 03 04:48:22 primo RoonBridge[948]: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:22 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:22 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:22 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:22 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:22 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:22 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:22 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:22 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:22 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:22 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:22 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:22 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:22 primo RoonBridge[948]: [ERROR] FATAL UNHANDLED EXCEPTION: System.TypeInitializationException: The type initializer for 'Sooloos.RoonPush' threw an exception. ---> System.TypeInitializationException: The type initializer for 'Sooloos.Bits' threw an exception. ---> System.ArgumentNullException: Value cannot be null. May 03 04:48:22 primo RoonBridge[948]: Parameter name: path2 May 03 04:48:22 primo RoonBridge[948]: at System.IO.Path.Combine (System.String path1, System.String path2) [0x00011] in <6a2b61f9dcec4fcea2ab16dbc47496b7>:0 May 03 04:48:22 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory (System.String appname, System.String appdir_dir) [0x0000e] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:22 primo RoonBridge[948]: at Base.Utils.GetLocalApplicationDataDirectory () [0x00005] in <004f65c17ddf47b787ec73afe96cdba6>:0 May 03 04:48:22 primo RoonBridge[948]: at Sooloos.SooloosRegistry.get_DefaultRoot () [0x00007] in :0 May 03 04:48:22 primo RoonBridge[948]: at Sooloos.SooloosRegistry..ctor (System.String product, System.String key) [0x0003a] in :0 May 03 04:48:22 primo RoonBridge[948]: at Sooloos.SooloosRegistry.Get (System.String product, System.String key) [0x00000] in :0 May 03 04:48:22 primo RoonBridge[948]: at Sooloos.Bits..cctor () [0x0004a] in :0 May 03 04:48:22 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:22 primo RoonBridge[948]: at Sooloos.RoonPush..cctor () [0x00000] in :0 May 03 04:48:22 primo RoonBridge[948]: --- End of inner exception stack trace --- May 03 04:48:22 primo RoonBridge[948]: at Sooloos.App.Main (System.String[] argv) [0x00000] in <144c9eaa6a4b4c9f96ce5bce4c1689ff>:0 May 03 04:48:22 primo RoonBridge[948]: Not Running (o.) May 03 04:48:23 primo volumio[1357]: info: Discovery: adding 4e118020-2572-45f7-9d89-b5489b3db281 May 03 04:48:23 primo volumio[1357]: info: Discovery: Found device primo May 03 04:48:23 primo volumio[1357]: info: CoreCommandRouter::volumioGetState May 03 04:48:23 primo volumio[1357]: info: CorePlayQueue::getTrack 0 May 03 04:48:23 primo volumio[1357]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 May 03 04:48:23 primo volumio[1357]: info: Discovery: adding b7fc5918-a0f6-4b11-ab10-c1aaddca73d6 May 03 04:48:23 primo volumio[1357]: info: Discovery: Found device mercury May 03 04:48:23 primo volumio[1357]: info: Discovery: Connecting to remote: 192.168.142.169 May 03 04:48:23 primo volumio[1357]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 03 04:48:23 primo volumio[1357]: info: [1714736903654] CoreMusicLibrary::Adding element Spotify May 03 04:48:23 primo volumio[1357]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 03 04:48:23 primo volumio[1357]: Cannot find translation for source Bandcamp Discover May 03 04:48:23 primo volumio[1357]: Cannot find translation for source Mixcloud May 03 04:48:23 primo volumio[1357]: Cannot find translation for source SoundCloud May 03 04:48:23 primo volumio[1357]: Cannot find translation for source 80s80s Radio May 03 04:48:23 primo volumio[1357]: Cannot find translation for source Podcast May 03 04:48:23 primo volumio[1357]: Cannot find translation for source LastFM May 03 04:48:23 primo volumio[1357]: Cannot find translation for source Spotify May 03 04:48:23 primo volumio[1357]: info: Completed starting Core Plugins May 03 04:48:23 primo volumio[1357]: info: ------------------------------------------- May 03 04:48:23 primo volumio[1357]: info: ----- MyVolumio plugins startup ---- May 03 04:48:23 primo volumio[1357]: info: ------------------------------------------- May 03 04:48:23 primo volumio[1357]: info: [MyVolumio PluginManager] Fetching plans data.... May 03 04:48:23 primo volumio[1357]: info: FIRST BRANCH May 03 04:48:23 primo volumio[1357]: info: Discovery: Connected to remote: 192.168.142.169 May 03 04:48:23 primo volumio[1357]: info: CoreCommandRouter::volumioGetState May 03 04:48:23 primo volumio[1357]: info: CorePlayQueue::getTrack 0 May 03 04:48:23 primo volumio[1357]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 04:48:23 primo volumio[1357]: Error: getaddrinfo ENOTFOUND ws.audioscrobbler.com May 03 04:48:23 primo volumio[1357]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { May 03 04:48:23 primo volumio[1357]: errno: -3007, May 03 04:48:23 primo volumio[1357]: code: 'ENOTFOUND', May 03 04:48:23 primo volumio[1357]: syscall: 'getaddrinfo', May 03 04:48:23 primo volumio[1357]: hostname: 'ws.audioscrobbler.com' May 03 04:48:23 primo volumio[1357]: } May 03 04:48:23 primo volumio[1357]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 03 04:48:23 primo volumio[1640]: 2024-05-03 04:48:23 [SPTF] 11:48:23.875 E [ap:1694] AP Socket Error: Timeout reached (20000) May 03 04:48:23 primo volumio[1640]: 2024-05-03 04:48:23 [SPTF] 11:48:23.875 E [ap:3953] Connection error: 117 May 03 04:48:23 primo volumio[1640]: 2024-05-03 04:48:23 [SPTF] 11:48:23.875 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 03 04:48:23 primo volumio[1640]: 2024-05-03 04:48:23 [SPTF] 11:48:23.964 I [ap:1226] Connected to AP: 104.154.127.126:4070 May 03 04:48:24 primo sudo[2022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-03 04:47 May 03 04:48:24 primo sudo[2022]: pam_unix(sudo:session): session opened for user root by (uid=0) May 03 04:48:24 primo volumio[1640]: 2024-05-03 04:48:24 [SPTF] 11:48:24.209 E [ap:3953] Connection error: 410 May 03 04:48:24 primo volumio[1640]: 2024-05-03 04:48:24 [WARN] Login failed: Bad username and/or password May 03 04:48:24 primo volumio[1640]: 2024-05-03 04:48:24 [ERR ] Could not get the playlist container. May 03 04:48:24 primo RoonBridge[948]: 00:00:29.858 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper May 03 04:48:24 primo RoonBridge[948]: Running May 03 04:48:24 primo systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 03 04:48:24 primo systemd[1]: spop.service: Failed with result 'signal'. 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:28:35 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="be8aa514427e3c503467ece9bc4fd48d"