-- Logs begin at Thu 2024-07-04 09:02:50 CST, end at Thu 2024-07-04 09:04:59 CST. -- Jul 04 09:03:00 hi-quality ntpd[886]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 04 09:03:00 hi-quality bthelper[772]: Changing power off succeeded Jul 04 09:03:00 hi-quality bthelper[772]: [CHG] Controller E4:5F:01:2E:0E:B2 Class: 0x0000041c Jul 04 09:03:00 hi-quality bthelper[772]: Changing power on succeeded Jul 04 09:03:01 hi-quality ntpd[886]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 04 09:03:01 hi-quality volumio-remote-updater[737]: [2024-07-04 09:03:01] [info] asio async_connect error: system:111 (Connection refused) Jul 04 09:03:01 hi-quality volumio-remote-updater[737]: [2024-07-04 09:03:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 04 09:03:01 hi-quality volumio-remote-updater[737]: [2024-07-04 09:03:01] [error] handle_connect error: Underlying Transport Error Jul 04 09:03:02 hi-quality openvpn[837]: Thu Jul 4 09:03:02 2024 TCP/UDP: Preserving recently used remote address: [AF_INET]200.5.51.74:1194 Jul 04 09:03:02 hi-quality openvpn[837]: Thu Jul 4 09:03:02 2024 Socket Buffers: R=[180224->180224] S=[180224->180224] Jul 04 09:03:02 hi-quality openvpn[837]: Thu Jul 4 09:03:02 2024 UDP link local: (not bound) Jul 04 09:03:02 hi-quality openvpn[837]: Thu Jul 4 09:03:02 2024 UDP link remote: [AF_INET]200.5.51.74:1194 Jul 04 09:03:02 hi-quality openvpn[837]: Thu Jul 4 09:03:02 2024 write UDP: Network is unreachable (code=101) Jul 04 09:03:02 hi-quality openvpn[837]: Thu Jul 4 09:03:02 2024 Network unreachable, restarting Jul 04 09:03:02 hi-quality openvpn[837]: Thu Jul 4 09:03:02 2024 SIGUSR1[soft,network-unreachable] received, process restarting Jul 04 09:03:02 hi-quality openvpn[837]: Thu Jul 4 09:03:02 2024 Restart pause, 5 second(s) Jul 04 09:03:02 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:03:02 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 1. Jul 04 09:03:02 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:03:02 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:03:02 hi-quality ovpn-server[1221]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:03:02 hi-quality ovpn-server[1221]: Options error: Please correct these errors. Jul 04 09:03:02 hi-quality ovpn-server[1221]: Use --help for more information. Jul 04 09:03:02 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:03:02 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:03:02 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:03:02 hi-quality sudo[1210]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:02 hi-quality wireless.js[717]: SETTING APPROPRIATE REG DOMAIN: US Jul 04 09:03:02 hi-quality sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 04 09:03:02 hi-quality sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:02 hi-quality sudo[1225]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:02 hi-quality sudo[1227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Jul 04 09:03:02 hi-quality sudo[1227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:02 hi-quality sudo[1227]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:02 hi-quality wireless.js[717]: SUCCESSFULLY SET NEW REGDOMAIN: US Jul 04 09:03:02 hi-quality wireless.js[717]: Start wireless flow Jul 04 09:03:02 hi-quality wireless.js[717]: Stopped hotspot (if there).. Jul 04 09:03:02 hi-quality sudo[1232]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 04 09:03:02 hi-quality sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:02 hi-quality sudo[1232]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:02 hi-quality sudo[1234]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 04 09:03:02 hi-quality sudo[1234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:03 hi-quality sudo[1234]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:03 hi-quality wireless.js[717]: DHCP IP Jul 04 09:03:03 hi-quality wireless.js[717]: Start ap Jul 04 09:03:03 hi-quality wpa_supplicant[1236]: Successfully initialized wpa_supplicant Jul 04 09:03:03 hi-quality sudo[1237]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jul 04 09:03:03 hi-quality sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:03 hi-quality kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 04 09:03:03 hi-quality dhcpcd[1238]: dev: loaded udev Jul 04 09:03:03 hi-quality dhcpcd-run-hooks[1256]: wlan0: starting wpa_supplicant Jul 04 09:03:03 hi-quality dhcpcd-run-hooks[1261]: wlan0: failed to start wpa_supplicant Jul 04 09:03:03 hi-quality dhcpcd-run-hooks[1262]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Jul 04 09:03:03 hi-quality dhcpcd[1238]: wlan0: connected to Access Point `' Jul 04 09:03:03 hi-quality dhcpcd[1238]: no interfaces have a carrier Jul 04 09:03:03 hi-quality dhcpcd[1238]: forked to background, child pid 1272 Jul 04 09:03:03 hi-quality dhcpcd[1272]: eth0: waiting for carrier Jul 04 09:03:03 hi-quality dhcpcd[1272]: wlan0: waiting for carrier Jul 04 09:03:03 hi-quality sudo[1237]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:04 hi-quality wireless.js[717]: trying... Jul 04 09:03:04 hi-quality sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 04 09:03:04 hi-quality sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:04 hi-quality sudo[1274]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:05 hi-quality wireless.js[717]: trying... Jul 04 09:03:05 hi-quality sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 04 09:03:05 hi-quality sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:05 hi-quality sudo[1277]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:06 hi-quality wpa_supplicant[1260]: wlan0: Trying to associate with SSID 'Hogar' Jul 04 09:03:06 hi-quality wireless.js[717]: trying... Jul 04 09:03:06 hi-quality sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 04 09:03:06 hi-quality sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:06 hi-quality sudo[1280]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:07 hi-quality openvpn[837]: Thu Jul 4 09:03:07 2024 TCP/UDP: Preserving recently used remote address: [AF_INET]200.5.51.74:1194 Jul 04 09:03:07 hi-quality openvpn[837]: Thu Jul 4 09:03:07 2024 Socket Buffers: R=[180224->180224] S=[180224->180224] Jul 04 09:03:07 hi-quality openvpn[837]: Thu Jul 4 09:03:07 2024 UDP link local: (not bound) Jul 04 09:03:07 hi-quality openvpn[837]: Thu Jul 4 09:03:07 2024 UDP link remote: [AF_INET]200.5.51.74:1194 Jul 04 09:03:07 hi-quality openvpn[837]: Thu Jul 4 09:03:07 2024 write UDP: Network is unreachable (code=101) Jul 04 09:03:07 hi-quality openvpn[837]: Thu Jul 4 09:03:07 2024 Network unreachable, restarting Jul 04 09:03:07 hi-quality openvpn[837]: Thu Jul 4 09:03:07 2024 SIGUSR1[soft,network-unreachable] received, process restarting Jul 04 09:03:07 hi-quality openvpn[837]: Thu Jul 4 09:03:07 2024 Restart pause, 5 second(s) Jul 04 09:03:07 hi-quality wireless.js[717]: trying... Jul 04 09:03:07 hi-quality sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 04 09:03:07 hi-quality sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:07 hi-quality sudo[1283]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:07 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:03:07 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 2. Jul 04 09:03:07 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:03:07 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:03:07 hi-quality ovpn-server[1285]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:03:07 hi-quality ovpn-server[1285]: Options error: Please correct these errors. Jul 04 09:03:07 hi-quality ovpn-server[1285]: Use --help for more information. Jul 04 09:03:07 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:03:07 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:03:07 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:03:08 hi-quality wireless.js[717]: trying... Jul 04 09:03:08 hi-quality sudo[1289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 04 09:03:08 hi-quality sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:08 hi-quality sudo[1289]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:09 hi-quality volumio-remote-updater[737]: [2024-07-04 09:03:09] [info] asio async_connect error: system:111 (Connection refused) Jul 04 09:03:09 hi-quality volumio-remote-updater[737]: [2024-07-04 09:03:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 04 09:03:09 hi-quality volumio-remote-updater[737]: [2024-07-04 09:03:09] [error] handle_connect error: Underlying Transport Error Jul 04 09:03:09 hi-quality dhcpcd[1272]: wlan0: carrier acquired Jul 04 09:03:09 hi-quality dhcpcd[1272]: wlan0: connected to Access Point `Hogar' Jul 04 09:03:09 hi-quality wpa_supplicant[1260]: wlan0: Associated with a0:08:6f:7c:19:80 Jul 04 09:03:09 hi-quality wpa_supplicant[1260]: wlan0: CTRL-EVENT-CONNECTED - Connection to a0:08:6f:7c:19:80 completed [id=0 id_str=] Jul 04 09:03:09 hi-quality wpa_supplicant[1260]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 04 09:03:09 hi-quality wpa_supplicant[1260]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Jul 04 09:03:09 hi-quality dhcpcd[1272]: DUID 00:01:00:01:2d:f2:1d:9e:e4:5f:01:2e:0e:af Jul 04 09:03:09 hi-quality dhcpcd[1272]: wlan0: IAID 01:2e:0e:b1 Jul 04 09:03:09 hi-quality dhcpcd[1272]: wlan0: adding address fe80::9427:ad2:7d7b:ce61 Jul 04 09:03:09 hi-quality dhcpcd[1272]: ipv6_addaddr1: Permission denied Jul 04 09:03:09 hi-quality dhcpcd[1272]: wlan0: carrier lost Jul 04 09:03:09 hi-quality dhcpcd[1272]: wlan0: carrier acquired Jul 04 09:03:09 hi-quality wireless.js[717]: trying... Jul 04 09:03:09 hi-quality dhcpcd[1272]: wlan0: IAID 01:2e:0e:b1 Jul 04 09:03:09 hi-quality sudo[1304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 04 09:03:09 hi-quality sudo[1304]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:09 hi-quality sudo[1304]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:09 hi-quality dhcpcd[1272]: wlan0: soliciting an IPv6 router Jul 04 09:03:10 hi-quality dhcpcd[1272]: wlan0: rebinding lease of 192.168.100.141 Jul 04 09:03:10 hi-quality dhcpcd[1272]: wlan0: probing address 192.168.100.141/24 Jul 04 09:03:10 hi-quality wireless.js[717]: trying... Jul 04 09:03:10 hi-quality sudo[1307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 04 09:03:10 hi-quality sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:10 hi-quality sudo[1307]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:11 hi-quality wireless.js[717]: trying... Jul 04 09:03:11 hi-quality sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 04 09:03:11 hi-quality sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:11 hi-quality sudo[1310]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:12 hi-quality openvpn[837]: Thu Jul 4 09:03:12 2024 TCP/UDP: Preserving recently used remote address: [AF_INET]200.5.51.74:1194 Jul 04 09:03:12 hi-quality openvpn[837]: Thu Jul 4 09:03:12 2024 Socket Buffers: R=[180224->180224] S=[180224->180224] Jul 04 09:03:12 hi-quality openvpn[837]: Thu Jul 4 09:03:12 2024 UDP link local: (not bound) Jul 04 09:03:12 hi-quality openvpn[837]: Thu Jul 4 09:03:12 2024 UDP link remote: [AF_INET]200.5.51.74:1194 Jul 04 09:03:12 hi-quality openvpn[837]: Thu Jul 4 09:03:12 2024 write UDP: Network is unreachable (code=101) Jul 04 09:03:12 hi-quality openvpn[837]: Thu Jul 4 09:03:12 2024 Network unreachable, restarting Jul 04 09:03:12 hi-quality openvpn[837]: Thu Jul 4 09:03:12 2024 SIGUSR1[soft,network-unreachable] received, process restarting Jul 04 09:03:12 hi-quality openvpn[837]: Thu Jul 4 09:03:12 2024 Restart pause, 5 second(s) Jul 04 09:03:12 hi-quality wireless.js[717]: trying... Jul 04 09:03:12 hi-quality sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 04 09:03:12 hi-quality sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:12 hi-quality sudo[1313]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:12 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:03:12 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 3. Jul 04 09:03:12 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:03:12 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:03:12 hi-quality ovpn-server[1315]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:03:12 hi-quality ovpn-server[1315]: Options error: Please correct these errors. Jul 04 09:03:12 hi-quality ovpn-server[1315]: Use --help for more information. Jul 04 09:03:12 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:03:12 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:03:12 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:03:13 hi-quality wireless.js[717]: trying... Jul 04 09:03:13 hi-quality sudo[1319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 04 09:03:13 hi-quality sudo[1319]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:13 hi-quality sudo[1319]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:14 hi-quality wireless.js[717]: trying... Jul 04 09:03:14 hi-quality sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 04 09:03:14 hi-quality sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:14 hi-quality sudo[1322]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:15 hi-quality dhcpcd[1272]: wlan0: leased 192.168.100.141 for 259200 seconds Jul 04 09:03:15 hi-quality avahi-daemon[729]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.141. Jul 04 09:03:15 hi-quality dhcpcd[1272]: wlan0: adding route to 192.168.100.0/24 Jul 04 09:03:15 hi-quality avahi-daemon[729]: New relevant interface wlan0.IPv4 for mDNS. Jul 04 09:03:15 hi-quality dhcpcd[1272]: wlan0: adding default route via 192.168.100.1 Jul 04 09:03:15 hi-quality avahi-daemon[729]: Registering new address record for 192.168.100.141 on wlan0.IPv4. Jul 04 09:03:15 hi-quality wireless.js[717]: trying... Jul 04 09:03:15 hi-quality sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 04 09:03:15 hi-quality sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:15 hi-quality sudo[1345]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:15 hi-quality wireless.js[717]: Connected to: ----Hogar Jul 04 09:03:15 hi-quality wireless.js[717]: ---- Jul 04 09:03:15 hi-quality sudo[1348]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 04 09:03:15 hi-quality sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:15 hi-quality sudo[1348]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:15 hi-quality wireless.js[717]: ... joined AP, wlan0 IPv4 is 192.168.100.141, ipV6 is undefined Jul 04 09:03:15 hi-quality wireless.js[717]: It's done! AP Jul 04 09:03:15 hi-quality kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 04 09:03:15 hi-quality systemd[1]: Started Wireless Services. Jul 04 09:03:15 hi-quality systemd[1]: Started Volumio Backend Module. Jul 04 09:03:15 hi-quality systemd[1]: Started Volumio Cpu Tweaker. Jul 04 09:03:15 hi-quality volumio-cpu-tweak[1354]: Setting RT Priority for mpd Jul 04 09:03:15 hi-quality volumio-cpu-tweak[1354]: pid 35's current scheduling policy: SCHED_OTHER Jul 04 09:03:15 hi-quality volumio-cpu-tweak[1354]: pid 35's current scheduling priority: 0 Jul 04 09:03:15 hi-quality volumio-cpu-tweak[1354]: Setting MPD Affinity Jul 04 09:03:15 hi-quality volumio-cpu-tweak[1354]: pid 3's current affinity mask: f Jul 04 09:03:15 hi-quality volumio-cpu-tweak[1354]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jul 04 09:03:15 hi-quality volumio-cpu-tweak[1354]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jul 04 09:03:15 hi-quality systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 04 09:03:16 hi-quality ntpd[886]: Listen normally on 3 wlan0 192.168.100.141:123 Jul 04 09:03:16 hi-quality ntpd[886]: new interface(s) found: waking up resolver Jul 04 09:03:17 hi-quality openvpn[837]: Thu Jul 4 09:03:17 2024 TCP/UDP: Preserving recently used remote address: [AF_INET]200.5.51.74:1194 Jul 04 09:03:17 hi-quality openvpn[837]: Thu Jul 4 09:03:17 2024 Socket Buffers: R=[180224->180224] S=[180224->180224] Jul 04 09:03:17 hi-quality openvpn[837]: Thu Jul 4 09:03:17 2024 UDP link local: (not bound) Jul 04 09:03:17 hi-quality openvpn[837]: Thu Jul 4 09:03:17 2024 UDP link remote: [AF_INET]200.5.51.74:1194 Jul 04 09:03:17 hi-quality openvpn[837]: Thu Jul 4 09:03:17 2024 TLS: Initial packet from [AF_INET]200.5.51.74:1194, sid=a608e68e e54db52d Jul 04 09:03:17 hi-quality openvpn[837]: Thu Jul 4 09:03:17 2024 VERIFY OK: depth=1, CN=cn_tqQ4kSieOaQN0WaD Jul 04 09:03:17 hi-quality openvpn[837]: Thu Jul 4 09:03:17 2024 VERIFY KU OK Jul 04 09:03:17 hi-quality openvpn[837]: Thu Jul 4 09:03:17 2024 Validating certificate extended key usage Jul 04 09:03:17 hi-quality openvpn[837]: Thu Jul 4 09:03:17 2024 ++ Certificate has EKU (str) TLS Web Server Authentication, expects TLS Web Server Authentication Jul 04 09:03:17 hi-quality openvpn[837]: Thu Jul 4 09:03:17 2024 VERIFY EKU OK Jul 04 09:03:17 hi-quality openvpn[837]: Thu Jul 4 09:03:17 2024 VERIFY X509NAME OK: CN=server_hTqHrvvkC6C66SSk Jul 04 09:03:17 hi-quality openvpn[837]: Thu Jul 4 09:03:17 2024 VERIFY OK: depth=0, CN=server_hTqHrvvkC6C66SSk Jul 04 09:03:17 hi-quality openvpn[837]: Thu Jul 4 09:03:17 2024 Control Channel: TLSv1.3, cipher TLSv1.3 TLS_AES_256_GCM_SHA384, 256 bit EC, curve: prime256v1 Jul 04 09:03:17 hi-quality openvpn[837]: Thu Jul 4 09:03:17 2024 [server_hTqHrvvkC6C66SSk] Peer Connection Initiated with [AF_INET]200.5.51.74:1194 Jul 04 09:03:17 hi-quality volumio[1353]: info: ------------------------------------------- Jul 04 09:03:17 hi-quality volumio[1353]: info: ----- Volumio3 ---- Jul 04 09:03:17 hi-quality volumio[1353]: info: ------------------------------------------- Jul 04 09:03:17 hi-quality volumio[1353]: info: ----- System startup ---- Jul 04 09:03:17 hi-quality volumio[1353]: info: ------------------------------------------- Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 SENT CONTROL [server_hTqHrvvkC6C66SSk]: 'PUSH_REQUEST' (status=1) Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 PUSH: Received control message: 'PUSH_REPLY,dhcp-option DNS 8.8.8.8,dhcp-option DNS 8.8.4.4,redirect-gateway def1 bypass-dhcp,route-gateway 10.8.0.1,topology subnet,ping 10,ping-restart 120,ifconfig 10.8.0.4 255.255.255.0,peer-id 0,cipher AES-128-GCM' Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 OPTIONS IMPORT: timers and/or timeouts modified Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 OPTIONS IMPORT: --ifconfig/up options modified Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 OPTIONS IMPORT: route options modified Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 OPTIONS IMPORT: route-related options modified Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 OPTIONS IMPORT: --ip-win32 and/or --dhcp-option options modified Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 OPTIONS IMPORT: peer-id set Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 OPTIONS IMPORT: adjusting link_mtu to 1624 Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 OPTIONS IMPORT: data channel crypto options modified Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 Outgoing Data Channel: Cipher 'AES-128-GCM' initialized with 128 bit key Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 Incoming Data Channel: Cipher 'AES-128-GCM' initialized with 128 bit key Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 ROUTE_GATEWAY 192.168.100.1/255.255.255.0 IFACE=wlan0 HWADDR=e4:5f:01:2e:0e:b1 Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 TUN/TAP device tun0 opened Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 TUN/TAP TX queue length set to 100 Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 /sbin/ip link set dev tun0 up mtu 1500 Jul 04 09:03:18 hi-quality kernel: tun: Universal TUN/TAP device driver, 1.6 Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 /sbin/ip addr add dev tun0 10.8.0.4/24 broadcast 10.8.0.255 Jul 04 09:03:18 hi-quality systemd-udevd[1384]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 /sbin/ip route add 200.5.51.74/32 via 192.168.100.1 Jul 04 09:03:18 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 /sbin/ip route add 0.0.0.0/1 via 10.8.0.1 Jul 04 09:03:18 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 4. Jul 04 09:03:18 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 /sbin/ip route add 128.0.0.0/1 via 10.8.0.1 Jul 04 09:03:18 hi-quality openvpn[837]: Thu Jul 4 09:03:18 2024 Initialization Sequence Completed Jul 04 09:03:18 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:03:18 hi-quality ovpn-server[1391]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:03:18 hi-quality ovpn-server[1391]: Options error: Please correct these errors. Jul 04 09:03:18 hi-quality ovpn-server[1391]: Use --help for more information. Jul 04 09:03:18 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:03:18 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:03:18 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:03:18 hi-quality volumio[1353]: info: MYVOLUMIO Environment detected Jul 04 09:03:18 hi-quality volumio[1353]: info: Plugin folders cleanup Jul 04 09:03:18 hi-quality volumio[1353]: info: Scanning into folder /volumio/app/plugins/ Jul 04 09:03:18 hi-quality volumio[1353]: info: Scanning category audio_interface Jul 04 09:03:18 hi-quality volumio[1353]: info: Scanning category miscellanea Jul 04 09:03:18 hi-quality volumio[1353]: info: Scanning category music_service Jul 04 09:03:18 hi-quality volumio[1353]: info: Scanning category plugins.json Jul 04 09:03:18 hi-quality volumio[1353]: info: Scanning category system_controller Jul 04 09:03:18 hi-quality volumio[1353]: info: Scanning category user_interface Jul 04 09:03:18 hi-quality volumio[1353]: info: Scanning into folder /data/plugins/ Jul 04 09:03:18 hi-quality volumio[1353]: info: Scanning category music_service Jul 04 09:03:18 hi-quality volumio[1353]: info: Scanning category system_hardware Jul 04 09:03:18 hi-quality volumio[1353]: info: Scanning category user_interface Jul 04 09:03:18 hi-quality volumio[1353]: info: Plugin folders cleanup completed Jul 04 09:03:18 hi-quality volumio[1353]: info: ------------------------------------------- Jul 04 09:03:18 hi-quality volumio[1353]: info: ----- Core plugins startup ---- Jul 04 09:03:18 hi-quality volumio[1353]: info: ------------------------------------------- Jul 04 09:03:18 hi-quality volumio[1353]: info: Loading plugins from folder /volumio/app/plugins/ Jul 04 09:03:18 hi-quality volumio[1353]: info: Adding plugin upnp to MyMusic Plugins Jul 04 09:03:18 hi-quality volumio[1353]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 04 09:03:18 hi-quality volumio[1353]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 04 09:03:18 hi-quality volumio[1353]: info: Loading plugins from folder /data/plugins/ Jul 04 09:03:18 hi-quality volumio[1353]: info: Loading plugin "system"... Jul 04 09:03:18 hi-quality volumio[1353]: info: Loading plugin "appearance"... Jul 04 09:03:18 hi-quality nmbd[887]: [2024/07/04 09:03:18.944451, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 04 09:03:18 hi-quality nmbd[887]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 04 09:03:18 hi-quality systemd[1]: Started Samba NMB Daemon. Jul 04 09:03:18 hi-quality nmbd[887]: [2024/07/04 09:03:18.959505, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 04 09:03:18 hi-quality nmbd[887]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.100.141 for name WORKGROUP<1d>. Jul 04 09:03:18 hi-quality nmbd[887]: This response was from IP 192.168.100.128, reporting an IP address of 192.168.100.128. Jul 04 09:03:18 hi-quality systemd[1]: Starting Samba Winbind Daemon... Jul 04 09:03:19 hi-quality volumio-remote-updater[737]: [2024-07-04 09:03:19] [connect] Successful connection Jul 04 09:03:19 hi-quality ntpd[886]: Listen normally on 4 tun0 10.8.0.4:123 Jul 04 09:03:19 hi-quality ntpd[886]: new interface(s) found: waking up resolver Jul 04 09:03:19 hi-quality winbindd[1403]: [2024/07/04 09:03:19.235187, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 04 09:03:19 hi-quality winbindd[1403]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 04 09:03:19 hi-quality winbindd[1403]: [2024/07/04 09:03:19.250870, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 04 09:03:19 hi-quality winbindd[1403]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 04 09:03:19 hi-quality systemd[1]: Started Samba Winbind Daemon. Jul 04 09:03:19 hi-quality systemd[1]: Starting Samba SMB Daemon... Jul 04 09:03:19 hi-quality volumio[1353]: info: Loading plugin "network"... Jul 04 09:03:19 hi-quality volumio[1353]: info: Refreshing Cached IP Addresses Jul 04 09:03:19 hi-quality sudo[1411]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 04 09:03:19 hi-quality sudo[1411]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:19 hi-quality sudo[1411]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:19 hi-quality volumio[1353]: info: Loading plugin "services"... Jul 04 09:03:19 hi-quality sudo[1415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 04 09:03:19 hi-quality volumio[1353]: info: Loading plugin "alsa_controller"... Jul 04 09:03:19 hi-quality sudo[1415]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:19 hi-quality sudo[1415]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:19 hi-quality sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 04 09:03:19 hi-quality sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:19 hi-quality smbd[1408]: [2024/07/04 09:03:19.671378, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 04 09:03:19 hi-quality systemd[1]: Started Samba SMB Daemon. Jul 04 09:03:19 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 09:03:19 hi-quality volumio[1353]: info: Loading plugin "wizard"... Jul 04 09:03:19 hi-quality smbd[1408]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 04 09:03:19 hi-quality systemd[1]: Reached target Multi-User System. Jul 04 09:03:19 hi-quality systemd[1]: Reached target Graphical Interface. Jul 04 09:03:19 hi-quality systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 04 09:03:19 hi-quality volumio[1353]: info: Loading plugin "networkfs"... Jul 04 09:03:19 hi-quality systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 04 09:03:19 hi-quality systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 04 09:03:19 hi-quality systemd[1]: Startup finished in 15.592s (kernel) + 31.619s (userspace) = 47.212s. Jul 04 09:03:19 hi-quality volumio[1353]: info: Starting Udev Watcher for removable devices Jul 04 09:03:19 hi-quality sudo[1431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=grey,password=Hra2ooomL,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.100.128/datos/Disco02/data/media/audio /mnt/NAS/Datos Jul 04 09:03:19 hi-quality sudo[1431]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:19 hi-quality volumio[1353]: info: Ignoring mount for partition: boot Jul 04 09:03:19 hi-quality volumio[1353]: info: Ignoring mount for partition: volumio Jul 04 09:03:19 hi-quality volumio[1353]: info: Ignoring mount for partition: volumio_data Jul 04 09:03:19 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 04 09:03:19 hi-quality volumio[1353]: info: Loading plugin "volumio_command_line_client"... Jul 04 09:03:19 hi-quality volumio[1353]: info: Loading plugin "upnp"... Jul 04 09:03:19 hi-quality volumio[1353]: info: [1720105399825] Starting Upmpd Daemon Jul 04 09:03:19 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 04 09:03:19 hi-quality volumio[1353]: info: Loading plugin "my_music"... Jul 04 09:03:19 hi-quality volumio[1353]: info: Loading plugin "mpd"... Jul 04 09:03:19 hi-quality kernel: Key type cifs.spnego registered Jul 04 09:03:19 hi-quality kernel: Key type cifs.idmap registered Jul 04 09:03:19 hi-quality kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jul 04 09:03:19 hi-quality kernel: CIFS: Attempting to mount \\192.168.100.128\datos Jul 04 09:03:20 hi-quality volumio[1353]: info: Loading plugin "upnp_browser"... Jul 04 09:03:20 hi-quality sudo[1431]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:20 hi-quality volumio[1353]: info: Loading plugin "alarm-clock"... Jul 04 09:03:20 hi-quality volumio[1353]: info: Loading plugin "airplay_emulation"... Jul 04 09:03:20 hi-quality volumio[1353]: info: Starting Shairport Sync Jul 04 09:03:20 hi-quality volumio[1353]: info: Loading plugin "last_100"... Jul 04 09:03:20 hi-quality volumio[1353]: info: Loading plugin "webradio"... Jul 04 09:03:20 hi-quality volumio[1353]: info: Loading plugin "i2s_dacs"... Jul 04 09:03:20 hi-quality volumio[1353]: info: Loading plugin "volumiodiscovery"... Jul 04 09:03:20 hi-quality volumio[1353]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 04 09:03:20 hi-quality volumio[1353]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 09:03:20 hi-quality volumio[1353]: *** WARNING *** For more information see Jul 04 09:03:20 hi-quality node[1353]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 04 09:03:20 hi-quality volumio[1353]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 04 09:03:20 hi-quality volumio[1353]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 09:03:20 hi-quality volumio[1353]: *** WARNING *** For more information see Jul 04 09:03:20 hi-quality node[1353]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 09:03:20 hi-quality node[1353]: *** WARNING *** For more information see Jul 04 09:03:20 hi-quality node[1353]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 04 09:03:20 hi-quality node[1353]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 04 09:03:20 hi-quality node[1353]: *** WARNING *** For more information see Jul 04 09:03:20 hi-quality volumio[1353]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 04 09:03:20 hi-quality volumio[1353]: info: Discovery: Started advertising with name: Hi-Quality Jul 04 09:03:20 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 04 09:03:20 hi-quality volumio[1353]: info: Loading plugin "ytmusic"... Jul 04 09:03:21 hi-quality volumio[1353]: info: Loading plugin "peppy_screensaver"... Jul 04 09:03:21 hi-quality sshd[1378]: Accepted password for volumio from 192.168.100.160 port 41694 ssh2 Jul 04 09:03:21 hi-quality sshd[1378]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jul 04 09:03:21 hi-quality systemd-logind[727]: New session 2 of user volumio. Jul 04 09:03:21 hi-quality systemd[1]: Created slice User Slice of UID 1000. Jul 04 09:03:21 hi-quality systemd[1]: Starting User Runtime Directory /run/user/1000... Jul 04 09:03:21 hi-quality systemd[1]: Started User Runtime Directory /run/user/1000. Jul 04 09:03:21 hi-quality systemd[1]: Starting User Manager for UID 1000... Jul 04 09:03:21 hi-quality systemd[1457]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Jul 04 09:03:22 hi-quality systemd[1457]: Listening on GnuPG cryptographic agent and passphrase cache. Jul 04 09:03:22 hi-quality systemd[1457]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Jul 04 09:03:22 hi-quality systemd[1457]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Jul 04 09:03:22 hi-quality systemd[1457]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Jul 04 09:03:22 hi-quality systemd[1457]: Starting D-Bus User Message Bus Socket. Jul 04 09:03:22 hi-quality systemd[1457]: Reached target Paths. Jul 04 09:03:22 hi-quality systemd[1457]: Reached target Timers. Jul 04 09:03:22 hi-quality systemd[1457]: Listening on GnuPG network certificate management daemon. Jul 04 09:03:22 hi-quality systemd[1457]: Listening on Sound System. Jul 04 09:03:22 hi-quality systemd[1457]: Listening on D-Bus User Message Bus Socket. Jul 04 09:03:22 hi-quality systemd[1457]: Reached target Sockets. Jul 04 09:03:22 hi-quality systemd[1457]: Reached target Basic System. Jul 04 09:03:22 hi-quality systemd[1]: Started User Manager for UID 1000. Jul 04 09:03:22 hi-quality systemd[1457]: Starting Music Player Daemon... Jul 04 09:03:22 hi-quality volumio[1353]: info: Applying required configuration parameters for plugin peppy_screensaver Jul 04 09:03:22 hi-quality systemd[1]: Started Session 2 of user volumio. Jul 04 09:03:22 hi-quality volumio[1353]: info: Loading plugin "outputs"... Jul 04 09:03:22 hi-quality volumio[1353]: info: Loading plugin "albumart"... Jul 04 09:03:22 hi-quality volumio[1353]: info: Plugin example_plugin is not enabled Jul 04 09:03:22 hi-quality volumio[1353]: info: Loading plugin "inputs"... Jul 04 09:03:22 hi-quality volumio[1353]: info: Loading plugin "updater_comm"... Jul 04 09:03:22 hi-quality volumio[1353]: Forking 3 albumart workers Jul 04 09:03:22 hi-quality volumio[1353]: info: Plugin mpdemulation is not enabled Jul 04 09:03:22 hi-quality volumio[1353]: info: Loading plugin "rest_api"... Jul 04 09:03:22 hi-quality volumio[1353]: info: Loading plugin "websocket"... Jul 04 09:03:23 hi-quality volumio[1353]: info: Starting Socket.io Server version 2.3.0 Jul 04 09:03:23 hi-quality volumio[1353]: info: Loading plugin "minidlna"... Jul 04 09:03:23 hi-quality volumio[1353]: info: Applying required configuration parameters for plugin minidlna Jul 04 09:03:23 hi-quality volumio[1353]: Starting albumart workers Jul 04 09:03:23 hi-quality volumio[1353]: info: Plugin ir_controller is not enabled Jul 04 09:03:23 hi-quality volumio[1353]: info: Plugin remotepi is not enabled Jul 04 09:03:23 hi-quality volumio[1353]: info: Loading plugin "touch_display"... Jul 04 09:03:23 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:03:23 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 5. Jul 04 09:03:23 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:03:23 hi-quality volumio[1353]: Starting albumart workers Jul 04 09:03:23 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:03:23 hi-quality ovpn-server[1508]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:03:23 hi-quality ovpn-server[1508]: Options error: Please correct these errors. Jul 04 09:03:23 hi-quality ovpn-server[1508]: Use --help for more information. Jul 04 09:03:23 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:03:23 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:03:23 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:03:23 hi-quality volumio[1353]: Starting albumart workers Jul 04 09:03:23 hi-quality sudo[1418]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:23 hi-quality volumio[1353]: info: Applying required configuration parameters for plugin touch_display Jul 04 09:03:24 hi-quality systemd[1]: systemd-fsckd.service: Succeeded. Jul 04 09:03:24 hi-quality volumio[1353]: info: Loading i18n strings for locale es Jul 04 09:03:24 hi-quality volumio[1353]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jul 04 09:03:24 hi-quality volumio[1353]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jul 04 09:03:24 hi-quality volumio[1353]: Updating browse sources language Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 09:03:24 hi-quality mpd[1468]: Jul 04 09:03 : server_socket: bind to '0.0.0.0:6600' failed (continuing anyway, because binding to '[::]:6600' succeeded): Failed to bind socket: Address already in use Jul 04 09:03:24 hi-quality mpd[1468]: Jul 04 09:03 : exception: Failed to set group 29: Operation not permitted Jul 04 09:03:24 hi-quality systemd[1457]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:03:24 hi-quality systemd[1457]: mpd.service: Failed with result 'exit-code'. Jul 04 09:03:24 hi-quality systemd[1457]: Failed to start Music Player Daemon. Jul 04 09:03:24 hi-quality systemd[1457]: Reached target Default. Jul 04 09:03:24 hi-quality systemd[1457]: Startup finished in 2.244s. Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::initPlayerControls Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 09:03:24 hi-quality volumio[1353]: Express server listening on port 3000 Jul 04 09:03:24 hi-quality volumio[1353]: [Metrics] WebUI: 7s 543.87ms Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreStateMachine::resetVolumioState Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreStateMachine::getcurrentVolume Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::volumioRetrievevolume Jul 04 09:03:24 hi-quality volumio[1353]: verbose: New Socket.io Connection to 192.168.100.141 from 192.168.100.169 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 04 09:03:24 hi-quality volumio[1353]: verbose: New Socket.io Connection to 192.168.100.141:3000 from 192.168.100.139 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 04 09:03:24 hi-quality volumio[1353]: verbose: New Socket.io Connection to 192.168.100.141 from 192.168.100.169 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jul 04 09:03:24 hi-quality volumio[1353]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 04 09:03:24 hi-quality volumio[1353]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:24 hi-quality volumio[1353]: info: Reloading queue from file Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreStateMachine::setRepeat null single undefined Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreStateMachine::pushState Jul 04 09:03:24 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::volumioPushState Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreStateMachine::setRandom null Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreStateMachine::pushState Jul 04 09:03:24 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::volumioPushState Jul 04 09:03:24 hi-quality volumio[1353]: info: Setting Device type: Raspberry PI Jul 04 09:03:24 hi-quality volumio[1353]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 04 09:03:24 hi-quality volumio[1353]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03114 Jul 04 09:03:24 hi-quality volumio[1353]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 04 09:03:24 hi-quality volumio[1353]: info: Completed loading Core Plugins Jul 04 09:03:24 hi-quality volumio[1353]: info: Preparing to generate the ALSA configuration file Jul 04 09:03:24 hi-quality volumio[1353]: info: VolumeController:: Volume=100 Mute =false Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreStateMachine::pushState Jul 04 09:03:24 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::volumioPushState Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreStateMachine::updateTrackBlock Jul 04 09:03:24 hi-quality volumio[1353]: info: CorePlayQueue::getTrackBlock Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::volumioRetrievevolume Jul 04 09:03:24 hi-quality volumio[1353]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jul 04 09:03:24 hi-quality volumio[1353]: info: Reading ALSA contributions from plugins. Jul 04 09:03:24 hi-quality volumio[1353]: info: VolumeController:: Volume=100 Mute =false Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreStateMachine::pushState Jul 04 09:03:24 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::volumioPushState Jul 04 09:03:24 hi-quality sh[614]: timed out Jul 04 09:03:24 hi-quality dhcpcd[664]: timed out Jul 04 09:03:24 hi-quality sh[614]: dhcpcd exited Jul 04 09:03:24 hi-quality dhcpcd[664]: dhcpcd exited Jul 04 09:03:24 hi-quality sh[614]: ifup: failed to bring up eth0 Jul 04 09:03:24 hi-quality systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:03:24 hi-quality systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 04 09:03:24 hi-quality volumio[1353]: info: Asound.conf file unchanged, so no further update is needed Jul 04 09:03:24 hi-quality volumio[1353]: info: Output device has changed, restarting MPD Jul 04 09:03:24 hi-quality sudo[1550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 09:03:24 hi-quality volumio[1353]: info: Output device has changed, restarting Shairport Sync Jul 04 09:03:24 hi-quality sudo[1550]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:24 hi-quality sudo[1550]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:24 hi-quality sudo[1552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 09:03:24 hi-quality sudo[1552]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:24 hi-quality volumio[1353]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 09:03:24 hi-quality volumio[1353]: info: ___________ START PLUGINS ___________ Jul 04 09:03:24 hi-quality volumio[1353]: info: ControllerMpd::onStart: Initializing MPD Jul 04 09:03:24 hi-quality volumio[1353]: info: Creating MPD Configuration file Jul 04 09:03:24 hi-quality sudo[1560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 09:03:24 hi-quality sudo[1560]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 09:03:24 hi-quality volumio[1353]: info: [1720105404882] CoreMusicLibrary::Adding element Media Servers Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:24 hi-quality sudo[1560]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:24 hi-quality sudo[1562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 09:03:24 hi-quality sudo[1562]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:24 hi-quality systemd[1]: Listening on mpd.socket. Jul 04 09:03:24 hi-quality systemd[1]: Starting Music Player Daemon... Jul 04 09:03:24 hi-quality systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 04 09:03:24 hi-quality systemd[1]: mpd.service: Succeeded. Jul 04 09:03:24 hi-quality systemd[1]: Stopped Music Player Daemon. Jul 04 09:03:24 hi-quality volumio[1353]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:24 hi-quality systemd[1]: Starting Music Player Daemon... Jul 04 09:03:24 hi-quality volumio[1353]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 09:03:24 hi-quality volumio[1353]: info: [1720105404979] CoreMusicLibrary::Adding element Last_100 Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 09:03:24 hi-quality volumio[1353]: info: [1720105404982] CoreMusicLibrary::Adding element Webradio Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 09:03:24 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 09:03:24 hi-quality volumio[1353]: info: Initializing BBC Radios Jul 04 09:03:25 hi-quality sudo[1569]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 09:03:25 hi-quality sudo[1569]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:25 hi-quality sudo[1569]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 04 09:03:25 hi-quality volumio[1353]: info: [1720105405048] CoreMusicLibrary::Adding element YouTube Music Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 09:03:25 hi-quality volumio[1353]: Cannot find translation for source YouTube Music Jul 04 09:03:25 hi-quality volumio[1353]: info: Loading i18n strings for locale es Jul 04 09:03:25 hi-quality volumio[1353]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jul 04 09:03:25 hi-quality volumio[1353]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jul 04 09:03:25 hi-quality volumio[1353]: info: /tmp/myfifo created Jul 04 09:03:25 hi-quality volumio[1353]: info: /tmp/myfifosa created Jul 04 09:03:25 hi-quality sudo[1590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Jul 04 09:03:25 hi-quality sudo[1590]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:25 hi-quality sudo[1590]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:25 hi-quality volumio[1353]: info: snd-dummy loaded Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:25 hi-quality volumio[1353]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 04 09:03:25 hi-quality volumio[1353]: info: Loading i18n strings for locale es Jul 04 09:03:25 hi-quality volumio[1353]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jul 04 09:03:25 hi-quality volumio[1353]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jul 04 09:03:25 hi-quality volumio[1353]: info: Loading i18n strings for locale es Jul 04 09:03:25 hi-quality volumio[1353]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Jul 04 09:03:25 hi-quality volumio[1353]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 09:03:25 hi-quality volumio[1353]: info: Volumio Calling Home Jul 04 09:03:25 hi-quality sudo[1615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jul 04 09:03:25 hi-quality sudo[1615]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:25 hi-quality sudo[1617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jul 04 09:03:25 hi-quality sudo[1617]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:25 hi-quality sudo[1621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 04 09:03:25 hi-quality sudo[1621]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:25 hi-quality sudo[1615]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:25 hi-quality systemd[1]: Reloading. Jul 04 09:03:25 hi-quality volumio[1353]: info: Discovery: adding fd6e6bce-6ac4-47d9-ab44-45bc56686bc8 Jul 04 09:03:25 hi-quality volumio[1353]: info: Discovery: Found device Hi-Quality Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:25 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:25 hi-quality volumio[1353]: info: touch_display: No backlight interface detected. Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:25 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:25 hi-quality volumio[1353]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jul 04 09:03:25 hi-quality volumio[1353]: info: minidlna: Found miniDLNA version 1.2.1 Jul 04 09:03:25 hi-quality volumio[1353]: info: minidlna: Starting minidlna.service Jul 04 09:03:25 hi-quality volumio[1353]: info: MPD Permissions set Jul 04 09:03:25 hi-quality volumio[1353]: info: MPD Permissions set Jul 04 09:03:25 hi-quality sudo[1641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jul 04 09:03:25 hi-quality sudo[1641]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:25 hi-quality volumio[1353]: info: Discovery: adding 79050200-93ac-4e36-b437-6fe84e80b058 Jul 04 09:03:25 hi-quality volumio[1353]: info: Discovery: Found device Radio GE Jul 04 09:03:25 hi-quality volumio[1353]: info: Discovery: Connecting to remote: 192.168.100.139 Jul 04 09:03:25 hi-quality volumio[1353]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 04 09:03:25 hi-quality systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:25 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:25 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality sudo[1648]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality sudo[1648]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality sudo[1648]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:26 hi-quality mpd[1580]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 04 09:03:26 hi-quality mpd[1580]: output: No 'audio_output' defined in config file Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality mpd[1580]: output: Successfully detected a sndio audio device Jul 04 09:03:26 hi-quality mpd[1580]: zeroconf: No global port, disabling zeroconf Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Jul 04 09:03:26 hi-quality volumio[1353]: info: Volumio called home Jul 04 09:03:26 hi-quality volumio[1353]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jul 04 09:03:26 hi-quality sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jul 04 09:03:26 hi-quality sudo[1660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 04 09:03:26 hi-quality volumio[1353]: info: Starting Shairport Sync Jul 04 09:03:26 hi-quality sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:26 hi-quality sudo[1660]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:26 hi-quality sudo[1662]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:26 hi-quality sudo[1660]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:26 hi-quality sudo[1666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 09:03:26 hi-quality sudo[1666]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:26 hi-quality volumio[1353]: info: Starting Shairport Sync Jul 04 09:03:26 hi-quality sudo[1671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 09:03:26 hi-quality sudo[1671]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:26 hi-quality sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 09:03:26 hi-quality sudo[1668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:26 hi-quality sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 04 09:03:26 hi-quality sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:26 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:26 hi-quality volumio[1353]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jul 04 09:03:26 hi-quality volumio[1353]: info: MPD Permissions set Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality sudo[1617]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality systemd[1]: Started Music Player Daemon. Jul 04 09:03:26 hi-quality sudo[1552]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:26 hi-quality sudo[1562]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:26 hi-quality systemd[1]: Reloading. Jul 04 09:03:26 hi-quality volumio[1353]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jul 04 09:03:26 hi-quality volumio[1353]: info: Discovery: Connected to remote: 192.168.100.139 Jul 04 09:03:26 hi-quality volumio[1353]: error: MPD error: The expression evaluated to a falsy value: Jul 04 09:03:26 hi-quality volumio[1353]: assert.ok(self.idling) Jul 04 09:03:26 hi-quality volumio[1353]: error: The expression evaluated to a falsy value: Jul 04 09:03:26 hi-quality volumio[1353]: assert.ok(self.idling) Jul 04 09:03:26 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jul 04 09:03:26 hi-quality systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 04 09:03:26 hi-quality volumio[1353]: error: updateQueue error: null Jul 04 09:03:26 hi-quality volumio[1353]: info: MPD running with PID1580 Jul 04 09:03:26 hi-quality volumio[1353]: ,establishing connection Jul 04 09:03:26 hi-quality sudo[1701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 04 09:03:26 hi-quality sudo[1701]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:26 hi-quality volumio[1353]: error: updateQueue error: null Jul 04 09:03:27 hi-quality volumio[1353]: verbose: New Socket.io Connection to 192.168.100.141:3000 from 192.168.100.139 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 04 09:03:27 hi-quality sudo[1621]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:27 hi-quality volumio[1353]: info: touch_display: systemctl daemon-reload succeeded. Jul 04 09:03:27 hi-quality sudo[1704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jul 04 09:03:27 hi-quality sudo[1704]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:27 hi-quality systemd[1]: Stopping Music Player Daemon... Jul 04 09:03:27 hi-quality systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 04 09:03:27 hi-quality sudo[1641]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:27 hi-quality volumio[1353]: info: minidlna: systemctl start minidlna.service succeeded. Jul 04 09:03:27 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jul 04 09:03:27 hi-quality systemd[1]: shairport-sync.service: Succeeded. Jul 04 09:03:27 hi-quality systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 04 09:03:27 hi-quality systemd[1]: mpd.service: Succeeded. Jul 04 09:03:27 hi-quality systemd[1]: Stopped Music Player Daemon. Jul 04 09:03:27 hi-quality systemd[1]: Starting Music Player Daemon... Jul 04 09:03:27 hi-quality systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 04 09:03:27 hi-quality sudo[1671]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:27 hi-quality sudo[1675]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:27 hi-quality sudo[1668]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:27 hi-quality sudo[1666]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:27 hi-quality volumio[1353]: info: Shairport-Sync Started Jul 04 09:03:27 hi-quality volumio[1353]: Error adding Membership: Error: addMembership EINVAL Jul 04 09:03:27 hi-quality volumio[1353]: info: Shairport-Sync Started Jul 04 09:03:27 hi-quality volumio[1353]: info: Shairport-Sync Started Jul 04 09:03:27 hi-quality volumio[1353]: info: Shairport-Sync Started Jul 04 09:03:27 hi-quality systemd[1]: Started Volumio Kiosk. Jul 04 09:03:27 hi-quality sudo[1704]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:27 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:27 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:27 hi-quality volumio[1353]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jul 04 09:03:27 hi-quality volumio[1353]: info: touch_display: Volumio Kiosk started. Jul 04 09:03:27 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:27 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:27 hi-quality volumio[1353]: info: Completed starting Core Plugins Jul 04 09:03:27 hi-quality volumio[1353]: info: ------------------------------------------- Jul 04 09:03:27 hi-quality volumio[1353]: info: ----- MyVolumio plugins startup ---- Jul 04 09:03:27 hi-quality volumio[1353]: info: ------------------------------------------- Jul 04 09:03:27 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 04 09:03:27 hi-quality sudo[1706]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 04 09:03:27 hi-quality sudo[1706]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:27 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:27 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:27 hi-quality sudo[1706]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:27 hi-quality volumio[1353]: info: touch_display: X display number found: 0 Jul 04 09:03:27 hi-quality startx[1709]: X.Org X Server 1.20.4 Jul 04 09:03:27 hi-quality startx[1709]: X Protocol Version 11, Revision 0 Jul 04 09:03:27 hi-quality startx[1709]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jul 04 09:03:27 hi-quality startx[1709]: Current Operating System: Linux hi-quality 6.1.77-v7l+ #1730 SMP Thu Feb 8 15:22:02 GMT 2024 armv7l Jul 04 09:03:27 hi-quality startx[1709]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=1824 bcm2708_fb.fbheight=984 bcm2708_fb.fbswap=1 smsc95xx.macaddr=E4:5F:01:2E:0E:AF vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=826680f7-a407-4ab9-8bd5-1855bda526fd imgfile=/volumio_current.sqsh bootpart=UUID=FD90-ED9E datapart=UUID=fecb0823-2e5b-4bba-9498-dd37b5084148 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jul 04 09:03:27 hi-quality startx[1709]: Build Date: 04 April 2023 07:50:56AM Jul 04 09:03:27 hi-quality startx[1709]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jul 04 09:03:27 hi-quality startx[1709]: Current version of pixman: 0.36.0 Jul 04 09:03:27 hi-quality startx[1709]: Before reporting problems, check http://wiki.x.org Jul 04 09:03:27 hi-quality startx[1709]: to make sure that you have the latest version. Jul 04 09:03:27 hi-quality startx[1709]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 04 09:03:27 hi-quality startx[1709]: (++) from command line, (!!) notice, (II) informational, Jul 04 09:03:27 hi-quality startx[1709]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 04 09:03:27 hi-quality startx[1709]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Jul 4 09:03:27 2024 Jul 04 09:03:27 hi-quality volumio[1353]: info: touch_display: X display number found: 0 Jul 04 09:03:27 hi-quality startx[1709]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jul 04 09:03:27 hi-quality startx[1709]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 04 09:03:27 hi-quality volumio[1353]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 04 09:03:27 hi-quality volumio[1353]: info: touch_display: X display number found: 0 Jul 04 09:03:28 hi-quality mpd[1726]: Jul 04 09:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 04 09:03:28 hi-quality systemd[1]: Started Music Player Daemon. Jul 04 09:03:28 hi-quality sudo[1701]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:28 hi-quality volumio[1353]: error: updateQueue error: null Jul 04 09:03:28 hi-quality systemd[1]: systemd-hostnamed.service: Succeeded. Jul 04 09:03:28 hi-quality volumio[1353]: verbose: New Socket.io Connection to 192.168.100.141 from 192.168.100.169 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/126.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jul 04 09:03:28 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:03:28 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 6. Jul 04 09:03:28 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:03:28 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 04 09:03:28 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetVisibleSources Jul 04 09:03:28 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 09:03:28 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:28 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:28 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:28 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:28 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 04 09:03:28 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 04 09:03:28 hi-quality volumio[1353]: info: Received Get System Info Jul 04 09:03:28 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 09:03:28 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 09:03:28 hi-quality volumio[1353]: info: Discovery: Getting this device information Jul 04 09:03:28 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:28 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:28 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 09:03:28 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:28 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:28 hi-quality volumio[1353]: info: Listing playlists Jul 04 09:03:28 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetQueue Jul 04 09:03:28 hi-quality volumio[1353]: info: CoreStateMachine::getQueue Jul 04 09:03:28 hi-quality volumio[1353]: info: CorePlayQueue::getQueue Jul 04 09:03:28 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:03:28 hi-quality ovpn-server[1757]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:03:28 hi-quality ovpn-server[1757]: Options error: Please correct these errors. Jul 04 09:03:28 hi-quality ovpn-server[1757]: Use --help for more information. Jul 04 09:03:28 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:03:28 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:03:28 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:03:29 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:29 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:29 hi-quality volumio[1353]: info: touch_display: Setting screensaver timeout to 30 seconds. Jul 04 09:03:29 hi-quality sudo[1811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 04 09:03:29 hi-quality sudo[1811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:29 hi-quality sudo[1811]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:29 hi-quality sudo[1814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 04 09:03:29 hi-quality sudo[1814]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:29 hi-quality sudo[1814]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:29 hi-quality sudo[1816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 04 09:03:29 hi-quality sudo[1816]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:29 hi-quality systemd[1]: Started UPnP Renderer front-end to MPD. Jul 04 09:03:29 hi-quality sudo[1816]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:29 hi-quality volumio[1353]: info: Upmpdcli Daemon Started Jul 04 09:03:30 hi-quality volumio[1819]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 04 09:03:31 hi-quality volumio[1353]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 04 09:03:32 hi-quality systemd[1457]: Starting Sound Service... Jul 04 09:03:32 hi-quality dbus-daemon[730]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.22' (uid=1000 pid=1879 comm="/usr/bin/pulseaudio --daemonize=no ") Jul 04 09:03:32 hi-quality systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jul 04 09:03:32 hi-quality dbus-daemon[730]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jul 04 09:03:32 hi-quality systemd[1]: Started RealtimeKit Scheduling Policy Service. Jul 04 09:03:32 hi-quality rtkit-daemon[1880]: Successfully called chroot. Jul 04 09:03:32 hi-quality rtkit-daemon[1880]: Successfully dropped privileges. Jul 04 09:03:32 hi-quality rtkit-daemon[1880]: Successfully limited resources. Jul 04 09:03:32 hi-quality rtkit-daemon[1880]: Running. Jul 04 09:03:32 hi-quality rtkit-daemon[1880]: Watchdog thread running. Jul 04 09:03:32 hi-quality rtkit-daemon[1880]: Canary thread running. Jul 04 09:03:32 hi-quality dbus-daemon[730]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.23' (uid=0 pid=1880 comm="/usr/lib/rtkit/rtkit-daemon ") Jul 04 09:03:32 hi-quality systemd[1]: Starting Authorization Manager... Jul 04 09:03:32 hi-quality polkitd[1883]: started daemon version 0.105 using authority implementation `local' version `0.105' Jul 04 09:03:32 hi-quality dbus-daemon[730]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jul 04 09:03:32 hi-quality systemd[1]: Started Authorization Manager. Jul 04 09:03:33 hi-quality systemd[1457]: Started D-Bus User Message Bus. Jul 04 09:03:33 hi-quality systemd[1457]: Started Sound Service. Jul 04 09:03:33 hi-quality bluetoothd[853]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSource Jul 04 09:03:33 hi-quality bluetoothd[853]: Endpoint registered: sender=:1.43 path=/MediaEndpoint/A2DPSink Jul 04 09:03:33 hi-quality pulseaudio[1879]: E: [pulseaudio] backend-ofono.c: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jul 04 09:03:33 hi-quality kernel: Bluetooth: RFCOMM TTY layer initialized Jul 04 09:03:33 hi-quality kernel: Bluetooth: RFCOMM socket layer initialized Jul 04 09:03:33 hi-quality kernel: Bluetooth: RFCOMM ver 1.11 Jul 04 09:03:33 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:03:33 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 7. Jul 04 09:03:33 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:03:33 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:03:33 hi-quality ovpn-server[1912]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:03:33 hi-quality ovpn-server[1912]: Options error: Please correct these errors. Jul 04 09:03:33 hi-quality ovpn-server[1912]: Use --help for more information. Jul 04 09:03:33 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:03:33 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:03:33 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:03:34 hi-quality volumio-remote-updater[737]: [2024-07-04 09:03:34] [connect] Successful connection Jul 04 09:03:34 hi-quality volumio-remote-updater[737]: [2024-07-04 09:03:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720105414 101 Jul 04 09:03:34 hi-quality volumio[1353]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 8 Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 04 09:03:35 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 04 09:03:35 hi-quality volumio[1353]: info: Adding plugin bluetooth to MyMusic Plugins Jul 04 09:03:35 hi-quality volumio[1353]: info: Adding plugin multiroom to MyMusic Plugins Jul 04 09:03:35 hi-quality volumio[1353]: info: Adding plugin metavolumio to MyMusic Plugins Jul 04 09:03:36 hi-quality volumio[1353]: info: Adding plugin cd_controller to MyMusic Plugins Jul 04 09:03:36 hi-quality volumio[1353]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 04 09:03:36 hi-quality volumio[1353]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 04 09:03:36 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 04 09:03:37 hi-quality volumio[1819]: ....................................................................................++++ Jul 04 09:03:37 hi-quality volumio[1353]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 04 09:03:37 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 04 09:03:37 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:37 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:37 hi-quality volumio[1353]: info: Starting MyVolumio Remote Streaming Endpoints Jul 04 09:03:37 hi-quality volumio[1353]: info: MyVolumio login type: Token Jul 04 09:03:37 hi-quality volumio[1353]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 04 09:03:37 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 04 09:03:39 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:03:39 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 8. Jul 04 09:03:39 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:03:39 hi-quality volumio[1353]: info: Starting Streaming Service Transparent Proxy Jul 04 09:03:39 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 04 09:03:39 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 04 09:03:39 hi-quality volumio[1353]: info: Streaming services startup Jul 04 09:03:39 hi-quality volumio[1353]: info: Starting Streaming Daemon Jul 04 09:03:39 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:03:39 hi-quality sudo[2026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 04 09:03:39 hi-quality sudo[2026]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:39 hi-quality ovpn-server[2017]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:03:39 hi-quality ovpn-server[2017]: Options error: Please correct these errors. Jul 04 09:03:39 hi-quality ovpn-server[2017]: Use --help for more information. Jul 04 09:03:39 hi-quality volumio[1353]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 04 09:03:39 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:03:39 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:03:39 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:03:39 hi-quality sudo[2026]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:39 hi-quality volumio[1353]: error: Cannot start Volumio Streaming Daemon Jul 04 09:03:39 hi-quality volumio[1353]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 04 09:03:39 hi-quality volumio[1353]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 04 09:03:39 hi-quality volumio[1353]: error: MyVolumio Custom Token format not valid, refreshing it Jul 04 09:03:40 hi-quality volumio[1353]: STREAMING PROXY: Starting server on port 3245 Jul 04 09:03:40 hi-quality volumio[1353]: Node JS runtime: 14 Jul 04 09:03:40 hi-quality sshd[1945]: Accepted password for volumio from 192.168.100.160 port 49422 ssh2 Jul 04 09:03:40 hi-quality sshd[1945]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jul 04 09:03:40 hi-quality systemd-logind[727]: New session 4 of user volumio. Jul 04 09:03:40 hi-quality systemd[1]: Started Session 4 of user volumio. Jul 04 09:03:41 hi-quality volumio[1353]: info: MyVolumio login type: Token Jul 04 09:03:41 hi-quality volumio[1353]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jul 04 09:03:42 hi-quality volumio[1353]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 04 09:03:42 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 04 09:03:42 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 04 09:03:42 hi-quality volumio[1353]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jul 04 09:03:43 hi-quality volumio[1353]: info: MyVolumio token set successfully Jul 04 09:03:43 hi-quality volumio[1353]: info: MYVOLUMIO: Adding device Jul 04 09:03:43 hi-quality volumio[1353]: info: MYVOLUMIO: Evaluating Server Jul 04 09:03:43 hi-quality volumio[1353]: info: MyVolumio status changed Jul 04 09:03:43 hi-quality volumio[1353]: info: Streaming services startup Jul 04 09:03:43 hi-quality volumio[1353]: info: Starting Streaming Daemon Jul 04 09:03:43 hi-quality volumio[1353]: info: Removing browser output: myVolumio user plan is not superstar Jul 04 09:03:43 hi-quality volumio[1353]: info: Removing audio output: Jul 04 09:03:43 hi-quality volumio[1353]: info: Stoppping Tunnel 1 Jul 04 09:03:43 hi-quality sudo[2077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 04 09:03:43 hi-quality sudo[2077]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:43 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 04 09:03:43 hi-quality sudo[2077]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:43 hi-quality sudo[2080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 04 09:03:43 hi-quality sudo[2080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 04 09:03:44 hi-quality sudo[2080]: pam_unix(sudo:session): session closed for user root Jul 04 09:03:44 hi-quality volumio[1353]: error: Cannot start Volumio Streaming Daemon Jul 04 09:03:44 hi-quality volumio[1353]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 04 09:03:44 hi-quality volumio[1353]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 04 09:03:44 hi-quality volumio[1353]: info: Remote SSH Stopped Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 04 09:03:44 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:03:44 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 9. Jul 04 09:03:44 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetVisibleSources Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:44 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 04 09:03:44 hi-quality volumio[1353]: info: Received Get System Info Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 09:03:44 hi-quality volumio[1353]: info: Discovery: Getting this device information Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:44 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:44 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:44 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:03:44 hi-quality volumio[1353]: info: Listing playlists Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 04 09:03:44 hi-quality ovpn-server[2082]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:03:44 hi-quality ovpn-server[2082]: Options error: Please correct these errors. Jul 04 09:03:44 hi-quality ovpn-server[2082]: Use --help for more information. Jul 04 09:03:44 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:03:44 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:03:44 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:03:44 hi-quality volumio[1353]: info: Setting Geolocation for MyVolumio to us3 Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:44 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:45 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:45 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:45 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 09:03:45 hi-quality volumio[1353]: info: Received Get System Info Jul 04 09:03:45 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 09:03:45 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 09:03:45 hi-quality volumio[1353]: info: Discovery: Getting this device information Jul 04 09:03:45 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:45 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:45 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 09:03:45 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 04 09:03:45 hi-quality volumio[1353]: info: Updating MyVolumio device info Jul 04 09:03:45 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:45 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:45 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:45 hi-quality volumio[1353]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 04 09:03:46 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jul 04 09:03:47 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 04 09:03:47 hi-quality volumio[1353]: info: Preload queue cleared Jul 04 09:03:47 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 09:03:47 hi-quality volumio[1353]: info: Received Get System Info Jul 04 09:03:47 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 09:03:47 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 09:03:47 hi-quality volumio[1353]: info: Discovery: Getting this device information Jul 04 09:03:47 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:47 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:47 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 09:03:47 hi-quality volumio[1353]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 04 09:03:48 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 04 09:03:48 hi-quality volumio[1353]: info: Received Get System Info Jul 04 09:03:48 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 09:03:48 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 09:03:48 hi-quality volumio[1353]: info: Discovery: Getting this device information Jul 04 09:03:48 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:48 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:48 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 09:03:49 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:03:49 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 10. Jul 04 09:03:49 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:03:49 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:03:49 hi-quality volumio[1353]: info: MYVOLUMIO: Adding device Jul 04 09:03:49 hi-quality volumio[1353]: info: MYVOLUMIO: Evaluating Server Jul 04 09:03:49 hi-quality ovpn-server[2085]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:03:49 hi-quality ovpn-server[2085]: Options error: Please correct these errors. Jul 04 09:03:49 hi-quality ovpn-server[2085]: Use --help for more information. Jul 04 09:03:49 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:03:49 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:03:49 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:03:50 hi-quality volumio[1353]: info: Setting Geolocation for MyVolumio to us1 Jul 04 09:03:50 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:51 hi-quality volumio[1353]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 04 09:03:51 hi-quality volumio[1353]: info: Updating MyVolumio device info Jul 04 09:03:51 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:52 hi-quality volumio[1353]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 04 09:03:52 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 04 09:03:52 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 04 09:03:52 hi-quality volumio[1353]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jul 04 09:03:52 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:03:52 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:03:54 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:03:54 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 11. Jul 04 09:03:54 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:03:54 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:03:54 hi-quality ovpn-server[2284]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:03:54 hi-quality ovpn-server[2284]: Options error: Please correct these errors. Jul 04 09:03:54 hi-quality ovpn-server[2284]: Use --help for more information. Jul 04 09:03:54 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:03:54 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:03:54 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:03:55 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 09:03:55 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:55 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 04 09:03:55 hi-quality volumio-remote-updater[737]: No test mode Jul 04 09:03:55 hi-quality volumio-remote-updater[737]: No alpha test mode Jul 04 09:03:55 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 04 09:03:56 hi-quality volumio[1353]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 04 09:03:56 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 04 09:03:56 hi-quality volumio[1819]: ...........................................................................................................................................................................................................++++ Jul 04 09:03:56 hi-quality volumio[1819]: e is 65537 (0x010001) Jul 04 09:03:56 hi-quality volumio[1819]: writing RSA key Jul 04 09:03:57 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:57 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:57 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 04 09:03:57 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 04 09:03:57 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 04 09:03:57 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 04 09:03:57 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetBrowseSources Jul 04 09:03:57 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 09:03:59 hi-quality volumio[1353]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 04 09:03:59 hi-quality volumio[1353]: info: Completed starting MyVolumio Plugin Jul 04 09:03:59 hi-quality volumio[1353]: info: BOOT COMPLETED Jul 04 09:03:59 hi-quality volumio[1353]: [Metrics] CommandRouter: 41s 790.23ms Jul 04 09:03:59 hi-quality volumio[1353]: info: CoreCommandRouter::volumiosetStartupVolume Jul 04 09:03:59 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 04 09:03:59 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 04 09:03:59 hi-quality volumio[1353]: info: CoreCommandRouter::Close All Modals sent Jul 04 09:03:59 hi-quality volumio[1353]: info: CoreCommandRouter::Close All Modals sent Jul 04 09:03:59 hi-quality kernel: pcm512x 1-004c: No SCLK, using BCLK: -2 Jul 04 09:04:00 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:04:00 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 12. Jul 04 09:04:00 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:04:00 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:04:00 hi-quality ovpn-server[2386]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:04:00 hi-quality ovpn-server[2386]: Options error: Please correct these errors. Jul 04 09:04:00 hi-quality ovpn-server[2386]: Use --help for more information. Jul 04 09:04:00 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:04:00 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:04:00 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:04:00 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 04 09:04:00 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 04 09:04:00 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 04 09:04:02 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 04 09:04:02 hi-quality volumio[1353]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 04 09:04:02 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 04 09:04:02 hi-quality volumio[1353]: info: Received Get System Version Jul 04 09:04:02 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 04 09:04:02 hi-quality volumio[1353]: info: Received Get System Info Jul 04 09:04:02 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 04 09:04:02 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 04 09:04:02 hi-quality volumio[1353]: info: Discovery: Getting this device information Jul 04 09:04:02 hi-quality volumio[1353]: info: CoreCommandRouter::volumioGetState Jul 04 09:04:02 hi-quality volumio[1353]: info: CorePlayQueue::getTrack 0 Jul 04 09:04:02 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 04 09:04:03 hi-quality ntpd[886]: Soliciting pool server 200.59.16.6 Jul 04 09:04:04 hi-quality ntpd[886]: Soliciting pool server 200.59.16.50 Jul 04 09:04:05 hi-quality ntpd[886]: Soliciting pool server 200.59.21.5 Jul 04 09:04:05 hi-quality ntpd[886]: Soliciting pool server 190.113.120.42 Jul 04 09:04:05 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:04:05 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 13. Jul 04 09:04:05 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:04:05 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:04:05 hi-quality ovpn-server[2389]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:04:05 hi-quality ovpn-server[2389]: Options error: Please correct these errors. Jul 04 09:04:05 hi-quality ovpn-server[2389]: Use --help for more information. Jul 04 09:04:05 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:04:05 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:04:05 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:04:06 hi-quality ntpd[886]: Soliciting pool server 200.59.16.34 Jul 04 09:04:07 hi-quality ntpd[886]: Soliciting pool server 186.177.18.74 Jul 04 09:04:07 hi-quality ntpd[886]: Soliciting pool server 2800:640:cc::1 Jul 04 09:04:10 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:04:10 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 14. Jul 04 09:04:10 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:04:10 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:04:10 hi-quality ovpn-server[2392]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:04:10 hi-quality ovpn-server[2392]: Options error: Please correct these errors. Jul 04 09:04:10 hi-quality ovpn-server[2392]: Use --help for more information. Jul 04 09:04:10 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:04:10 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:04:10 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:04:47 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:04:47 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 15. Jul 04 09:04:47 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:04:47 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:04:48 hi-quality ovpn-server[2395]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:04:48 hi-quality ovpn-server[2395]: Options error: Please correct these errors. Jul 04 09:04:48 hi-quality ovpn-server[2395]: Use --help for more information. Jul 04 09:04:48 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:04:48 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:04:48 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:04:53 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:04:53 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 16. Jul 04 09:04:53 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:04:53 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:04:53 hi-quality ovpn-server[2398]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:04:53 hi-quality ovpn-server[2398]: Options error: Please correct these errors. Jul 04 09:04:53 hi-quality ovpn-server[2398]: Use --help for more information. Jul 04 09:04:53 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:04:53 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:04:53 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:04:57 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 04 09:04:57 hi-quality volumio[1353]: info: Preload queue cleared Jul 04 09:04:58 hi-quality systemd[1]: openvpn@server.service: Service RestartSec=5s expired, scheduling restart. Jul 04 09:04:58 hi-quality systemd[1]: openvpn@server.service: Scheduled restart job, restart counter is at 17. Jul 04 09:04:58 hi-quality systemd[1]: Stopped OpenVPN connection to server. Jul 04 09:04:58 hi-quality systemd[1]: Starting OpenVPN connection to server... Jul 04 09:04:58 hi-quality ovpn-server[2401]: Options error: --status fails with '/var/log/openvpn/status.log': No such file or directory (errno=2) Jul 04 09:04:58 hi-quality ovpn-server[2401]: Options error: Please correct these errors. Jul 04 09:04:58 hi-quality ovpn-server[2401]: Use --help for more information. Jul 04 09:04:58 hi-quality systemd[1]: openvpn@server.service: Main process exited, code=exited, status=1/FAILURE Jul 04 09:04:58 hi-quality systemd[1]: openvpn@server.service: Failed with result 'exit-code'. Jul 04 09:04:58 hi-quality systemd[1]: Failed to start OpenVPN connection to server. Jul 04 09:04:59 hi-quality volumio[1353]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 04 09:04:59 hi-quality volumio[1353]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 09:04:59 hi-quality volumio[1353]: TypeError: Cannot read property 'length' of undefined Jul 04 09:04:59 hi-quality volumio[1353]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jul 04 09:04:59 hi-quality volumio[1353]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jul 04 09:04:59 hi-quality volumio[1353]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jul 04 09:04:59 hi-quality volumio[1353]: at Parser.emit (events.js:315:20) Jul 04 09:04:59 hi-quality volumio[1353]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jul 04 09:04:59 hi-quality volumio[1353]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jul 04 09:04:59 hi-quality volumio[1353]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jul 04 09:04:59 hi-quality volumio[1353]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jul 04 09:04:59 hi-quality volumio[1353]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jul 04 09:04:59 hi-quality volumio[1353]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jul 04 09:04:59 hi-quality volumio[1353]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jul 04 09:04:59 hi-quality volumio[1353]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jul 04 09:04:59 hi-quality volumio[1353]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jul 04 09:04:59 hi-quality volumio[1353]: at IncomingMessage.emit (events.js:327:22) Jul 04 09:04:59 hi-quality volumio[1353]: at endReadableNT (internal/streams/readable.js:1327:12) Jul 04 09:04:59 hi-quality volumio[1353]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 04 09:04:59 hi-quality volumio[1353]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 04 09:04:59 hi-quality sudo[2413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-04 09:03 Jul 04 09:04:59 hi-quality sudo[2413]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"