-- Logs begin at Sat 2023-04-01 06:43:41 UTC, end at Sat 2024-09-07 08:31:51 UTC. -- Sep 07 08:31:10 volumio2 systemd[1]: Time has been changed Sep 07 08:31:10 volumio2 smbd[901]: Starting SMB/CIFS daemon: smbd[2024/09/07 08:31:10, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 07 08:31:10 volumio2 smbd[901]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 07 08:31:10 volumio2 smbd[901]: [2024/09/07 08:31:10, 0] ../lib/util/util.c:220(directory_create_or_exist) Sep 07 08:31:10 volumio2 smbd[901]: mkdir failed on directory /var/log/samba/cores: No such file or directory Sep 07 08:31:10 volumio2 smbd[901]: [2024/09/07 08:31:10, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Sep 07 08:31:10 volumio2 smbd[901]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Sep 07 08:31:10 volumio2 smbd[901]: [2024/09/07 08:31:10, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Sep 07 08:31:10 volumio2 smbd[901]: Unable to setup corepath for smbd: No such file or directory Sep 07 08:31:10 volumio2 smbd[901]: [2024/09/07 08:31:10, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 07 08:31:10 volumio2 smbd[901]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 07 08:31:10 volumio2 smbd[901]: [2024/09/07 08:31:10, 0] ../source3/smbd/server.c:1241(main) Sep 07 08:31:10 volumio2 smbd[901]: smbd version 4.2.14-Debian started. Sep 07 08:31:10 volumio2 smbd[901]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Sep 07 08:31:10 volumio2 smbd[983]: [2024/09/07 08:31:10.798581, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 07 08:31:10 volumio2 smbd[983]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 07 08:31:10 volumio2 smbd[983]: [2024/09/07 08:31:10.801100, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 07 08:31:10 volumio2 smbd[983]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 07 08:31:10 volumio2 smbd[983]: [2024/09/07 08:31:10.801499, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 07 08:31:10 volumio2 smbd[983]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 07 08:31:10 volumio2 smbd[901]: [2024/09/07 08:31:10.798581, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 07 08:31:10 volumio2 smbd[901]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 07 08:31:10 volumio2 smbd[901]: [2024/09/07 08:31:10.801100, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 07 08:31:10 volumio2 smbd[901]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 07 08:31:10 volumio2 smbd[901]: [2024/09/07 08:31:10.801499, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 07 08:31:10 volumio2 smbd[901]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 07 08:31:10 volumio2 systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Sep 07 08:31:10 volumio2 smbd[901]: . Sep 07 08:31:10 volumio2 winbindd[988]: [2024/09/07 08:31:10.897100, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 07 08:31:10 volumio2 winbindd[988]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Sep 07 08:31:10 volumio2 kernel: RTW: wlan0 wakeup m0=0x00000002, ori reg_0x48c=0x00000002 Sep 07 08:31:10 volumio2 kernel: RTW: survey done event(16) band:0 for wlan0 Sep 07 08:31:10 volumio2 kernel: RTW: rtw_indicate_scan_done(wlan0) Sep 07 08:31:10 volumio2 kernel: RTW: =>cfg80211_rtw_connect(wlan0) - Start to Connection Sep 07 08:31:10 volumio2 kernel: RTW: privacy=1, key= (null), key_len=0, key_idx=0, auth_type=0 Sep 07 08:31:10 volumio2 kernel: RTW: ssid=VWGOLF2, len=7 Sep 07 08:31:10 volumio2 kernel: RTW: bssid=c4:3c:ea:e0:d6:58 Sep 07 08:31:10 volumio2 kernel: RTW: rtw_cfg80211_set_wpa_version, wpa_version=2 Sep 07 08:31:10 volumio2 kernel: RTW: rtw_cfg80211_set_auth_type, nl80211_auth_type=0 Sep 07 08:31:10 volumio2 kernel: RTW: cfg80211_rtw_connect, ie_len=28 Sep 07 08:31:10 volumio2 kernel: RTW: set wpa_ie(length:28): Sep 07 08:31:10 volumio2 kernel: RTW: 30 14 01 00 00 0F AC 04 01 00 00 0F AC 04 01 00 Sep 07 08:31:10 volumio2 kernel: RTW: 00 0F AC 02 00 00 7F 04 00 00 0A 02 Sep 07 08:31:10 volumio2 kernel: RTW: got wpa2_ie, wpa2_ielen:20 Sep 07 08:31:10 volumio2 kernel: RTW: rtw_cfg80211_set_cipher, ucast=1, cipher=0xfac04 Sep 07 08:31:10 volumio2 kernel: RTW: rtw_cfg80211_set_cipher, ucast=0, cipher=0xfac04 Sep 07 08:31:10 volumio2 kernel: RTW: rtw_cfg80211_set_key_mgt, key_mgt=0xfac02 Sep 07 08:31:10 volumio2 kernel: RTW: rtw_set_802_11_connect(wlan0) fw_state=0x00000000 Sep 07 08:31:10 volumio2 kernel: RTW: [by_bssid:1][assoc_ssid:VWGOLF2][to_roam:0] new candidate: VWGOLF2(c4:3c:ea:e0:d6:58, ch11) rssi:-59 Sep 07 08:31:10 volumio2 kernel: RTW: rtw_select_and_join_from_scanned_queue: candidate: VWGOLF2(c4:3c:ea:e0:d6:58, ch:11) Sep 07 08:31:10 volumio2 kernel: RTW: link to Realtek AP Sep 07 08:31:10 volumio2 kernel: RTW: [HT] HAL Support STBC = 0x01 Sep 07 08:31:10 volumio2 kernel: RTW: [HT] Declare supporting RX STBC = 1 Sep 07 08:31:10 volumio2 kernel: RTW: rtw_restructure_ht_ie IEEE80211_HT_CAP_MAX_AMSDU is set Sep 07 08:31:10 volumio2 kernel: RTW: set ssid:dot11AuthAlgrthm=2, dot11PrivacyAlgrthm=4, dot118021XGrpPrivacy=4 Sep 07 08:31:10 volumio2 kernel: RTW: <=cfg80211_rtw_connect, ret 0 Sep 07 08:31:10 volumio2 kernel: RTW: rtw_chk_start_clnt_join(wlan0) req: 11,0,0 Sep 07 08:31:10 volumio2 kernel: RTW: rtw_chk_start_clnt_join(wlan0) union: 11,0,0 Sep 07 08:31:10 volumio2 kernel: RTW: rtw_hal_set_bssid wlan0- hw port -0 BSSID: c4:3c:ea:e0:d6:58 Sep 07 08:31:10 volumio2 wpa_supplicant[945]: wlan0: Trying to associate with c4:3c:ea:e0:d6:58 (SSID='VWGOLF2' freq=2462 MHz) Sep 07 08:31:11 volumio2 winbindd[989]: [2024/09/07 08:31:11.075196, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 07 08:31:11 volumio2 winbindd[989]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory Sep 07 08:31:11 volumio2 kernel: RTW: OnBeacon: beacon keys ready Sep 07 08:31:11 volumio2 kernel: RTW: link to Realtek AP Sep 07 08:31:11 volumio2 kernel: RTW: start auth Sep 07 08:31:11 volumio2 kernel: RTW: issue_auth Sep 07 08:31:11 volumio2 kernel: RTW: OnAuthClient Sep 07 08:31:11 volumio2 kernel: RTW: auth success, start assoc Sep 07 08:31:11 volumio2 kernel: RTW: network.SupportedRates[0]=82 Sep 07 08:31:11 volumio2 kernel: RTW: network.SupportedRates[1]=84 Sep 07 08:31:11 volumio2 kernel: RTW: network.SupportedRates[2]=8B Sep 07 08:31:11 volumio2 kernel: RTW: network.SupportedRates[3]=96 Sep 07 08:31:11 volumio2 kernel: RTW: network.SupportedRates[4]=0C Sep 07 08:31:11 volumio2 kernel: RTW: network.SupportedRates[5]=12 Sep 07 08:31:11 volumio2 kernel: RTW: network.SupportedRates[6]=18 Sep 07 08:31:11 volumio2 kernel: RTW: network.SupportedRates[7]=24 Sep 07 08:31:11 volumio2 kernel: RTW: network.SupportedRates[8]=30 Sep 07 08:31:11 volumio2 kernel: RTW: network.SupportedRates[9]=48 Sep 07 08:31:11 volumio2 kernel: RTW: network.SupportedRates[10]=60 Sep 07 08:31:11 volumio2 kernel: RTW: network.SupportedRates[11]=6C Sep 07 08:31:11 volumio2 kernel: RTW: bssrate_len = 12 Sep 07 08:31:11 volumio2 kernel: RTW: OnAssocRsp Sep 07 08:31:11 volumio2 kernel: RTW: report_join_res(4, 0) Sep 07 08:31:11 volumio2 kernel: RTW: rtw_joinbss_update_network Sep 07 08:31:11 volumio2 kernel: RTW: +rtw_update_ht_cap() Sep 07 08:31:11 volumio2 kernel: RTW: rtw_alloc_macid(wlan0) if1, mac_addr:c4:3c:ea:e0:d6:58 macid:0 Sep 07 08:31:11 volumio2 kernel: RTW: rtw_joinbss_update_stainfo Sep 07 08:31:11 volumio2 kernel: RTW: rtw_get_sta_tx_nss: 1 SS Sep 07 08:31:11 volumio2 kernel: RTW: rtw_get_sta_rx_nss: 1 SS Sep 07 08:31:11 volumio2 kernel: RTW: STA - MAC_ID:0, Tx - 1 SS, Rx - 1 SS Sep 07 08:31:11 volumio2 kernel: RTW: STA - MAC_ID:0, SM_PS 3 Sep 07 08:31:11 volumio2 kernel: RTW: ht_mcs_set=ff 00 00 00, nss=1, bitmap=000000ff Sep 07 08:31:11 volumio2 kernel: RTW: ### Set STA_(0) info ### Sep 07 08:31:11 volumio2 kernel: RTW: rtw_cfg80211_indicate_connect(wlan0) Sep 07 08:31:11 volumio2 kernel: RTW: assoc success Sep 07 08:31:11 volumio2 kernel: RTW: HW_VAR_BASIC_RATE: 0x15f->0x15f->0x15f Sep 07 08:31:11 volumio2 kernel: RTW: recv eapol packet Sep 07 08:31:11 volumio2 kernel: RTW: send eapol packet Sep 07 08:31:11 volumio2 kernel: RTW: WMM(0): 0, a42b Sep 07 08:31:11 volumio2 kernel: RTW: WMM(1): 0, a44f Sep 07 08:31:11 volumio2 kernel: RTW: WMM(2): 0, 5e4322 Sep 07 08:31:11 volumio2 kernel: RTW: WMM(3): 0, 2f3222 Sep 07 08:31:11 volumio2 kernel: RTW: wmm_para_seq(0): 0 Sep 07 08:31:11 volumio2 kernel: RTW: wmm_para_seq(1): 1 Sep 07 08:31:11 volumio2 kernel: RTW: wmm_para_seq(2): 2 Sep 07 08:31:11 volumio2 kernel: RTW: wmm_para_seq(3): 3 Sep 07 08:31:11 volumio2 kernel: RTW: HTOnAssocRsp Sep 07 08:31:11 volumio2 wpa_supplicant[945]: wlan0: Associated with c4:3c:ea:e0:d6:58 Sep 07 08:31:11 volumio2 kernel: RTW: ============ STA [c4:3c:ea:e0:d6:58] =================== Sep 07 08:31:11 volumio2 kernel: RTW: mac_id : 0 Sep 07 08:31:11 volumio2 kernel: RTW: wireless_mode : 0x0b Sep 07 08:31:11 volumio2 kernel: RTW: mimo_type : 0 Sep 07 08:31:11 volumio2 kernel: RTW: bw_mode : 20MHz, ra_bw_mode : 20MHz Sep 07 08:31:11 volumio2 kernel: RTW: rate_id : 0 Sep 07 08:31:11 volumio2 kernel: RTW: rssi : 31 (%), rssi_level : 0 Sep 07 08:31:11 volumio2 kernel: RTW: is_support_sgi : Y, is_vht_enable : N Sep 07 08:31:11 volumio2 kernel: RTW: disable_ra : N, disable_pt : N Sep 07 08:31:11 volumio2 kernel: RTW: is_noisy : N Sep 07 08:31:11 volumio2 kernel: RTW: txrx_state : 0 Sep 07 08:31:11 volumio2 kernel: RTW: curr_tx_rate : CCK_1M (L) Sep 07 08:31:11 volumio2 kernel: RTW: curr_tx_bw : 20MHz Sep 07 08:31:11 volumio2 kernel: RTW: curr_retry_ratio : 0 Sep 07 08:31:11 volumio2 kernel: RTW: ra_mask : 0x00000000000fffff Sep 07 08:31:11 volumio2 kernel: RTW: ### MacID(1),Set Max Tx RPT MID(2) Sep 07 08:31:11 volumio2 kernel: RTW: wlan0 wakeup macid=0, ori reg_0x48c=0x00000000 Sep 07 08:31:11 volumio2 kernel: RTW: rtw_reg_notifier: NL80211_REGDOM_SET_BY_COUNTRY_IE Sep 07 08:31:11 volumio2 wpa_supplicant[945]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP Sep 07 08:31:11 volumio2 kernel: RTW: =>mlmeext_joinbss_event_callback - End to Connection without 4-way Sep 07 08:31:11 volumio2 smbd[984]: [2024/09/07 08:31:11.160112, 0] ../lib/util/become_daemon.c:124(daemon_ready) Sep 07 08:31:11 volumio2 smbd[984]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 07 08:31:11 volumio2 smbd[992]: [2024/09/07 08:31:11.167745, 0] ../lib/util/debug.c:603(reopen_logs_internal) Sep 07 08:31:11 volumio2 smbd[992]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Sep 07 08:31:11 volumio2 kernel: RTW: hw_rate_to_m_rate(): Non supported Rate [ff]!!! Sep 07 08:31:11 volumio2 kernel: RTW: hw_rate_to_m_rate(): Non supported Rate [ff]!!! Sep 07 08:31:11 volumio2 wireless.js[774]: trying... Sep 07 08:31:11 volumio2 sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 08:31:11 volumio2 sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:11 volumio2 sudo[996]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:12 volumio2 kernel: RTW: recv eapol packet Sep 07 08:31:12 volumio2 kernel: RTW: send eapol packet Sep 07 08:31:12 volumio2 kernel: RTW: recv eapol packet Sep 07 08:31:12 volumio2 kernel: RTW: send eapol packet Sep 07 08:31:12 volumio2 wpa_supplicant[945]: wlan0: WPA: Key negotiation completed with c4:3c:ea:e0:d6:58 [PTK=CCMP GTK=CCMP] Sep 07 08:31:12 volumio2 wpa_supplicant[945]: wlan0: CTRL-EVENT-CONNECTED - Connection to c4:3c:ea:e0:d6:58 completed [id=0 id_str=] Sep 07 08:31:12 volumio2 dhcpcd[967]: wlan0: carrier acquired Sep 07 08:31:12 volumio2 kernel: RTW: cfg80211_rtw_add_key(wlan0) adding key for c4:3c:ea:e0:d6:58 Sep 07 08:31:12 volumio2 kernel: RTW: cfg80211_rtw_add_key(wlan0) cipher=0xfac04 Sep 07 08:31:12 volumio2 kernel: RTW: cfg80211_rtw_add_key(wlan0) key_len=16, key_index=0 Sep 07 08:31:12 volumio2 kernel: RTW: cfg80211_rtw_add_key(wlan0) pairwise=1 Sep 07 08:31:12 volumio2 kernel: RTW: cfg80211_rtw_add_key(wlan0) seq_len:6, seq:0x0 Sep 07 08:31:12 volumio2 kernel: RTW: rtw_cfg80211_set_encryption Sep 07 08:31:12 volumio2 kernel: RTW: rtw_cfg80211_set_encryption(wlan0) set CCMP PTK idx:0, len:16 Sep 07 08:31:12 volumio2 kernel: RTW: rtw_cfg80211_set_encryption, ret=0 Sep 07 08:31:12 volumio2 kernel: RTW: set pairwise key camid:4, addr:c4:3c:ea:e0:d6:58, kid:0, type:AES Sep 07 08:31:12 volumio2 kernel: RTW: cfg80211_rtw_set_default_key(wlan0) key_index=0, unicast=1, multicast=0 Sep 07 08:31:12 volumio2 kernel: RTW: cfg80211_rtw_add_key(wlan0) cipher=0xfac04 Sep 07 08:31:12 volumio2 kernel: RTW: cfg80211_rtw_add_key(wlan0) key_len=16, key_index=1 Sep 07 08:31:12 volumio2 kernel: RTW: cfg80211_rtw_add_key(wlan0) pairwise=0 Sep 07 08:31:12 volumio2 kernel: RTW: cfg80211_rtw_add_key(wlan0) seq_len:6, seq:0x14ee7 Sep 07 08:31:12 volumio2 kernel: RTW: rtw_cfg80211_set_encryption Sep 07 08:31:12 volumio2 kernel: RTW: rtw_cfg80211_set_encryption(wlan0) set CCMP GTK idx:1, len:16 Sep 07 08:31:12 volumio2 kernel: RTW: ==> rtw_set_key algorithm(4),keyid(1),key_mask(0) Sep 07 08:31:12 volumio2 kernel: RTW: rtw_cfg80211_set_encryption, ret=0 Sep 07 08:31:12 volumio2 kernel: RTW: rtw_ndev_notifier_call(wlan0) state:4 Sep 07 08:31:12 volumio2 kernel: RTW: cfg80211_rtw_change_station(wlan0) mac:c4:3c:ea:e0:d6:58 Sep 07 08:31:12 volumio2 kernel: RTW: set group key camid:5, addr:c4:3c:ea:e0:d6:58, kid:1, type:AES Sep 07 08:31:12 volumio2 dhcpcd[967]: wlan0: soliciting an IPv6 router Sep 07 08:31:12 volumio2 wireless.js[774]: trying... Sep 07 08:31:12 volumio2 sudo[1022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 08:31:12 volumio2 sudo[1022]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:12 volumio2 sudo[1022]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:13 volumio2 wireless.js[774]: trying... Sep 07 08:31:13 volumio2 sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 08:31:13 volumio2 sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:13 volumio2 sudo[1031]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:14 volumio2 wireless.js[774]: trying... Sep 07 08:31:14 volumio2 sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 08:31:14 volumio2 sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:14 volumio2 sudo[1040]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:15 volumio2 volumio-remote-updater[638]: [2024-09-07 08:31:15] [info] asio async_connect error: system:111 (Connection refused) Sep 07 08:31:15 volumio2 volumio-remote-updater[638]: [2024-09-07 08:31:15] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Sep 07 08:31:15 volumio2 volumio-remote-updater[638]: [2024-09-07 08:31:15] [error] handle_connect error: Underlying Transport Error Sep 07 08:31:15 volumio2 kernel: RTW: hw_rate_to_m_rate(): Non supported Rate [ff]!!! Sep 07 08:31:15 volumio2 wireless.js[774]: trying... Sep 07 08:31:15 volumio2 sudo[1049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 08:31:15 volumio2 sudo[1049]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:15 volumio2 sudo[1049]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:16 volumio2 wireless.js[774]: trying... Sep 07 08:31:16 volumio2 sudo[1058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 08:31:16 volumio2 sudo[1058]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:16 volumio2 sudo[1058]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:17 volumio2 dhcpcd[967]: wlan0: using IPv4LL address 169.254.118.234 Sep 07 08:31:17 volumio2 dhcpcd[967]: eth0: adding route to 10.82.208.0/22 Sep 07 08:31:17 volumio2 dhcpcd[967]: eth0: adding default route via 10.82.208.1 Sep 07 08:31:17 volumio2 dhcpcd[967]: wlan0: adding host route to 169.254.118.234 via 127.0.0.1 Sep 07 08:31:17 volumio2 dhcpcd[967]: wlan0: adding route to 169.254.0.0/16 Sep 07 08:31:17 volumio2 avahi-daemon[688]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.118.234. Sep 07 08:31:17 volumio2 avahi-daemon[688]: New relevant interface wlan0.IPv4 for mDNS. Sep 07 08:31:17 volumio2 avahi-daemon[688]: Registering new address record for 169.254.118.234 on wlan0.IPv4. Sep 07 08:31:17 volumio2 wireless.js[774]: trying... Sep 07 08:31:17 volumio2 sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 07 08:31:17 volumio2 sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:17 volumio2 dhcpcd[967]: eth0: removing route to 10.82.208.0/22 Sep 07 08:31:17 volumio2 dhcpcd[967]: eth0: removing default route via 10.82.208.1 Sep 07 08:31:17 volumio2 sudo[1086]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:17 volumio2 wireless.js[774]: Connected to: ----VWGOLF2 Sep 07 08:31:17 volumio2 wireless.js[774]: ---- Sep 07 08:31:17 volumio2 sudo[1095]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 07 08:31:17 volumio2 sudo[1095]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:17 volumio2 sudo[1095]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:17 volumio2 wireless.js[774]: ... joined AP, wlan0 IPv4 is 169.254.118.234, ipV6 is undefined Sep 07 08:31:17 volumio2 wireless.js[774]: It's done! AP Sep 07 08:31:18 volumio2 systemd[1]: Started Wireless Services. Sep 07 08:31:18 volumio2 systemd[1]: Starting Volumio Backend Module... Sep 07 08:31:18 volumio2 systemd[1]: Started Volumio Backend Module. Sep 07 08:31:18 volumio2 systemd[1]: Starting Volumio Streaming Daemon... Sep 07 08:31:18 volumio2 systemd[1]: Started Volumio Streaming Daemon. Sep 07 08:31:18 volumio2 systemd[1]: Starting Multi-User System. Sep 07 08:31:18 volumio2 systemd[1]: Reached target Multi-User System. Sep 07 08:31:18 volumio2 systemd[1]: Starting Graphical Interface. Sep 07 08:31:18 volumio2 systemd[1]: Reached target Graphical Interface. Sep 07 08:31:18 volumio2 systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 07 08:31:18 volumio2 systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 07 08:31:18 volumio2 systemd[1]: Startup finished in 10.108s (kernel) + 33.260s (userspace) = 43.368s. Sep 07 08:31:18 volumio2 volumio-streaming-daemon[1105]: ############################ Sep 07 08:31:18 volumio2 volumio-streaming-daemon[1105]: # Volumio Streaming Daemon # Sep 07 08:31:18 volumio2 volumio-streaming-daemon[1105]: # Running on port 7777 # Sep 07 08:31:18 volumio2 volumio-streaming-daemon[1105]: ############################ Sep 07 08:31:18 volumio2 volumio-streaming-daemon[1105]: Environment: production Sep 07 08:31:19 volumio2 kernel: RTW: hw_rate_to_m_rate(): Non supported Rate [ff]!!! Sep 07 08:31:19 volumio2 ntpd[766]: Listen normally on 4 wlan0 169.254.118.234 UDP 123 Sep 07 08:31:19 volumio2 ntpd[766]: peers refreshed Sep 07 08:31:22 volumio2 dhcpcd[967]: wlan0: soliciting a DHCP lease Sep 07 08:31:23 volumio2 volumio[1104]: info: ------------------------------------------- Sep 07 08:31:23 volumio2 volumio[1104]: info: ----- Volumio2 ---- Sep 07 08:31:23 volumio2 volumio[1104]: info: ------------------------------------------- Sep 07 08:31:23 volumio2 volumio[1104]: info: ----- System startup ---- Sep 07 08:31:23 volumio2 volumio[1104]: info: ------------------------------------------- Sep 07 08:31:23 volumio2 volumio[1104]: info: MYVOLUMIO Environment detected Sep 07 08:31:23 volumio2 kernel: RTW: hw_rate_to_m_rate(): Non supported Rate [ff]!!! Sep 07 08:31:23 volumio2 kernel: RTW: hw_rate_to_m_rate(): Non supported Rate [ff]!!! Sep 07 08:31:25 volumio2 volumio-remote-updater[638]: [2024-09-07 08:31:25] [connect] Successful connection Sep 07 08:31:25 volumio2 volumio[1104]: info: Plugin folders cleanup Sep 07 08:31:25 volumio2 volumio[1104]: info: Scanning into folder /volumio/app/plugins/ Sep 07 08:31:25 volumio2 volumio[1104]: info: Scanning category audio_interface Sep 07 08:31:25 volumio2 volumio[1104]: info: Scanning category miscellanea Sep 07 08:31:25 volumio2 volumio[1104]: info: Scanning category music_service Sep 07 08:31:25 volumio2 volumio[1104]: info: Scanning category plugins.json Sep 07 08:31:25 volumio2 volumio[1104]: info: Scanning category system_controller Sep 07 08:31:25 volumio2 volumio[1104]: info: Scanning category user_interface Sep 07 08:31:25 volumio2 volumio[1104]: info: Scanning into folder /data/plugins/ Sep 07 08:31:25 volumio2 volumio[1104]: info: Scanning category miscellanea Sep 07 08:31:25 volumio2 volumio[1104]: info: Plugin folders cleanup completed Sep 07 08:31:25 volumio2 volumio[1104]: info: ------------------------------------------- Sep 07 08:31:25 volumio2 volumio[1104]: info: ----- Core plugins startup ---- Sep 07 08:31:25 volumio2 volumio[1104]: info: ------------------------------------------- Sep 07 08:31:25 volumio2 volumio[1104]: info: Loading plugins from folder /volumio/app/plugins/ Sep 07 08:31:25 volumio2 volumio[1104]: info: Adding plugin upnp to MyMusic Plugins Sep 07 08:31:25 volumio2 volumio[1104]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 07 08:31:25 volumio2 volumio[1104]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 07 08:31:25 volumio2 volumio[1104]: info: Loading plugins from folder /data/plugins/ Sep 07 08:31:25 volumio2 volumio[1104]: info: Loading plugin "system"... Sep 07 08:31:25 volumio2 volumio[1104]: info: Loading plugin "appearance"... Sep 07 08:31:27 volumio2 kernel: RTW: hw_rate_to_m_rate(): Non supported Rate [ff]!!! Sep 07 08:31:28 volumio2 volumio[1104]: info: Loading plugin "network"... Sep 07 08:31:28 volumio2 volumio[1104]: info: Refreshing Cached IP Addresses Sep 07 08:31:28 volumio2 dhcpcd[967]: wlan0: offered 10.82.209.52 from 10.82.208.1 `T0040601' Sep 07 08:31:28 volumio2 sudo[1130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 07 08:31:28 volumio2 volumio[1104]: info: Loading plugin "services"... Sep 07 08:31:28 volumio2 volumio[1104]: info: Loading plugin "alsa_controller"... Sep 07 08:31:28 volumio2 sudo[1130]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:28 volumio2 sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 07 08:31:28 volumio2 sudo[1130]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:28 volumio2 sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:28 volumio2 sudo[1132]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:28 volumio2 volumio[1104]: info: Loading plugin "wizard"... Sep 07 08:31:28 volumio2 volumio[1104]: info: Loading plugin "volumio_command_line_client"... Sep 07 08:31:28 volumio2 volumio[1104]: info: Loading plugin "upnp"... Sep 07 08:31:28 volumio2 volumio[1104]: info: [1725697888945] Starting Upmpd Daemon Sep 07 08:31:28 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 08:31:28 volumio2 volumio[1104]: info: Loading plugin "my_music"... Sep 07 08:31:28 volumio2 volumio[1104]: info: Loading plugin "mpd"... Sep 07 08:31:29 volumio2 volumio[1104]: info: Loading plugin "upnp_browser"... Sep 07 08:31:31 volumio2 volumio[1104]: info: Loading plugin "networkfs"... Sep 07 08:31:31 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 08:31:31 volumio2 volumio[1104]: info: Loading plugin "alarm-clock"... Sep 07 08:31:31 volumio2 volumio[1104]: info: Loading plugin "airplay_emulation"... Sep 07 08:31:31 volumio2 volumio[1104]: info: Starting Shairport Sync Sep 07 08:31:31 volumio2 volumio[1104]: info: Loading plugin "last_100"... Sep 07 08:31:31 volumio2 volumio[1104]: info: Loading plugin "webradio"... Sep 07 08:31:32 volumio2 kernel: RTW: hw_rate_to_m_rate(): Non supported Rate [ff]!!! Sep 07 08:31:32 volumio2 volumio[1104]: info: Loading plugin "i2s_dacs"... Sep 07 08:31:32 volumio2 volumio[1104]: info: I2S DAC not set, start Auto-detection Sep 07 08:31:32 volumio2 volumio[1104]: info: Loading plugin "volumiodiscovery"... Sep 07 08:31:32 volumio2 volumio[1104]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 08:31:32 volumio2 node[1104]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 07 08:31:32 volumio2 node[1104]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 08:31:32 volumio2 node[1104]: *** WARNING *** For more information see Sep 07 08:31:32 volumio2 node[1104]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 08:31:32 volumio2 node[1104]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 08:31:32 volumio2 node[1104]: *** WARNING *** For more information see Sep 07 08:31:32 volumio2 volumio[1104]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 08:31:32 volumio2 volumio[1104]: *** WARNING *** For more information see Sep 07 08:31:32 volumio2 volumio[1104]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 07 08:31:32 volumio2 volumio[1104]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 07 08:31:32 volumio2 volumio[1104]: *** WARNING *** For more information see Sep 07 08:31:32 volumio2 volumio[1104]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 07 08:31:32 volumio2 volumio[1104]: Discovery: StartAdv! undefined Sep 07 08:31:32 volumio2 volumio[1104]: Discovery: Started advertising... Volumio2 - undefined Sep 07 08:31:32 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 07 08:31:32 volumio2 volumio[1104]: info: Loading plugin "auto_play"... Sep 07 08:31:32 volumio2 volumio[1104]: info: ControllerAutoPlay - connecting mpd on host: localhost; port: 6600 Sep 07 08:31:32 volumio2 volumio[1104]: info: Plugin autostart is not enabled Sep 07 08:31:32 volumio2 volumio[1104]: info: Loading plugin "outputs"... Sep 07 08:31:32 volumio2 volumio[1104]: info: Loading plugin "albumart"... Sep 07 08:31:32 volumio2 volumio[1104]: info: Plugin example_plugin is not enabled Sep 07 08:31:32 volumio2 volumio[1104]: info: Loading plugin "inputs"... Sep 07 08:31:32 volumio2 volumio[1104]: info: Loading plugin "updater_comm"... Sep 07 08:31:33 volumio2 volumio[1104]: info: Plugin mpdemulation is not enabled Sep 07 08:31:33 volumio2 volumio[1104]: info: Loading plugin "rest_api"... Sep 07 08:31:33 volumio2 volumio[1104]: info: Loading plugin "websocket"... Sep 07 08:31:33 volumio2 volumio[1104]: info: ___________ START PLUGINS ___________ Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 08:31:33 volumio2 volumio[1104]: info: [1725697893132] CoreMusicLibrary::Adding element Media Servers Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 08:31:33 volumio2 volumio[1104]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 07 08:31:33 volumio2 dhcpcd[967]: wlan0: leased 10.82.209.52 for 7200 seconds Sep 07 08:31:33 volumio2 dhcpcd[967]: eth0: adding route to 10.82.208.0/22 Sep 07 08:31:33 volumio2 dhcpcd[967]: eth0: adding default route via 10.82.208.1 Sep 07 08:31:33 volumio2 dhcpcd[967]: wlan0: adding host route to 10.82.209.52 via 127.0.0.1 Sep 07 08:31:33 volumio2 dhcpcd[967]: wlan0: adding route to 10.82.208.0/22 Sep 07 08:31:33 volumio2 dhcpcd[967]: wlan0: adding default route via 10.82.208.1 Sep 07 08:31:33 volumio2 dhcpcd[967]: wlan0: deleting host route to 169.254.118.234 via 127.0.0.1 Sep 07 08:31:33 volumio2 dhcpcd[967]: wlan0: deleting route to 169.254.0.0/16 Sep 07 08:31:33 volumio2 avahi-daemon[688]: Registering new address record for 10.82.209.52 on wlan0.IPv4. Sep 07 08:31:33 volumio2 avahi-daemon[688]: Withdrawing address record for 169.254.118.234 on wlan0. Sep 07 08:31:33 volumio2 avahi-daemon[688]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.118.234. Sep 07 08:31:33 volumio2 avahi-daemon[688]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.82.209.52. Sep 07 08:31:33 volumio2 volumio[1104]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 08:31:33 volumio2 volumio[1104]: info: [1725697893368] CoreMusicLibrary::Adding element Last_100 Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 07 08:31:33 volumio2 volumio[1104]: info: [1725697893377] CoreMusicLibrary::Adding element Webradio Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 07 08:31:33 volumio2 volumio[1104]: Forking 3 albumart workers Sep 07 08:31:33 volumio2 volumio[1104]: info: ------------------------------------------- Sep 07 08:31:33 volumio2 volumio[1104]: info: ----- MyVolumio plugins startup ---- Sep 07 08:31:33 volumio2 volumio[1104]: info: ------------------------------------------- Sep 07 08:31:33 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Fetching plans data.... Sep 07 08:31:33 volumio2 volumio[1104]: info: Loading i18n strings for locale ja Sep 07 08:31:33 volumio2 volumio[1104]: Updating browse sources language Sep 07 08:31:33 volumio2 volumio[1104]: Cannot find translation for sourceMedia Servers Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 07 08:31:33 volumio2 dhcpcd[967]: eth0: removing route to 10.82.208.0/22 Sep 07 08:31:33 volumio2 dhcpcd[967]: eth0: removing default route via 10.82.208.1 Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::initPlayerControls Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 07 08:31:33 volumio2 volumio[1104]: info: BOOT COMPLETED Sep 07 08:31:33 volumio2 volumio[1104]: [Metrics] CommandRouter: 10s 433.10ms Sep 07 08:31:33 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 08:31:34 volumio2 ntpd[766]: Listen normally on 5 wlan0 10.82.209.52 UDP 123 Sep 07 08:31:34 volumio2 ntpd[766]: Deleting interface #4 wlan0, 169.254.118.234#123, interface stats: received=0, sent=0, dropped=0, active_time=15 secs Sep 07 08:31:34 volumio2 ntpd[766]: peers refreshed Sep 07 08:31:35 volumio2 volumio[1104]: Starting albumart workers Sep 07 08:31:35 volumio2 volumio[1104]: Starting albumart workers Sep 07 08:31:35 volumio2 volumio[1104]: Starting albumart workers Sep 07 08:31:36 volumio2 kernel: RTW: hw_rate_to_m_rate(): Non supported Rate [ff]!!! Sep 07 08:31:39 volumio2 volumio[1104]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Sep 07 08:31:39 volumio2 volumio[1104]: info: CoreCommandRouter::Close All Modals sent Sep 07 08:31:39 volumio2 volumio[1104]: info: CoreCommandRouter::Close All Modals sent Sep 07 08:31:39 volumio2 volumio[1104]: Express server listening on port 3000 Sep 07 08:31:39 volumio2 volumio[1104]: [Metrics] WebUI: 17s 584.96ms Sep 07 08:31:39 volumio2 volumio[1104]: Volumio Calling Home Sep 07 08:31:39 volumio2 volumio[1104]: info: CoreStateMachine::resetVolumioState Sep 07 08:31:39 volumio2 volumio[1104]: info: CoreStateMachine::getcurrentVolume Sep 07 08:31:39 volumio2 volumio[1104]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 08:31:39 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 08:31:39 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 07 08:31:39 volumio2 sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 07 08:31:39 volumio2 sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 07 08:31:39 volumio2 sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:39 volumio2 sudo[1246]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:39 volumio2 sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:39 volumio2 sudo[1248]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:39 volumio2 volumio[1104]: Unable to parse: Sep 07 08:31:39 volumio2 volumio[1104]: Simple mixer control 'PCM',0 Sep 07 08:31:39 volumio2 volumio[1104]: Capabilities: pswitch pswitch-joined Sep 07 08:31:39 volumio2 volumio[1104]: Playback channels: Mono Sep 07 08:31:39 volumio2 volumio[1104]: Mono: Playback [on] Sep 07 08:31:39 volumio2 volumio[1104]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 07 08:31:39 volumio2 volumio[1104]: info: Setting Device type: Raspberry PI Sep 07 08:31:39 volumio2 volumio[1104]: info: MPD running with PID694 Sep 07 08:31:39 volumio2 volumio[1104]: ,establishing connection Sep 07 08:31:39 volumio2 sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Sep 07 08:31:39 volumio2 sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:39 volumio2 sudo[1265]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:39 volumio2 volumio[1104]: Unable to parse: Sep 07 08:31:39 volumio2 volumio[1104]: Simple mixer control 'PCM',0 Sep 07 08:31:39 volumio2 volumio[1104]: Capabilities: pswitch pswitch-joined Sep 07 08:31:39 volumio2 volumio[1104]: Playback channels: Mono Sep 07 08:31:39 volumio2 volumio[1104]: Mono: Playback [on] Sep 07 08:31:39 volumio2 volumio[1104]: info: VolumeController:: Volume=undefined Mute =false Sep 07 08:31:39 volumio2 volumio[1104]: info: CoreStateMachine::pushState Sep 07 08:31:39 volumio2 volumio[1104]: info: CorePlayQueue::getTrack 0 Sep 07 08:31:39 volumio2 volumio[1104]: info: CoreCommandRouter::volumioPushState Sep 07 08:31:39 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreStateMachine::updateTrackBlock Sep 07 08:31:40 volumio2 volumio[1104]: info: CorePlayQueue::getTrackBlock Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreCommandRouter::volumioRetrievevolume Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 07 08:31:40 volumio2 volumio[1104]: info: Reloading queue from file Sep 07 08:31:40 volumio2 volumio[1104]: error: updateQueue error: null Sep 07 08:31:40 volumio2 volumio[1104]: error: Failed LSINFO: Error: [50@0] {} No such directory Sep 07 08:31:40 volumio2 volumio-remote-updater[638]: [2024-09-07 08:31:40] [connect] Successful connection Sep 07 08:31:40 volumio2 kernel: RTW: hw_rate_to_m_rate(): Non supported Rate [ff]!!! Sep 07 08:31:40 volumio2 volumio[1104]: Unable to parse: Sep 07 08:31:40 volumio2 volumio[1104]: Simple mixer control 'PCM',0 Sep 07 08:31:40 volumio2 volumio[1104]: Capabilities: pswitch pswitch-joined Sep 07 08:31:40 volumio2 volumio[1104]: Playback channels: Mono Sep 07 08:31:40 volumio2 volumio[1104]: Mono: Playback [on] Sep 07 08:31:40 volumio2 volumio[1104]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreStateMachine::setRepeat false single undefined Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreStateMachine::pushState Sep 07 08:31:40 volumio2 volumio[1104]: info: CorePlayQueue::getTrack 0 Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreCommandRouter::volumioPushState Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreStateMachine::setRandom true Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreStateMachine::pushState Sep 07 08:31:40 volumio2 volumio[1104]: info: CorePlayQueue::getTrack 0 Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreCommandRouter::volumioPushState Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 08:31:40 volumio2 volumio-remote-updater[638]: [2024-09-07 08:31:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1725697900 101 Sep 07 08:31:40 volumio2 volumio[1104]: Unable to parse: Sep 07 08:31:40 volumio2 volumio[1104]: Simple mixer control 'PCM',0 Sep 07 08:31:40 volumio2 volumio[1104]: Capabilities: pswitch pswitch-joined Sep 07 08:31:40 volumio2 volumio[1104]: Playback channels: Mono Sep 07 08:31:40 volumio2 volumio[1104]: Mono: Playback [on] Sep 07 08:31:40 volumio2 volumio[1104]: info: VolumeController:: Volume=undefined Mute =false Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreStateMachine::pushState Sep 07 08:31:40 volumio2 volumio[1104]: info: CorePlayQueue::getTrack 0 Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreCommandRouter::volumioPushState Sep 07 08:31:40 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 07 08:31:40 volumio2 volumio[1104]: info: Starting Shairport Sync Sep 07 08:31:40 volumio2 volumio[1104]: info: Starting Shairport Sync Sep 07 08:31:40 volumio2 sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 07 08:31:40 volumio2 sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:40 volumio2 sudo[1282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 07 08:31:40 volumio2 systemd[1]: Starting UPnP Renderer front-end to MPD... Sep 07 08:31:40 volumio2 systemd[1]: Started UPnP Renderer front-end to MPD. Sep 07 08:31:40 volumio2 sudo[1282]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:40 volumio2 sudo[1280]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:40 volumio2 sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 07 08:31:40 volumio2 sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:40 volumio2 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 07 08:31:40 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 07 08:31:40 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 07 08:31:40 volumio2 sudo[1282]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:40 volumio2 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Sep 07 08:31:40 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 07 08:31:40 volumio2 sudo[1286]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:41 volumio2 volumio[1104]: Discovery: adding d3f92838-95d6-489f-b4c7-1afe50c583c4 Sep 07 08:31:41 volumio2 volumio[1104]: info: mDNS: Found device Volumio2 Sep 07 08:31:41 volumio2 volumio[1104]: info: CoreCommandRouter::volumioGetState Sep 07 08:31:41 volumio2 volumio[1104]: info: CorePlayQueue::getTrack 0 Sep 07 08:31:41 volumio2 volumio[1104]: Discovery: this is already registered, d3f92838-95d6-489f-b4c7-1afe50c583c4 Sep 07 08:31:41 volumio2 volumio[1104]: info: mDNS: Found device Volumio2 Sep 07 08:31:41 volumio2 volumio[1104]: info: CoreCommandRouter::volumioGetState Sep 07 08:31:41 volumio2 volumio[1104]: info: CorePlayQueue::getTrack 0 Sep 07 08:31:41 volumio2 volumio[1104]: info: Shairport-Sync Started Sep 07 08:31:41 volumio2 volumio[1104]: Error adding Membership: Error: addMembership EINVAL Sep 07 08:31:41 volumio2 volumio[1104]: info: Shairport-Sync Started Sep 07 08:31:41 volumio2 volumio[1104]: info: Upmpdcli Daemon Started Sep 07 08:31:41 volumio2 volumio[1104]: info: ControllerAutoPlay - mpd ready Sep 07 08:31:41 volumio2 volumio[1104]: info: CoreCommandRouter::volumioGetState Sep 07 08:31:41 volumio2 volumio[1104]: info: CorePlayQueue::getTrack 0 Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 07 08:31:42 volumio2 volumio[1104]: info: Adding plugin bluetooth to MyMusic Plugins Sep 07 08:31:42 volumio2 volumio[1104]: info: Adding plugin cd_controller to MyMusic Plugins Sep 07 08:31:42 volumio2 volumio[1104]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 07 08:31:42 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 07 08:31:44 volumio2 kernel: RTW: hw_rate_to_m_rate(): Non supported Rate [ff]!!! Sep 07 08:31:47 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 07 08:31:48 volumio2 kernel: RTW: hw_rate_to_m_rate(): Non supported Rate [ff]!!! Sep 07 08:31:50 volumio2 volumio[1104]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 07 08:31:50 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 07 08:31:50 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 08:31:50 volumio2 volumio[1104]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 07 08:31:50 volumio2 volumio[1104]: info: Starting MyVolumio Remote Streaming Endpoints Sep 07 08:31:50 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 07 08:31:50 volumio2 volumio[1104]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 07 08:31:50 volumio2 volumio[1104]: info: Streaming services startup Sep 07 08:31:50 volumio2 volumio[1104]: info: Starting Streaming Daemon Sep 07 08:31:50 volumio2 volumio[1104]: info: MyVolumio not started Sep 07 08:31:50 volumio2 volumio[1104]: info: Initializing device activation check Sep 07 08:31:50 volumio2 sudo[1334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 07 08:31:50 volumio2 sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 07 08:31:50 volumio2 systemd[1]: Stopping Volumio Streaming Daemon... Sep 07 08:31:50 volumio2 volumio[1104]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 07 08:31:50 volumio2 systemd[1]: Starting Volumio Streaming Daemon... Sep 07 08:31:50 volumio2 systemd[1]: Started Volumio Streaming Daemon. Sep 07 08:31:50 volumio2 sudo[1334]: pam_unix(sudo:session): session closed for user root Sep 07 08:31:50 volumio2 volumio-streaming-daemon[1340]: ############################ Sep 07 08:31:50 volumio2 volumio-streaming-daemon[1340]: # Volumio Streaming Daemon # Sep 07 08:31:50 volumio2 volumio-streaming-daemon[1340]: # Running on port 7777 # Sep 07 08:31:50 volumio2 volumio-streaming-daemon[1340]: ############################ Sep 07 08:31:50 volumio2 volumio-streaming-daemon[1340]: Environment: production Sep 07 08:31:50 volumio2 volumio[1104]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 08:31:50 volumio2 volumio[1104]: { Error: addMembership ENODEV Sep 07 08:31:50 volumio2 volumio[1104]: at _errnoException (util.js:1022:11) Sep 07 08:31:50 volumio2 volumio[1104]: at Socket.addMembership (dgram.js:615:11) Sep 07 08:31:50 volumio2 volumio[1104]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:264:16) Sep 07 08:31:50 volumio2 volumio[1104]: at ontimeout (timers.js:482:11) Sep 07 08:31:50 volumio2 volumio[1104]: at tryOnTimeout (timers.js:317:5) Sep 07 08:31:50 volumio2 volumio[1104]: at Timer.listOnTimeout (timers.js:277:5) code: 'ENODEV', errno: 'ENODEV', syscall: 'addMembership' } Sep 07 08:31:50 volumio2 volumio[1104]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 07 08:31:51 volumio2 sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-07 08:30 Sep 07 08:31:51 volumio2 sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="ceaca221623bd3cfdc448100963369088321c5bb" VOLUMIO_FE_VERSION="4917998f86709e30d6499627aa39df0350cb55f8" VOLUMIO_BE_VERSION="2eb1d573179cee7a262e71ad6e6f6c07489b563f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Jul 29 20:47:51 CEST 2020" VOLUMIO_VERSION="2.806" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="a5dbec2d8b23cb06ebf9780d5fc2c84f"