-- Logs begin at Tue 2019-03-12 18:57:25 UTC, end at Wed 2024-07-10 20:55:25 UTC. -- Jul 10 20:54:00 volumio wireless.js[694]: WIRELESS DAEMON: start Jul 10 20:54:00 volumio wireless.js[694]: WIRELESS: Loaded configuration Jul 10 20:54:00 volumio wireless.js[694]: Cleaning previous... Jul 10 20:54:01 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jul 10 20:54:01 volumio systemd[1]: Stopping Xbindrc... Jul 10 20:54:01 volumio systemd[1]: Starting Xbindrc... Jul 10 20:54:01 volumio systemd[1]: Started Xbindrc. Jul 10 20:54:01 volumio systemd[1]: Stopped hotspot.service. Jul 10 20:54:02 volumio systemd[1]: Started LSB: start Samba daemons for the AD DC. Jul 10 20:54:03 volumio sudo[786]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 10 20:54:03 volumio sudo[786]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:54:03 volumio sudo[786]: pam_unix(sudo:session): session closed for user root Jul 10 20:54:04 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jul 10 20:54:04 volumio sudo[794]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 10 20:54:04 volumio systemd[1]: Stopping Xbindrc... Jul 10 20:54:04 volumio systemd[1]: Starting Xbindrc... Jul 10 20:54:04 volumio systemd[1]: Started Xbindrc. Jul 10 20:54:04 volumio sudo[794]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:54:04 volumio sudo[794]: pam_unix(sudo:session): session closed for user root Jul 10 20:54:05 volumio systemd[1]: Started Music Player Daemon. Jul 10 20:54:05 volumio wireless.js[694]: Stopped aP Jul 10 20:54:06 volumio systemd[1]: Stopped hotspot.service. Jul 10 20:54:06 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jul 10 20:54:06 volumio systemd[1]: Stopping Xbindrc... Jul 10 20:54:06 volumio systemd[1]: Starting Xbindrc... Jul 10 20:54:06 volumio sudo[820]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 10 20:54:06 volumio systemd[1]: Started Xbindrc. Jul 10 20:54:06 volumio sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:54:06 volumio sudo[820]: pam_unix(sudo:session): session closed for user root Jul 10 20:54:07 volumio sudo[830]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 10 20:54:07 volumio sudo[830]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:54:07 volumio winbind[588]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Jul 10 20:54:07 volumio winbind[588]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Jul 10 20:54:07 volumio winbind[588]: Unable to setup corepath for winbindd: No such file or directory Jul 10 20:54:07 volumio winbind[588]: mkdir failed on directory /var/log/samba/cores: No such file or directory Jul 10 20:54:07 volumio winbind[588]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Jul 10 20:54:07 volumio winbind[588]: Unable to setup corepath for winbindd: No such file or directory Jul 10 20:54:07 volumio winbind[588]: [2024/07/10 20:54:07, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:07 volumio winbind[588]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jul 10 20:54:07 volumio winbind[588]: [2024/07/10 20:54:07, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:07 volumio winbind[588]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jul 10 20:54:07 volumio winbind[588]: [2024/07/10 20:54:07, 0] ../source3/winbindd/winbindd.c:1549(main) Jul 10 20:54:07 volumio winbind[588]: winbindd version 4.2.14-Debian started. Jul 10 20:54:07 volumio winbind[588]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Jul 10 20:54:07 volumio winbind[588]: [2024/07/10 20:54:07, 0] ../lib/util/util.c:220(directory_create_or_exist) Jul 10 20:54:07 volumio winbind[588]: mkdir failed on directory /var/log/samba/cores: No such file or directory Jul 10 20:54:07 volumio winbind[588]: [2024/07/10 20:54:07, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Jul 10 20:54:07 volumio winbind[588]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Jul 10 20:54:07 volumio winbind[588]: [2024/07/10 20:54:07, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Jul 10 20:54:07 volumio winbind[588]: Unable to setup corepath for winbindd: No such file or directory Jul 10 20:54:07 volumio sudo[830]: pam_unix(sudo:session): session closed for user root Jul 10 20:54:07 volumio winbindd[784]: [2024/07/10 20:54:07.623641, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:07 volumio winbindd[784]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jul 10 20:54:07 volumio winbindd[784]: [2024/07/10 20:54:07.627699, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:07 volumio winbindd[784]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jul 10 20:54:07 volumio winbindd[837]: [2024/07/10 20:54:07.804325, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Jul 10 20:54:07 volumio winbindd[837]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 10 20:54:07 volumio systemd[1]: Started LSB: start Winbind daemon. Jul 10 20:54:07 volumio winbind[588]: [2024/07/10 20:54:07.623641, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:07 volumio winbind[588]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jul 10 20:54:07 volumio winbind[588]: [2024/07/10 20:54:07.627699, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:07 volumio winbind[588]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jul 10 20:54:07 volumio winbind[588]: . Jul 10 20:54:08 volumio winbindd[837]: [2024/07/10 20:54:08.005653, 0] ../lib/util/become_daemon.c:124(daemon_ready) Jul 10 20:54:08 volumio winbindd[837]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 10 20:54:08 volumio winbindd[841]: [2024/07/10 20:54:08.084173, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:08 volumio avahi-daemon[619]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jul 10 20:54:08 volumio avahi-daemon[619]: New relevant interface wlan0.IPv4 for mDNS. Jul 10 20:54:08 volumio avahi-daemon[619]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jul 10 20:54:08 volumio winbindd[841]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jul 10 20:54:08 volumio nmbd[596]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Jul 10 20:54:08 volumio nmbd[596]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Jul 10 20:54:08 volumio nmbd[596]: Unable to setup corepath for nmbd: No such file or directory Jul 10 20:54:08 volumio nmbd[596]: [2024/07/10 20:54:08, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:08 volumio nmbd[596]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jul 10 20:54:08 volumio nmbd[596]: [2024/07/10 20:54:08, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:08 volumio nmbd[596]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jul 10 20:54:08 volumio nmbd[815]: [2024/07/10 20:54:08.354289, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:08 volumio nmbd[815]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jul 10 20:54:08 volumio nmbd[815]: [2024/07/10 20:54:08.360221, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:08 volumio nmbd[815]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jul 10 20:54:08 volumio systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Jul 10 20:54:08 volumio systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Jul 10 20:54:08 volumio nmbd[844]: [2024/07/10 20:54:08.483579, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) Jul 10 20:54:08 volumio nmbd[844]: started asyncdns process 845 Jul 10 20:54:08 volumio nmbd[596]: [2024/07/10 20:54:08, 0] ../source3/nmbd/nmbd.c:908(main) Jul 10 20:54:08 volumio nmbd[596]: nmbd version 4.2.14-Debian started. Jul 10 20:54:08 volumio nmbd[596]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Jul 10 20:54:08 volumio nmbd[596]: [2024/07/10 20:54:08.354289, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:08 volumio nmbd[596]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jul 10 20:54:08 volumio nmbd[596]: [2024/07/10 20:54:08.360221, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:08 volumio nmbd[596]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jul 10 20:54:08 volumio nmbd[596]: . Jul 10 20:54:08 volumio nmbd[844]: [2024/07/10 20:54:08.679595, 0] ../lib/util/become_daemon.c:124(daemon_ready) Jul 10 20:54:08 volumio nmbd[844]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 10 20:54:08 volumio systemd[1]: Starting hotspot.service... Jul 10 20:54:08 volumio systemd[1]: Started hotspot.service. Jul 10 20:54:08 volumio systemd[1]: Starting dhcpd.service... Jul 10 20:54:08 volumio systemd[1]: Starting dnsmasq.service... Jul 10 20:54:09 volumio volumio-remote-updater[579]: [2024-07-10 20:54:09] [info] asio async_connect error: system:111 (Connection refused) Jul 10 20:54:09 volumio volumio-remote-updater[579]: [2024-07-10 20:54:09] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 10 20:54:09 volumio volumio-remote-updater[579]: [2024-07-10 20:54:09] [error] handle_connect error: Underlying Transport Error Jul 10 20:54:09 volumio systemd[1]: Started Wireless Services. Jul 10 20:54:09 volumio dnsmasq[861]: started, version 2.72 cachesize 150 Jul 10 20:54:09 volumio dnsmasq[861]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect Jul 10 20:54:09 volumio dnsmasq[861]: reading /etc/resolv.conf Jul 10 20:54:09 volumio dnsmasq[861]: using nameserver 192.168.1.1#53 Jul 10 20:54:09 volumio dnsmasq[861]: using nameserver 208.67.222.222#53 Jul 10 20:54:09 volumio dnsmasq[861]: using nameserver 208.67.220.220#53 Jul 10 20:54:09 volumio dnsmasq[861]: read /etc/hosts - 4 addresses Jul 10 20:54:09 volumio systemd[1]: Started dnsmasq.service. Jul 10 20:54:09 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jul 10 20:54:09 volumio systemd[1]: Stopping Xbindrc... Jul 10 20:54:09 volumio systemd[1]: Starting Xbindrc... Jul 10 20:54:09 volumio systemd[1]: Started Xbindrc. Jul 10 20:54:09 volumio systemd[1]: Starting Host and Network Name Lookups. Jul 10 20:54:09 volumio systemd[1]: Reached target Host and Network Name Lookups. Jul 10 20:54:09 volumio systemd[1]: Starting Volumio Backend Module... Jul 10 20:54:09 volumio systemd[1]: Started Volumio Backend Module. Jul 10 20:54:09 volumio systemd[1]: Starting Volumio Streaming Daemon... Jul 10 20:54:09 volumio systemd[1]: Started Volumio Streaming Daemon. Jul 10 20:54:09 volumio dhcpd[854]: Internet Systems Consortium DHCP Server 4.3.1 Jul 10 20:54:09 volumio dhcpd[854]: Copyright 2004-2014 Internet Systems Consortium. Jul 10 20:54:09 volumio dhcpd[854]: All rights reserved. Jul 10 20:54:09 volumio dhcpd[854]: For info, please visit https://www.isc.org/software/dhcp/ Jul 10 20:54:09 volumio dhcpd[854]: Config file: /etc/dhcp/dhcpd.conf Jul 10 20:54:09 volumio dhcpd[854]: Database file: /var/lib/dhcp/dhcpd.leases Jul 10 20:54:09 volumio dhcpd[854]: PID file: /var/run/dhcpd.pid Jul 10 20:54:09 volumio ntpd[677]: Listen normally on 4 wlan0 192.168.211.1 UDP 123 Jul 10 20:54:09 volumio ntpd[677]: peers refreshed Jul 10 20:54:10 volumio hotspot[854]: Internet Systems Consortium DHCP Server 4.3.1 Jul 10 20:54:10 volumio hotspot[854]: Copyright 2004-2014 Internet Systems Consortium. Jul 10 20:54:10 volumio hotspot[854]: All rights reserved. Jul 10 20:54:10 volumio hotspot[854]: For info, please visit https://www.isc.org/software/dhcp/ Jul 10 20:54:10 volumio hotspot[854]: Config file: /etc/dhcp/dhcpd.conf Jul 10 20:54:10 volumio hotspot[854]: Database file: /var/lib/dhcp/dhcpd.leases Jul 10 20:54:10 volumio hotspot[854]: PID file: /var/run/dhcpd.pid Jul 10 20:54:10 volumio dhcpd[854]: Internet Systems Consortium DHCP Server 4.3.1 Jul 10 20:54:10 volumio dhcpd[854]: Copyright 2004-2014 Internet Systems Consortium. Jul 10 20:54:10 volumio dhcpd[854]: All rights reserved. Jul 10 20:54:10 volumio dhcpd[854]: For info, please visit https://www.isc.org/software/dhcp/ Jul 10 20:54:10 volumio dhcpd[854]: Wrote 0 leases to leases file. Jul 10 20:54:10 volumio hotspot[854]: Wrote 0 leases to leases file. Jul 10 20:54:10 volumio dhcpd[854]: Jul 10 20:54:10 volumio dhcpd[854]: No subnet declaration for eth0 (192.168.1.122). Jul 10 20:54:10 volumio dhcpd[854]: ** Ignoring requests on eth0. If this is not what Jul 10 20:54:10 volumio dhcpd[854]: you want, please write a subnet declaration Jul 10 20:54:10 volumio dhcpd[854]: in your dhcpd.conf file for the network segment Jul 10 20:54:10 volumio dhcpd[854]: to which interface eth0 is attached. ** Jul 10 20:54:10 volumio dhcpd[854]: Jul 10 20:54:10 volumio dhcpd[854]: Listening on LPF/wlan0/60:70:72:08:23:ad/192.168.211.0/24 Jul 10 20:54:10 volumio dhcpd[854]: Sending on LPF/wlan0/60:70:72:08:23:ad/192.168.211.0/24 Jul 10 20:54:10 volumio dhcpd[854]: Sending on Socket/fallback/fallback-net Jul 10 20:54:10 volumio systemd[1]: Started dhcpd.service. Jul 10 20:54:10 volumio dhcpd[870]: Server starting service. Jul 10 20:54:10 volumio hotspot[854]: No subnet declaration for eth0 (192.168.1.122). Jul 10 20:54:10 volumio hotspot[854]: ** Ignoring requests on eth0. If this is not what Jul 10 20:54:10 volumio hotspot[854]: you want, please write a subnet declaration Jul 10 20:54:10 volumio hotspot[854]: in your dhcpd.conf file for the network segment Jul 10 20:54:10 volumio hotspot[854]: to which interface eth0 is attached. ** Jul 10 20:54:10 volumio hotspot[854]: Listening on LPF/wlan0/60:70:72:08:23:ad/192.168.211.0/24 Jul 10 20:54:10 volumio hotspot[854]: Sending on LPF/wlan0/60:70:72:08:23:ad/192.168.211.0/24 Jul 10 20:54:10 volumio hotspot[854]: Sending on Socket/fallback/fallback-net Jul 10 20:54:11 volumio volumio-streaming-daemon[864]: ############################ Jul 10 20:54:11 volumio volumio-streaming-daemon[864]: # Volumio Streaming Daemon # Jul 10 20:54:11 volumio volumio-streaming-daemon[864]: # Running on port 7777 # Jul 10 20:54:11 volumio volumio-streaming-daemon[864]: ############################ Jul 10 20:54:11 volumio volumio-streaming-daemon[864]: Checking variant in static cache Jul 10 20:54:11 volumio volumio-streaming-daemon[864]: Enabled services: Jul 10 20:54:11 volumio volumio-streaming-daemon[864]: 1) tidal Jul 10 20:54:11 volumio volumio-streaming-daemon[864]: 2) qobuz Jul 10 20:54:12 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jul 10 20:54:12 volumio systemd[1]: Stopping Xbindrc... Jul 10 20:54:12 volumio systemd[1]: Starting Xbindrc... Jul 10 20:54:12 volumio systemd[1]: Started Xbindrc. Jul 10 20:54:13 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jul 10 20:54:13 volumio systemd[1]: Stopping Xbindrc... Jul 10 20:54:13 volumio systemd[1]: Starting Xbindrc... Jul 10 20:54:14 volumio systemd[1]: Started Xbindrc. Jul 10 20:54:14 volumio hotspot[850]: Launching Ordinary Hostapd Jul 10 20:54:15 volumio avahi-daemon[619]: Interface wlan0.IPv4 no longer relevant for mDNS. Jul 10 20:54:15 volumio avahi-daemon[619]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jul 10 20:54:15 volumio dhcpd[870]: receive_packet failed on wlan0: Network is down Jul 10 20:54:15 volumio hotspot[850]: Configuration file: /etc/hostapd/hostapd.conf Jul 10 20:54:15 volumio hotspot[850]: nl80211: Could not configure driver mode Jul 10 20:54:15 volumio hotspot[850]: nl80211 driver initialization failed. Jul 10 20:54:15 volumio hotspot[850]: hostapd_free_hapd_data: Interface wlan0 wasn't started Jul 10 20:54:15 volumio avahi-daemon[619]: Withdrawing address record for 192.168.211.1 on wlan0. Jul 10 20:54:15 volumio avahi-daemon[619]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jul 10 20:54:15 volumio avahi-daemon[619]: New relevant interface wlan0.IPv4 for mDNS. Jul 10 20:54:15 volumio avahi-daemon[619]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Jul 10 20:54:15 volumio systemd[1]: hotspot.service: main process exited, code=exited, status=1/FAILURE Jul 10 20:54:15 volumio systemd[1]: Stopping dhcpd.service... Jul 10 20:54:15 volumio systemd[1]: Stopping Host and Network Name Lookups. Jul 10 20:54:15 volumio systemd[1]: Stopped target Host and Network Name Lookups. Jul 10 20:54:15 volumio dnsmasq[861]: exiting on receipt of SIGTERM Jul 10 20:54:15 volumio systemd[1]: Stopping dnsmasq.service... Jul 10 20:54:15 volumio systemd[1]: Stopped dnsmasq.service. Jul 10 20:54:15 volumio systemd[1]: Stopped dhcpd.service. Jul 10 20:54:16 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jul 10 20:54:16 volumio systemd[1]: Stopping Xbindrc... Jul 10 20:54:16 volumio systemd[1]: Starting Xbindrc... Jul 10 20:54:16 volumio systemd[1]: Started Xbindrc. Jul 10 20:54:18 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jul 10 20:54:18 volumio systemd[1]: Stopping Xbindrc... Jul 10 20:54:18 volumio systemd[1]: Starting Xbindrc... Jul 10 20:54:18 volumio systemd[1]: Started Xbindrc. Jul 10 20:54:19 volumio volumio-remote-updater[579]: [2024-07-10 20:54:19] [info] asio async_connect error: system:111 (Connection refused) Jul 10 20:54:19 volumio volumio-remote-updater[579]: [2024-07-10 20:54:19] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 10 20:54:19 volumio volumio-remote-updater[579]: [2024-07-10 20:54:19] [error] handle_connect error: Underlying Transport Error Jul 10 20:54:19 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jul 10 20:54:19 volumio systemd[1]: Stopping Xbindrc... Jul 10 20:54:19 volumio systemd[1]: Starting Xbindrc... Jul 10 20:54:19 volumio systemd[1]: Started Xbindrc. Jul 10 20:54:20 volumio hotspot[886]: Killing Hostapd Jul 10 20:54:20 volumio sudo[902]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall hostapd Jul 10 20:54:20 volumio sudo[902]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:54:21 volumio hotspot[886]: hostapd: no process found Jul 10 20:54:21 volumio sudo[902]: pam_unix(sudo:session): session closed for user root Jul 10 20:54:21 volumio hotspot[886]: Killing Dhcpd Jul 10 20:54:21 volumio sudo[910]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall dhcpd Jul 10 20:54:21 volumio sudo[910]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:54:21 volumio hotspot[886]: dhcpd: no process found Jul 10 20:54:21 volumio sudo[910]: pam_unix(sudo:session): session closed for user root Jul 10 20:54:22 volumio systemd[1]: hotspot.service: control process exited, code=exited status=1 Jul 10 20:54:22 volumio systemd[1]: Unit hotspot.service entered failed state. Jul 10 20:54:22 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jul 10 20:54:22 volumio systemd[1]: Stopping Xbindrc... Jul 10 20:54:22 volumio systemd[1]: Starting Xbindrc... Jul 10 20:54:22 volumio systemd[1]: Started Xbindrc. Jul 10 20:54:24 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jul 10 20:54:24 volumio systemd[1]: Stopping Xbindrc... Jul 10 20:54:24 volumio systemd[1]: Starting Xbindrc... Jul 10 20:54:24 volumio systemd[1]: Started Xbindrc. Jul 10 20:54:25 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jul 10 20:54:25 volumio systemd[1]: Stopping Xbindrc... Jul 10 20:54:25 volumio systemd[1]: Starting Xbindrc... Jul 10 20:54:25 volumio systemd[1]: Started Xbindrc. Jul 10 20:54:26 volumio smbd[846]: Starting SMB/CIFS daemon: smbd[2024/07/10 20:54:26, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:26 volumio smbd[846]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jul 10 20:54:26 volumio smbd[846]: [2024/07/10 20:54:26, 0] ../lib/util/util.c:220(directory_create_or_exist) Jul 10 20:54:26 volumio smbd[846]: mkdir failed on directory /var/log/samba/cores: No such file or directory Jul 10 20:54:26 volumio smbd[846]: [2024/07/10 20:54:26, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Jul 10 20:54:26 volumio smbd[846]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Jul 10 20:54:26 volumio smbd[846]: [2024/07/10 20:54:26, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Jul 10 20:54:26 volumio smbd[846]: Unable to setup corepath for smbd: No such file or directory Jul 10 20:54:26 volumio smbd[846]: [2024/07/10 20:54:26, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:26 volumio smbd[846]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jul 10 20:54:26 volumio smbd[846]: [2024/07/10 20:54:26, 0] ../source3/smbd/server.c:1241(main) Jul 10 20:54:26 volumio smbd[846]: smbd version 4.2.14-Debian started. Jul 10 20:54:26 volumio smbd[846]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Jul 10 20:54:26 volumio smbd[924]: [2024/07/10 20:54:26.950386, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:26 volumio smbd[924]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jul 10 20:54:26 volumio smbd[846]: [2024/07/10 20:54:26.950386, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:26 volumio smbd[846]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jul 10 20:54:26 volumio smbd[924]: [2024/07/10 20:54:26.982728, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:26 volumio smbd[924]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jul 10 20:54:26 volumio smbd[924]: [2024/07/10 20:54:26.983626, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:26 volumio smbd[924]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jul 10 20:54:27 volumio smbd[846]: [2024/07/10 20:54:26.982728, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:27 volumio smbd[846]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jul 10 20:54:27 volumio smbd[846]: [2024/07/10 20:54:26.983626, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:27 volumio smbd[846]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jul 10 20:54:27 volumio systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Jul 10 20:54:27 volumio systemd[1]: Starting Multi-User System. Jul 10 20:54:27 volumio systemd[1]: Reached target Multi-User System. Jul 10 20:54:27 volumio systemd[1]: Starting Graphical Interface. Jul 10 20:54:27 volumio systemd[1]: Reached target Graphical Interface. Jul 10 20:54:27 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 10 20:54:27 volumio smbd[846]: . Jul 10 20:54:27 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 10 20:54:27 volumio systemd[1]: Startup finished in 9.901s (kernel) + 1min 33.720s (userspace) = 1min 43.622s. Jul 10 20:54:27 volumio winbindd[938]: [2024/07/10 20:54:27.695133, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:27 volumio winbindd[938]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Jul 10 20:54:27 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jul 10 20:54:28 volumio systemd[1]: Stopping Xbindrc... Jul 10 20:54:28 volumio systemd[1]: Starting Xbindrc... Jul 10 20:54:28 volumio systemd[1]: xbindkeysrc.service start request repeated too quickly, refusing to start. Jul 10 20:54:28 volumio systemd[1]: Failed to start Xbindrc. Jul 10 20:54:28 volumio systemd[1]: Unit xbindkeysrc.service entered failed state. Jul 10 20:54:28 volumio winbindd[942]: [2024/07/10 20:54:28.131023, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:28 volumio winbindd[942]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory Jul 10 20:54:28 volumio smbd[932]: [2024/07/10 20:54:28.493632, 0] ../lib/util/become_daemon.c:124(daemon_ready) Jul 10 20:54:28 volumio smbd[932]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 10 20:54:28 volumio smbd[943]: [2024/07/10 20:54:28.534823, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jul 10 20:54:28 volumio smbd[943]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jul 10 20:54:29 volumio volumio-remote-updater[579]: [2024-07-10 20:54:29] [info] asio async_connect error: system:111 (Connection refused) Jul 10 20:54:29 volumio volumio-remote-updater[579]: [2024-07-10 20:54:29] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 10 20:54:29 volumio volumio-remote-updater[579]: [2024-07-10 20:54:29] [error] handle_connect error: Underlying Transport Error Jul 10 20:54:35 volumio volumio[863]: info: ------------------------------------------- Jul 10 20:54:35 volumio volumio[863]: info: ----- Volumio2 ---- Jul 10 20:54:35 volumio volumio[863]: info: ------------------------------------------- Jul 10 20:54:35 volumio volumio[863]: info: ----- System startup ---- Jul 10 20:54:35 volumio volumio[863]: info: ------------------------------------------- Jul 10 20:54:36 volumio volumio[863]: info: MYVOLUMIO Environment detected Jul 10 20:54:36 volumio volumio[863]: info: Plugin folders cleanup Jul 10 20:54:36 volumio volumio[863]: info: Scanning into folder /volumio/app/plugins/ Jul 10 20:54:36 volumio volumio[863]: info: Scanning category audio_interface Jul 10 20:54:36 volumio volumio[863]: info: Scanning category miscellanea Jul 10 20:54:36 volumio volumio[863]: info: Scanning category music_service Jul 10 20:54:36 volumio volumio[863]: info: Scanning category plugins.json Jul 10 20:54:36 volumio volumio[863]: info: Scanning category system_controller Jul 10 20:54:36 volumio volumio[863]: info: Scanning category user_interface Jul 10 20:54:36 volumio volumio[863]: info: Scanning into folder /data/plugins/ Jul 10 20:54:36 volumio volumio[863]: info: Plugin folders cleanup completed Jul 10 20:54:36 volumio volumio[863]: info: ------------------------------------------- Jul 10 20:54:36 volumio volumio[863]: info: ----- Core plugins startup ---- Jul 10 20:54:36 volumio volumio[863]: info: ------------------------------------------- Jul 10 20:54:36 volumio volumio[863]: info: Loading plugins from folder /volumio/app/plugins/ Jul 10 20:54:37 volumio volumio[863]: info: Adding plugin upnp to MyMusic Plugins Jul 10 20:54:37 volumio volumio[863]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 10 20:54:37 volumio volumio[863]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 10 20:54:37 volumio volumio[863]: info: Loading plugins from folder /data/plugins/ Jul 10 20:54:37 volumio volumio[863]: info: Loading plugin "system"... Jul 10 20:54:37 volumio volumio[863]: info: Loading plugin "appearance"... Jul 10 20:54:39 volumio volumio-remote-updater[579]: [2024-07-10 20:54:39] [connect] Successful connection Jul 10 20:54:44 volumio volumio[863]: info: Loading plugin "network"... Jul 10 20:54:46 volumio volumio[863]: info: Loading plugin "services"... Jul 10 20:54:46 volumio volumio[863]: info: Loading plugin "alsa_controller"... Jul 10 20:54:47 volumio volumio[863]: info: Loading plugin "wizard"... Jul 10 20:54:47 volumio volumio[863]: info: Loading plugin "volumio_command_line_client"... Jul 10 20:54:47 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:54:47 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:54:47 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:54:47 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:54:47 volumio volumio[863]: info: Loading plugin "upnp"... Jul 10 20:54:47 volumio volumio[863]: info: [1720644887822] Starting Upmpd Daemon Jul 10 20:54:47 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 20:54:47 volumio volumio[863]: info: Loading plugin "my_music"... Jul 10 20:54:47 volumio volumio[863]: info: Loading plugin "mpd"... Jul 10 20:54:49 volumio volumio[863]: info: Loading plugin "upnp_browser"... Jul 10 20:54:54 volumio volumio-remote-updater[579]: [2024-07-10 20:54:54] [connect] Successful connection Jul 10 20:54:55 volumio volumio[863]: info: Loading plugin "networkfs"... Jul 10 20:54:56 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 20:54:56 volumio volumio[863]: info: Loading plugin "alarm-clock"... Jul 10 20:54:58 volumio volumio[863]: info: Loading plugin "airplay_emulation"... Jul 10 20:54:58 volumio volumio[863]: info: Starting Shairport Sync Jul 10 20:54:58 volumio volumio[863]: info: Loading plugin "last_100"... Jul 10 20:54:58 volumio volumio[863]: info: Loading plugin "webradio"... Jul 10 20:54:58 volumio volumio[863]: info: Loading plugin "i2s_dacs"... Jul 10 20:54:58 volumio volumio[863]: info: I2S DAC not set, start Auto-detection Jul 10 20:54:58 volumio volumio[863]: info: Loading plugin "volumiodiscovery"... Jul 10 20:54:59 volumio volumio[863]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 10 20:54:59 volumio node[863]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 10 20:54:59 volumio node[863]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 20:54:59 volumio node[863]: *** WARNING *** For more information see Jul 10 20:54:59 volumio node[863]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 10 20:54:59 volumio node[863]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 20:54:59 volumio node[863]: *** WARNING *** For more information see Jul 10 20:54:59 volumio volumio[863]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 20:54:59 volumio volumio[863]: *** WARNING *** For more information see Jul 10 20:54:59 volumio volumio[863]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 10 20:54:59 volumio volumio[863]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 20:54:59 volumio volumio[863]: *** WARNING *** For more information see Jul 10 20:54:59 volumio volumio[863]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 10 20:54:59 volumio volumio[863]: Discovery: StartAdv! undefined Jul 10 20:54:59 volumio volumio[863]: Discovery: Started advertising... undefined - undefined Jul 10 20:54:59 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 20:54:59 volumio volumio[863]: info: Loading plugin "outputs"... Jul 10 20:54:59 volumio volumio[863]: info: Loading plugin "albumart"... Jul 10 20:54:59 volumio volumio[863]: info: Plugin example_plugin is not enabled Jul 10 20:54:59 volumio volumio[863]: info: Loading plugin "updater_comm"... Jul 10 20:55:02 volumio volumio[863]: info: Plugin mpdemulation is not enabled Jul 10 20:55:02 volumio volumio[863]: info: Loading plugin "rest_api"... Jul 10 20:55:02 volumio volumio[863]: info: Loading plugin "websocket"... Jul 10 20:55:02 volumio volumio[863]: info: ___________ START PLUGINS ___________ Jul 10 20:55:02 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 20:55:02 volumio volumio[863]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 20:55:02 volumio volumio[863]: info: [1720644902873] CoreMusicLibrary::Adding element Media Servers Jul 10 20:55:02 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 20:55:03 volumio volumio[863]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 20:55:03 volumio volumio[863]: info: [1720644903271] CoreMusicLibrary::Adding element Last_100 Jul 10 20:55:03 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 20:55:03 volumio volumio[863]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 20:55:03 volumio volumio[863]: info: [1720644903331] CoreMusicLibrary::Adding element Webradio Jul 10 20:55:03 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 20:55:03 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 20:55:03 volumio volumio[863]: info: ------------------------------------------- Jul 10 20:55:03 volumio volumio[863]: info: ----- MyVolumio plugins startup ---- Jul 10 20:55:03 volumio volumio[863]: info: ------------------------------------------- Jul 10 20:55:03 volumio volumio[863]: info: Loading plugins from folder /myvolumio/plugins Jul 10 20:55:03 volumio volumio[863]: info: Loading plugins from folder /data/myvolumio/plugins Jul 10 20:55:03 volumio volumio[863]: info: Loading plugin "streaming_services"... Jul 10 20:55:04 volumio volumio[863]: Forking 1 albumart workers Jul 10 20:55:06 volumio volumio[863]: info: Loading plugin "my_volumio"... Jul 10 20:55:09 volumio volumio-remote-updater[579]: [2024-07-10 20:55:09] [connect] Successful connection Jul 10 20:55:14 volumio volumio[863]: info: Streaming services startup Jul 10 20:55:14 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 20:55:14 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 20:55:14 volumio volumio[863]: info: Starting MyVolumio Remote Streaming Endpoints Jul 10 20:55:15 volumio sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 10 20:55:15 volumio sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 20:55:15 volumio volumio[863]: Starting albumart workers Jul 10 20:55:15 volumio systemd[1]: Stopping Volumio Streaming Daemon... Jul 10 20:55:15 volumio systemd[1]: Starting Volumio Streaming Daemon... Jul 10 20:55:15 volumio systemd[1]: Started Volumio Streaming Daemon. Jul 10 20:55:15 volumio sudo[976]: pam_unix(sudo:session): session closed for user root Jul 10 20:55:15 volumio volumio-streaming-daemon[982]: ############################ Jul 10 20:55:15 volumio volumio-streaming-daemon[982]: # Volumio Streaming Daemon # Jul 10 20:55:15 volumio volumio-streaming-daemon[982]: # Running on port 7777 # Jul 10 20:55:15 volumio volumio-streaming-daemon[982]: ############################ Jul 10 20:55:15 volumio volumio-streaming-daemon[982]: Checking variant in static cache Jul 10 20:55:15 volumio volumio-streaming-daemon[982]: Enabled services: Jul 10 20:55:15 volumio volumio-streaming-daemon[982]: 1) tidal Jul 10 20:55:15 volumio volumio-streaming-daemon[982]: 2) qobuz Jul 10 20:55:15 volumio volumio[863]: info: Loading i18n strings for locale en Jul 10 20:55:16 volumio volumio[863]: Updating browse sources language Jul 10 20:55:16 volumio volumio[863]: Cannot find translation for sourceMedia Servers Jul 10 20:55:16 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 20:55:16 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 20:55:16 volumio volumio[863]: info: CoreCommandRouter::initPlayerControls Jul 10 20:55:16 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:55:16 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jul 10 20:55:16 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:55:16 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:55:16 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:55:16 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:55:16 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 20:55:17 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 20:55:17 volumio volumio[863]: info: BOOT COMPLETED Jul 10 20:55:17 volumio volumio[863]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 20:55:17 volumio volumio[863]: info: CoreCommandRouter::Close All Modals sent Jul 10 20:55:17 volumio volumio[863]: info: CoreCommandRouter::Close All Modals sent Jul 10 20:55:17 volumio volumio[863]: Express server listening on port 3000 Jul 10 20:55:17 volumio volumio[863]: Volumio Calling Home Jul 10 20:55:18 volumio volumio[863]: info: MyVolumio not started Jul 10 20:55:18 volumio volumio[863]: info: CoreStateMachine::resetVolumioState Jul 10 20:55:18 volumio volumio[863]: info: CoreStateMachine::getcurrentVolume Jul 10 20:55:18 volumio volumio[863]: info: CoreCommandRouter::volumioRetrievevolume Jul 10 20:55:18 volumio volumio[863]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 20:55:19 volumio volumio[863]: TypeError: Cannot read property 'charAt' of undefined Jul 10 20:55:19 volumio volumio[863]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:208:28) Jul 10 20:55:19 volumio volumio[863]: at ontimeout (timers.js:482:11) Jul 10 20:55:19 volumio volumio[863]: at tryOnTimeout (timers.js:317:5) Jul 10 20:55:19 volumio volumio[863]: at Timer.listOnTimeout (timers.js:277:5) Jul 10 20:55:19 volumio volumio[863]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 20:55:24 volumio volumio-remote-updater[579]: [2024-07-10 20:55:24] [connect] Successful connection Jul 10 20:55:25 volumio sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-10 20:54 Jul 10 20:55:25 volumio sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f1b59d96a3893a42f8609371956ecba0438017d7" VOLUMIO_FE_VERSION="95791212f747b95a2cb050c16459a831bfe5c7e7" VOLUMIO_BE_VERSION="1c3c01aeeeb4cff8e6c9a4b6c34e1a558d1b237b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 12 19:57:25 CET 2019" VOLUMIO_VERSION="2.565" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="64b803aae2d00ba86d00d612c4abb38d"