-- 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"