-- Logs begin at Wed 2024-04-17 11:50:56 UTC, end at Wed 2024-04-17 11:52:17 UTC. -- Apr 17 11:51:00 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/nvram_ap6255.txt (1644 bytes) open success Apr 17 11:51:00 volumio kernel: NVRAM version: AP6255_NVRAM_V1.0_29052015 Apr 17 11:51:00 volumio kernel: dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded. Apr 17 11:51:00 volumio kernel: dhd_bus_init: enable 0x06, ready 0x06 (waited 0us) Apr 17 11:51:00 volumio kernel: bcmsdh_oob_intr_register: Enter Apr 17 11:51:00 volumio kernel: bcmsdh_oob_intr_register: HW_OOB enabled Apr 17 11:51:00 volumio kernel: bcmsdh_oob_intr_register OOB irq=100 flags=0x4 Apr 17 11:51:00 volumio kernel: bcmsdh_oob_intr_register change flags to 0x4 Apr 17 11:51:00 volumio kernel: bcmsdh_oob_intr_register: enable_irq_wake Apr 17 11:51:00 volumio kernel: bcmsdh_oob_intr_register: enable_irq_wake failed with -6 Apr 17 11:51:00 volumio kernel: Disable tdls_auto_op failed. -1 Apr 17 11:51:00 volumio kernel: dhd_conf_set_intiovar: set WLC_SET_BAND 142 0 Apr 17 11:51:00 volumio kernel: dhd_preinit_ioctls: Set tcpack_sup_mode 0 Apr 17 11:51:00 volumio kernel: dhd_apply_default_clm: Ignore clm file /lib/firmware/brcm/clm_bcmdhd.blob Apr 17 11:51:00 volumio kernel: Firmware up: op_mode=0x0005, MAC=00:90:4c:c5:12:38 Apr 17 11:51:00 volumio kernel: dhd_conf_set_country: set country SG, revision 0 Apr 17 11:51:00 volumio kernel: Country code: SG (SG/0) Apr 17 11:51:00 volumio kernel: dhd_conf_set_intiovar: set roam_off 1 Apr 17 11:51:00 volumio kernel: dhd_conf_set_intiovar: set mimo_bw_cap 1 Apr 17 11:51:00 volumio kernel: Firmware version = wl0: Aug 25 2015 18:58:57 version 7.45.69 (r581703) FWID 01-24037f6e Apr 17 11:51:00 volumio kernel: Driver: 1.363.59.144.9 (r) Firmware: wl0: Aug 25 2015 18:58:57 version 7.45.69 (r581703) FWID 01-24037f6e Apr 17 11:51:00 volumio kernel: clm = 7.14.8 Apr 17 11:51:00 volumio kernel: dhd_txglom_enable: enable 1 Apr 17 11:51:00 volumio kernel: dhd_conf_set_txglom_params: swtxglom=0, txglom_ext=0, txglom_bucket_size=0 Apr 17 11:51:00 volumio kernel: dhd_conf_set_txglom_params: txglomsize=36, deferred_tx_len=0, bus_txglom=-1 Apr 17 11:51:00 volumio kernel: dhd_conf_set_txglom_params: tx_in_rx=1, txinrx_thres=-1, dhd_txminmax=1 Apr 17 11:51:00 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=1 Apr 17 11:51:00 volumio kernel: sdioh_set_mode: set txglom_mode to multi-desc Apr 17 11:51:00 volumio kernel: dhd_conf_get_disable_proptx: fw_proptx=1, disable_proptx=-1 Apr 17 11:51:00 volumio kernel: dhd_wlfc_hostreorder_init(): successful bdcv2 tlv signaling, 64 Apr 17 11:51:00 volumio kernel: dhd_conf_set_intiovar: set ampdu_hostreorder 1 Apr 17 11:51:00 volumio kernel: wl_android_wifi_on: Success Apr 17 11:51:00 volumio kernel: wl_create_event_handler(): thread:wl_event_handler:1162 started Apr 17 11:51:00 volumio kernel: tsk Enter, tsk = 0xffffff8001579bb8 Apr 17 11:51:00 volumio kernel: CFG80211-ERROR) wl_update_wiphybands : error reading txbf_bfe_cap (-23) Apr 17 11:51:00 volumio kernel: CFG80211-ERROR) wl_update_wiphybands : error reading txbf_bfr_cap (-23) Apr 17 11:51:00 volumio kernel: dhd_open: Exit ret=0 Apr 17 11:51:00 volumio networking[4375]: done. Apr 17 11:51:00 volumio systemd[1]: Started LSB: Raise network interfaces.. Apr 17 11:51:00 volumio systemd[1]: Starting ifup for wlan0... Apr 17 11:51:00 volumio systemd[1]: Started ifup for wlan0. Apr 17 11:51:00 volumio systemd[1]: Starting ifup for eth0... Apr 17 11:51:00 volumio systemd[1]: Started ifup for eth0. Apr 17 11:51:00 volumio systemd[1]: Starting Network. Apr 17 11:51:00 volumio systemd[1]: Reached target Network. Apr 17 11:51:00 volumio systemd[1]: Starting Network is Online. Apr 17 11:51:00 volumio systemd[1]: Reached target Network is Online. Apr 17 11:51:00 volumio systemd[1]: Starting LSB: RPC portmapper replacement... Apr 17 11:51:00 volumio ifup[4459]: dhcpcd[4483]: version 6.0.5 starting Apr 17 11:51:00 volumio dhcpcd[4483]: version 6.0.5 starting Apr 17 11:51:00 volumio rpcbind[4476]: Starting rpcbind daemon.... Apr 17 11:51:00 volumio systemd[1]: Started LSB: RPC portmapper replacement. Apr 17 11:51:00 volumio systemd[1]: Starting RPC Port Mapper. Apr 17 11:51:00 volumio ifup[4459]: dhcpcd[4483]: eth0: soliciting a DHCP lease Apr 17 11:51:00 volumio dhcpcd[4483]: eth0: soliciting a DHCP lease Apr 17 11:51:00 volumio systemd[1]: Reached target RPC Port Mapper. Apr 17 11:51:00 volumio systemd[1]: Starting LSB: NFS support files common to client and server... Apr 17 11:51:00 volumio rpc.statd[4560]: Version 1.2.8 starting Apr 17 11:51:00 volumio sm-notify[4561]: Version 1.2.8 starting Apr 17 11:51:00 volumio dhcpcd[4483]: eth0: carrier lost Apr 17 11:51:00 volumio ifup[4459]: dhcpcd[4483]: eth0: carrier lost Apr 17 11:51:00 volumio kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Apr 17 11:51:00 volumio nfs-common[4554]: Starting NFS common utilities: statd idmapd. Apr 17 11:51:00 volumio systemd[1]: Started LSB: NFS support files common to client and server. Apr 17 11:51:00 volumio systemd[1]: Starting System Initialization. Apr 17 11:51:00 volumio systemd[1]: Reached target System Initialization. Apr 17 11:51:00 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. Apr 17 11:51:00 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Apr 17 11:51:00 volumio systemd[1]: Starting mpd.socket. Apr 17 11:51:00 volumio systemd[1]: Listening on mpd.socket. Apr 17 11:51:00 volumio systemd[1]: Starting D-Bus System Message Bus Socket. Apr 17 11:51:00 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Apr 17 11:51:00 volumio systemd[1]: Starting Sockets. Apr 17 11:51:00 volumio systemd[1]: Reached target Sockets. Apr 17 11:51:00 volumio systemd[1]: Starting Daily Cleanup of Temporary Directories. Apr 17 11:51:00 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Apr 17 11:51:00 volumio systemd[1]: Starting Timers. Apr 17 11:51:01 volumio systemd[1]: Reached target Timers. Apr 17 11:51:01 volumio systemd[1]: Started Manage Sound Card State (restore and store). Apr 17 11:51:01 volumio systemd[1]: Starting Restore Sound Card State... Apr 17 11:51:01 volumio systemd[1]: Starting Basic System. Apr 17 11:51:01 volumio systemd[1]: Reached target Basic System. Apr 17 11:51:01 volumio systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... Apr 17 11:51:01 volumio alsactl[4587]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:DAC Digital Playback Volume:0' : No such file or directory Apr 17 11:51:01 volumio kernel: aml_audio_i2s_mute_flag: flag=0 Apr 17 11:51:01 volumio kernel: aml_audio_set_spdif_mute: flag=0 Apr 17 11:51:01 volumio kernel: aml_snd_card: aml_m8_set_spk: aml_m8_spk_enabled=0 Apr 17 11:51:01 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Apr 17 11:51:01 volumio systemd[1]: Starting cd.service... Apr 17 11:51:01 volumio systemd[1]: Started cd.service. Apr 17 11:51:01 volumio volumio[4590]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Apr 17 11:51:01 volumio systemd[1]: Starting Volumio Iptables Module... Apr 17 11:51:01 volumio systemd[1]: Started Volumio Iptables Module. Apr 17 11:51:01 volumio systemd[1]: Starting mount disks automatically with standby... Apr 17 11:51:01 volumio systemd[1]: Started mount disks automatically with standby. Apr 17 11:51:01 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Apr 17 11:51:01 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 17 11:51:01 volumio systemd[1]: Starting volumio-remote-updater.service... Apr 17 11:51:01 volumio systemd[1]: Started volumio-remote-updater.service. Apr 17 11:51:01 volumio systemd[1]: Starting Volumio SSH enabler... Apr 17 11:51:01 volumio systemd[1]: Started Volumio SSH enabler. Apr 17 11:51:01 volumio systemd[1]: Starting Wireless Services... Apr 17 11:51:01 volumio systemd[1]: Starting Xbindrc... Apr 17 11:51:01 volumio systemd[1]: Started Xbindrc. Apr 17 11:51:01 volumio systemd[1]: Starting /etc/rc.local Compatibility... Apr 17 11:51:01 volumio systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. Apr 17 11:51:01 volumio systemd[1]: Starting Login Service... Apr 17 11:51:01 volumio systemd[1]: Starting LSB: start Winbind daemon... Apr 17 11:51:01 volumio systemd[1]: Starting LSB: start Samba daemons for the AD DC... Apr 17 11:51:01 volumio systemd[1]: Starting LSB: Start NTP daemon... Apr 17 11:51:01 volumio systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... Apr 17 11:51:01 volumio rc.local[4602]: kernel 3.14.29 Apr 17 11:51:01 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Apr 17 11:51:01 volumio systemd[1]: Starting LSB: Starts LIRC daemon.... Apr 17 11:51:01 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Apr 17 11:51:01 volumio kernel: fb: osd[0] canvas.idx =0x40 Apr 17 11:51:01 volumio kernel: fb: osd[0] canvas.addr=0x7d100000 Apr 17 11:51:01 volumio kernel: fb: osd[0] canvas.width=3840 Apr 17 11:51:01 volumio kernel: fb: osd[0] canvas.height=1440 Apr 17 11:51:01 volumio kernel: fb: osd[0] frame.width=1280 Apr 17 11:51:01 volumio kernel: fb: osd[0] frame.height=720 Apr 17 11:51:01 volumio systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... Apr 17 11:51:01 volumio systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... Apr 17 11:51:01 volumio kernel: fb: Frame buffer memory assigned at Apr 17 11:51:01 volumio kernel: fb: 1, phy: 0x000000007e900000, vir:0xffffff8003680000, size=1024K Apr 17 11:51:01 volumio kernel: fb: fb1 open, logo index:1 Apr 17 11:51:01 volumio kernel: fb: osd[1] canvas.idx =0x43 Apr 17 11:51:01 volumio kernel: fb: osd[1] canvas.addr=0x7e900000 Apr 17 11:51:01 volumio kernel: fb: osd[1] canvas.width=128 Apr 17 11:51:01 volumio kernel: fb: osd[1] canvas.height=32 Apr 17 11:51:01 volumio kernel: fb: osd[1] frame.width=32 Apr 17 11:51:01 volumio kernel: fb: osd[1] frame.height=32 Apr 17 11:51:01 volumio lirc[4646]: No valid /etc/lirc/lircd.conf has been found.. Apr 17 11:51:01 volumio lirc[4646]: Remote control support has been disabled.. Apr 17 11:51:01 volumio lirc[4646]: Reconfigure LIRC or manually replace /etc/lirc/lircd.conf to enable.. Apr 17 11:51:01 volumio kernel: vout_serve: vmode set to 720p60hz Apr 17 11:51:01 volumio kernel: fb: current vmode=1080p60hz, cmd: 0x50000 Apr 17 11:51:01 volumio kernel: tv_vout: tv_set_current_vmode[919]fps_target_mode=10 Apr 17 11:51:01 volumio kernel: tv_vout: mode is 10,sync_duration_den=1,sync_duration_num=60 Apr 17 11:51:01 volumio kernel: tv_vout: TV mode 720p60hz selected. Apr 17 11:51:01 volumio kernel: vdac_enable: on:0,module_sel:8 Apr 17 11:51:01 volumio kernel: vdac_enable: on:0,module_sel:8 Apr 17 11:51:01 volumio kernel: tv_vout: new mode =720p60hz set ok Apr 17 11:51:01 volumio kernel: vout_serve: vinfo mode is: 720p60hz Apr 17 11:51:01 volumio kernel: vout_serve: new mode 720p60hz set ok Apr 17 11:51:01 volumio kernel: hdmitx: video: get current mode: 720p60hz Apr 17 11:51:01 volumio kernel: hdmitx: update rx hdr info 0 Apr 17 11:51:01 volumio kernel: hdmitx: update physcial size: 400 250 Apr 17 11:51:01 volumio kernel: hdmitx: system: already init VIC = 0 Now VIC = 4 Apr 17 11:51:01 volumio kernel: hdmitx: rx no SCDC present indicator Apr 17 11:51:01 volumio kernel: hdmitx: ddc rd8b error 0x54 0x01 Apr 17 11:51:01 volumio kernel: hdmitx: system: irq 80000001 Apr 17 11:51:01 volumio kernel: Start = 0x10000100 End = 0x100001ff Apr 17 11:51:01 volumio kernel: [0x10000104]: 0x00000001 Apr 17 11:51:01 volumio kernel: [0x10000105]: 0x00000003 Apr 17 11:51:01 volumio kernel: [0x10000170]: 0x00000004 Apr 17 11:51:01 volumio kernel: [0x10000180]: 0x000000ff Apr 17 11:51:01 volumio kernel: [0x10000181]: 0x000000ff Apr 17 11:51:01 volumio kernel: [0x10000182]: 0x00000003 Apr 17 11:51:01 volumio kernel: [0x10000183]: 0x00000007 Apr 17 11:51:01 volumio kernel: [0x10000184]: 0x0000003f Apr 17 11:51:01 volumio kernel: [0x10000185]: 0x00000002 Apr 17 11:51:01 volumio kernel: [0x10000187]: 0x000000ff Apr 17 11:51:01 volumio kernel: [0x10000188]: 0x00000003 Apr 17 11:51:01 volumio kernel: hdmitx: ddc rd8b error 0x54 0x01 Apr 17 11:51:01 volumio kernel: hdmirx version is 1.4 or below Apr 17 11:51:01 volumio kernel: hdmitx div40: 0 Apr 17 11:51:01 volumio loadcpufreq[4620]: Loading cpufreq kernel modules...done (none). Apr 17 11:51:01 volumio ifplugd(eth0)[4684]: ifplugd 0.28 initializing. Apr 17 11:51:01 volumio ifplugd(eth0)[4684]: Using interface eth0/00:AC:2F:79:C0:FF with driver (version: March_2013) Apr 17 11:51:01 volumio ifplugd(eth0)[4684]: Using detection mode: SIOCETHTOOL Apr 17 11:51:01 volumio ifplugd(eth0)[4684]: Initialization complete, link beat not detected. Apr 17 11:51:01 volumio ifplugd[4647]: Network Interface Plugging Daemon...start eth0...done. Apr 17 11:51:01 volumio ntpd[4656]: ntpd 4.2.6p5@1.2349-o Fri Jul 22 17:59:22 UTC 2016 (1) Apr 17 11:51:01 volumio ntpd[4685]: proto: precision = 0.416 usec Apr 17 11:51:01 volumio ntp[4610]: Starting NTP server: ntpd. Apr 17 11:51:01 volumio ntpd[4685]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Apr 17 11:51:01 volumio ntpd[4685]: Listen and drop on 1 v6wildcard :: UDP 123 Apr 17 11:51:01 volumio ntpd[4685]: Listen normally on 2 lo 127.0.0.1 UDP 123 Apr 17 11:51:01 volumio ntpd[4685]: peers refreshed Apr 17 11:51:01 volumio ntpd[4685]: Listening on routing socket on fd #19 for interface updates Apr 17 11:51:01 volumio sudo[4601]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Apr 17 11:51:01 volumio sudo[4601]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:01 volumio sudo[4601]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:01 volumio kernel: hdmitx: system: set mode VIC 4 (cd0,cs0,pm1,vd0,1) Apr 17 11:51:01 volumio kernel: hdmitx: system: set pll Apr 17 11:51:01 volumio kernel: hdmitx: system: param->VIC:4 Apr 17 11:51:01 volumio kernel: hdmitx: set clk: VIC = 4 cd = 4 frac_rate = 0 Apr 17 11:51:01 volumio kernel: config HPLL = 2970000 Apr 17 11:51:01 volumio kernel: HPLL: 0xc000027b Apr 17 11:51:01 volumio kernel: config HPLL done Apr 17 11:51:01 volumio kernel: set_hpll_od3_clk_div[437] div = 6 Apr 17 11:51:01 volumio kernel: j = 2 vid_clk_div = 1 Apr 17 11:51:01 volumio kernel: hdmitx: set enc for VIC: 4 Apr 17 11:51:01 volumio kernel: hdmitx_set_hw[4947] set VIC = 4 Apr 17 11:51:01 volumio kernel: hdmitx: system: irq 80000001 Apr 17 11:51:01 volumio kernel: Start = 0x10000100 End = 0x100001ff Apr 17 11:51:01 volumio kernel: [0x10000104]: 0x00000001 Apr 17 11:51:01 volumio kernel: [0x10000105]: 0x00000003 Apr 17 11:51:01 volumio kernel: [0x10000170]: 0x00000004 Apr 17 11:51:01 volumio kernel: [0x10000180]: 0x000000ff Apr 17 11:51:01 volumio kernel: [0x10000181]: 0x000000ff Apr 17 11:51:01 volumio kernel: [0x10000182]: 0x00000003 Apr 17 11:51:01 volumio kernel: [0x10000183]: 0x00000007 Apr 17 11:51:01 volumio kernel: [0x10000184]: 0x0000003f Apr 17 11:51:01 volumio kernel: [0x10000185]: 0x00000002 Apr 17 11:51:01 volumio kernel: [0x10000187]: 0x000000ff Apr 17 11:51:01 volumio kernel: [0x10000188]: 0x00000003 Apr 17 11:51:01 volumio kernel: hdmitx: ddc w1b error 0x54 0x20 0x00 Apr 17 11:51:01 volumio kernel: hdmitx: system: irq 80000001 Apr 17 11:51:01 volumio kernel: Start = 0x10000100 End = 0x100001ff Apr 17 11:51:01 volumio kernel: [0x10000104]: 0x00000001 Apr 17 11:51:01 volumio kernel: [0x10000105]: 0x00000003 Apr 17 11:51:01 volumio kernel: [0x10000170]: 0x00000004 Apr 17 11:51:01 volumio kernel: [0x10000180]: 0x000000ff Apr 17 11:51:01 volumio kernel: [0x10000181]: 0x000000ff Apr 17 11:51:01 volumio kernel: [0x10000182]: 0x00000003 Apr 17 11:51:01 volumio kernel: [0x10000183]: 0x00000007 Apr 17 11:51:01 volumio kernel: [0x10000184]: 0x0000003f Apr 17 11:51:01 volumio kernel: [0x10000185]: 0x00000002 Apr 17 11:51:01 volumio kernel: [0x10000187]: 0x000000ff Apr 17 11:51:01 volumio kernel: [0x10000188]: 0x00000003 Apr 17 11:51:01 volumio kernel: hdmitx: ddc w1b error 0x54 0x20 0x00 Apr 17 11:51:01 volumio kernel: hdmitx: system: irq 80000001 Apr 17 11:51:01 volumio kernel: Start = 0x10000100 End = 0x100001ff Apr 17 11:51:01 volumio kernel: [0x10000104]: 0x00000001 Apr 17 11:51:01 volumio kernel: [0x10000105]: 0x00000003 Apr 17 11:51:01 volumio kernel: [0x10000170]: 0x00000004 Apr 17 11:51:01 volumio kernel: [0x10000180]: 0x000000ff Apr 17 11:51:01 volumio kernel: [0x10000181]: 0x000000ff Apr 17 11:51:01 volumio kernel: [0x10000182]: 0x00000003 Apr 17 11:51:01 volumio kernel: [0x10000183]: 0x00000007 Apr 17 11:51:01 volumio kernel: [0x10000184]: 0x0000003f Apr 17 11:51:01 volumio kernel: [0x10000185]: 0x00000002 Apr 17 11:51:01 volumio kernel: [0x10000187]: 0x000000ff Apr 17 11:51:01 volumio kernel: [0x10000188]: 0x00000003 Apr 17 11:51:01 volumio kernel: hdmitx: ddc rd8b error 0x54 0x21 Apr 17 11:51:01 volumio kernel: hdmitx: system: PHY Setting Done Apr 17 11:51:01 volumio kernel: hdmitx: system: irq 80000001 Apr 17 11:51:01 volumio kernel: Start = 0x10000100 End = 0x100001ff Apr 17 11:51:01 volumio kernel: [0x10000104]: 0x00000001 Apr 17 11:51:01 volumio kernel: [0x10000180]: 0x000000ff Apr 17 11:51:01 volumio kernel: [0x10000181]: 0x000000ff Apr 17 11:51:01 volumio kernel: [0x10000182]: 0x00000003 Apr 17 11:51:01 volumio kernel: [0x10000183]: 0x00000007 Apr 17 11:51:01 volumio kernel: [0x10000184]: 0x0000003f Apr 17 11:51:01 volumio kernel: [0x10000185]: 0x00000002 Apr 17 11:51:01 volumio kernel: [0x10000187]: 0x000000ff Apr 17 11:51:01 volumio kernel: [0x10000188]: 0x00000003 Apr 17 11:51:01 volumio kernel: Sink is HDMI device Apr 17 11:51:01 volumio kernel: hdmitx: system: packet: can't get vendor data Apr 17 11:51:01 volumio kernel: 720p60hz 60 1 Apr 17 11:51:01 volumio kernel: 720p60hz 60 1 Apr 17 11:51:01 volumio kernel: hdmtix: set audio Apr 17 11:51:01 volumio kernel: hdmitx tx_aud_src = 0 Apr 17 11:51:01 volumio kernel: hdmitx: fs = 3, cd = 4, tmds_clk = 74250 Apr 17 11:51:01 volumio kernel: hdmitx aud_n_para = 6144 Apr 17 11:51:01 volumio kernel: hdmitx set channel status Apr 17 11:51:01 volumio kernel: hdmitx: audio: Audio Type: PCM Apr 17 11:51:01 volumio kernel: fb: current vmode=720p60hz, cmd: 0x10000 Apr 17 11:51:01 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Apr 17 11:51:01 volumio systemd[1]: Starting Music Player Daemon... Apr 17 11:51:01 volumio systemd[1]: Starting D-Bus System Message Bus... Apr 17 11:51:01 volumio systemd[1]: Started D-Bus System Message Bus. Apr 17 11:51:01 volumio kernel: fb: osd[0] enable: 1 (hdmi.sh) Apr 17 11:51:01 volumio kernel: fb: osd[1] enable: 0 (hdmi.sh) Apr 17 11:51:01 volumio dhcpcd[4688]: Not running dhcpcd because there is aleady an interface specific instance ... failed! Apr 17 11:51:01 volumio dhcpcd[4688]: /var/run/dhcpcd-eth0.pid ... failed! Apr 17 11:51:01 volumio volumio-remote-updater[4596]: Error: No active session Apr 17 11:51:01 volumio volumio-remote-updater[4596]: [2024-04-17 11:51:01] [info] asio async_connect error: system:111 (Connection refused) Apr 17 11:51:01 volumio volumio-remote-updater[4596]: [2024-04-17 11:51:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 17 11:51:01 volumio volumio-remote-updater[4596]: [2024-04-17 11:51:01] [error] handle_connect error: Underlying Transport Error Apr 17 11:51:01 volumio avahi-daemon[4689]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). Apr 17 11:51:01 volumio systemd[1]: Starting Permit User Sessions... Apr 17 11:51:01 volumio avahi-daemon[4689]: Successfully dropped root privileges. Apr 17 11:51:01 volumio avahi-daemon[4689]: avahi-daemon 0.6.31 starting up. Apr 17 11:51:02 volumio systemd[1]: Started Restore Sound Card State. Apr 17 11:51:02 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Apr 17 11:51:02 volumio systemd[1]: Unit cd.service entered failed state. Apr 17 11:51:02 volumio avahi-daemon[4689]: Successfully called chroot(). Apr 17 11:51:02 volumio avahi-daemon[4689]: Successfully dropped remaining capabilities. Apr 17 11:51:02 volumio avahi-daemon[4689]: No service file found in /etc/avahi/services. Apr 17 11:51:02 volumio avahi-daemon[4689]: Network interface enumeration completed. Apr 17 11:51:02 volumio avahi-daemon[4689]: Registering HINFO record with values 'AARCH64'/'LINUX'. Apr 17 11:51:02 volumio avahi-daemon[4689]: Server startup complete. Host name is volumio.local. Local service cookie is 188622432. Apr 17 11:51:02 volumio systemd[1]: Started /etc/rc.local Compatibility. Apr 17 11:51:02 volumio systemd[1]: Started LSB: Start NTP daemon. Apr 17 11:51:02 volumio dbus[4691]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' Apr 17 11:51:02 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Apr 17 11:51:02 volumio systemd[1]: Started LSB: Starts LIRC daemon.. Apr 17 11:51:02 volumio systemd[1]: Started LSB: Brings up/down network automatically. Apr 17 11:51:02 volumio systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. Apr 17 11:51:02 volumio systemd[1]: dhcpcd.service: control process exited, code=exited status=6 Apr 17 11:51:02 volumio systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. Apr 17 11:51:02 volumio systemd[1]: Unit dhcpcd.service entered failed state. Apr 17 11:51:02 volumio systemd[1]: Started Permit User Sessions. Apr 17 11:51:02 volumio volumio[4595]: Could not open config: /tmp/upmpdcli.conf Apr 17 11:51:02 volumio systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE Apr 17 11:51:02 volumio systemd[1]: Unit upmpdcli.service entered failed state. Apr 17 11:51:02 volumio haveged[4588]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K Apr 17 11:51:02 volumio haveged[4588]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 9/40; sz: 15780/68380 Apr 17 11:51:02 volumio haveged[4588]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00121 Apr 17 11:51:02 volumio haveged[4588]: haveged: fills: 0, generated: 0 Apr 17 11:51:02 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Apr 17 11:51:02 volumio systemd-logind[4603]: New seat seat0. Apr 17 11:51:02 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Apr 17 11:51:02 volumio systemd[1]: Started Login Service. Apr 17 11:51:02 volumio systemd[1]: Starting Disk Manager (legacy version)... Apr 17 11:51:02 volumio systemd[1]: Stopping cd.service... Apr 17 11:51:02 volumio systemd[1]: Starting cd.service... Apr 17 11:51:02 volumio systemd[1]: Started cd.service. Apr 17 11:51:02 volumio volumio[4717]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Apr 17 11:51:02 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Apr 17 11:51:02 volumio systemd[1]: Starting Wait for Plymouth Boot Screen to Quit... Apr 17 11:51:02 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 17 11:51:02 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Apr 17 11:51:02 volumio systemd[1]: Unit cd.service entered failed state. Apr 17 11:51:02 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Apr 17 11:51:02 volumio systemd[1]: Started Wait for Plymouth Boot Screen to Quit. Apr 17 11:51:02 volumio systemd[1]: Starting Getty on tty1... Apr 17 11:51:02 volumio systemd[1]: Started Getty on tty1. Apr 17 11:51:02 volumio dbus[4691]: [system] Successfully activated service 'org.freedesktop.UDisks' Apr 17 11:51:02 volumio systemd[1]: Starting Serial Getty on ttyS0... Apr 17 11:51:02 volumio systemd[1]: Started Serial Getty on ttyS0. Apr 17 11:51:02 volumio systemd[1]: Starting Login Prompts. Apr 17 11:51:02 volumio systemd[1]: Reached target Login Prompts. Apr 17 11:51:02 volumio systemd[1]: Started Disk Manager (legacy version). Apr 17 11:51:02 volumio cpufrequtils[4719]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Apr 17 11:51:02 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Apr 17 11:51:02 volumio dbus[4691]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' Apr 17 11:51:02 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Apr 17 11:51:02 volumio systemd[1]: Stopping cd.service... Apr 17 11:51:02 volumio systemd[1]: Starting cd.service... Apr 17 11:51:02 volumio systemd[1]: Started cd.service. Apr 17 11:51:02 volumio systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... Apr 17 11:51:02 volumio volumio[4738]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Apr 17 11:51:02 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Apr 17 11:51:02 volumio systemd[1]: Unit cd.service entered failed state. Apr 17 11:51:02 volumio polkitd[4739]: started daemon version 0.105 using authority implementation `local' version `0.105' Apr 17 11:51:02 volumio dbus[4691]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 17 11:51:02 volumio systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. Apr 17 11:51:02 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Apr 17 11:51:02 volumio systemd[1]: Stopping cd.service... Apr 17 11:51:02 volumio systemd[1]: Starting cd.service... Apr 17 11:51:02 volumio systemd[1]: Started cd.service. Apr 17 11:51:02 volumio volumio[4744]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Apr 17 11:51:02 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Apr 17 11:51:02 volumio systemd[1]: Unit cd.service entered failed state. Apr 17 11:51:02 volumio udisks-glue[4593]: Device file /dev/sda3 inserted Apr 17 11:51:02 volumio udisks-glue[4593]: Device file /dev/sda inserted Apr 17 11:51:02 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Apr 17 11:51:02 volumio systemd[1]: Stopping cd.service... Apr 17 11:51:02 volumio udisks-glue[4593]: Device file /dev/misc inserted Apr 17 11:51:02 volumio udisks-glue[4593]: Device file /dev/mmcblk0rpmb inserted Apr 17 11:51:02 volumio systemd[1]: Starting cd.service... Apr 17 11:51:02 volumio systemd[1]: Started cd.service. Apr 17 11:51:02 volumio udisks-glue[4593]: Device file /dev/bootloader inserted Apr 17 11:51:02 volumio volumio[4748]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Apr 17 11:51:02 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Apr 17 11:51:02 volumio systemd[1]: Unit cd.service entered failed state. Apr 17 11:51:02 volumio dhcpcd[4483]: eth0: carrier acquired Apr 17 11:51:02 volumio ifup[4459]: dhcpcd[4483]: eth0: carrier acquired Apr 17 11:51:02 volumio kernel: libphy: stmmac-0:00 - Link is Up - 1000/Full Apr 17 11:51:02 volumio udisks-glue[4593]: Device file /dev/tee inserted Apr 17 11:51:02 volumio udisks-glue[4593]: Trying to automount /dev/tee... Apr 17 11:51:02 volumio kernel: EXT4-fs (mmcblk0p8): warning: maximal mount count reached, running e2fsck is recommended Apr 17 11:51:02 volumio ifup[4459]: dhcpcd[4483]: eth0: soliciting a DHCP lease Apr 17 11:51:02 volumio dhcpcd[4483]: eth0: soliciting a DHCP lease Apr 17 11:51:02 volumio dhcpcd[4483]: eth0: offered 10.0.0.18 from 10.0.0.1 Apr 17 11:51:02 volumio ifup[4459]: dhcpcd[4483]: eth0: offered 10.0.0.18 from 10.0.0.1 Apr 17 11:51:02 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Apr 17 11:51:02 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Apr 17 11:51:02 volumio systemd[1]: Stopping cd.service... Apr 17 11:51:02 volumio kernel: EXT4-fs (mmcblk0p8): mounted filesystem with ordered data mode. Opts: (null) Apr 17 11:51:02 volumio udisks-glue[4593]: Successfully automounted /dev/tee at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b Apr 17 11:51:02 volumio udisks-glue[4593]: Device file /dev/logo inserted Apr 17 11:51:02 volumio systemd[1]: Starting cd.service... Apr 17 11:51:02 volumio systemd[1]: cd.service start request repeated too quickly, refusing to start. Apr 17 11:51:02 volumio systemd[1]: Failed to start cd.service. Apr 17 11:51:02 volumio systemd[1]: Unit cd.service entered failed state. Apr 17 11:51:02 volumio systemd[1]: Stopping Xbindrc... Apr 17 11:51:02 volumio udisks-glue[4593]: Device file /dev/env inserted Apr 17 11:51:02 volumio systemd[1]: Starting Xbindrc... Apr 17 11:51:02 volumio udisks-glue[4593]: Device file /dev/boot inserted Apr 17 11:51:02 volumio systemd[1]: Started Xbindrc. Apr 17 11:51:02 volumio udisks-glue[4593]: Device file /dev/mmcblk0boot0 inserted Apr 17 11:51:03 volumio udisks-glue[4593]: Device file /dev/mmcblk0boot1 inserted Apr 17 11:51:03 volumio dhcpcd[4483]: eth0: leased 10.0.0.18 for 86400 seconds Apr 17 11:51:03 volumio dhcpcd[4483]: eth0: adding host route to 10.0.0.18 via 127.0.0.1 Apr 17 11:51:03 volumio dhcpcd[4483]: eth0: adding route to 10.0.0.0/24 Apr 17 11:51:03 volumio dhcpcd[4483]: eth0: adding default route via 10.0.0.1 Apr 17 11:51:03 volumio avahi-daemon[4689]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.0.0.18. Apr 17 11:51:03 volumio avahi-daemon[4689]: New relevant interface eth0.IPv4 for mDNS. Apr 17 11:51:03 volumio avahi-daemon[4689]: Registering new address record for 10.0.0.18 on eth0.IPv4. Apr 17 11:51:03 volumio ifup[4459]: dhcpcd[4483]: eth0: leased 10.0.0.18 for 86400 seconds Apr 17 11:51:03 volumio ifup[4459]: dhcpcd[4483]: eth0: adding host route to 10.0.0.18 via 127.0.0.1 Apr 17 11:51:03 volumio ifup[4459]: dhcpcd[4483]: eth0: adding route to 10.0.0.0/24 Apr 17 11:51:03 volumio ifup[4459]: dhcpcd[4483]: eth0: adding default route via 10.0.0.1 Apr 17 11:51:03 volumio udisks-glue[4593]: Device file /dev/recovery inserted Apr 17 11:51:03 volumio udisks-glue[4593]: Device file /dev/system inserted Apr 17 11:51:03 volumio udisks-glue[4593]: Trying to automount /dev/system... Apr 17 11:51:03 volumio dhcpcd[4483]: forked to background, child pid 4788 Apr 17 11:51:03 volumio ifup[4459]: dhcpcd[4483]: forked to background, child pid 4788 Apr 17 11:51:03 volumio kernel: EXT4-fs (mmcblk0p12): warning: maximal mount count reached, running e2fsck is recommended Apr 17 11:51:03 volumio kernel: EXT4-fs (mmcblk0p12): mounted filesystem with ordered data mode. Opts: (null) Apr 17 11:51:03 volumio kernel: nf_conntrack: automatic helper assignment is deprecated and it will be removed soon. Use the iptables CT target to attach helpers instead. Apr 17 11:51:03 volumio udisks-glue[4593]: Successfully automounted /dev/system at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b_ Apr 17 11:51:03 volumio udisks-glue[4593]: Device file /dev/rsv inserted Apr 17 11:51:03 volumio udisks-glue[4593]: Device file /dev/cache inserted Apr 17 11:51:03 volumio udisks-glue[4593]: Trying to automount /dev/cache... Apr 17 11:51:03 volumio kernel: EXT4-fs (mmcblk0p3): warning: maximal mount count reached, running e2fsck is recommended Apr 17 11:51:03 volumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Apr 17 11:51:03 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Apr 17 11:51:03 volumio systemd[1]: Stopping Xbindrc... Apr 17 11:51:03 volumio systemd[1]: Starting Xbindrc... Apr 17 11:51:03 volumio udisks-glue[4593]: Successfully automounted /dev/cache at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b__ Apr 17 11:51:03 volumio udisks-glue[4593]: Device file /dev/reserved inserted Apr 17 11:51:03 volumio systemd[1]: Started Xbindrc. Apr 17 11:51:03 volumio udisks-glue[4593]: Device file /dev/crypt inserted Apr 17 11:51:03 volumio udisks-glue[4593]: Device file /dev/mmcblk0 inserted Apr 17 11:51:03 volumio udisks-glue[4593]: Device file /dev/data inserted Apr 17 11:51:03 volumio udisks-glue[4593]: Trying to automount /dev/data... Apr 17 11:51:03 volumio kernel: EXT4-fs (mmcblk0p13): mounted filesystem with ordered data mode. Opts: (null) Apr 17 11:51:03 volumio udisks-glue[4593]: Successfully automounted /dev/data at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b___ Apr 17 11:51:03 volumio udisks-glue[4593]: Device file /dev/tee mounted at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b Apr 17 11:51:03 volumio udisks-glue[4593]: Device file /dev/system mounted at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b_ Apr 17 11:51:03 volumio udisks-glue[4593]: Device file /dev/cache mounted at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b__ Apr 17 11:51:03 volumio udisks-glue[4593]: Device file /dev/data mounted at /media/57f8f4bc-abf4-655f-bf67-946fc0f9f25b___ Apr 17 11:51:03 volumio ifplugd(eth0)[4684]: Link beat detected. Apr 17 11:51:03 volumio ntpd[4685]: Deferring DNS for 0.debian.pool.ntp.org 1 Apr 17 11:51:03 volumio ntpd[4834]: signal_no_reset: signal 17 had flags 4000000 Apr 17 11:51:03 volumio ifplugd(eth0)[4684]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 17 11:51:03 volumio ifplugd(eth0)[4684]: client: /sbin/ifup: interface eth0 already configured Apr 17 11:51:03 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Apr 17 11:51:03 volumio systemd[1]: Stopping Xbindrc... Apr 17 11:51:03 volumio ifplugd(eth0)[4684]: Program executed successfully. Apr 17 11:51:03 volumio systemd[1]: Starting Xbindrc... Apr 17 11:51:03 volumio systemd[1]: Started Xbindrc. Apr 17 11:51:04 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Apr 17 11:51:04 volumio systemd[1]: Stopping Xbindrc... Apr 17 11:51:04 volumio systemd[1]: Starting Xbindrc... Apr 17 11:51:04 volumio systemd[1]: Started Xbindrc. Apr 17 11:51:04 volumio wireless.js[4703]: WIRELESS DAEMON: start Apr 17 11:51:04 volumio wireless.js[4703]: WIRELESS: Loaded configuration Apr 17 11:51:04 volumio wireless.js[4703]: Cleaning previous... Apr 17 11:51:04 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Apr 17 11:51:04 volumio systemd[1]: Stopping Xbindrc... Apr 17 11:51:04 volumio systemd[1]: Starting Xbindrc... Apr 17 11:51:04 volumio systemd[1]: xbindkeysrc.service start request repeated too quickly, refusing to start. Apr 17 11:51:04 volumio systemd[1]: Failed to start Xbindrc. Apr 17 11:51:04 volumio systemd[1]: Unit xbindkeysrc.service entered failed state. Apr 17 11:51:04 volumio systemd[1]: Stopped hotspot.service. Apr 17 11:51:04 volumio systemd[1]: Started LSB: start Samba daemons for the AD DC. Apr 17 11:51:04 volumio sudo[4890]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 17 11:51:04 volumio sudo[4890]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:04 volumio sudo[4890]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:04 volumio sudo[4901]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 17 11:51:04 volumio sudo[4901]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:04 volumio kernel: dhd_stop: Enter ffffffc0515cc000 Apr 17 11:51:04 volumio kernel: wl_event_handler: was terminated Apr 17 11:51:04 volumio kernel: wl_destroy_event_handler(): thread:wl_event_handler:1162 terminated OK Apr 17 11:51:04 volumio kernel: CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized Apr 17 11:51:05 volumio kernel: CFGP2P-ERROR) wl_cfgp2p_deinit_priv : In Apr 17 11:51:05 volumio kernel: wl_android_wifi_off in 1 Apr 17 11:51:05 volumio kernel: wl_android_wifi_off in 2: g_wifi_on=1, on_failure=1 Apr 17 11:51:05 volumio kernel: bcmsdh_oob_intr_unregister: Enter Apr 17 11:51:05 volumio kernel: dhd_txglom_enable: enable 0 Apr 17 11:51:05 volumio kernel: dhd_conf_set_txglom_params: swtxglom=0, txglom_ext=0, txglom_bucket_size=0 Apr 17 11:51:05 volumio kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0, bus_txglom=-1 Apr 17 11:51:05 volumio kernel: dhd_conf_set_txglom_params: tx_in_rx=1, txinrx_thres=-1, dhd_txminmax=1 Apr 17 11:51:05 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=1 Apr 17 11:51:05 volumio kernel: sdioh_set_mode: set txglom_mode to multi-desc Apr 17 11:51:05 volumio kernel: dhd_bus_devreset: WLAN OFF DONE Apr 17 11:51:05 volumio kernel: wifi_platform_set_power = 0 Apr 17 11:51:05 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Apr 17 11:51:05 volumio kernel: wl_android_wifi_off out Apr 17 11:51:05 volumio winbind[4604]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Apr 17 11:51:05 volumio winbind[4604]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Apr 17 11:51:05 volumio winbind[4604]: Unable to setup corepath for winbindd: No such file or directory Apr 17 11:51:05 volumio winbind[4604]: mkdir failed on directory /var/log/samba/cores: No such file or directory Apr 17 11:51:05 volumio winbind[4604]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Apr 17 11:51:05 volumio winbind[4604]: Unable to setup corepath for winbindd: No such file or directory Apr 17 11:51:05 volumio winbind[4604]: [2024/04/17 11:51:05, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:05 volumio winbind[4604]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Apr 17 11:51:05 volumio winbind[4604]: [2024/04/17 11:51:05, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:05 volumio winbind[4604]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Apr 17 11:51:05 volumio winbind[4604]: [2024/04/17 11:51:05, 0] ../source3/winbindd/winbindd.c:1549(main) Apr 17 11:51:05 volumio winbind[4604]: winbindd version 4.2.14-Debian started. Apr 17 11:51:05 volumio winbind[4604]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Apr 17 11:51:05 volumio winbind[4604]: [2024/04/17 11:51:05, 0] ../lib/util/util.c:220(directory_create_or_exist) Apr 17 11:51:05 volumio winbind[4604]: mkdir failed on directory /var/log/samba/cores: No such file or directory Apr 17 11:51:05 volumio winbind[4604]: [2024/04/17 11:51:05, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Apr 17 11:51:05 volumio winbind[4604]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Apr 17 11:51:05 volumio winbind[4604]: [2024/04/17 11:51:05, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Apr 17 11:51:05 volumio winbind[4604]: Unable to setup corepath for winbindd: No such file or directory Apr 17 11:51:05 volumio winbindd[4900]: [2024/04/17 11:51:05.138191, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:05 volumio winbindd[4900]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Apr 17 11:51:05 volumio winbindd[4900]: [2024/04/17 11:51:05.139033, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:05 volumio winbindd[4900]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Apr 17 11:51:05 volumio nmbd[4904]: [2024/04/17 11:51:05.145342, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:05 volumio nmbd[4904]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Apr 17 11:51:05 volumio nmbd[4904]: [2024/04/17 11:51:05.146698, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:05 volumio nmbd[4904]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Apr 17 11:51:05 volumio nmbd[4905]: [2024/04/17 11:51:05.149509, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) Apr 17 11:51:05 volumio nmbd[4905]: started asyncdns process 4906 Apr 17 11:51:05 volumio winbind[4604]: [2024/04/17 11:51:05.138191, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:05 volumio winbind[4604]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Apr 17 11:51:05 volumio winbind[4604]: [2024/04/17 11:51:05.139033, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:05 volumio winbind[4604]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Apr 17 11:51:05 volumio nmbd[4612]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Apr 17 11:51:05 volumio nmbd[4612]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Apr 17 11:51:05 volumio nmbd[4612]: Unable to setup corepath for nmbd: No such file or directory Apr 17 11:51:05 volumio nmbd[4612]: [2024/04/17 11:51:05, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:05 volumio nmbd[4612]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Apr 17 11:51:05 volumio nmbd[4612]: [2024/04/17 11:51:05, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:05 volumio nmbd[4612]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Apr 17 11:51:05 volumio nmbd[4612]: [2024/04/17 11:51:05, 0] ../source3/nmbd/nmbd.c:908(main) Apr 17 11:51:05 volumio nmbd[4612]: nmbd version 4.2.14-Debian started. Apr 17 11:51:05 volumio nmbd[4612]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Apr 17 11:51:05 volumio nmbd[4612]: [2024/04/17 11:51:05.145342, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:05 volumio nmbd[4612]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Apr 17 11:51:05 volumio nmbd[4612]: [2024/04/17 11:51:05.146698, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:05 volumio nmbd[4612]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Apr 17 11:51:05 volumio systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Apr 17 11:51:05 volumio systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Apr 17 11:51:05 volumio nmbd[4612]: . Apr 17 11:51:05 volumio sudo[4901]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:05 volumio kernel: dhd_stop: Exit Apr 17 11:51:05 volumio winbind[4604]: . Apr 17 11:51:05 volumio winbindd[4916]: [2024/04/17 11:51:05.240383, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Apr 17 11:51:05 volumio nmbd[4905]: [2024/04/17 11:51:05.240553, 0] ../lib/util/become_daemon.c:124(daemon_ready) Apr 17 11:51:05 volumio nmbd[4905]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 17 11:51:05 volumio winbindd[4916]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 17 11:51:05 volumio systemd[1]: Started LSB: start Winbind daemon. Apr 17 11:51:05 volumio winbindd[4916]: [2024/04/17 11:51:05.278814, 0] ../lib/util/become_daemon.c:124(daemon_ready) Apr 17 11:51:05 volumio winbindd[4916]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 17 11:51:05 volumio winbindd[4921]: [2024/04/17 11:51:05.284919, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:05 volumio winbindd[4921]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 17 11:51:05 volumio wireless.js[4703]: Stopped aP Apr 17 11:51:05 volumio wireless.js[4703]: Start wireless flow Apr 17 11:51:05 volumio wireless.js[4703]: Stopped hotspot (if there).. Apr 17 11:51:05 volumio sudo[4925]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 17 11:51:05 volumio sudo[4925]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:05 volumio sudo[4925]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:05 volumio sudo[4927]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 17 11:51:05 volumio sudo[4927]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:05 volumio sudo[4927]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:05 volumio wireless.js[4703]: DHCP IP Apr 17 11:51:05 volumio wireless.js[4703]: Start ap Apr 17 11:51:05 volumio sudo[4930]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 17 11:51:05 volumio sudo[4930]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:05 volumio dhcpcd[4931]: version 6.0.5 starting Apr 17 11:51:05 volumio kernel: dhd_open: Enter ffffffc0515cc000 Apr 17 11:51:05 volumio kernel: Dongle Host Driver, version 1.363.59.144.9 (r) Apr 17 11:51:05 volumio wpa_supplicant[4929]: Successfully initialized wpa_supplicant Apr 17 11:51:05 volumio kernel: wl_android_wifi_on in 1 Apr 17 11:51:05 volumio kernel: wl_android_wifi_on in 2: g_wifi_on=0 Apr 17 11:51:05 volumio kernel: wifi_platform_set_power = 1 Apr 17 11:51:05 volumio kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Apr 17 11:51:05 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 Apr 17 11:51:05 volumio systemd[1]: Started Music Player Daemon. Apr 17 11:51:05 volumio udisks-glue[4593]: Updating DB (#1) ... Apr 17 11:51:05 volumio udisks-glue[4593]: volume: n/a repeat: off random: off single: off consume: off Apr 17 11:51:05 volumio udisks-glue[4593]: Updating DB (#1) ... Apr 17 11:51:05 volumio udisks-glue[4593]: volume: n/a repeat: off random: off single: off consume: off Apr 17 11:51:05 volumio udisks-glue[4593]: Updating DB (#1) ... Apr 17 11:51:05 volumio udisks-glue[4593]: volume: n/a repeat: off random: off single: off consume: off Apr 17 11:51:05 volumio udisks-glue[4593]: Updating DB (#1) ... Apr 17 11:51:05 volumio udisks-glue[4593]: volume: n/a repeat: off random: off single: off consume: off Apr 17 11:51:05 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Enable! 240 Apr 17 11:51:06 volumio smbd[4907]: Starting SMB/CIFS daemon: smbd[2024/04/17 11:51:06, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:06 volumio smbd[4907]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 17 11:51:06 volumio smbd[4907]: [2024/04/17 11:51:06, 0] ../lib/util/util.c:220(directory_create_or_exist) Apr 17 11:51:06 volumio smbd[4907]: mkdir failed on directory /var/log/samba/cores: No such file or directory Apr 17 11:51:06 volumio smbd[4907]: [2024/04/17 11:51:06, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Apr 17 11:51:06 volumio smbd[4907]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Apr 17 11:51:06 volumio smbd[4907]: [2024/04/17 11:51:06, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Apr 17 11:51:06 volumio smbd[4907]: Unable to setup corepath for smbd: No such file or directory Apr 17 11:51:06 volumio smbd[4907]: [2024/04/17 11:51:06, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:06 volumio smbd[4907]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 17 11:51:06 volumio smbd[4907]: [2024/04/17 11:51:06, 0] ../source3/smbd/server.c:1241(main) Apr 17 11:51:06 volumio smbd[4907]: smbd version 4.2.14-Debian started. Apr 17 11:51:06 volumio smbd[4907]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Apr 17 11:51:06 volumio smbd[4937]: [2024/04/17 11:51:06.142328, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:06 volumio smbd[4937]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 17 11:51:06 volumio smbd[4937]: [2024/04/17 11:51:06.143514, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:06 volumio smbd[4937]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 17 11:51:06 volumio smbd[4937]: [2024/04/17 11:51:06.143702, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:06 volumio smbd[4937]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 17 11:51:06 volumio smbd[4907]: [2024/04/17 11:51:06.142328, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:06 volumio smbd[4907]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 17 11:51:06 volumio smbd[4907]: [2024/04/17 11:51:06.143514, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:06 volumio smbd[4907]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 17 11:51:06 volumio smbd[4907]: [2024/04/17 11:51:06.143702, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:06 volumio smbd[4907]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 17 11:51:06 volumio kernel: sdio_reset_comm(): Apr 17 11:51:06 volumio kernel: sdio: clk 200000000 SDR mode tuning start Apr 17 11:51:06 volumio kernel: sdio: rx_tuning_result[0] = 10 Apr 17 11:51:06 volumio kernel: sdio: rx_tuning_result[1] = 10 Apr 17 11:51:06 volumio kernel: sdio: rx_tuning_result[3] = 10 Apr 17 11:51:06 volumio kernel: sdio: rx_tuning_result[4] = 10 Apr 17 11:51:06 volumio kernel: sdio: best_win_start =3, best_win_size =4 Apr 17 11:51:06 volumio kernel: sdio: sd_emmc_regs->gclock=0x1000245,sd_emmc_regs->gadjust=0x2000 Apr 17 11:51:06 volumio kernel: sdio: gclock =0x1000245, gdelay=0x0, gadjust=0x2000 Apr 17 11:51:06 volumio kernel: sdioh_start: set sd_f2_blocksize 256 Apr 17 11:51:06 volumio kernel: dhd_bus_devreset: == WLAN ON == Apr 17 11:51:06 volumio kernel: F1 signature read @0x18000000=0x15264345 Apr 17 11:51:06 volumio kernel: F1 signature OK, socitype:0x1 chip:0x4345 rev:0x6 pkg:0x2 Apr 17 11:51:06 volumio kernel: DHD: dongle ram size is set to 819200(orig 819200) at 0x198000 Apr 17 11:51:06 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/config.txt (399 bytes) open success Apr 17 11:51:06 volumio kernel: dhd_conf_read_others: ccode = SG Apr 17 11:51:06 volumio kernel: dhd_conf_read_others: bcn_timeout = 20 Apr 17 11:51:06 volumio kernel: dhd_conf_read_sdio_params: dhd_slpauto = 1 Apr 17 11:51:06 volumio kernel: dhd_conf_read_others: mimo_bw_cap = 1 Apr 17 11:51:06 volumio kernel: dhd_conf_read_pm_params: PM = 0 Apr 17 11:51:06 volumio kernel: dhd_conf_read_nv_by_chip: nv_by_chip_count=12 Apr 17 11:51:06 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa962, chiprev=0, name=nvram_ap6181.txt Apr 17 11:51:06 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa962, chiprev=1, name=nvram_ap6210.txt Apr 17 11:51:06 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=0, name=nvram_ap6212.txt Apr 17 11:51:06 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=1, name=nvram_ap6212a.txt Apr 17 11:51:06 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4345, chiprev=6, name=nvram_ap6255.txt Apr 17 11:51:06 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4330, chiprev=4, name=nvram_ap6330.txt Apr 17 11:51:06 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4339, chiprev=1, name=nvram_ap6335.txt Apr 17 11:51:06 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4354, chiprev=2, name=nvram_ap6356.txt Apr 17 11:51:06 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4335, chiprev=1, name=nvram_bcm4335.txt Apr 17 11:51:06 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa94c, chiprev=2, name=nvram_ap6234.txt Apr 17 11:51:06 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4359, chiprev=9, name=nvram_ap6359sa.txt Apr 17 11:51:06 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4334, chiprev=3, name=nvram_bcm4334.txt Apr 17 11:51:06 volumio wireless.js[4703]: trying... Apr 17 11:51:06 volumio kernel: Final fw_path=/lib/firmware/brcm/fw_bcm43455c0_ag.bin Apr 17 11:51:06 volumio kernel: Final nv_path=/lib/firmware/brcm/nvram_ap6255.txt Apr 17 11:51:06 volumio kernel: Final clm_path=/lib/firmware/brcm/clm_bcmdhd.blob Apr 17 11:51:06 volumio kernel: Final conf_path=/lib/firmware/brcm/config.txt Apr 17 11:51:06 volumio kernel: dhd_set_bus_params: set use_rxchain 0 Apr 17 11:51:06 volumio kernel: dhd_set_bus_params: set txglomsize 36 Apr 17 11:51:06 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/fw_bcm43455c0_ag.bin (483181 bytes) open success Apr 17 11:51:06 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/nvram_ap6255.txt (1644 bytes) open success Apr 17 11:51:06 volumio kernel: NVRAM version: AP6255_NVRAM_V1.0_29052015 Apr 17 11:51:06 volumio kernel: dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded. Apr 17 11:51:06 volumio kernel: dhd_bus_init: enable 0x06, ready 0x06 (waited 0us) Apr 17 11:51:06 volumio kernel: bcmsdh_oob_intr_register: Enter Apr 17 11:51:06 volumio kernel: bcmsdh_oob_intr_register: HW_OOB enabled Apr 17 11:51:06 volumio kernel: bcmsdh_oob_intr_register OOB irq=100 flags=0x4 Apr 17 11:51:06 volumio kernel: bcmsdh_oob_intr_register change flags to 0x4 Apr 17 11:51:06 volumio kernel: bcmsdh_oob_intr_register: enable_irq_wake Apr 17 11:51:06 volumio kernel: bcmsdh_oob_intr_register: enable_irq_wake failed with -6 Apr 17 11:51:06 volumio kernel: Disable tdls_auto_op failed. -1 Apr 17 11:51:06 volumio kernel: dhd_conf_set_intiovar: set WLC_SET_BAND 142 0 Apr 17 11:51:06 volumio kernel: dhd_preinit_ioctls: Set tcpack_sup_mode 0 Apr 17 11:51:06 volumio kernel: dhd_tcpack_suppress_set 347: already set to 0 Apr 17 11:51:06 volumio kernel: dhd_apply_default_clm: Ignore clm file /lib/firmware/brcm/clm_bcmdhd.blob Apr 17 11:51:06 volumio kernel: Firmware up: op_mode=0x0005, MAC=00:90:4c:c5:12:38 Apr 17 11:51:06 volumio kernel: dhd_conf_set_country: set country SG, revision 0 Apr 17 11:51:06 volumio kernel: Country code: SG (SG/0) Apr 17 11:51:06 volumio kernel: dhd_conf_set_intiovar: set roam_off 1 Apr 17 11:51:06 volumio kernel: dhd_conf_set_intiovar: set mimo_bw_cap 1 Apr 17 11:51:06 volumio kernel: Firmware version = wl0: Aug 25 2015 18:58:57 version 7.45.69 (r581703) FWID 01-24037f6e Apr 17 11:51:06 volumio kernel: Driver: 1.363.59.144.9 (r) Firmware: wl0: Aug 25 2015 18:58:57 version 7.45.69 (r581703) FWID 01-24037f6e Apr 17 11:51:06 volumio kernel: clm = 7.14.8 Apr 17 11:51:06 volumio kernel: dhd_txglom_enable: enable 1 Apr 17 11:51:06 volumio kernel: dhd_conf_set_txglom_params: swtxglom=0, txglom_ext=0, txglom_bucket_size=0 Apr 17 11:51:06 volumio kernel: dhd_conf_set_txglom_params: txglomsize=36, deferred_tx_len=0, bus_txglom=-1 Apr 17 11:51:06 volumio kernel: dhd_conf_set_txglom_params: tx_in_rx=1, txinrx_thres=-1, dhd_txminmax=1 Apr 17 11:51:06 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=1 Apr 17 11:51:06 volumio kernel: sdioh_set_mode: set txglom_mode to multi-desc Apr 17 11:51:06 volumio kernel: dhd_conf_get_disable_proptx: fw_proptx=1, disable_proptx=-1 Apr 17 11:51:06 volumio kernel: dhd_wlfc_hostreorder_init(): successful bdcv2 tlv signaling, 64 Apr 17 11:51:06 volumio kernel: dhd_conf_set_intiovar: set ampdu_hostreorder 1 Apr 17 11:51:06 volumio kernel: wl_android_wifi_on: Success Apr 17 11:51:06 volumio kernel: wl_create_event_handler(): thread:wl_event_handler:134f started Apr 17 11:51:06 volumio kernel: tsk Enter, tsk = 0xffffff8001579bb8 Apr 17 11:51:06 volumio kernel: CFG80211-ERROR) wl_update_wiphybands : error reading txbf_bfe_cap (-23) Apr 17 11:51:06 volumio kernel: CFG80211-ERROR) wl_update_wiphybands : error reading txbf_bfr_cap (-23) Apr 17 11:51:06 volumio kernel: dhd_open: Exit ret=0 Apr 17 11:51:06 volumio ntpd[4685]: Listen normally on 3 eth0 10.0.0.18 UDP 123 Apr 17 11:51:06 volumio ntpd[4685]: peers refreshed Apr 17 11:51:06 volumio ntpd_intres[4834]: DNS 0.debian.pool.ntp.org -> 84.16.67.12 Apr 17 11:51:06 volumio kernel: Error getting the SSID Apr 17 11:51:06 volumio smbd[4907]: . Apr 17 11:51:06 volumio sudo[4941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:06 volumio sudo[4941]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:06 volumio systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Apr 17 11:51:06 volumio sudo[4941]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:06 volumio winbindd[4967]: [2024/04/17 11:51:06.952188, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:06 volumio winbindd[4967]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Apr 17 11:51:06 volumio volumio-remote-updater[4596]: [2024-04-17 11:51:06] [info] asio async_connect error: system:111 (Connection refused) Apr 17 11:51:06 volumio volumio-remote-updater[4596]: [2024-04-17 11:51:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 17 11:51:06 volumio volumio-remote-updater[4596]: [2024-04-17 11:51:06] [error] handle_connect error: Underlying Transport Error Apr 17 11:51:06 volumio winbindd[4980]: [2024/04/17 11:51:06.983862, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:06 volumio winbindd[4980]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory Apr 17 11:51:07 volumio smbd[4946]: [2024/04/17 11:51:07.003410, 0] ../lib/util/become_daemon.c:124(daemon_ready) Apr 17 11:51:07 volumio smbd[4946]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 17 11:51:07 volumio smbd[4982]: [2024/04/17 11:51:07.006526, 0] ../lib/util/debug.c:603(reopen_logs_internal) Apr 17 11:51:07 volumio smbd[4982]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Apr 17 11:51:07 volumio dhcpcd[4931]: eth0: rebinding lease of 10.0.0.18 Apr 17 11:51:07 volumio dhcpcd[4931]: wlan0: waiting for carrier Apr 17 11:51:07 volumio dhcpcd[4931]: tunl0: unsupported interface type 300, falling back to ethernet Apr 17 11:51:07 volumio dhcpcd[4931]: tunl0: soliciting a DHCP lease Apr 17 11:51:07 volumio dhcpcd[4931]: eth0: leased 10.0.0.18 for 86400 seconds Apr 17 11:51:07 volumio dhcpcd[4931]: eth0: adding host route to 10.0.0.18 via 127.0.0.1 Apr 17 11:51:07 volumio dhcpcd[4931]: eth0: adding route to 10.0.0.0/24 Apr 17 11:51:07 volumio dhcpcd[4931]: eth0: adding default route via 10.0.0.1 Apr 17 11:51:07 volumio dhcpcd[4788]: eth0: removing route to 10.0.0.0/24 Apr 17 11:51:07 volumio dhcpcd[4788]: eth0: removing default route via 10.0.0.1 Apr 17 11:51:07 volumio dhcpcd[4931]: forked to background, child pid 5025 Apr 17 11:51:07 volumio sudo[4930]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:07 volumio dhcpcd[5025]: sit0: unsupported interface type 308, falling back to ethernet Apr 17 11:51:07 volumio dhcpcd[5025]: sit0: soliciting a DHCP lease Apr 17 11:51:07 volumio dhcpcd[5025]: ip6tnl0: unsupported interface type 301, falling back to ethernet Apr 17 11:51:07 volumio kernel: meson_uart c81004c0.serial: ttyS0 use xtal(8M) 24000000 change 115200 to 115200 Apr 17 11:51:07 volumio kernel: meson_uart c81004c0.serial: ttyS0 use xtal(8M) 24000000 change 115200 to 115200 Apr 17 11:51:07 volumio kernel: meson_uart c81004c0.serial: ttyS0 use xtal(8M) 24000000 change 115200 to 115200 Apr 17 11:51:07 volumio kernel: meson_uart c81004c0.serial: ttyS0 use xtal(8M) 24000000 change 115200 to 115200 Apr 17 11:51:07 volumio dhcpcd[5025]: ip6tnl0: soliciting a DHCP lease Apr 17 11:51:07 volumio dhcpcd[5025]: wlan0: carrier acquired Apr 17 11:51:07 volumio dhcpcd[5025]: wlan0: carrier lost Apr 17 11:51:07 volumio dhcpcd[5025]: wlan0: waiting for carrier Apr 17 11:51:07 volumio dhcpcd[5025]: eth0: removing route to 10.0.0.0/24 Apr 17 11:51:07 volumio dhcpcd[5025]: eth0: removing default route via 10.0.0.1 Apr 17 11:51:07 volumio wireless.js[4703]: trying... Apr 17 11:51:07 volumio sudo[5113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:07 volumio sudo[5113]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:07 volumio sudo[5113]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:08 volumio wireless.js[4703]: trying... Apr 17 11:51:08 volumio sudo[5116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:08 volumio sudo[5116]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:08 volumio sudo[5116]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:09 volumio wireless.js[4703]: trying... Apr 17 11:51:09 volumio sudo[5121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:09 volumio sudo[5121]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:10 volumio sudo[5121]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:10 volumio wpa_supplicant[4984]: wlan0: Trying to associate with e0:f4:42:92:3e:10 (SSID='AADPM5_5G' freq=5220 MHz) Apr 17 11:51:10 volumio kernel: Connecting with e0:f4:42:92:3e:10 ssid "AADPM5_5G", len (9) channel=44 Apr 17 11:51:11 volumio wireless.js[4703]: trying... Apr 17 11:51:11 volumio sudo[5124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:11 volumio sudo[5124]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:11 volumio sudo[5124]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:11 volumio kernel: wl_iw_event: Link UP with BSSID=e0:f4:42:92:3e:10 Apr 17 11:51:11 volumio kernel: wl_bss_connect_done succeeded with e0:f4:42:92:3e:10 Apr 17 11:51:11 volumio wpa_supplicant[4984]: wlan0: Associated with e0:f4:42:92:3e:10 Apr 17 11:51:11 volumio kernel: wl_bss_connect_done succeeded with e0:f4:42:92:3e:10 Apr 17 11:51:12 volumio wireless.js[4703]: trying... Apr 17 11:51:12 volumio sudo[5129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:12 volumio sudo[5129]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:12 volumio sudo[5129]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:13 volumio wireless.js[4703]: trying... Apr 17 11:51:13 volumio sudo[5132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:13 volumio sudo[5132]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:13 volumio sudo[5132]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:14 volumio wireless.js[4703]: trying... Apr 17 11:51:14 volumio sudo[5136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:14 volumio sudo[5136]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:14 volumio sudo[5136]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:14 volumio volumio-remote-updater[4596]: [2024-04-17 11:51:14] [info] asio async_connect error: system:111 (Connection refused) Apr 17 11:51:14 volumio volumio-remote-updater[4596]: [2024-04-17 11:51:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 17 11:51:14 volumio volumio-remote-updater[4596]: [2024-04-17 11:51:14] [error] handle_connect error: Underlying Transport Error Apr 17 11:51:15 volumio systemd[1]: Time has been changed Apr 17 11:51:15 volumio wireless.js[4703]: trying... Apr 17 11:51:15 volumio sudo[5139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:15 volumio sudo[5139]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:15 volumio sudo[5139]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:16 volumio wireless.js[4703]: trying... Apr 17 11:51:16 volumio sudo[5144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:16 volumio sudo[5144]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:16 volumio sudo[5144]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:17 volumio wireless.js[4703]: trying... Apr 17 11:51:17 volumio sudo[5147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:17 volumio sudo[5147]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:17 volumio sudo[5147]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:18 volumio wireless.js[4703]: trying... Apr 17 11:51:18 volumio sudo[5152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:18 volumio sudo[5152]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:18 volumio sudo[5152]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:19 volumio wireless.js[4703]: trying... Apr 17 11:51:19 volumio sudo[5155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:19 volumio sudo[5155]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:19 volumio sudo[5155]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:20 volumio wireless.js[4703]: trying... Apr 17 11:51:20 volumio sudo[5160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:20 volumio sudo[5160]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:20 volumio sudo[5160]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:21 volumio wpa_supplicant[4984]: wlan0: Authentication with e0:f4:42:92:3e:10 timed out. Apr 17 11:51:21 volumio wpa_supplicant[4984]: wlan0: CTRL-EVENT-DISCONNECTED bssid=e0:f4:42:92:3e:10 reason=3 locally_generated=1 Apr 17 11:51:21 volumio wpa_supplicant[4984]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect Apr 17 11:51:21 volumio wpa_supplicant[4984]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="AADPM5_5G" auth_failures=1 duration=10 reason=WRONG_KEY Apr 17 11:51:21 volumio kernel: CFG80211-ERROR) wl_cfg80211_disconnect : Reason 3 Apr 17 11:51:21 volumio kernel: wl_iw_event: Link Down with BSSID=e0:f4:42:92:3e:10 Apr 17 11:51:21 volumio kernel: CFG80211-ERROR) wl_is_linkdown : Link down Reason : WLC_E_LINK Apr 17 11:51:21 volumio kernel: link down if wlan0 may call cfg80211_disconnected. event : 16, reason=2 from e0:f4:42:92:3e:10 Apr 17 11:51:21 volumio wireless.js[4703]: trying... Apr 17 11:51:21 volumio sudo[5163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:21 volumio sudo[5163]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:21 volumio sudo[5163]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:22 volumio wireless.js[4703]: trying... Apr 17 11:51:22 volumio sudo[5168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:22 volumio sudo[5168]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:22 volumio sudo[5168]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:23 volumio wireless.js[4703]: trying... Apr 17 11:51:23 volumio sudo[5171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:23 volumio sudo[5171]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:23 volumio sudo[5171]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:24 volumio wireless.js[4703]: trying... Apr 17 11:51:24 volumio sudo[5176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:24 volumio sudo[5176]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:24 volumio sudo[5176]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:25 volumio volumio-remote-updater[4596]: [2024-04-17 11:51:25] [info] asio async_connect error: system:111 (Connection refused) Apr 17 11:51:25 volumio volumio-remote-updater[4596]: [2024-04-17 11:51:25] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 17 11:51:25 volumio volumio-remote-updater[4596]: [2024-04-17 11:51:25] [error] handle_connect error: Underlying Transport Error Apr 17 11:51:25 volumio wireless.js[4703]: trying... Apr 17 11:51:25 volumio sudo[5181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:25 volumio sudo[5181]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:25 volumio sudo[5181]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:26 volumio wireless.js[4703]: trying... Apr 17 11:51:26 volumio sudo[5184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 17 11:51:26 volumio sudo[5184]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:26 volumio sudo[5184]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:27 volumio wireless.js[4703]: Overtime, starting plan B Apr 17 11:51:27 volumio systemd[1]: Started Wireless Services. Apr 17 11:51:27 volumio systemd[1]: Starting Volumio Backend Module... Apr 17 11:51:27 volumio systemd[1]: Started Volumio Backend Module. Apr 17 11:51:27 volumio systemd[1]: Starting Volumio Streaming Daemon... Apr 17 11:51:27 volumio systemd[1]: Started Volumio Streaming Daemon. Apr 17 11:51:27 volumio systemd[1]: Starting Multi-User System. Apr 17 11:51:27 volumio systemd[1]: Reached target Multi-User System. Apr 17 11:51:27 volumio systemd[1]: Starting Graphical Interface. Apr 17 11:51:27 volumio systemd[1]: Reached target Graphical Interface. Apr 17 11:51:27 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 17 11:51:28 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 17 11:51:28 volumio systemd[1]: Startup finished in 16.108s (kernel) + 32.035s (userspace) = 48.144s. Apr 17 11:51:28 volumio volumio-streaming-daemon[5190]: ############################ Apr 17 11:51:28 volumio volumio-streaming-daemon[5190]: # Volumio Streaming Daemon # Apr 17 11:51:28 volumio volumio-streaming-daemon[5190]: # Running on port 7777 # Apr 17 11:51:28 volumio volumio-streaming-daemon[5190]: ############################ Apr 17 11:51:30 volumio volumio[5189]: info: ------------------------------------------- Apr 17 11:51:30 volumio volumio[5189]: info: ----- Volumio2 ---- Apr 17 11:51:30 volumio volumio[5189]: info: ------------------------------------------- Apr 17 11:51:30 volumio volumio[5189]: info: ----- System startup ---- Apr 17 11:51:30 volumio volumio[5189]: info: ------------------------------------------- Apr 17 11:51:30 volumio volumio[5189]: info: MYVOLUMIO Environment detected Apr 17 11:51:30 volumio volumio[5189]: info: Plugin folders cleanup Apr 17 11:51:30 volumio volumio[5189]: info: Scanning into folder /volumio/app/plugins/ Apr 17 11:51:30 volumio volumio[5189]: info: Scanning category audio_interface Apr 17 11:51:30 volumio volumio[5189]: info: Scanning category miscellanea Apr 17 11:51:30 volumio volumio[5189]: info: Scanning category music_service Apr 17 11:51:30 volumio volumio[5189]: info: Scanning category plugins.json Apr 17 11:51:30 volumio volumio[5189]: info: Scanning category system_controller Apr 17 11:51:30 volumio volumio[5189]: info: Scanning category user_interface Apr 17 11:51:30 volumio volumio[5189]: info: Scanning into folder /data/plugins/ Apr 17 11:51:30 volumio volumio[5189]: info: Plugin folders cleanup completed Apr 17 11:51:30 volumio volumio[5189]: info: ------------------------------------------- Apr 17 11:51:30 volumio volumio[5189]: info: ----- Core plugins startup ---- Apr 17 11:51:30 volumio volumio[5189]: info: ------------------------------------------- Apr 17 11:51:30 volumio volumio[5189]: info: Loading plugins from folder /volumio/app/plugins/ Apr 17 11:51:30 volumio volumio[5189]: info: Adding plugin upnp to MyMusic Plugins Apr 17 11:51:30 volumio volumio[5189]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 17 11:51:30 volumio volumio[5189]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 17 11:51:30 volumio volumio[5189]: info: Loading plugins from folder /data/plugins/ Apr 17 11:51:30 volumio volumio[5189]: info: Loading plugin "system"... Apr 17 11:51:30 volumio volumio[5189]: info: Loading plugin "appearance"... Apr 17 11:51:31 volumio volumio[5189]: info: Loading plugin "network"... Apr 17 11:51:32 volumio volumio[5189]: info: Loading plugin "services"... Apr 17 11:51:32 volumio volumio[5189]: info: Loading plugin "alsa_controller"... Apr 17 11:51:32 volumio volumio[5189]: info: Loading plugin "wizard"... Apr 17 11:51:32 volumio volumio[5189]: info: Loading plugin "volumio_command_line_client"... Apr 17 11:51:32 volumio volumio[5189]: info: Loading plugin "upnp"... Apr 17 11:51:32 volumio volumio[5189]: info: [1713354692318] Starting Upmpd Daemon Apr 17 11:51:32 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 11:51:32 volumio volumio[5189]: info: Loading plugin "my_music"... Apr 17 11:51:32 volumio volumio[5189]: info: Loading plugin "mpd"... Apr 17 11:51:32 volumio volumio[5189]: info: Loading plugin "upnp_browser"... Apr 17 11:51:33 volumio volumio[5189]: info: Loading plugin "networkfs"... Apr 17 11:51:34 volumio sudo[5224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=df,password=8,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.0.2/d/音乐 /mnt/NAS/nas Apr 17 11:51:34 volumio sudo[5224]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:34 volumio sudo[5225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=df,password=8,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.0.2/d/试音 /mnt/NAS/nas Apr 17 11:51:34 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 11:51:34 volumio sudo[5225]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:34 volumio volumio[5189]: info: Loading plugin "alarm-clock"... Apr 17 11:51:34 volumio sudo[5229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=df,password=8,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.0.0.2/e/音乐 /mnt/NAS/nas Apr 17 11:51:34 volumio sudo[5229]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:34 volumio sudo[5224]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:34 volumio sudo[5229]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:34 volumio sudo[5225]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:34 volumio volumio[5189]: info: Loading plugin "airplay_emulation"... Apr 17 11:51:34 volumio volumio[5189]: info: Starting Shairport Sync Apr 17 11:51:34 volumio volumio[5189]: info: Loading plugin "last_100"... Apr 17 11:51:34 volumio volumio[5189]: info: Loading plugin "webradio"... Apr 17 11:51:34 volumio volumio[5189]: info: Loading plugin "i2s_dacs"... Apr 17 11:51:34 volumio volumio[5189]: info: I2S DAC not set, start Auto-detection Apr 17 11:51:34 volumio volumio[5189]: info: Loading plugin "volumiodiscovery"... Apr 17 11:51:34 volumio volumio[5189]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 17 11:51:34 volumio node[5189]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 17 11:51:34 volumio node[5189]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 11:51:34 volumio node[5189]: *** WARNING *** For more information see Apr 17 11:51:34 volumio node[5189]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 17 11:51:34 volumio node[5189]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 11:51:34 volumio node[5189]: *** WARNING *** For more information see Apr 17 11:51:34 volumio volumio[5189]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 11:51:34 volumio volumio[5189]: *** WARNING *** For more information see Apr 17 11:51:34 volumio volumio[5189]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 17 11:51:34 volumio volumio[5189]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 17 11:51:34 volumio volumio[5189]: *** WARNING *** For more information see Apr 17 11:51:34 volumio volumio[5189]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 17 11:51:34 volumio volumio[5189]: Discovery: StartAdv! undefined Apr 17 11:51:34 volumio volumio[5189]: Discovery: Started advertising... Volumio - undefined Apr 17 11:51:34 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 17 11:51:34 volumio volumio[5189]: info: Loading plugin "outputs"... Apr 17 11:51:34 volumio wpa_supplicant[4984]: wlan0: CTRL-EVENT-SSID-REENABLED id=1 ssid="AADPM5_5G" Apr 17 11:51:34 volumio wpa_supplicant[4984]: wlan0: Trying to associate with e0:f4:42:92:3e:10 (SSID='AADPM5_5G' freq=5220 MHz) Apr 17 11:51:34 volumio volumio[5189]: info: Loading plugin "albumart"... Apr 17 11:51:34 volumio kernel: Connecting with e0:f4:42:92:3e:10 ssid "AADPM5_5G", len (9) channel=44 Apr 17 11:51:34 volumio volumio[5189]: info: Plugin example_plugin is not enabled Apr 17 11:51:34 volumio volumio[5189]: info: Loading plugin "inputs"... Apr 17 11:51:34 volumio volumio[5189]: info: Loading plugin "updater_comm"... Apr 17 11:51:34 volumio kernel: wl_iw_event: Link UP with BSSID=e0:f4:42:92:3e:10 Apr 17 11:51:34 volumio kernel: wl_bss_connect_done succeeded with e0:f4:42:92:3e:10 Apr 17 11:51:34 volumio wpa_supplicant[4984]: wlan0: Associated with e0:f4:42:92:3e:10 Apr 17 11:51:34 volumio kernel: wl_bss_connect_done succeeded with e0:f4:42:92:3e:10 Apr 17 11:51:34 volumio volumio[5189]: info: Plugin mpdemulation is not enabled Apr 17 11:51:34 volumio volumio[5189]: info: Loading plugin "rest_api"... Apr 17 11:51:34 volumio volumio[5189]: info: Loading plugin "websocket"... Apr 17 11:51:34 volumio volumio[5189]: info: ___________ START PLUGINS ___________ Apr 17 11:51:34 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 11:51:34 volumio volumio[5189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 11:51:34 volumio volumio[5189]: info: [1713354694952] CoreMusicLibrary::Adding element Media Servers Apr 17 11:51:34 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 11:51:34 volumio volumio[5189]: Forking 3 albumart workers Apr 17 11:51:35 volumio volumio[5189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 11:51:35 volumio volumio-remote-updater[4596]: [2024-04-17 11:51:35] [connect] Successful connection Apr 17 11:51:35 volumio volumio[5189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 17 11:51:35 volumio volumio[5189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 11:51:35 volumio volumio[5189]: info: [1713354695043] CoreMusicLibrary::Adding element Last_100 Apr 17 11:51:35 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 11:51:35 volumio volumio[5189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 17 11:51:35 volumio volumio[5189]: info: [1713354695048] CoreMusicLibrary::Adding element Webradio Apr 17 11:51:35 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 11:51:35 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 11:51:35 volumio volumio[5189]: info: ------------------------------------------- Apr 17 11:51:35 volumio volumio[5189]: info: ----- MyVolumio plugins startup ---- Apr 17 11:51:35 volumio volumio[5189]: info: ------------------------------------------- Apr 17 11:51:35 volumio volumio[5189]: info: Loading plugins from folder /myvolumio/plugins Apr 17 11:51:35 volumio volumio[5189]: info: Loading plugins from folder /data/myvolumio/plugins Apr 17 11:51:35 volumio volumio[5189]: info: Loading plugin "cd_controller"... Apr 17 11:51:35 volumio volumio[5189]: info: Loading plugin "streaming_services"... Apr 17 11:51:35 volumio volumio[5189]: info: Loading plugin "my_volumio"... Apr 17 11:51:35 volumio volumio[5189]: Starting albumart workers Apr 17 11:51:35 volumio volumio[5189]: Starting albumart workers Apr 17 11:51:35 volumio volumio[5189]: Starting albumart workers Apr 17 11:51:36 volumio volumio[5189]: info: Adding CD REST API Endpoints Apr 17 11:51:36 volumio volumio[5189]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Apr 17 11:51:36 volumio volumio[5189]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Apr 17 11:51:36 volumio volumio[5189]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Apr 17 11:51:36 volumio volumio[5189]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 17 11:51:36 volumio volumio[5189]: info: Detecting CD presence Apr 17 11:51:36 volumio volumio[5189]: info: Streaming services startup Apr 17 11:51:36 volumio volumio[5189]: info: Starting Streaming Daemon Apr 17 11:51:36 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 11:51:36 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 11:51:36 volumio volumio[5189]: info: Starting MyVolumio Remote Streaming Endpoints Apr 17 11:51:36 volumio sudo[5294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 17 11:51:36 volumio sudo[5294]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:36 volumio volumio[5189]: info: Loading i18n strings for locale zh Apr 17 11:51:36 volumio systemd[1]: Stopping Volumio Streaming Daemon... Apr 17 11:51:36 volumio volumio[5189]: Updating browse sources language Apr 17 11:51:36 volumio volumio[5189]: Cannot find translation for sourceMedia Servers Apr 17 11:51:36 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 11:51:36 volumio systemd[1]: Starting Volumio Streaming Daemon... Apr 17 11:51:36 volumio systemd[1]: Started Volumio Streaming Daemon. Apr 17 11:51:36 volumio sudo[5294]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:36 volumio volumio-streaming-daemon[5297]: ############################ Apr 17 11:51:36 volumio volumio-streaming-daemon[5297]: # Volumio Streaming Daemon # Apr 17 11:51:36 volumio volumio-streaming-daemon[5297]: # Running on port 7777 # Apr 17 11:51:36 volumio volumio-streaming-daemon[5297]: ############################ Apr 17 11:51:36 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 11:51:36 volumio volumio[5189]: info: CoreCommandRouter::initPlayerControls Apr 17 11:51:36 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 11:51:36 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Apr 17 11:51:36 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 11:51:36 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 11:51:36 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 11:51:36 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 11:51:36 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 17 11:51:36 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 11:51:36 volumio volumio[5189]: info: BOOT COMPLETED Apr 17 11:51:36 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 11:51:41 volumio volumio[5189]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Apr 17 11:51:41 volumio volumio[5189]: info: CoreCommandRouter::Close All Modals sent Apr 17 11:51:41 volumio volumio[5189]: info: CoreCommandRouter::Close All Modals sent Apr 17 11:51:41 volumio volumio[5189]: Express server listening on port 3000 Apr 17 11:51:41 volumio volumio[5189]: Volumio Calling Home Apr 17 11:51:41 volumio volumio[5189]: info: MyVolumio not started Apr 17 11:51:41 volumio volumio[5189]: info: Initializing device activation check Apr 17 11:51:41 volumio volumio[5189]: info: CoreStateMachine::resetVolumioState Apr 17 11:51:41 volumio volumio[5189]: info: CoreStateMachine::getcurrentVolume Apr 17 11:51:41 volumio volumio[5189]: info: CoreCommandRouter::volumioRetrievevolume Apr 17 11:51:41 volumio volumio[5189]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 17 11:51:42 volumio volumio[5189]: info: Setting Device type: Amlogic Apr 17 11:51:42 volumio volumio[5189]: info: MPD running with PID4690 ,establishing connection Apr 17 11:51:42 volumio sudo[5321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Apr 17 11:51:42 volumio sudo[5321]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:42 volumio sudo[5321]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:42 volumio volumio[5189]: info: VolumeController:: Volume=60 Mute =false Apr 17 11:51:42 volumio volumio[5189]: info: CoreStateMachine::pushState Apr 17 11:51:42 volumio volumio[5189]: info: CoreStateMachine::getState Apr 17 11:51:42 volumio volumio[5189]: info: CorePlayQueue::getTrack 0 Apr 17 11:51:42 volumio volumio[5189]: info: CoreCommandRouter::volumioPushState Apr 17 11:51:42 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 17 11:51:42 volumio sudo[5324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 11:51:42 volumio sudo[5324]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:42 volumio sudo[5324]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:42 volumio volumio[5189]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Apr 17 11:51:42 volumio volumio[5189]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Apr 17 11:51:42 volumio volumio[5189]: info: Reloading queue from file Apr 17 11:51:42 volumio volumio[5189]: info: Apr 17 11:51:42 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 17 11:51:42 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 17 11:51:42 volumio volumio[5189]: info: CoreStateMachine::setRepeat null single undefined Apr 17 11:51:42 volumio volumio[5189]: info: CoreStateMachine::pushState Apr 17 11:51:42 volumio volumio[5189]: info: CoreStateMachine::getState Apr 17 11:51:42 volumio volumio[5189]: info: CorePlayQueue::getTrack 0 Apr 17 11:51:42 volumio volumio[5189]: info: CoreCommandRouter::volumioPushState Apr 17 11:51:42 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 17 11:51:42 volumio volumio[5189]: info: CoreStateMachine::setRandom null Apr 17 11:51:42 volumio volumio[5189]: info: CoreStateMachine::pushState Apr 17 11:51:42 volumio volumio[5189]: info: CoreStateMachine::getState Apr 17 11:51:42 volumio volumio[5189]: info: CorePlayQueue::getTrack 0 Apr 17 11:51:42 volumio volumio[5189]: info: CoreCommandRouter::volumioPushState Apr 17 11:51:42 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 17 11:51:42 volumio sudo[5330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Apr 17 11:51:42 volumio sudo[5330]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:42 volumio sudo[5332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Apr 17 11:51:42 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Apr 17 11:51:42 volumio systemd[1]: Started ShairportSync AirTunes receiver. Apr 17 11:51:42 volumio sudo[5332]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:42 volumio sudo[5330]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:42 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Apr 17 11:51:42 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Apr 17 11:51:42 volumio systemd[1]: Started ShairportSync AirTunes receiver. Apr 17 11:51:42 volumio sudo[5332]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:42 volumio volumio[5189]: Discovery: adding c196827b-4a80-4d63-8628-344867740b1e Apr 17 11:51:42 volumio volumio[5189]: info: mDNS: Found device Volumio Apr 17 11:51:42 volumio shairport-sync[5337]: Successful Startup Apr 17 11:51:42 volumio sudo[5340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 11:51:42 volumio sudo[5340]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:42 volumio sudo[5340]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:42 volumio volumio[5189]: info: Shairport-Sync Started Apr 17 11:51:42 volumio volumio[5189]: Error adding Membership: Error: addMembership EINVAL Apr 17 11:51:42 volumio sudo[5344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 17 11:51:42 volumio sudo[5344]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:42 volumio volumio[5189]: info: Shairport-Sync Started Apr 17 11:51:43 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Apr 17 11:51:43 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 17 11:51:43 volumio sudo[5344]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:43 volumio volumio[5189]: info: CoreCommandRouter::volumioGetState Apr 17 11:51:43 volumio volumio[5189]: info: CoreStateMachine::getState Apr 17 11:51:43 volumio volumio[5189]: info: CorePlayQueue::getTrack 0 Apr 17 11:51:43 volumio sudo[5348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 11:51:43 volumio sudo[5348]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:43 volumio sudo[5348]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:43 volumio volumio[5189]: info: Upmpdcli Daemon Started Apr 17 11:51:43 volumio volumio[5189]: info: CoreCommandRouter::volumioGetVisibleSources Apr 17 11:51:43 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 11:51:43 volumio volumio[5189]: info: CoreCommandRouter::volumioGetState Apr 17 11:51:43 volumio volumio[5189]: info: CoreStateMachine::getState Apr 17 11:51:43 volumio volumio[5189]: info: CorePlayQueue::getTrack 0 Apr 17 11:51:43 volumio sudo[5351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 11:51:43 volumio sudo[5351]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:43 volumio sudo[5351]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:43 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 17 11:51:43 volumio volumio[5189]: info: Listing playlists Apr 17 11:51:43 volumio volumio[5189]: info: CoreCommandRouter::volumioGetQueue Apr 17 11:51:43 volumio volumio[5189]: info: CoreStateMachine::getQueue Apr 17 11:51:43 volumio volumio[5189]: info: CorePlayQueue::getQueue Apr 17 11:51:43 volumio sudo[5354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 17 11:51:43 volumio sudo[5354]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:51:43 volumio sudo[5354]: pam_unix(sudo:session): session closed for user root Apr 17 11:51:44 volumio wpa_supplicant[4984]: wlan0: Authentication with e0:f4:42:92:3e:10 timed out. Apr 17 11:51:44 volumio kernel: CFG80211-ERROR) wl_cfg80211_disconnect : Reason 3 Apr 17 11:51:44 volumio kernel: wl_iw_event: Link Down with BSSID=e0:f4:42:92:3e:10 Apr 17 11:51:44 volumio wpa_supplicant[4984]: wlan0: CTRL-EVENT-DISCONNECTED bssid=e0:f4:42:92:3e:10 reason=3 locally_generated=1 Apr 17 11:51:44 volumio wpa_supplicant[4984]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect Apr 17 11:51:44 volumio wpa_supplicant[4984]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="AADPM5_5G" auth_failures=2 duration=23 reason=WRONG_KEY Apr 17 11:51:44 volumio wpa_supplicant[4984]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="AADPM5_5G" auth_failures=3 duration=46 reason=CONN_FAILED Apr 17 11:51:44 volumio kernel: CFG80211-ERROR) wl_is_linkdown : Link down Reason : WLC_E_LINK Apr 17 11:51:44 volumio kernel: link down if wlan0 may call cfg80211_disconnected. event : 16, reason=2 from e0:f4:42:92:3e:10 Apr 17 11:51:44 volumio volumio[5189]: info: Fetching Streaming Services browse cache Apr 17 11:51:44 volumio volumio[5189]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Apr 17 11:51:44 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 11:51:44 volumio volumio[5189]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Apr 17 11:51:44 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 17 11:51:50 volumio volumio-remote-updater[4596]: [2024-04-17 11:51:50] [connect] Successful connection Apr 17 11:51:50 volumio volumio-remote-updater[4596]: [2024-04-17 11:51:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1713354710 101 Apr 17 11:51:52 volumio volumio[5189]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=vim1&variante=volumio&version=2.603&uuid=c647bfb1e0bab86fefab558442c6fed0" http://updates.volumio.org:7070/downloader-v1/track-device Apr 17 11:51:52 volumio volumio[5189]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 17 11:51:52 volumio volumio[5189]: Dload Upload Total Spent Left Speed Apr 17 11:51:52 volumio volumio[5189]: [235B blob data] Apr 17 11:51:52 volumio volumio[5189]: retrying in 5 seconds, trial 0 Apr 17 11:51:52 volumio volumio[5189]: Volumio Calling Home Apr 17 11:51:56 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 17 11:51:56 volumio volumio[5189]: info: CURURI: music-library/NAS Apr 17 11:51:58 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 17 11:51:58 volumio volumio[5189]: info: CURURI: music-library Apr 17 11:51:59 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 17 11:51:59 volumio volumio[5189]: info: CURURI: music-library/NAS Apr 17 11:52:01 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 17 11:52:01 volumio volumio[5189]: info: CURURI: music-library/NAS/nas Apr 17 11:52:02 volumio volumio[5189]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=vim1&variante=volumio&version=2.603&uuid=c647bfb1e0bab86fefab558442c6fed0" http://updates.volumio.org:7070/downloader-v1/track-device Apr 17 11:52:02 volumio volumio[5189]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 17 11:52:02 volumio volumio[5189]: Dload Upload Total Spent Left Speed Apr 17 11:52:02 volumio volumio[5189]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Apr 17 11:52:02 volumio volumio[5189]: retrying in 5 seconds, trial 1 Apr 17 11:52:02 volumio volumio[5189]: Volumio Calling Home Apr 17 11:52:05 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 17 11:52:05 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 17 11:52:06 volumio volumio[5189]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 11:52:06 volumio volumio[5189]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 17 11:52:06 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 17 11:52:06 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 17 11:52:06 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 17 11:52:06 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 17 11:52:06 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 11:52:06 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 11:52:06 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 11:52:06 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 17 11:52:06 volumio volumio[5189]: info: CoreCommandRouter::volumioGetBrowseSources Apr 17 11:52:07 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 17 11:52:07 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 17 11:52:11 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 17 11:52:13 volumio volumio[5189]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=vim1&variante=volumio&version=2.603&uuid=c647bfb1e0bab86fefab558442c6fed0" http://updates.volumio.org:7070/downloader-v1/track-device Apr 17 11:52:13 volumio volumio[5189]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 17 11:52:13 volumio volumio[5189]: Dload Upload Total Spent Left Speed Apr 17 11:52:13 volumio volumio[5189]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Apr 17 11:52:13 volumio volumio[5189]: retrying in 5 seconds, trial 2 Apr 17 11:52:13 volumio volumio[5189]: Volumio Calling Home Apr 17 11:52:15 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 17 11:52:16 volumio volumio[5189]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Apr 17 11:52:16 volumio sudo[5488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/nas Apr 17 11:52:16 volumio sudo[5488]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 17 11:52:16 volumio sudo[5488]: pam_unix(sudo:session): session closed for user root Apr 17 11:52:16 volumio volumio[5189]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 11:52:16 volumio volumio[5189]: { Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/nas' Apr 17 11:52:16 volumio volumio[5189]: at Object.fs.rmdirSync (fs.js:846:18) Apr 17 11:52:16 volumio volumio[5189]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Apr 17 11:52:16 volumio volumio[5189]: at ChildProcess.exithandler (child_process.js:267:7) Apr 17 11:52:16 volumio volumio[5189]: at emitTwo (events.js:126:13) Apr 17 11:52:16 volumio volumio[5189]: at ChildProcess.emit (events.js:214:7) Apr 17 11:52:16 volumio volumio[5189]: at maybeClose (internal/child_process.js:925:16) Apr 17 11:52:16 volumio volumio[5189]: at Socket.stream.socket.on (internal/child_process.js:346:11) Apr 17 11:52:16 volumio volumio[5189]: at emitOne (events.js:116:13) Apr 17 11:52:16 volumio volumio[5189]: at Socket.emit (events.js:211:7) Apr 17 11:52:16 volumio volumio[5189]: at Pipe._handle.close [as _onclose] (net.js:567:12) Apr 17 11:52:16 volumio volumio[5189]: errno: -16, Apr 17 11:52:16 volumio volumio[5189]: code: 'EBUSY', Apr 17 11:52:16 volumio volumio[5189]: syscall: 'rmdir', Apr 17 11:52:16 volumio volumio[5189]: path: '/mnt/NAS/nas' } Apr 17 11:52:16 volumio volumio[5189]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 17 11:52:17 volumio sudo[5498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-17 11:51 Apr 17 11:52:17 volumio sudo[5498]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7434a810e3a57a07e8ee53a8f2063b5b15d2425c" VOLUMIO_FE_VERSION="8d42aa2ed20c7c37b42519af63b83b0cbcd3af68" VOLUMIO_BE_VERSION="fd4f91711c718295fa83ff62d1b2772d1cb50601" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Sep 3 19:53:55 CEST 2019" VOLUMIO_VERSION="2.603" VOLUMIO_HARDWARE="vim1" VOLUMIO_HASH="b2272113b215eebd4e2ea68b15bca646"