-- Logs begin at Sun 2025-05-11 04:08:18 UTC, end at Fri 2025-05-16 02:14:55 UTC. -- May 16 02:13:35 volumio systemd[1]: Time has been changed May 16 02:13:35 volumio nmbd[4807]: [2025/05/16 02:13:35.706465, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 16 02:13:35 volumio nmbd[4807]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.3.215 for name WORKGROUP<1d>. May 16 02:13:35 volumio nmbd[4807]: This response was from IP 192.168.3.149, reporting an IP address of 192.168.3.149. May 16 02:13:36 volumio wireless.js[4689]: trying... May 16 02:13:36 volumio sudo[5154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 16 02:13:36 volumio sudo[5154]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:13:36 volumio sudo[5154]: pam_unix(sudo:session): session closed for user root May 16 02:13:37 volumio wireless.js[4689]: trying... May 16 02:13:37 volumio sudo[5165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 16 02:13:37 volumio sudo[5165]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:13:37 volumio sudo[5165]: pam_unix(sudo:session): session closed for user root May 16 02:13:38 volumio wireless.js[4689]: trying... May 16 02:13:38 volumio sudo[5168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 16 02:13:38 volumio sudo[5168]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:13:38 volumio sudo[5168]: pam_unix(sudo:session): session closed for user root May 16 02:13:38 volumio dhcpcd[5084]: wlan0: using IPv4LL address 169.254.61.83 May 16 02:13:38 volumio avahi-daemon[4666]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.61.83. May 16 02:13:38 volumio avahi-daemon[4666]: New relevant interface wlan0.IPv4 for mDNS. May 16 02:13:38 volumio dhcpcd[5084]: eth0: adding route to 192.168.3.0/24 May 16 02:13:38 volumio avahi-daemon[4666]: Registering new address record for 169.254.61.83 on wlan0.IPv4. May 16 02:13:38 volumio dhcpcd[5084]: eth0: adding default route via 192.168.3.1 May 16 02:13:38 volumio dhcpcd[5084]: wlan0: adding host route to 169.254.61.83 via 127.0.0.1 May 16 02:13:38 volumio dhcpcd[5084]: wlan0: adding route to 169.254.0.0/16 May 16 02:13:38 volumio dhcpcd[5084]: eth0: removing route to 192.168.3.0/24 May 16 02:13:38 volumio dhcpcd[5084]: eth0: removing default route via 192.168.3.1 May 16 02:13:38 volumio dhcpcd[5084]: wlan0: hardware address da:fc:8e:cf:44:c2 claims 169.254.61.83 May 16 02:13:38 volumio dhcpcd[5084]: eth0: adding route to 192.168.3.0/24 May 16 02:13:38 volumio dhcpcd[5084]: eth0: adding default route via 192.168.3.1 May 16 02:13:38 volumio dhcpcd[5084]: wlan0: deleting host route to 169.254.61.83 via 127.0.0.1 May 16 02:13:38 volumio dhcpcd[5084]: wlan0: deleting route to 169.254.0.0/16 May 16 02:13:38 volumio avahi-daemon[4666]: Withdrawing address record for 169.254.61.83 on wlan0. May 16 02:13:38 volumio avahi-daemon[4666]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.61.83. May 16 02:13:38 volumio avahi-daemon[4666]: Interface wlan0.IPv4 no longer relevant for mDNS. May 16 02:13:38 volumio dhcpcd[5084]: eth0: removing route to 192.168.3.0/24 May 16 02:13:38 volumio dhcpcd[5084]: eth0: removing default route via 192.168.3.1 May 16 02:13:38 volumio volumio-remote-updater[4577]: [2025-05-16 02:13:38] [info] asio async_connect error: system:111 (Connection refused) May 16 02:13:38 volumio volumio-remote-updater[4577]: [2025-05-16 02:13:38] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 16 02:13:38 volumio volumio-remote-updater[4577]: [2025-05-16 02:13:38] [error] handle_connect error: Underlying Transport Error May 16 02:13:39 volumio wireless.js[4689]: trying... May 16 02:13:39 volumio sudo[5210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 16 02:13:39 volumio sudo[5210]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:13:39 volumio sudo[5210]: pam_unix(sudo:session): session closed for user root May 16 02:13:39 volumio dhcpcd[5084]: wlan0: probing for an IPv4LL address May 16 02:13:40 volumio wireless.js[4689]: trying... May 16 02:13:40 volumio sudo[5213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 16 02:13:40 volumio sudo[5213]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:13:40 volumio sudo[5213]: pam_unix(sudo:session): session closed for user root May 16 02:13:41 volumio wireless.js[4689]: Overtime, starting plan B May 16 02:13:41 volumio wireless.js[4689]: STARTING HOTSPOT May 16 02:13:41 volumio wpa_supplicant[4878]: wlan0: CTRL-EVENT-DISCONNECTED bssid=ac:92:32:17:c9:98 reason=3 locally_generated=1 May 16 02:13:41 volumio kernel: CFG80211-ERROR) wl_cfg80211_disconnect : Reason 3 May 16 02:13:41 volumio kernel: wl_iw_event: Link Down with BSSID=ac:92:32:17:c9:98 May 16 02:13:41 volumio kernel: CFG80211-ERROR) wl_is_linkdown : Link down Reason : WLC_E_LINK May 16 02:13:41 volumio kernel: link down if wlan0 may call cfg80211_disconnected. event : 16, reason=2 from ac:92:32:17:c9:98 May 16 02:13:41 volumio dhcpcd[5084]: wlan0: carrier lost May 16 02:13:41 volumio dhcpcd[5084]: eth0: adding route to 192.168.3.0/24 May 16 02:13:41 volumio dhcpcd[5084]: eth0: adding default route via 192.168.3.1 May 16 02:13:41 volumio dhcpcd[5084]: eth0: removing route to 192.168.3.0/24 May 16 02:13:41 volumio dhcpcd[5084]: eth0: removing default route via 192.168.3.1 May 16 02:13:41 volumio dhcpcd[5084]: wlan0: carrier acquired May 16 02:13:41 volumio kernel: CFG80211-ERROR) wl_cfg80211_disconnect : Reason 3 May 16 02:13:41 volumio kernel: dhd_stop: Enter ffffffc061a94000 May 16 02:13:41 volumio kernel: wl_event_handler: was terminated May 16 02:13:41 volumio kernel: wl_destroy_event_handler(): thread:wl_event_handler:12e7 terminated OK May 16 02:13:41 volumio kernel: CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized May 16 02:13:41 volumio kernel: CFGP2P-ERROR) wl_cfgp2p_deinit_priv : In May 16 02:13:41 volumio kernel: wl_android_wifi_off in 1 May 16 02:13:41 volumio kernel: wl_android_wifi_off in 2: g_wifi_on=1, on_failure=1 May 16 02:13:41 volumio kernel: bcmsdh_oob_intr_unregister: Enter May 16 02:13:41 volumio kernel: dhd_txglom_enable: enable 0 May 16 02:13:41 volumio kernel: dhd_conf_set_txglom_params: swtxglom=0, txglom_ext=0, txglom_bucket_size=0 May 16 02:13:41 volumio kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0, bus_txglom=-1 May 16 02:13:41 volumio kernel: dhd_conf_set_txglom_params: tx_in_rx=1, txinrx_thres=-1, dhd_txminmax=1 May 16 02:13:41 volumio dhcpcd[5084]: wlan0: carrier lost May 16 02:13:41 volumio dhcpcd[5084]: eth0: adding route to 192.168.3.0/24 May 16 02:13:41 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=1 May 16 02:13:41 volumio kernel: sdioh_set_mode: set txglom_mode to multi-desc May 16 02:13:41 volumio kernel: dhd_bus_devreset: WLAN OFF DONE May 16 02:13:41 volumio kernel: wifi_platform_set_power = 0 May 16 02:13:41 volumio kernel: ======== PULL WL_REG_ON(-1) LOW! ======== May 16 02:13:41 volumio kernel: wl_android_wifi_off out May 16 02:13:41 volumio wpa_supplicant[4878]: wlan0: CTRL-EVENT-TERMINATING May 16 02:13:41 volumio dhcpcd[5084]: eth0: adding default route via 192.168.3.1 May 16 02:13:41 volumio kernel: dhd_stop: Exit May 16 02:13:41 volumio kernel: cfg80211: Calling CRDA to update world regulatory domain May 16 02:13:41 volumio kernel: cfg80211: World regulatory domain updated: May 16 02:13:41 volumio kernel: cfg80211: DFS Master region: unset May 16 02:13:41 volumio kernel: cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp) May 16 02:13:41 volumio kernel: cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm) May 16 02:13:41 volumio dhcpcd[5084]: wlan0: waiting for carrier May 16 02:13:41 volumio dhcpcd[5084]: eth0: removing route to 192.168.3.0/24 May 16 02:13:41 volumio dhcpcd[5084]: eth0: removing default route via 192.168.3.1 May 16 02:13:41 volumio kernel: cfg80211: (2457000 KHz - 2482000 KHz @ 20000 KHz), (N/A, 2000 mBm) May 16 02:13:41 volumio kernel: cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm) May 16 02:13:41 volumio kernel: cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz), (N/A, 2000 mBm) May 16 02:13:41 volumio kernel: cfg80211: (5250000 KHz - 5330000 KHz @ 80000 KHz), (N/A, 2000 mBm) May 16 02:13:41 volumio kernel: cfg80211: (5490000 KHz - 5730000 KHz @ 160000 KHz), (N/A, 2000 mBm) May 16 02:13:41 volumio kernel: cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm) May 16 02:13:41 volumio kernel: cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm) May 16 02:13:44 volumio systemd[1]: Stopped hotspot.service. May 16 02:13:44 volumio sudo[5283]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 16 02:13:44 volumio sudo[5283]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:13:44 volumio sudo[5283]: pam_unix(sudo:session): session closed for user root May 16 02:13:44 volumio sudo[5285]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 16 02:13:44 volumio sudo[5285]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:13:44 volumio sudo[5285]: pam_unix(sudo:session): session closed for user root May 16 02:13:44 volumio wireless.js[4689]: Hotspot is disabled, not starting it May 16 02:13:44 volumio kernel: dhd_open: Enter ffffffc061a94000 May 16 02:13:44 volumio kernel: Dongle Host Driver, version 1.363.59.144.9 (r) May 16 02:13:44 volumio kernel: wl_android_wifi_on in 1 May 16 02:13:44 volumio kernel: wl_android_wifi_on in 2: g_wifi_on=0 May 16 02:13:44 volumio kernel: wifi_platform_set_power = 1 May 16 02:13:44 volumio kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== May 16 02:13:44 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Disable! 240 May 16 02:13:44 volumio kernel: aml_wifi wifi.33: [extern_wifi_set_enable] WIFI Enable! 240 May 16 02:13:45 volumio kernel: sdio_reset_comm(): May 16 02:13:45 volumio kernel: sdio: clk 200000000 SDR mode tuning start May 16 02:13:45 volumio kernel: sdio: rx_tuning_result[0] = 10 May 16 02:13:45 volumio kernel: sdio: rx_tuning_result[1] = 10 May 16 02:13:45 volumio kernel: sdio: rx_tuning_result[3] = 10 May 16 02:13:45 volumio kernel: sdio: rx_tuning_result[4] = 10 May 16 02:13:45 volumio kernel: sdio: best_win_start =3, best_win_size =4 May 16 02:13:45 volumio kernel: sdio: sd_emmc_regs->gclock=0x1000245,sd_emmc_regs->gadjust=0x2000 May 16 02:13:45 volumio kernel: sdio: gclock =0x1000245, gdelay=0x0, gadjust=0x2000 May 16 02:13:45 volumio kernel: sdioh_start: set sd_f2_blocksize 256 May 16 02:13:45 volumio kernel: dhd_bus_devreset: == WLAN ON == May 16 02:13:45 volumio kernel: F1 signature read @0x18000000=0x15264345 May 16 02:13:45 volumio kernel: F1 signature OK, socitype:0x1 chip:0x4345 rev:0x6 pkg:0x2 May 16 02:13:45 volumio kernel: DHD: dongle ram size is set to 819200(orig 819200) at 0x198000 May 16 02:13:45 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/config.txt (399 bytes) open success May 16 02:13:45 volumio kernel: dhd_conf_read_others: ccode = SG May 16 02:13:45 volumio kernel: dhd_conf_read_others: bcn_timeout = 20 May 16 02:13:45 volumio kernel: dhd_conf_read_sdio_params: dhd_slpauto = 1 May 16 02:13:45 volumio kernel: dhd_conf_read_others: mimo_bw_cap = 1 May 16 02:13:45 volumio kernel: dhd_conf_read_pm_params: PM = 0 May 16 02:13:45 volumio kernel: dhd_conf_read_nv_by_chip: nv_by_chip_count=12 May 16 02:13:45 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa962, chiprev=0, name=nvram_ap6181.txt May 16 02:13:45 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa962, chiprev=1, name=nvram_ap6210.txt May 16 02:13:45 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=0, name=nvram_ap6212.txt May 16 02:13:45 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=1, name=nvram_ap6212a.txt May 16 02:13:45 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4345, chiprev=6, name=nvram_ap6255.txt May 16 02:13:45 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4330, chiprev=4, name=nvram_ap6330.txt May 16 02:13:45 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4339, chiprev=1, name=nvram_ap6335.txt May 16 02:13:45 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4354, chiprev=2, name=nvram_ap6356.txt May 16 02:13:45 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4335, chiprev=1, name=nvram_bcm4335.txt May 16 02:13:45 volumio kernel: dhd_conf_read_nv_by_chip: chip=0xa94c, chiprev=2, name=nvram_ap6234.txt May 16 02:13:45 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4359, chiprev=9, name=nvram_ap6359sa.txt May 16 02:13:45 volumio kernel: dhd_conf_read_nv_by_chip: chip=0x4334, chiprev=3, name=nvram_bcm4334.txt May 16 02:13:45 volumio kernel: Final fw_path=/lib/firmware/brcm/fw_bcm43455c0_ag.bin May 16 02:13:45 volumio kernel: Final nv_path=/lib/firmware/brcm/nvram_ap6255.txt May 16 02:13:45 volumio kernel: Final clm_path=/lib/firmware/brcm/clm_bcmdhd.blob May 16 02:13:45 volumio kernel: Final conf_path=/lib/firmware/brcm/config.txt May 16 02:13:45 volumio kernel: dhd_set_bus_params: set use_rxchain 0 May 16 02:13:45 volumio kernel: dhd_set_bus_params: set txglomsize 36 May 16 02:13:45 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/fw_bcm43455c0_ag.bin (483181 bytes) open success May 16 02:13:45 volumio kernel: dhd_os_open_image: /lib/firmware/brcm/nvram_ap6255.txt (1644 bytes) open success May 16 02:13:45 volumio kernel: NVRAM version: AP6255_NVRAM_V1.0_29052015 May 16 02:13:45 volumio kernel: dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded. May 16 02:13:45 volumio kernel: dhd_bus_init: enable 0x06, ready 0x06 (waited 0us) May 16 02:13:45 volumio kernel: bcmsdh_oob_intr_register: Enter May 16 02:13:45 volumio kernel: bcmsdh_oob_intr_register: HW_OOB enabled May 16 02:13:45 volumio kernel: bcmsdh_oob_intr_register OOB irq=100 flags=0x4 May 16 02:13:45 volumio kernel: bcmsdh_oob_intr_register change flags to 0x4 May 16 02:13:45 volumio kernel: bcmsdh_oob_intr_register: enable_irq_wake May 16 02:13:45 volumio kernel: bcmsdh_oob_intr_register: enable_irq_wake failed with -6 May 16 02:13:45 volumio kernel: Disable tdls_auto_op failed. -1 May 16 02:13:45 volumio kernel: dhd_conf_set_intiovar: set WLC_SET_BAND 142 0 May 16 02:13:45 volumio kernel: dhd_preinit_ioctls: Set tcpack_sup_mode 0 May 16 02:13:45 volumio kernel: dhd_tcpack_suppress_set 347: already set to 0 May 16 02:13:45 volumio kernel: dhd_apply_default_clm: Ignore clm file /lib/firmware/brcm/clm_bcmdhd.blob May 16 02:13:45 volumio kernel: Firmware up: op_mode=0x0005, MAC=00:90:4c:c5:12:38 May 16 02:13:45 volumio kernel: dhd_conf_set_country: set country SG, revision 0 May 16 02:13:45 volumio kernel: Country code: SG (SG/0) May 16 02:13:45 volumio kernel: dhd_conf_set_intiovar: set roam_off 1 May 16 02:13:45 volumio kernel: dhd_conf_set_intiovar: set mimo_bw_cap 1 May 16 02:13:45 volumio kernel: Firmware version = wl0: Aug 25 2015 18:58:57 version 7.45.69 (r581703) FWID 01-24037f6e May 16 02:13:45 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 May 16 02:13:45 volumio kernel: clm = 7.14.8 May 16 02:13:45 volumio kernel: dhd_txglom_enable: enable 1 May 16 02:13:45 volumio kernel: dhd_conf_set_txglom_params: swtxglom=0, txglom_ext=0, txglom_bucket_size=0 May 16 02:13:45 volumio kernel: dhd_conf_set_txglom_params: txglomsize=36, deferred_tx_len=0, bus_txglom=-1 May 16 02:13:45 volumio kernel: dhd_conf_set_txglom_params: tx_in_rx=1, txinrx_thres=-1, dhd_txminmax=1 May 16 02:13:45 volumio kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=1 May 16 02:13:45 volumio kernel: sdioh_set_mode: set txglom_mode to multi-desc May 16 02:13:45 volumio kernel: dhd_conf_get_disable_proptx: fw_proptx=1, disable_proptx=-1 May 16 02:13:45 volumio kernel: dhd_wlfc_hostreorder_init(): successful bdcv2 tlv signaling, 64 May 16 02:13:45 volumio kernel: dhd_conf_set_intiovar: set ampdu_hostreorder 1 May 16 02:13:45 volumio kernel: wl_android_wifi_on: Success May 16 02:13:45 volumio kernel: wl_create_event_handler(): thread:wl_event_handler:14a9 started May 16 02:13:45 volumio kernel: tsk Enter, tsk = 0xffffff8001550bb8 May 16 02:13:45 volumio kernel: CFG80211-ERROR) wl_update_wiphybands : error reading txbf_bfe_cap (-23) May 16 02:13:45 volumio kernel: CFG80211-ERROR) wl_update_wiphybands : error reading txbf_bfr_cap (-23) May 16 02:13:45 volumio dhcpcd[5084]: wlan0: carrier acquired May 16 02:13:45 volumio kernel: dhd_open: Exit ret=0 May 16 02:13:45 volumio systemd[1]: Started Wireless Services. May 16 02:13:45 volumio systemd[1]: Starting Volumio Backend Module... May 16 02:13:45 volumio systemd[1]: Started Volumio Backend Module. May 16 02:13:45 volumio systemd[1]: Starting Volumio Streaming Daemon... May 16 02:13:45 volumio systemd[1]: Started Volumio Streaming Daemon. May 16 02:13:45 volumio systemd[1]: Starting Multi-User System. May 16 02:13:45 volumio systemd[1]: Reached target Multi-User System. May 16 02:13:45 volumio systemd[1]: Starting Graphical Interface. May 16 02:13:45 volumio systemd[1]: Reached target Graphical Interface. May 16 02:13:45 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 16 02:13:45 volumio dhcpcd[5084]: wlan0: soliciting an IPv6 router May 16 02:13:45 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 16 02:13:45 volumio volumio-streaming-daemon[5302]: ############################ May 16 02:13:45 volumio volumio-streaming-daemon[5302]: # Volumio Streaming Daemon # May 16 02:13:45 volumio volumio-streaming-daemon[5302]: # Running on port 7777 # May 16 02:13:45 volumio volumio-streaming-daemon[5302]: ############################ May 16 02:13:47 volumio volumio[5298]: info: ------------------------------------------- May 16 02:13:47 volumio volumio[5298]: info: ----- Volumio2 ---- May 16 02:13:47 volumio volumio[5298]: info: ------------------------------------------- May 16 02:13:47 volumio volumio[5298]: info: ----- System startup ---- May 16 02:13:47 volumio volumio[5298]: info: ------------------------------------------- May 16 02:13:48 volumio volumio[5298]: info: MYVOLUMIO Environment detected May 16 02:13:48 volumio volumio[5298]: info: Plugin folders cleanup May 16 02:13:48 volumio volumio[5298]: info: Scanning into folder /volumio/app/plugins/ May 16 02:13:48 volumio volumio[5298]: info: Scanning category audio_interface May 16 02:13:48 volumio volumio[5298]: info: Scanning category miscellanea May 16 02:13:48 volumio volumio[5298]: info: Scanning category music_service May 16 02:13:48 volumio volumio[5298]: info: Scanning category plugins.json May 16 02:13:48 volumio volumio[5298]: info: Scanning category system_controller May 16 02:13:48 volumio volumio[5298]: info: Scanning category user_interface May 16 02:13:48 volumio volumio[5298]: info: Scanning into folder /data/plugins/ May 16 02:13:48 volumio volumio[5298]: info: Plugin folders cleanup completed May 16 02:13:48 volumio volumio[5298]: info: ------------------------------------------- May 16 02:13:48 volumio volumio[5298]: info: ----- Core plugins startup ---- May 16 02:13:48 volumio volumio[5298]: info: ------------------------------------------- May 16 02:13:48 volumio volumio[5298]: info: Loading plugins from folder /volumio/app/plugins/ May 16 02:13:48 volumio volumio[5298]: info: Adding plugin upnp to MyMusic Plugins May 16 02:13:48 volumio volumio[5298]: info: Adding plugin airplay_emulation to MyMusic Plugins May 16 02:13:48 volumio volumio[5298]: info: Adding plugin upnp_browser to MyMusic Plugins May 16 02:13:48 volumio volumio[5298]: info: Loading plugins from folder /data/plugins/ May 16 02:13:48 volumio volumio[5298]: info: Loading plugin "system"... May 16 02:13:48 volumio volumio[5298]: info: Loading plugin "appearance"... May 16 02:13:48 volumio volumio-remote-updater[4577]: [2025-05-16 02:13:48] [connect] Successful connection May 16 02:13:49 volumio volumio[5298]: info: Loading plugin "network"... May 16 02:13:49 volumio volumio[5298]: info: Loading plugin "services"... May 16 02:13:49 volumio volumio[5298]: info: Loading plugin "alsa_controller"... May 16 02:13:49 volumio volumio[5298]: info: Loading plugin "wizard"... May 16 02:13:49 volumio volumio[5298]: info: Loading plugin "volumio_command_line_client"... May 16 02:13:49 volumio volumio[5298]: info: Loading plugin "upnp"... May 16 02:13:49 volumio volumio[5298]: info: [1747361629964] Starting Upmpd Daemon May 16 02:13:49 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 16 02:13:49 volumio volumio[5298]: info: Loading plugin "my_music"... May 16 02:13:49 volumio volumio[5298]: info: Loading plugin "mpd"... May 16 02:13:50 volumio volumio[5298]: info: Loading plugin "upnp_browser"... May 16 02:13:51 volumio volumio[5298]: info: Loading plugin "networkfs"... May 16 02:13:51 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 16 02:13:51 volumio volumio[5298]: info: Loading plugin "alarm-clock"... May 16 02:13:51 volumio sudo[5340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hcd1984822,password=huang1984,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.3.149/音乐 /mnt/NAS/NAS May 16 02:13:51 volumio sudo[5340]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:13:51 volumio dhcpcd[5084]: wlan0: using IPv4LL address 169.254.61.83 May 16 02:13:51 volumio avahi-daemon[4666]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.61.83. May 16 02:13:51 volumio dhcpcd[5084]: eth0: adding route to 192.168.3.0/24 May 16 02:13:51 volumio dhcpcd[5084]: eth0: adding default route via 192.168.3.1 May 16 02:13:51 volumio dhcpcd[5084]: wlan0: adding host route to 169.254.61.83 via 127.0.0.1 May 16 02:13:51 volumio dhcpcd[5084]: wlan0: adding route to 169.254.0.0/16 May 16 02:13:51 volumio avahi-daemon[4666]: New relevant interface wlan0.IPv4 for mDNS. May 16 02:13:51 volumio avahi-daemon[4666]: Registering new address record for 169.254.61.83 on wlan0.IPv4. May 16 02:13:51 volumio sudo[5340]: pam_unix(sudo:session): session closed for user root May 16 02:13:51 volumio dhcpcd[5084]: eth0: removing route to 192.168.3.0/24 May 16 02:13:51 volumio dhcpcd[5084]: eth0: removing default route via 192.168.3.1 May 16 02:13:51 volumio volumio[5298]: info: Loading plugin "airplay_emulation"... May 16 02:13:51 volumio volumio[5298]: info: Starting Shairport Sync May 16 02:13:51 volumio volumio[5298]: info: Loading plugin "last_100"... May 16 02:13:51 volumio volumio[5298]: info: Loading plugin "webradio"... May 16 02:13:52 volumio volumio[5298]: info: Loading plugin "i2s_dacs"... May 16 02:13:52 volumio volumio[5298]: info: I2S DAC not set, start Auto-detection May 16 02:13:52 volumio volumio[5298]: info: Loading plugin "volumiodiscovery"... May 16 02:13:52 volumio volumio[5298]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 16 02:13:52 volumio node[5298]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 16 02:13:52 volumio node[5298]: *** WARNING *** Please fix your application to use the native API of Avahi! May 16 02:13:52 volumio node[5298]: *** WARNING *** For more information see May 16 02:13:52 volumio node[5298]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 16 02:13:52 volumio node[5298]: *** WARNING *** Please fix your application to use the native API of Avahi! May 16 02:13:52 volumio node[5298]: *** WARNING *** For more information see May 16 02:13:52 volumio volumio[5298]: *** WARNING *** Please fix your application to use the native API of Avahi! May 16 02:13:52 volumio volumio[5298]: *** WARNING *** For more information see May 16 02:13:52 volumio volumio[5298]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 16 02:13:52 volumio volumio[5298]: *** WARNING *** Please fix your application to use the native API of Avahi! May 16 02:13:52 volumio volumio[5298]: *** WARNING *** For more information see May 16 02:13:52 volumio volumio[5298]: info: Applying required configuration parameters for plugin volumiodiscovery May 16 02:13:52 volumio volumio[5298]: Discovery: StartAdv! undefined May 16 02:13:52 volumio volumio[5298]: Discovery: Started advertising... Volumio - undefined May 16 02:13:52 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 16 02:13:52 volumio volumio[5298]: info: Loading plugin "outputs"... May 16 02:13:52 volumio volumio[5298]: info: Loading plugin "albumart"... May 16 02:13:52 volumio volumio[5298]: info: Plugin example_plugin is not enabled May 16 02:13:52 volumio volumio[5298]: info: Loading plugin "inputs"... May 16 02:13:52 volumio volumio[5298]: info: Loading plugin "updater_comm"... May 16 02:13:52 volumio volumio[5298]: info: Plugin mpdemulation is not enabled May 16 02:13:52 volumio volumio[5298]: info: Loading plugin "rest_api"... May 16 02:13:52 volumio volumio[5298]: info: Loading plugin "websocket"... May 16 02:13:52 volumio volumio[5298]: info: ___________ START PLUGINS ___________ May 16 02:13:52 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 16 02:13:52 volumio volumio[5298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 16 02:13:52 volumio volumio[5298]: info: [1747361632523] CoreMusicLibrary::Adding element Media Servers May 16 02:13:52 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 02:13:52 volumio volumio[5298]: Forking 3 albumart workers May 16 02:13:52 volumio volumio[5298]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 16 02:13:52 volumio volumio[5298]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 16 02:13:52 volumio volumio[5298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 16 02:13:52 volumio volumio[5298]: info: [1747361632616] CoreMusicLibrary::Adding element Last_100 May 16 02:13:52 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 02:13:52 volumio volumio[5298]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 16 02:13:52 volumio volumio[5298]: info: [1747361632621] CoreMusicLibrary::Adding element Webradio May 16 02:13:52 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 02:13:52 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 16 02:13:52 volumio volumio[5298]: info: ------------------------------------------- May 16 02:13:52 volumio volumio[5298]: info: ----- MyVolumio plugins startup ---- May 16 02:13:52 volumio volumio[5298]: info: ------------------------------------------- May 16 02:13:52 volumio volumio[5298]: info: Loading plugins from folder /myvolumio/plugins May 16 02:13:52 volumio volumio[5298]: info: Loading plugins from folder /data/myvolumio/plugins May 16 02:13:52 volumio volumio[5298]: info: Loading plugin "cd_controller"... May 16 02:13:52 volumio volumio[5298]: info: Loading plugin "streaming_services"... May 16 02:13:53 volumio volumio[5298]: info: Loading plugin "my_volumio"... May 16 02:13:53 volumio ntpd[4647]: Listen normally on 5 wlan0 169.254.61.83 UDP 123 May 16 02:13:53 volumio ntpd[4647]: peers refreshed May 16 02:13:53 volumio volumio[5298]: Starting albumart workers May 16 02:13:53 volumio volumio[5298]: Starting albumart workers May 16 02:13:53 volumio volumio[5298]: Starting albumart workers May 16 02:13:53 volumio volumio[5298]: info: Adding CD REST API Endpoints May 16 02:13:53 volumio volumio[5298]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller May 16 02:13:53 volumio volumio[5298]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller May 16 02:13:53 volumio volumio[5298]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller May 16 02:13:53 volumio volumio[5298]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 16 02:13:53 volumio volumio[5298]: info: Detecting CD presence May 16 02:13:53 volumio volumio[5298]: info: Streaming services startup May 16 02:13:53 volumio volumio[5298]: info: Starting Streaming Daemon May 16 02:13:53 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 02:13:53 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 02:13:53 volumio volumio[5298]: info: Starting MyVolumio Remote Streaming Endpoints May 16 02:13:53 volumio sudo[5410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 16 02:13:53 volumio volumio[5298]: info: Loading i18n strings for locale zh May 16 02:13:53 volumio sudo[5410]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:13:53 volumio volumio[5298]: Updating browse sources language May 16 02:13:53 volumio volumio[5298]: Cannot find translation for sourceMedia Servers May 16 02:13:53 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 02:13:53 volumio systemd[1]: Stopping Volumio Streaming Daemon... May 16 02:13:53 volumio systemd[1]: Starting Volumio Streaming Daemon... May 16 02:13:53 volumio systemd[1]: Started Volumio Streaming Daemon. May 16 02:13:53 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 16 02:13:53 volumio sudo[5410]: pam_unix(sudo:session): session closed for user root May 16 02:13:53 volumio volumio[5298]: info: CoreCommandRouter::initPlayerControls May 16 02:13:53 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 02:13:53 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards May 16 02:13:53 volumio volumio-streaming-daemon[5413]: ############################ May 16 02:13:53 volumio volumio-streaming-daemon[5413]: # Volumio Streaming Daemon # May 16 02:13:53 volumio volumio-streaming-daemon[5413]: # Running on port 7777 # May 16 02:13:53 volumio volumio-streaming-daemon[5413]: ############################ May 16 02:13:53 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 02:13:53 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 02:13:53 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 02:13:53 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 02:13:53 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 16 02:13:53 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 16 02:13:53 volumio volumio[5298]: info: BOOT COMPLETED May 16 02:13:53 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 02:13:54 volumio dhcpcd[5084]: wlan0: soliciting a DHCP lease May 16 02:13:59 volumio volumio[5298]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo May 16 02:13:59 volumio volumio[5298]: info: CoreCommandRouter::Close All Modals sent May 16 02:13:59 volumio volumio[5298]: info: CoreCommandRouter::Close All Modals sent May 16 02:13:59 volumio volumio[5298]: Express server listening on port 3000 May 16 02:13:59 volumio volumio[5298]: Volumio Calling Home May 16 02:13:59 volumio volumio[5298]: info: MyVolumio not started May 16 02:13:59 volumio volumio[5298]: info: Initializing device activation check May 16 02:13:59 volumio volumio[5298]: info: CoreStateMachine::resetVolumioState May 16 02:13:59 volumio volumio[5298]: info: CoreStateMachine::getcurrentVolume May 16 02:13:59 volumio volumio[5298]: info: CoreCommandRouter::volumioRetrievevolume May 16 02:13:59 volumio volumio[5298]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 16 02:13:59 volumio volumio[5298]: info: Setting Device type: Amlogic May 16 02:13:59 volumio volumio[5298]: info: MPD running with PID4667 ,establishing connection May 16 02:13:59 volumio sudo[5438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 May 16 02:13:59 volumio sudo[5438]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:13:59 volumio sudo[5438]: pam_unix(sudo:session): session closed for user root May 16 02:13:59 volumio volumio[5298]: info: VolumeController:: Volume=undefined Mute =false May 16 02:13:59 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:13:59 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:13:59 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:13:59 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:13:59 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:00 volumio volumio[5298]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 May 16 02:14:00 volumio volumio[5298]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory May 16 02:14:00 volumio volumio[5298]: info: Reloading queue from file May 16 02:14:00 volumio sudo[5441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:00 volumio sudo[5441]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:00 volumio sudo[5441]: pam_unix(sudo:session): session closed for user root May 16 02:14:00 volumio volumio[5298]: info: May 16 02:14:00 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 16 02:14:00 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 16 02:14:00 volumio volumio[5298]: info: CoreStateMachine::setRepeat null single undefined May 16 02:14:00 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:00 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:00 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:00 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:00 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:00 volumio volumio[5298]: info: CoreStateMachine::setRandom true May 16 02:14:00 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:00 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:00 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:00 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:00 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:00 volumio sudo[5448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay May 16 02:14:00 volumio sudo[5448]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:00 volumio systemd[1]: Starting ShairportSync AirTunes receiver... May 16 02:14:00 volumio systemd[1]: Started ShairportSync AirTunes receiver. May 16 02:14:00 volumio sudo[5451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay May 16 02:14:00 volumio sudo[5448]: pam_unix(sudo:session): session closed for user root May 16 02:14:00 volumio sudo[5451]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:00 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... May 16 02:14:00 volumio systemd[1]: Starting ShairportSync AirTunes receiver... May 16 02:14:00 volumio systemd[1]: Started ShairportSync AirTunes receiver. May 16 02:14:00 volumio sudo[5451]: pam_unix(sudo:session): session closed for user root May 16 02:14:00 volumio shairport-sync[5457]: Successful Startup May 16 02:14:00 volumio volumio[5298]: info: Shairport-Sync Started May 16 02:14:00 volumio volumio[5298]: Error adding Membership: Error: addMembership EINVAL May 16 02:14:00 volumio volumio[5298]: info: Shairport-Sync Started May 16 02:14:00 volumio volumio[5298]: info: CoreCommandRouter::volumioGetState May 16 02:14:00 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:00 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:00 volumio sudo[5461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 16 02:14:00 volumio sudo[5461]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:00 volumio volumio[5298]: Discovery: adding 45e4cd1b-7f1c-42f6-998e-b8ba360a39cd May 16 02:14:00 volumio volumio[5298]: info: mDNS: Found device Volumio May 16 02:14:00 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... May 16 02:14:00 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 16 02:14:00 volumio sudo[5461]: pam_unix(sudo:session): session closed for user root May 16 02:14:00 volumio volumio[5298]: Discovery: this is already registered, 45e4cd1b-7f1c-42f6-998e-b8ba360a39cd May 16 02:14:00 volumio volumio[5298]: info: mDNS: Found device Volumio May 16 02:14:00 volumio sudo[5465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:00 volumio sudo[5465]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:00 volumio sudo[5465]: pam_unix(sudo:session): session closed for user root May 16 02:14:00 volumio sudo[5467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:00 volumio sudo[5467]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:00 volumio sudo[5467]: pam_unix(sudo:session): session closed for user root May 16 02:14:00 volumio volumio[5298]: info: Upmpdcli Daemon Started May 16 02:14:02 volumio volumio[5298]: info: Fetching Streaming Services browse cache May 16 02:14:02 volumio volumio[5298]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL May 16 02:14:02 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 02:14:02 volumio volumio[5298]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ May 16 02:14:02 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 02:14:03 volumio volumio-remote-updater[4577]: [2025-05-16 02:14:03] [connect] Successful connection May 16 02:14:03 volumio volumio-remote-updater[4577]: [2025-05-16 02:14:03] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1747361643 101 May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::sendMpdCommand status May 16 02:14:07 volumio volumio[5298]: info: May 16 02:14:07 volumio volumio[5298]: ---------------------------- MPD announces state update: update May 16 02:14:07 volumio volumio[5298]: info: sending command... May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::getState May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::sendMpdCommand status May 16 02:14:07 volumio volumio[5298]: info: sending command... May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::sendMpdCommand status May 16 02:14:07 volumio volumio[5298]: info: May 16 02:14:07 volumio volumio[5298]: ---------------------------- MPD announces state update: update May 16 02:14:07 volumio volumio[5298]: info: parsing response... May 16 02:14:07 volumio volumio[5298]: info: parsing response... May 16 02:14:07 volumio volumio[5298]: info: sending command... May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::getState May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::sendMpdCommand status May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::parseState May 16 02:14:07 volumio volumio[5298]: info: Command Router : Notfying DB Updatetrue May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::parseState May 16 02:14:07 volumio volumio[5298]: info: sending command... May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::servicePushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::syncState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 02:14:07 volumio volumio[5298]: info: CURRENT POSITION 0 May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::syncState stateService stop May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::syncState currentStatus stop May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:07 volumio sudo[5507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:07 volumio sudo[5507]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:07 volumio volumio[5298]: info: No code May 16 02:14:07 volumio sudo[5507]: pam_unix(sudo:session): session closed for user root May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:07 volumio sudo[5510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:07 volumio sudo[5510]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:07 volumio sudo[5510]: pam_unix(sudo:session): session closed for user root May 16 02:14:07 volumio sudo[5512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:07 volumio sudo[5512]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:07 volumio volumio[5298]: info: ------------------------------ 298ms May 16 02:14:07 volumio sudo[5512]: pam_unix(sudo:session): session closed for user root May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::sendMpdCommand status May 16 02:14:07 volumio volumio[5298]: info: May 16 02:14:07 volumio volumio[5298]: ---------------------------- MPD announces state update: update May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::sendMpdCommand status May 16 02:14:07 volumio volumio[5298]: info: May 16 02:14:07 volumio volumio[5298]: ---------------------------- MPD announces state update: update May 16 02:14:07 volumio volumio[5298]: info: sending command... May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::getState May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::sendMpdCommand status May 16 02:14:07 volumio volumio[5298]: info: parsing response... May 16 02:14:07 volumio volumio[5298]: info: sending command... May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::getState May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::sendMpdCommand status May 16 02:14:07 volumio volumio[5298]: info: parsing response... May 16 02:14:07 volumio volumio[5298]: info: sending command... May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::parseState May 16 02:14:07 volumio volumio[5298]: info: Command Router : Notfying DB Updatefalse May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::Close All Modals sent May 16 02:14:07 volumio volumio[5298]: info: sending command... May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::parseState May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::servicePushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::syncState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 02:14:07 volumio volumio[5298]: info: CURRENT POSITION 0 May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::syncState stateService stop May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::syncState currentStatus stop May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:07 volumio sudo[5518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:07 volumio sudo[5518]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:07 volumio sudo[5518]: pam_unix(sudo:session): session closed for user root May 16 02:14:07 volumio volumio[5298]: info: No code May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:07 volumio sudo[5521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:07 volumio sudo[5521]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:07 volumio sudo[5521]: pam_unix(sudo:session): session closed for user root May 16 02:14:07 volumio volumio[5298]: info: ------------------------------ 401ms May 16 02:14:07 volumio sudo[5524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:07 volumio sudo[5524]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:07 volumio sudo[5524]: pam_unix(sudo:session): session closed for user root May 16 02:14:07 volumio volumio[5298]: info: parsing response... May 16 02:14:07 volumio volumio[5298]: info: parsing response... May 16 02:14:07 volumio volumio[5298]: info: parsing response... May 16 02:14:07 volumio volumio[5298]: info: parsing response... May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::parseState May 16 02:14:07 volumio volumio[5298]: info: Command Router : Notfying DB Updatefalse May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::Close All Modals sent May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::parseState May 16 02:14:07 volumio volumio[5298]: info: Command Router : Notfying DB Updatefalse May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::Close All Modals sent May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::parseState May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::parseState May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::servicePushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::syncState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 02:14:07 volumio volumio[5298]: info: CURRENT POSITION 0 May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::syncState stateService stop May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::syncState currentStatus stop May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:07 volumio sudo[5531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:07 volumio sudo[5531]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:07 volumio sudo[5531]: pam_unix(sudo:session): session closed for user root May 16 02:14:07 volumio volumio[5298]: info: No code May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:07 volumio sudo[5534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:07 volumio sudo[5534]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:07 volumio sudo[5534]: pam_unix(sudo:session): session closed for user root May 16 02:14:07 volumio volumio[5298]: info: ControllerMpd::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::servicePushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::syncState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:07 volumio sudo[5537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:07 volumio sudo[5537]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:07 volumio sudo[5537]: pam_unix(sudo:session): session closed for user root May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 02:14:07 volumio volumio[5298]: info: CURRENT POSITION 0 May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::syncState stateService stop May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::syncState currentStatus stop May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:07 volumio sudo[5540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:07 volumio sudo[5540]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:07 volumio sudo[5540]: pam_unix(sudo:session): session closed for user root May 16 02:14:07 volumio volumio[5298]: info: No code May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:07 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:07 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:07 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:07 volumio sudo[5544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:07 volumio sudo[5544]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:08 volumio sudo[5544]: pam_unix(sudo:session): session closed for user root May 16 02:14:08 volumio sudo[5547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:08 volumio sudo[5547]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:08 volumio sudo[5547]: pam_unix(sudo:session): session closed for user root May 16 02:14:08 volumio volumio[5298]: info: ------------------------------ 348ms May 16 02:14:08 volumio volumio[5298]: info: ------------------------------ 349ms May 16 02:14:09 volumio volumio[5298]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=vim1&variante=volumio&version=2.603&uuid=9fd03e1b297776b13a1d1e700dabe96d" http://updates.volumio.org:7070/downloader-v1/track-device May 16 02:14:09 volumio volumio[5298]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 02:14:09 volumio volumio[5298]: Dload Upload Total Spent Left Speed May 16 02:14:09 volumio volumio[5298]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused May 16 02:14:09 volumio volumio[5298]: retrying in 5 seconds, trial 0 May 16 02:14:09 volumio volumio[5298]: Volumio Calling Home May 16 02:14:22 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 16 02:14:22 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 02:14:22 volumio volumio[5298]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=vim1&variante=volumio&version=2.603&uuid=9fd03e1b297776b13a1d1e700dabe96d" http://updates.volumio.org:7070/downloader-v1/track-device May 16 02:14:22 volumio volumio[5298]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 02:14:22 volumio volumio[5298]: Dload Upload Total Spent Left Speed May 16 02:14:22 volumio volumio[5298]: [314B blob data] May 16 02:14:22 volumio volumio[5298]: retrying in 5 seconds, trial 1 May 16 02:14:22 volumio volumio[5298]: Volumio Calling Home May 16 02:14:25 volumio volumio[5298]: info: ControllerMpd::sendMpdCommand status May 16 02:14:25 volumio volumio[5298]: info: May 16 02:14:25 volumio volumio[5298]: ---------------------------- MPD announces state update: update May 16 02:14:25 volumio volumio[5298]: info: sending command... May 16 02:14:25 volumio volumio[5298]: info: ControllerMpd::getState May 16 02:14:25 volumio volumio[5298]: info: ControllerMpd::sendMpdCommand status May 16 02:14:25 volumio volumio[5298]: info: sending command... May 16 02:14:25 volumio volumio[5298]: info: ControllerMpd::sendMpdCommand status May 16 02:14:25 volumio volumio[5298]: info: May 16 02:14:25 volumio volumio[5298]: ---------------------------- MPD announces state update: update May 16 02:14:25 volumio volumio[5298]: info: sending command... May 16 02:14:25 volumio volumio[5298]: info: ControllerMpd::getState May 16 02:14:25 volumio volumio[5298]: info: ControllerMpd::sendMpdCommand status May 16 02:14:25 volumio volumio[5298]: info: parsing response... May 16 02:14:25 volumio volumio[5298]: info: parsing response... May 16 02:14:25 volumio volumio[5298]: info: sending command... May 16 02:14:25 volumio volumio[5298]: info: ControllerMpd::parseState May 16 02:14:25 volumio volumio[5298]: info: Command Router : Notfying DB Updatefalse May 16 02:14:25 volumio volumio[5298]: info: CoreCommandRouter::Close All Modals sent May 16 02:14:25 volumio volumio[5298]: info: ControllerMpd::parseState May 16 02:14:25 volumio volumio[5298]: info: ControllerMpd::pushState May 16 02:14:25 volumio volumio[5298]: info: CoreCommandRouter::servicePushState May 16 02:14:25 volumio volumio[5298]: info: CoreStateMachine::syncState May 16 02:14:25 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:25 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:25 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:25 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:25 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:25 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:25 volumio volumio[5298]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 02:14:25 volumio volumio[5298]: info: CURRENT POSITION 0 May 16 02:14:25 volumio volumio[5298]: info: CoreStateMachine::syncState stateService stop May 16 02:14:25 volumio volumio[5298]: info: CoreStateMachine::syncState currentStatus stop May 16 02:14:25 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:25 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:25 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:25 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:25 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:25 volumio sudo[5615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:25 volumio sudo[5615]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:25 volumio sudo[5615]: pam_unix(sudo:session): session closed for user root May 16 02:14:25 volumio volumio[5298]: info: No code May 16 02:14:25 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:25 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:25 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:25 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:25 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:25 volumio sudo[5618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:25 volumio sudo[5618]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:25 volumio sudo[5618]: pam_unix(sudo:session): session closed for user root May 16 02:14:25 volumio sudo[5621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:25 volumio volumio[5298]: info: ------------------------------ 113ms May 16 02:14:25 volumio sudo[5621]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:25 volumio volumio[5298]: info: parsing response... May 16 02:14:25 volumio volumio[5298]: info: parsing response... May 16 02:14:25 volumio volumio[5298]: info: ControllerMpd::parseState May 16 02:14:25 volumio sudo[5621]: pam_unix(sudo:session): session closed for user root May 16 02:14:26 volumio volumio[5298]: info: Command Router : Notfying DB Updatefalse May 16 02:14:26 volumio volumio[5298]: info: CoreCommandRouter::Close All Modals sent May 16 02:14:26 volumio volumio[5298]: info: ControllerMpd::parseState May 16 02:14:26 volumio volumio[5298]: info: ControllerMpd::pushState May 16 02:14:26 volumio volumio[5298]: info: CoreCommandRouter::servicePushState May 16 02:14:26 volumio volumio[5298]: info: CoreStateMachine::syncState May 16 02:14:26 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:26 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:26 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:26 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:26 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:26 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:26 volumio volumio[5298]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 16 02:14:26 volumio volumio[5298]: info: CURRENT POSITION 0 May 16 02:14:26 volumio volumio[5298]: info: CoreStateMachine::syncState stateService stop May 16 02:14:26 volumio volumio[5298]: info: CoreStateMachine::syncState currentStatus stop May 16 02:14:26 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:26 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:26 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:26 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:26 volumio sudo[5627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:26 volumio sudo[5627]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:26 volumio sudo[5627]: pam_unix(sudo:session): session closed for user root May 16 02:14:26 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:26 volumio volumio[5298]: info: No code May 16 02:14:26 volumio volumio[5298]: info: CoreStateMachine::pushState May 16 02:14:26 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:26 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:26 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:26 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:26 volumio sudo[5630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:26 volumio sudo[5630]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:26 volumio sudo[5630]: pam_unix(sudo:session): session closed for user root May 16 02:14:26 volumio volumio[5298]: info: ------------------------------ 205ms May 16 02:14:26 volumio sudo[5632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:26 volumio sudo[5632]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:26 volumio sudo[5632]: pam_unix(sudo:session): session closed for user root May 16 02:14:30 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getDevices May 16 02:14:30 volumio sudo[5652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:30 volumio sudo[5652]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:30 volumio sudo[5652]: pam_unix(sudo:session): session closed for user root May 16 02:14:33 volumio volumio[5298]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=vim1&variante=volumio&version=2.603&uuid=9fd03e1b297776b13a1d1e700dabe96d" http://updates.volumio.org:7070/downloader-v1/track-device May 16 02:14:33 volumio volumio[5298]: % Total % Received % Xferd Average Speed Time Time Time Current May 16 02:14:33 volumio volumio[5298]: Dload Upload Total Spent Left Speed May 16 02:14:33 volumio volumio[5298]: [235B blob data] May 16 02:14:33 volumio volumio[5298]: retrying in 5 seconds, trial 2 May 16 02:14:33 volumio volumio[5298]: Volumio Calling Home May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::getUIConfigOnPlugin May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::getUIConfigOnPlugin May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::volumioGetQueue May 16 02:14:33 volumio volumio[5298]: info: CoreStateMachine::getQueue May 16 02:14:33 volumio volumio[5298]: info: CorePlayQueue::getQueue May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::volumioGetState May 16 02:14:33 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:33 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::volumioGetState May 16 02:14:33 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:33 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:33 volumio sudo[5670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:33 volumio sudo[5670]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::volumioGetState May 16 02:14:33 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:33 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:33 volumio sudo[5670]: pam_unix(sudo:session): session closed for user root May 16 02:14:33 volumio sudo[5673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:33 volumio sudo[5673]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:33 volumio sudo[5673]: pam_unix(sudo:session): session closed for user root May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::volumioGetState May 16 02:14:33 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:33 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:33 volumio sudo[5677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:33 volumio sudo[5677]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::volumioGetVisibleSources May 16 02:14:33 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 02:14:33 volumio sudo[5677]: pam_unix(sudo:session): session closed for user root May 16 02:14:33 volumio sudo[5680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:33 volumio sudo[5680]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:33 volumio sudo[5680]: pam_unix(sudo:session): session closed for user root May 16 02:14:40 volumio volumio[5298]: info: CoreCommandRouter::volumioNext May 16 02:14:40 volumio volumio[5298]: info: CoreStateMachine::next May 16 02:14:40 volumio volumio[5298]: info: CoreStateMachine::stop May 16 02:14:40 volumio volumio[5298]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 02:14:40 volumio volumio[5298]: UNSET VOLATILE May 16 02:14:40 volumio volumio[5298]: info: CoreStateMachine::play index undefined May 16 02:14:40 volumio volumio[5298]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 02:14:40 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:41 volumio systemd[1]: Job dev-disk-by\x2dlabel-BOOT.device/start timed out. May 16 02:14:41 volumio systemd[1]: Timed out waiting for device dev-disk-by\x2dlabel-BOOT.device. May 16 02:14:41 volumio systemd[1]: Dependency failed for File System Check on /dev/disk/by-label/BOOT. May 16 02:14:41 volumio systemd[1]: Startup finished in 10.778s (kernel) + 1min 30.507s (userspace) = 1min 41.285s. May 16 02:14:48 volumio volumio[5298]: info: CoreCommandRouter::volumioGetState May 16 02:14:48 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:48 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:48 volumio volumio[5298]: info: CoreCommandRouter::volumioGetState May 16 02:14:48 volumio volumio[5298]: info: CoreStateMachine::getState May 16 02:14:48 volumio volumio[5298]: info: CorePlayQueue::getTrack 0 May 16 02:14:48 volumio sudo[5723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:48 volumio sudo[5723]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:48 volumio sudo[5723]: pam_unix(sudo:session): session closed for user root May 16 02:14:48 volumio volumio[5298]: info: CoreCommandRouter::volumioGetVisibleSources May 16 02:14:48 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 16 02:14:48 volumio sudo[5726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:48 volumio sudo[5726]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:48 volumio sudo[5726]: pam_unix(sudo:session): session closed for user root May 16 02:14:50 volumio volumio[5298]: info: No input data May 16 02:14:52 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri May 16 02:14:54 volumio volumio[5298]: info: CoreCommandRouter::volumioReplaceandPlayItems May 16 02:14:54 volumio volumio[5298]: info: CoreStateMachine::ClearQueue May 16 02:14:54 volumio volumio[5298]: info: CoreStateMachine::stop May 16 02:14:54 volumio volumio[5298]: info: CoreStateMachine::setConsumeUpdateService undefined May 16 02:14:54 volumio volumio[5298]: UNSET VOLATILE May 16 02:14:54 volumio volumio[5298]: info: CorePlayQueue::clearPlayQueue May 16 02:14:54 volumio volumio[5298]: info: CorePlayQueue::saveQueue May 16 02:14:54 volumio volumio[5298]: info: CoreStateMachine::pushEmptyState May 16 02:14:54 volumio volumio[5298]: info: CoreCommandRouter::volumioPushState May 16 02:14:54 volumio volumio[5298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 16 02:14:54 volumio volumio[5298]: info: CoreCommandRouter::volumioPushQueue May 16 02:14:54 volumio volumio[5298]: info: CoreStateMachine::addQueueItems May 16 02:14:54 volumio volumio[5298]: info: CorePlayQueue::addQueueItems May 16 02:14:54 volumio volumio[5298]: info: Adding Item to queue: May 16 02:14:54 volumio sudo[5745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 16 02:14:54 volumio sudo[5745]: pam_unix(sudo:session): session opened for user root by (uid=0) May 16 02:14:54 volumio volumio[5298]: info: Exploding uri in service volumio May 16 02:14:54 volumio sudo[5745]: pam_unix(sudo:session): session closed for user root May 16 02:14:54 volumio volumio[5298]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 02:14:54 volumio volumio[5298]: TypeError: Cannot read property 'explodeUri' of undefined May 16 02:14:54 volumio volumio[5298]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1323:16) May 16 02:14:54 volumio volumio[5298]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:153:54) May 16 02:14:54 volumio volumio[5298]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:249:24) May 16 02:14:54 volumio volumio[5298]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:483:27) May 16 02:14:54 volumio volumio[5298]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:78:31) May 16 02:14:54 volumio volumio[5298]: at emitOne (events.js:116:13) May 16 02:14:54 volumio volumio[5298]: at Socket.emit (events.js:211:7) May 16 02:14:54 volumio volumio[5298]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 16 02:14:54 volumio volumio[5298]: at _combinedTickCallback (internal/process/next_tick.js:131:7) May 16 02:14:54 volumio volumio[5298]: at process._tickCallback (internal/process/next_tick.js:180:9) May 16 02:14:54 volumio volumio[5298]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 16 02:14:55 volumio sudo[5755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-16 02:13 May 16 02:14:55 volumio sudo[5755]: 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"