-- Logs begin at Sun 2024-11-24 13:29:47 UTC, end at Sun 2024-11-24 13:32:54 UTC. -- Nov 24 13:31:58 volumio systemd[1]: Time has been changed Nov 24 13:31:58 volumio wireless.js[846]: trying... Nov 24 13:31:58 volumio wpa_supplicant[1018]: wlan0: Trying to associate with SSID 'freebox_stefx' Nov 24 13:31:58 volumio sudo[1054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:31:58 volumio sudo[1054]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:31:58 volumio sudo[1054]: pam_unix(sudo:session): session closed for user root Nov 24 13:31:59 volumio wireless.js[846]: trying... Nov 24 13:31:59 volumio sudo[1063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:31:59 volumio sudo[1063]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:31:59 volumio sudo[1063]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:00 volumio wireless.js[846]: trying... Nov 24 13:32:00 volumio sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:00 volumio sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:00 volumio sudo[1072]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:01 volumio wpa_supplicant[1018]: wlan0: Associated with 70:fc:8f:35:4e:cb Nov 24 13:32:01 volumio wireless.js[846]: trying... Nov 24 13:32:01 volumio sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:01 volumio sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:01 volumio sudo[1081]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:02 volumio wireless.js[846]: trying... Nov 24 13:32:02 volumio sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:02 volumio sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:02 volumio sudo[1090]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:03 volumio wireless.js[846]: trying... Nov 24 13:32:03 volumio sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:03 volumio sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:03 volumio sudo[1099]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:04 volumio volumio-remote-updater[670]: [2024-11-24 13:32:04] [info] asio async_connect error: system:111 (Connection refused) Nov 24 13:32:04 volumio volumio-remote-updater[670]: [2024-11-24 13:32:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 24 13:32:04 volumio volumio-remote-updater[670]: [2024-11-24 13:32:04] [error] handle_connect error: Underlying Transport Error Nov 24 13:32:04 volumio wireless.js[846]: trying... Nov 24 13:32:04 volumio sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:04 volumio sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:04 volumio sudo[1108]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:05 volumio wpa_supplicant[1018]: wlan0: CTRL-EVENT-DISCONNECTED bssid=70:fc:8f:35:4e:cb reason=15 locally_generated=1 Nov 24 13:32:05 volumio wpa_supplicant[1018]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect Nov 24 13:32:05 volumio wpa_supplicant[1018]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="freebox_stefx" auth_failures=1 duration=10 reason=WRONG_KEY Nov 24 13:32:05 volumio wpa_supplicant[1018]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Nov 24 13:32:05 volumio wireless.js[846]: trying... Nov 24 13:32:05 volumio sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:05 volumio sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:05 volumio sudo[1119]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:06 volumio wireless.js[846]: trying... Nov 24 13:32:06 volumio sudo[1128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:06 volumio sudo[1128]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:06 volumio sudo[1128]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:07 volumio wireless.js[846]: trying... Nov 24 13:32:07 volumio sudo[1137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:07 volumio sudo[1137]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:07 volumio sudo[1137]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:08 volumio wireless.js[846]: trying... Nov 24 13:32:08 volumio sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:08 volumio sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:08 volumio sudo[1146]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:09 volumio wireless.js[846]: trying... Nov 24 13:32:09 volumio sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:09 volumio sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:09 volumio sudo[1155]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:11 volumio wireless.js[846]: trying... Nov 24 13:32:11 volumio sudo[1164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:11 volumio sudo[1164]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:11 volumio sudo[1164]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:12 volumio wireless.js[846]: trying... Nov 24 13:32:12 volumio sudo[1173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:12 volumio sudo[1173]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:12 volumio sudo[1173]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:13 volumio wireless.js[846]: trying... Nov 24 13:32:13 volumio sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:13 volumio sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:13 volumio sudo[1182]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:14 volumio wireless.js[846]: trying... Nov 24 13:32:14 volumio sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:14 volumio sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:14 volumio sudo[1191]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:14 volumio volumio-remote-updater[670]: [2024-11-24 13:32:14] [info] asio async_connect error: system:111 (Connection refused) Nov 24 13:32:14 volumio volumio-remote-updater[670]: [2024-11-24 13:32:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 24 13:32:14 volumio volumio-remote-updater[670]: [2024-11-24 13:32:14] [error] handle_connect error: Underlying Transport Error Nov 24 13:32:15 volumio wireless.js[846]: trying... Nov 24 13:32:15 volumio sudo[1200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:15 volumio sudo[1200]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:15 volumio sudo[1200]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:16 volumio wpa_supplicant[1018]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="freebox_stefx" Nov 24 13:32:16 volumio wpa_supplicant[1018]: wlan0: Trying to associate with SSID 'freebox_stefx' Nov 24 13:32:16 volumio wireless.js[846]: trying... Nov 24 13:32:16 volumio sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 24 13:32:16 volumio sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:16 volumio sudo[1209]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:17 volumio wireless.js[846]: Overtime, starting plan B Nov 24 13:32:17 volumio wireless.js[846]: STARTING HOTSPOT Nov 24 13:32:17 volumio wpa_supplicant[1018]: wlan0: CTRL-EVENT-DISCONNECTED bssid=00:00:00:00:00:00 reason=3 locally_generated=1 Nov 24 13:32:17 volumio wpa_supplicant[1018]: wlan0: CTRL-EVENT-TERMINATING Nov 24 13:32:20 volumio systemd[1]: Stopped hotspot.service. Nov 24 13:32:20 volumio sudo[1226]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 24 13:32:20 volumio sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:20 volumio sudo[1226]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:20 volumio sudo[1234]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 24 13:32:20 volumio sudo[1234]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:20 volumio sudo[1234]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:20 volumio avahi-daemon[733]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Nov 24 13:32:20 volumio avahi-daemon[733]: New relevant interface wlan0.IPv4 for mDNS. Nov 24 13:32:20 volumio avahi-daemon[733]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Nov 24 13:32:20 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 24 13:32:20 volumio systemd[1]: Starting hotspot.service... Nov 24 13:32:20 volumio systemd[1]: Started hotspot.service. Nov 24 13:32:20 volumio systemd[1]: Starting dhcpd.service... Nov 24 13:32:20 volumio systemd[1]: Starting dnsmasq.service... Nov 24 13:32:20 volumio systemd[1]: Started Wireless Services. Nov 24 13:32:20 volumio systemd[1]: Starting Volumio Backend Module... Nov 24 13:32:20 volumio systemd[1]: Started Volumio Backend Module. Nov 24 13:32:20 volumio systemd[1]: Starting Volumio Streaming Daemon... Nov 24 13:32:20 volumio systemd[1]: Started Volumio Streaming Daemon. Nov 24 13:32:20 volumio systemd[1]: Starting Multi-User System. Nov 24 13:32:20 volumio systemd[1]: Reached target Multi-User System. Nov 24 13:32:20 volumio systemd[1]: Starting Graphical Interface. Nov 24 13:32:20 volumio systemd[1]: Reached target Graphical Interface. Nov 24 13:32:20 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 24 13:32:21 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 24 13:32:21 volumio dnsmasq[1260]: started, version 2.72 cachesize 150 Nov 24 13:32:21 volumio dnsmasq[1260]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect Nov 24 13:32:21 volumio dnsmasq[1260]: reading /etc/resolv.conf Nov 24 13:32:21 volumio dnsmasq[1260]: using nameserver 192.168.0.254#53 Nov 24 13:32:21 volumio dnsmasq[1260]: using nameserver 208.67.222.222#53 Nov 24 13:32:21 volumio dnsmasq[1260]: using nameserver 208.67.220.220#53 Nov 24 13:32:21 volumio dnsmasq[1260]: read /etc/hosts - 5 addresses Nov 24 13:32:21 volumio systemd[1]: Started dnsmasq.service. Nov 24 13:32:21 volumio systemd[1]: Starting Host and Network Name Lookups. Nov 24 13:32:21 volumio systemd[1]: Reached target Host and Network Name Lookups. Nov 24 13:32:21 volumio dhcpd[1247]: Internet Systems Consortium DHCP Server 4.3.1 Nov 24 13:32:21 volumio dhcpd[1247]: Copyright 2004-2014 Internet Systems Consortium. Nov 24 13:32:21 volumio dhcpd[1247]: All rights reserved. Nov 24 13:32:21 volumio dhcpd[1247]: For info, please visit https://www.isc.org/software/dhcp/ Nov 24 13:32:21 volumio dhcpd[1247]: Config file: /etc/dhcp/dhcpd.conf Nov 24 13:32:21 volumio dhcpd[1247]: Database file: /var/lib/dhcp/dhcpd.leases Nov 24 13:32:21 volumio dhcpd[1247]: PID file: /var/run/dhcpd.pid Nov 24 13:32:21 volumio hotspot[1247]: Internet Systems Consortium DHCP Server 4.3.1 Nov 24 13:32:21 volumio hotspot[1247]: Copyright 2004-2014 Internet Systems Consortium. Nov 24 13:32:21 volumio hotspot[1247]: All rights reserved. Nov 24 13:32:21 volumio hotspot[1247]: For info, please visit https://www.isc.org/software/dhcp/ Nov 24 13:32:21 volumio hotspot[1247]: Config file: /etc/dhcp/dhcpd.conf Nov 24 13:32:21 volumio hotspot[1247]: Database file: /var/lib/dhcp/dhcpd.leases Nov 24 13:32:21 volumio hotspot[1247]: PID file: /var/run/dhcpd.pid Nov 24 13:32:21 volumio dhcpd[1247]: Internet Systems Consortium DHCP Server 4.3.1 Nov 24 13:32:21 volumio dhcpd[1247]: Copyright 2004-2014 Internet Systems Consortium. Nov 24 13:32:21 volumio dhcpd[1247]: All rights reserved. Nov 24 13:32:21 volumio dhcpd[1247]: For info, please visit https://www.isc.org/software/dhcp/ Nov 24 13:32:21 volumio dhcpd[1247]: Wrote 2 leases to leases file. Nov 24 13:32:21 volumio hotspot[1247]: Wrote 2 leases to leases file. Nov 24 13:32:21 volumio dhcpd[1247]: Listening on LPF/wlan0/b8:27:eb:9b:e4:3d/192.168.211.0/24 Nov 24 13:32:21 volumio dhcpd[1247]: Sending on LPF/wlan0/b8:27:eb:9b:e4:3d/192.168.211.0/24 Nov 24 13:32:21 volumio dhcpd[1247]: Nov 24 13:32:21 volumio dhcpd[1247]: No subnet declaration for eth0 (192.168.0.22). Nov 24 13:32:21 volumio dhcpd[1247]: ** Ignoring requests on eth0. If this is not what Nov 24 13:32:21 volumio dhcpd[1247]: you want, please write a subnet declaration Nov 24 13:32:21 volumio dhcpd[1247]: in your dhcpd.conf file for the network segment Nov 24 13:32:21 volumio dhcpd[1247]: to which interface eth0 is attached. ** Nov 24 13:32:21 volumio dhcpd[1247]: Nov 24 13:32:21 volumio dhcpd[1247]: Sending on Socket/fallback/fallback-net Nov 24 13:32:21 volumio dhcpd[1266]: Server starting service. Nov 24 13:32:21 volumio systemd[1]: Started dhcpd.service. Nov 24 13:32:21 volumio systemd[1]: Startup finished in 10.186s (kernel) + 39.577s (userspace) = 49.763s. Nov 24 13:32:21 volumio hotspot[1247]: Listening on LPF/wlan0/b8:27:eb:9b:e4:3d/192.168.211.0/24 Nov 24 13:32:21 volumio hotspot[1247]: Sending on LPF/wlan0/b8:27:eb:9b:e4:3d/192.168.211.0/24 Nov 24 13:32:21 volumio hotspot[1247]: No subnet declaration for eth0 (192.168.0.22). Nov 24 13:32:21 volumio hotspot[1247]: ** Ignoring requests on eth0. If this is not what Nov 24 13:32:21 volumio hotspot[1247]: you want, please write a subnet declaration Nov 24 13:32:21 volumio hotspot[1247]: in your dhcpd.conf file for the network segment Nov 24 13:32:21 volumio hotspot[1247]: to which interface eth0 is attached. ** Nov 24 13:32:21 volumio hotspot[1247]: Sending on Socket/fallback/fallback-net Nov 24 13:32:21 volumio volumio-streaming-daemon[1250]: ############################ Nov 24 13:32:21 volumio volumio-streaming-daemon[1250]: # Volumio Streaming Daemon # Nov 24 13:32:21 volumio volumio-streaming-daemon[1250]: # Running on port 7777 # Nov 24 13:32:21 volumio volumio-streaming-daemon[1250]: ############################ Nov 24 13:32:21 volumio volumio-streaming-daemon[1250]: Environment: production Nov 24 13:32:21 volumio hotspot[1246]: Launching Hostapd 2.8 Nov 24 13:32:21 volumio hotspot[1246]: Configuration file: /etc/hostapd/hostapd.conf Nov 24 13:32:21 volumio hotspot[1246]: wlan0: Could not connect to kernel driver Nov 24 13:32:21 volumio hotspot[1246]: Using interface wlan0 with hwaddr b8:27:eb:9b:e4:3d and ssid "Volumio" Nov 24 13:32:21 volumio hotspot[1246]: random: Only 15/20 bytes of strong random data available Nov 24 13:32:21 volumio hotspot[1246]: random: Not enough entropy pool available for secure operations Nov 24 13:32:21 volumio hotspot[1246]: WPA: Not enough entropy in random pool for secure operations - update keys later when the first station connects Nov 24 13:32:21 volumio dhcpcd[1023]: wlan0: carrier acquired Nov 24 13:32:21 volumio hotspot[1246]: wlan0: interface state UNINITIALIZED->ENABLED Nov 24 13:32:21 volumio hotspot[1246]: wlan0: AP-ENABLED Nov 24 13:32:21 volumio dhcpcd[1023]: wlan0: soliciting a DHCP lease Nov 24 13:32:21 volumio dhcpd[1266]: DHCPDISCOVER from b8:27:eb:9b:e4:3d via wlan0 Nov 24 13:32:22 volumio dhcpd[1266]: DHCPOFFER on 192.168.211.5 to b8:27:eb:9b:e4:3d (volumio) via wlan0 Nov 24 13:32:22 volumio ntpd[811]: Listen normally on 4 wlan0 192.168.211.1 UDP 123 Nov 24 13:32:22 volumio ntpd[811]: peers refreshed Nov 24 13:32:23 volumio volumio[1249]: info: ------------------------------------------- Nov 24 13:32:23 volumio volumio[1249]: info: ----- Volumio2 ---- Nov 24 13:32:23 volumio volumio[1249]: info: ------------------------------------------- Nov 24 13:32:23 volumio volumio[1249]: info: ----- System startup ---- Nov 24 13:32:23 volumio volumio[1249]: info: ------------------------------------------- Nov 24 13:32:24 volumio volumio-remote-updater[670]: [2024-11-24 13:32:24] [connect] Successful connection Nov 24 13:32:24 volumio volumio[1249]: info: MYVOLUMIO Environment detected Nov 24 13:32:25 volumio volumio[1249]: info: Plugin folders cleanup Nov 24 13:32:25 volumio volumio[1249]: info: Scanning into folder /volumio/app/plugins/ Nov 24 13:32:25 volumio volumio[1249]: info: Scanning category audio_interface Nov 24 13:32:25 volumio volumio[1249]: info: Scanning category miscellanea Nov 24 13:32:25 volumio volumio[1249]: info: Scanning category music_service Nov 24 13:32:25 volumio volumio[1249]: info: Scanning category plugins.json Nov 24 13:32:25 volumio volumio[1249]: info: Scanning category system_controller Nov 24 13:32:25 volumio volumio[1249]: info: Scanning category user_interface Nov 24 13:32:25 volumio volumio[1249]: info: Scanning into folder /data/plugins/ Nov 24 13:32:25 volumio volumio[1249]: info: Scanning category audio_interface Nov 24 13:32:25 volumio volumio[1249]: info: Scanning category music_service Nov 24 13:32:25 volumio volumio[1249]: info: Plugin folders cleanup completed Nov 24 13:32:25 volumio volumio[1249]: info: ------------------------------------------- Nov 24 13:32:25 volumio volumio[1249]: info: ----- Core plugins startup ---- Nov 24 13:32:25 volumio volumio[1249]: info: ------------------------------------------- Nov 24 13:32:25 volumio volumio[1249]: info: Loading plugins from folder /volumio/app/plugins/ Nov 24 13:32:25 volumio volumio[1249]: info: Adding plugin upnp to MyMusic Plugins Nov 24 13:32:25 volumio volumio[1249]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 24 13:32:25 volumio volumio[1249]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 24 13:32:25 volumio volumio[1249]: info: Loading plugins from folder /data/plugins/ Nov 24 13:32:25 volumio volumio[1249]: info: Loading plugin "system"... Nov 24 13:32:25 volumio volumio[1249]: info: Loading plugin "appearance"... Nov 24 13:32:26 volumio dhcpd[1266]: DHCPDISCOVER from b8:27:eb:9b:e4:3d (volumio) via wlan0 Nov 24 13:32:26 volumio dhcpd[1266]: DHCPOFFER on 192.168.211.5 to b8:27:eb:9b:e4:3d (volumio) via wlan0 Nov 24 13:32:26 volumio volumio[1249]: info: Loading plugin "network"... Nov 24 13:32:26 volumio volumio[1249]: info: Refreshing Cached IP Addresses Nov 24 13:32:26 volumio sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 13:32:26 volumio volumio[1249]: info: Loading plugin "services"... Nov 24 13:32:26 volumio volumio[1249]: info: Loading plugin "alsa_controller"... Nov 24 13:32:26 volumio sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:26 volumio sudo[1312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 13:32:26 volumio sudo[1312]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:26 volumio sudo[1310]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:26 volumio sudo[1312]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:26 volumio volumio[1249]: info: Loading plugin "wizard"... Nov 24 13:32:26 volumio volumio[1249]: info: Loading plugin "volumio_command_line_client"... Nov 24 13:32:26 volumio volumio[1249]: info: Loading plugin "upnp"... Nov 24 13:32:26 volumio volumio[1249]: info: [1732455146689] Starting Upmpd Daemon Nov 24 13:32:26 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 24 13:32:26 volumio volumio[1249]: info: Loading plugin "my_music"... Nov 24 13:32:26 volumio volumio[1249]: info: Loading plugin "mpd"... Nov 24 13:32:27 volumio volumio[1249]: info: Loading plugin "upnp_browser"... Nov 24 13:32:27 volumio volumio[1249]: info: Loading plugin "networkfs"... Nov 24 13:32:27 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 24 13:32:27 volumio volumio[1249]: info: Loading plugin "alarm-clock"... Nov 24 13:32:27 volumio sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=FX,password=AzErTy293028!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.17/music/itunes media /mnt/NAS/NAS Nov 24 13:32:27 volumio sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:28 volumio kernel: FS-Cache: Netfs 'cifs' registered for caching Nov 24 13:32:28 volumio kernel: Key type cifs.spnego registered Nov 24 13:32:28 volumio kernel: Key type cifs.idmap registered Nov 24 13:32:28 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Nov 24 13:32:28 volumio volumio[1249]: info: Loading plugin "airplay_emulation"... Nov 24 13:32:28 volumio volumio[1249]: info: Starting Shairport Sync Nov 24 13:32:28 volumio volumio[1249]: info: Loading plugin "last_100"... Nov 24 13:32:28 volumio volumio[1249]: info: Loading plugin "webradio"... Nov 24 13:32:28 volumio volumio[1249]: info: Loading plugin "i2s_dacs"... Nov 24 13:32:28 volumio volumio[1249]: info: I2S DAC not set, start Auto-detection Nov 24 13:32:28 volumio volumio[1249]: info: Loading plugin "volumiodiscovery"... Nov 24 13:32:28 volumio volumio[1249]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 24 13:32:28 volumio node[1249]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 24 13:32:28 volumio node[1249]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 24 13:32:28 volumio node[1249]: *** WARNING *** For more information see Nov 24 13:32:28 volumio node[1249]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 24 13:32:28 volumio node[1249]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 24 13:32:28 volumio node[1249]: *** WARNING *** For more information see Nov 24 13:32:28 volumio volumio[1249]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 24 13:32:28 volumio volumio[1249]: *** WARNING *** For more information see Nov 24 13:32:28 volumio volumio[1249]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 24 13:32:28 volumio volumio[1249]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 24 13:32:28 volumio volumio[1249]: *** WARNING *** For more information see Nov 24 13:32:28 volumio volumio[1249]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 24 13:32:28 volumio volumio[1249]: Discovery: StartAdv! undefined Nov 24 13:32:28 volumio volumio[1249]: Discovery: Started advertising... Volumio - undefined Nov 24 13:32:28 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 24 13:32:28 volumio volumio[1249]: info: Loading plugin "volsimpleequal"... Nov 24 13:32:28 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Nov 24 13:32:28 volumio volumio[1249]: info: Applying required configuration parameters for plugin volsimpleequal Nov 24 13:32:28 volumio volumio[1249]: info: Loading plugin "outputs"... Nov 24 13:32:28 volumio volumio[1249]: info: Loading plugin "albumart"... Nov 24 13:32:28 volumio volumio[1249]: info: Plugin example_plugin is not enabled Nov 24 13:32:28 volumio volumio[1249]: info: Loading plugin "inputs"... Nov 24 13:32:28 volumio volumio[1249]: info: Loading plugin "updater_comm"... Nov 24 13:32:29 volumio volumio[1249]: info: Plugin mpdemulation is not enabled Nov 24 13:32:29 volumio volumio[1249]: info: Loading plugin "rest_api"... Nov 24 13:32:29 volumio volumio[1249]: info: Loading plugin "websocket"... Nov 24 13:32:29 volumio volumio[1249]: info: Plugin spop is not enabled Nov 24 13:32:29 volumio volumio[1249]: info: Plugin volspotconnect2 is not enabled Nov 24 13:32:29 volumio volumio[1249]: info: ___________ START PLUGINS ___________ Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 24 13:32:29 volumio volumio[1249]: info: [1732455149218] CoreMusicLibrary::Adding element Media Servers Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 13:32:29 volumio volumio[1249]: Forking 3 albumart workers Nov 24 13:32:29 volumio volumio[1249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 13:32:29 volumio volumio[1249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 24 13:32:29 volumio volumio[1249]: info: [1732455149350] CoreMusicLibrary::Adding element Last_100 Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 24 13:32:29 volumio volumio[1249]: info: [1732455149359] CoreMusicLibrary::Adding element Webradio Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 13:32:29 volumio sudo[1336]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: ------------------------------------------- Nov 24 13:32:29 volumio volumio[1249]: info: ----- MyVolumio plugins startup ---- Nov 24 13:32:29 volumio volumio[1249]: info: ------------------------------------------- Nov 24 13:32:29 volumio volumio[1249]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 24 13:32:29 volumio volumio[1249]: info: Loading i18n strings for locale fr Nov 24 13:32:29 volumio volumio[1249]: Updating browse sources language Nov 24 13:32:29 volumio volumio[1249]: Cannot find translation for sourceMedia Servers Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 13:32:29 volumio sudo[1427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Nov 24 13:32:29 volumio sudo[1427]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:29 volumio sudo[1427]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::initPlayerControls Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 13:32:29 volumio volumio[1249]: info: BOOT COMPLETED Nov 24 13:32:29 volumio volumio[1249]: [Metrics] CommandRouter: 5s 890.91ms Nov 24 13:32:29 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:32:30 volumio volumio[1249]: Starting albumart workers Nov 24 13:32:30 volumio volumio[1249]: Starting albumart workers Nov 24 13:32:30 volumio volumio[1249]: Starting albumart workers Nov 24 13:32:33 volumio dhcpd[1266]: DHCPDISCOVER from b8:27:eb:9b:e4:3d (volumio) via wlan0 Nov 24 13:32:33 volumio dhcpd[1266]: DHCPOFFER on 192.168.211.5 to b8:27:eb:9b:e4:3d (volumio) via wlan0 Nov 24 13:32:35 volumio volumio[1249]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Nov 24 13:32:35 volumio volumio[1249]: info: CoreCommandRouter::Close All Modals sent Nov 24 13:32:35 volumio volumio[1249]: info: CoreCommandRouter::Close All Modals sent Nov 24 13:32:35 volumio volumio[1249]: Express server listening on port 3000 Nov 24 13:32:35 volumio volumio[1249]: [Metrics] WebUI: 12s 928.21ms Nov 24 13:32:35 volumio volumio[1249]: Volumio Calling Home Nov 24 13:32:35 volumio volumio[1249]: info: Volsimpleequal Started Nov 24 13:32:35 volumio volumio[1249]: info: CoreStateMachine::resetVolumioState Nov 24 13:32:35 volumio volumio[1249]: info: CoreStateMachine::getcurrentVolume Nov 24 13:32:35 volumio volumio[1249]: info: CoreCommandRouter::volumioRetrievevolume Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , disableI2SDAC Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 24 13:32:36 volumio volumio[1249]: info: Output device has changed, restarting MPD Nov 24 13:32:36 volumio volumio[1249]: info: Output device has changed, restarting Shairport Sync Nov 24 13:32:36 volumio sudo[1474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 13:32:36 volumio sudo[1474]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:36 volumio sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 13:32:36 volumio sudo[1474]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:36 volumio sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:36 volumio systemd[1]: Stopping Music Player Daemon... Nov 24 13:32:36 volumio volumio[1249]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 13:32:36 volumio volumio[1249]: info: wwwwwwwwwwwwwwwwWWWWWWWWWWWWWWWWwwwwwwwwwwwWWWWWWWWWwwwwwwwwwwWWWWWwwOutput device has changed, continuing config Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 24 13:32:36 volumio volumio[1249]: info: Output is Loopback, do not update Mixer Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 13:32:36 volumio volumio[1249]: info: MPD Permissions set Nov 24 13:32:36 volumio volumio[1249]: info: Setting Device type: Raspberry PI Nov 24 13:32:36 volumio volumio[1249]: info: snd_aloop loaded Nov 24 13:32:36 volumio systemd[1]: Starting Music Player Daemon... Nov 24 13:32:36 volumio volumio[1249]: info: MPD running with PID735 Nov 24 13:32:36 volumio volumio[1249]: ,establishing connection Nov 24 13:32:36 volumio sudo[1497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Nov 24 13:32:36 volumio sudo[1497]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:36 volumio sudo[1497]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:36 volumio volumio[1249]: info: VolumeController:: Volume=100 Mute =true Nov 24 13:32:36 volumio volumio[1249]: info: CoreStateMachine::pushState Nov 24 13:32:36 volumio volumio[1249]: info: CorePlayQueue::getTrack 0 Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 13:32:36 volumio volumio[1249]: info: CoreStateMachine::updateTrackBlock Nov 24 13:32:36 volumio volumio[1249]: info: CorePlayQueue::getTrackBlock Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::volumioRetrievevolume Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:32:36 volumio sudo[1507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 24 13:32:36 volumio sudo[1507]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:36 volumio sudo[1507]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:36 volumio volumio[1249]: info: No Overlays Loaded Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: Reloading queue from file Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 24 13:32:36 volumio sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 24 13:32:36 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 24 13:32:36 volumio sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:37 volumio sudo[1531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 13:32:37 volumio sudo[1529]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:37 volumio sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:37 volumio sudo[1531]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:37 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Nov 24 13:32:37 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 24 13:32:37 volumio volumio[1249]: info: CoreStateMachine::setRepeat null single undefined Nov 24 13:32:37 volumio volumio[1249]: info: CoreStateMachine::pushState Nov 24 13:32:37 volumio volumio[1249]: info: CorePlayQueue::getTrack 0 Nov 24 13:32:37 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Nov 24 13:32:37 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 13:32:37 volumio volumio[1249]: info: CoreStateMachine::setRandom true Nov 24 13:32:37 volumio volumio[1249]: info: CoreStateMachine::pushState Nov 24 13:32:37 volumio volumio[1249]: info: CorePlayQueue::getTrack 0 Nov 24 13:32:37 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Nov 24 13:32:37 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 13:32:37 volumio volumio[1249]: info: VolumeController:: Volume=100 Mute =true Nov 24 13:32:37 volumio volumio[1249]: info: CoreStateMachine::pushState Nov 24 13:32:37 volumio volumio[1249]: info: CorePlayQueue::getTrack 0 Nov 24 13:32:37 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Nov 24 13:32:37 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 13:32:37 volumio volumio[1249]: info: Starting Shairport Sync Nov 24 13:32:37 volumio volumio[1249]: info: Starting Shairport Sync Nov 24 13:32:37 volumio volumio[1249]: info: Starting Shairport Sync Nov 24 13:32:37 volumio sudo[1550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 13:32:37 volumio volumio[1249]: info: asound.conf file written Nov 24 13:32:37 volumio sudo[1552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 13:32:37 volumio sudo[1550]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:37 volumio sudo[1552]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:37 volumio sudo[1555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 13:32:37 volumio sudo[1555]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:37 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 24 13:32:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 24 13:32:37 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 24 13:32:37 volumio sudo[1550]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:37 volumio sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/asoundrc /etc/asound.conf Nov 24 13:32:37 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:37 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 24 13:32:37 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 24 13:32:37 volumio sudo[1552]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:37 volumio sudo[1555]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:37 volumio sudo[1563]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:37 volumio volumio[1249]: Discovery: adding 33d51f11-bfe6-4cc9-a281-0ee68432bb19 Nov 24 13:32:37 volumio volumio[1249]: info: mDNS: Found device Volumio Nov 24 13:32:37 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Nov 24 13:32:37 volumio volumio[1249]: info: CorePlayQueue::getTrack 0 Nov 24 13:32:37 volumio volumio[1249]: info: Shairport-Sync Started Nov 24 13:32:37 volumio volumio[1249]: Error adding Membership: Error: addMembership EINVAL Nov 24 13:32:37 volumio volumio[1249]: info: Shairport-Sync Started Nov 24 13:32:37 volumio volumio[1249]: info: Shairport-Sync Started Nov 24 13:32:37 volumio volumio[1249]: Discovery: this is already registered, 33d51f11-bfe6-4cc9-a281-0ee68432bb19 Nov 24 13:32:37 volumio volumio[1249]: info: mDNS: Found device Volumio Nov 24 13:32:37 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Nov 24 13:32:37 volumio volumio[1249]: info: CorePlayQueue::getTrack 0 Nov 24 13:32:37 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Nov 24 13:32:37 volumio volumio[1249]: info: CorePlayQueue::getTrack 0 Nov 24 13:32:37 volumio sudo[1600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 24 13:32:37 volumio sudo[1600]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:37 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Nov 24 13:32:37 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Nov 24 13:32:37 volumio sudo[1600]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:37 volumio volumio[1249]: info: Upmpdcli Daemon Started Nov 24 13:32:38 volumio sudo[1610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 13:32:38 volumio sudo[1610]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:38 volumio sudo[1612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 13:32:38 volumio sudo[1610]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:38 volumio sudo[1612]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:38 volumio sudo[1612]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 24 13:32:38 volumio volumio[1249]: info: Adding plugin bluetooth to MyMusic Plugins Nov 24 13:32:38 volumio volumio[1249]: info: Adding plugin cd_controller to MyMusic Plugins Nov 24 13:32:38 volumio volumio[1249]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 24 13:32:38 volumio volumio[1249]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 24 13:32:39 volumio volumio-remote-updater[670]: [2024-11-24 13:32:39] [connect] Successful connection Nov 24 13:32:40 volumio volumio[1249]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 24 13:32:42 volumio volumio[1249]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 24 13:32:42 volumio volumio[1249]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 24 13:32:42 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:32:42 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:32:42 volumio volumio[1249]: info: Starting MyVolumio Remote Streaming Endpoints Nov 24 13:32:42 volumio volumio[1249]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 24 13:32:42 volumio volumio[1249]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 24 13:32:42 volumio volumio[1249]: info: Streaming services startup Nov 24 13:32:42 volumio volumio[1249]: info: Starting Streaming Daemon Nov 24 13:32:42 volumio volumio[1249]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 24 13:32:42 volumio sudo[1628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 24 13:32:42 volumio sudo[1628]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:42 volumio systemd[1]: Stopping Volumio Streaming Daemon... Nov 24 13:32:42 volumio systemd[1]: Starting Volumio Streaming Daemon... Nov 24 13:32:42 volumio systemd[1]: Started Volumio Streaming Daemon. Nov 24 13:32:42 volumio sudo[1628]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:42 volumio volumio-streaming-daemon[1634]: ############################ Nov 24 13:32:42 volumio volumio-streaming-daemon[1634]: # Volumio Streaming Daemon # Nov 24 13:32:42 volumio volumio-streaming-daemon[1634]: # Running on port 7777 # Nov 24 13:32:42 volumio volumio-streaming-daemon[1634]: ############################ Nov 24 13:32:42 volumio volumio-streaming-daemon[1634]: Environment: production Nov 24 13:32:42 volumio volumio[1249]: { device: '5', Nov 24 13:32:42 volumio volumio[1249]: name: 'TEAC UD-501', Nov 24 13:32:42 volumio volumio[1249]: mixer: 'PCM', Nov 24 13:32:42 volumio volumio[1249]: mixertype: 'Hardware', Nov 24 13:32:42 volumio volumio[1249]: maxvolume: '100', Nov 24 13:32:42 volumio volumio[1249]: volumecurve: 'logarithmic', Nov 24 13:32:42 volumio volumio[1249]: volumestart: 'disabled', Nov 24 13:32:42 volumio volumio[1249]: volumesteps: '10' } Nov 24 13:32:42 volumio volumio[1249]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 24 13:32:42 volumio volumio[1249]: info: Updating Volume Controller Parameters: Device: 5 Name: TEAC UD-501 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Nov 24 13:32:42 volumio volumio-remote-updater[670]: [2024-11-24 13:32:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1732455159 101 Nov 24 13:32:42 volumio volumio[1249]: info: VolumeController:: Volume=100 Mute =true Nov 24 13:32:42 volumio volumio[1249]: info: CoreStateMachine::pushState Nov 24 13:32:42 volumio volumio[1249]: info: CorePlayQueue::getTrack 0 Nov 24 13:32:42 volumio volumio[1249]: info: CoreCommandRouter::volumioPushState Nov 24 13:32:42 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 13:32:42 volumio sudo[1645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 24 13:32:42 volumio sudo[1645]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:42 volumio sudo[1647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 24 13:32:42 volumio sudo[1645]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:42 volumio sudo[1647]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:42 volumio sudo[1647]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:42 volumio sudo[1663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volsimpleequal.service Nov 24 13:32:42 volumio sudo[1663]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:42 volumio systemd[1]: Starting alsaloop for volsimpleequal... Nov 24 13:32:42 volumio systemd[1]: Started alsaloop for volsimpleequal. Nov 24 13:32:42 volumio sudo[1663]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:42 volumio volumio[1249]: info: Alsaloop bridge ok Nov 24 13:32:42 volumio volumio[1249]: info: CoreCommandRouter::volumioGetState Nov 24 13:32:42 volumio volumio[1249]: info: CorePlayQueue::getTrack 0 Nov 24 13:32:42 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 24 13:32:42 volumio volumio[1249]: info: CoreCommandRouter::volumioGetVisibleSources Nov 24 13:32:42 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 13:32:42 volumio volumio[1249]: info: Listing playlists Nov 24 13:32:42 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 24 13:32:42 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Nov 24 13:32:42 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Nov 24 13:32:42 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 24 13:32:42 volumio volumio[1249]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 24 13:32:42 volumio volumio[1249]: info: MYVOLUMIO: Adding device Nov 24 13:32:42 volumio volumio[1249]: info: MYVOLUMIO: Evaluating Server Nov 24 13:32:43 volumio volumio[1249]: info: MyVolumio status changed Nov 24 13:32:43 volumio volumio[1249]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Nov 24 13:32:43 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 13:32:43 volumio volumio[1249]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Nov 24 13:32:43 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 13:32:43 volumio volumio[1249]: info: Streaming services startup Nov 24 13:32:43 volumio volumio[1249]: info: Re-Starting Streaming Daemon Nov 24 13:32:43 volumio volumio[1249]: info: Removing browser output: myVolumio user plan is not superstar Nov 24 13:32:43 volumio volumio[1249]: info: Removing audio output: Nov 24 13:32:43 volumio volumio[1249]: info: Stoppping Tunnel 1 Nov 24 13:32:43 volumio sudo[1694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 24 13:32:43 volumio sudo[1694]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:44 volumio volumio-streaming-daemon[1634]: Restarting daemon Nov 24 13:32:44 volumio volumio-streaming-daemon[1634]: Environment: production Nov 24 13:32:44 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 24 13:32:44 volumio sudo[1694]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:44 volumio volumio[1249]: info: Remote SSH Stopped Nov 24 13:32:44 volumio volumio[1249]: info: Setting Geolocation for MyVolumio to eu4 Nov 24 13:32:44 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:32:44 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:32:44 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:32:44 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Nov 24 13:32:44 volumio systemd[1]: Started Music Player Daemon. Nov 24 13:32:44 volumio sudo[1476]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:44 volumio volumio[1249]: error: updateQueue error: null Nov 24 13:32:44 volumio volumio[1249]: error: Failed LSINFO: Error: [50@0] {} No such directory Nov 24 13:32:44 volumio volumio-streaming-daemon[1634]: Environment: production Nov 24 13:32:44 volumio volumio[1249]: info: Fetching Streaming Services browse cache Nov 24 13:32:44 volumio volumio[1249]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Nov 24 13:32:44 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 13:32:44 volumio volumio[1249]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Nov 24 13:32:44 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 13:32:44 volumio volumio[1249]: error: updateQueue error: null Nov 24 13:32:45 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 13:32:45 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 13:32:45 volumio volumio[1249]: info: Updating MyVolumio device info Nov 24 13:32:45 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:32:45 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:32:45 volumio volumio-streaming-daemon[1634]: Environment: production Nov 24 13:32:45 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:32:46 volumio volumio[1249]: Cannot compose Albumart path Nov 24 13:32:46 volumio volumio[1249]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.834&uuid=fee41b2ab0829ddc6eac29cae7581a8c" http://updates.volumio.org:7070/downloader-v1/track-device Nov 24 13:32:46 volumio volumio[1249]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 24 13:32:46 volumio volumio[1249]: Dload Upload Total Spent Left Speed Nov 24 13:32:46 volumio volumio[1249]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Nov 24 13:32:46 volumio volumio[1249]: retrying in 5 seconds, trial 0 Nov 24 13:32:46 volumio volumio[1249]: Volumio Calling Home Nov 24 13:32:46 volumio volumio[1249]: info: Fetching Streaming Services browse cache Nov 24 13:32:46 volumio volumio[1249]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Nov 24 13:32:46 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 13:32:46 volumio volumio[1249]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Nov 24 13:32:46 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 13:32:46 volumio volumio[1249]: info: Enabling plugin volspotconnect2 Nov 24 13:32:46 volumio volumio[1249]: info: Loading plugin "volspotconnect2"... Nov 24 13:32:47 volumio volumio[1249]: info: PLUGIN START: volspotconnect2 Nov 24 13:32:47 volumio volumio[1249]: [SpotifyConnect] Creating VLS config file Nov 24 13:32:47 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:47 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:47 volumio volumio[1249]: [SpotifyConnect] Unimplemented debug mode!! Nov 24 13:32:47 volumio volumio[1249]: [SpotifyConnect] Starting metadata listener Nov 24 13:32:47 volumio sudo[1738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Nov 24 13:32:47 volumio sudo[1738]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:47 volumio systemd[1]: Starting Volspotconnect2 Daemon... Nov 24 13:32:47 volumio systemd[1]: Started Volspotconnect2 Daemon. Nov 24 13:32:47 volumio sudo[1738]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:47 volumio volumio[1249]: [SpotifyConnect] Vollibrespot Daemon service started! Nov 24 13:32:47 volumio volumio[1249]: [Metrics] SpotifyConnect: 0s 164.93ms Nov 24 13:32:47 volumio volumio[1249]: info: Done. Nov 24 13:32:47 volumio volumio[1743]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Nov 24 13:32:47 volumio volumio[1743]: Reading Config from "volspotify.toml" Nov 24 13:32:47 volumio volumio[1249]: info: Enabling plugin spop Nov 24 13:32:47 volumio volumio[1249]: info: Loading plugin "spop"... Nov 24 13:32:47 volumio volumio[1743]: [Vollibrespot] : Using Alsa backend with device: plughw:5 Nov 24 13:32:47 volumio volumio[1743]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Nov 24 13:32:49 volumio volumio[1249]: info: PLUGIN START: spop Nov 24 13:32:49 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 24 13:32:49 volumio volumio[1249]: [SpotifyConnect] Creating VLS config file Nov 24 13:32:49 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:49 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:49 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:49 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:49 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:49 volumio volumio[1249]: [SpotifyConnect] Starting metadata listener Nov 24 13:32:49 volumio sudo[1751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Nov 24 13:32:49 volumio sudo[1751]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:49 volumio sudo[1751]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:49 volumio volumio[1249]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Nov 24 13:32:49 volumio volumio[1249]: at Object._errnoException (util.js:1022:11) Nov 24 13:32:49 volumio volumio[1249]: at _exceptionWithHostPort (util.js:1044:20) Nov 24 13:32:49 volumio volumio[1249]: at _handle.lookup (dgram.js:266:18) Nov 24 13:32:49 volumio volumio[1249]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Nov 24 13:32:49 volumio volumio[1249]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 24 13:32:49 volumio volumio[1249]: [SpotifyConnect] Error [ERR_SOCKET_CANNOT_SEND]: Unable to send data Nov 24 13:32:49 volumio volumio[1249]: at Socket.onListenError (dgram.js:362:22) Nov 24 13:32:49 volumio volumio[1249]: at Object.onceWrapper (events.js:315:30) Nov 24 13:32:49 volumio volumio[1249]: at emitOne (events.js:121:20) Nov 24 13:32:49 volumio volumio[1249]: at Socket.emit (events.js:211:7) Nov 24 13:32:49 volumio volumio[1249]: at _handle.lookup (dgram.js:267:14) Nov 24 13:32:49 volumio volumio[1249]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Nov 24 13:32:49 volumio volumio[1249]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 24 13:32:49 volumio sudo[1757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Nov 24 13:32:49 volumio sudo[1757]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:49 volumio systemd[1]: Starting Volspotconnect2 Daemon... Nov 24 13:32:49 volumio systemd[1]: Started Volspotconnect2 Daemon. Nov 24 13:32:49 volumio volumio[1249]: info: Enabling plugin spop Nov 24 13:32:49 volumio volumio[1249]: info: Loading plugin "spop"... Nov 24 13:32:49 volumio sudo[1757]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:49 volumio volumio[1249]: info: PLUGIN START: spop Nov 24 13:32:49 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 24 13:32:49 volumio volumio[1249]: [SpotifyConnect] Creating VLS config file Nov 24 13:32:49 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:49 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:49 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:49 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:49 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:49 volumio volumio[1249]: [SpotifyConnect] Starting metadata listener Nov 24 13:32:49 volumio sudo[1773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Nov 24 13:32:49 volumio volumio[1249]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Nov 24 13:32:49 volumio volumio[1249]: at Object._errnoException (util.js:1022:11) Nov 24 13:32:49 volumio volumio[1249]: at _exceptionWithHostPort (util.js:1044:20) Nov 24 13:32:49 volumio volumio[1249]: at _handle.lookup (dgram.js:266:18) Nov 24 13:32:49 volumio volumio[1249]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Nov 24 13:32:49 volumio volumio[1249]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 24 13:32:49 volumio sudo[1773]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:49 volumio volumio[1249]: [SpotifyConnect] Error [ERR_SOCKET_CANNOT_SEND]: Unable to send data Nov 24 13:32:49 volumio volumio[1249]: at Socket.onListenError (dgram.js:362:22) Nov 24 13:32:49 volumio volumio[1249]: at Object.onceWrapper (events.js:315:30) Nov 24 13:32:49 volumio volumio[1249]: at emitOne (events.js:121:20) Nov 24 13:32:49 volumio volumio[1249]: at Socket.emit (events.js:211:7) Nov 24 13:32:49 volumio volumio[1249]: at _handle.lookup (dgram.js:267:14) Nov 24 13:32:49 volumio volumio[1249]: at _combinedTickCallback (internal/process/next_tick.js:141:11) Nov 24 13:32:49 volumio volumio[1249]: at process._tickCallback (internal/process/next_tick.js:180:9) Nov 24 13:32:49 volumio sudo[1773]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:49 volumio sudo[1777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Nov 24 13:32:49 volumio volumio[1249]: [SpotifyConnect] Vollibrespot Daemon service started! Nov 24 13:32:49 volumio volumio[1249]: [Metrics] SpotifyConnect: 0s 44.63ms Nov 24 13:32:49 volumio volumio[1249]: info: Done. Nov 24 13:32:49 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:49 volumio systemd[1]: Started Volspotconnect2 Daemon. Nov 24 13:32:49 volumio sudo[1777]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:49 volumio volumio[1249]: [SpotifyConnect] Vollibrespot Daemon service started! Nov 24 13:32:49 volumio volumio[1249]: [Metrics] SpotifyConnect: 0s 85.00ms Nov 24 13:32:49 volumio volumio[1249]: info: Done. Nov 24 13:32:49 volumio volumio[1767]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Nov 24 13:32:49 volumio volumio[1767]: Reading Config from "/tmp/volspotify.toml" Nov 24 13:32:49 volumio volumio[1767]: [Vollibrespot] : Using Alsa backend with device: plughw:Loopback Nov 24 13:32:49 volumio volumio[1767]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Nov 24 13:32:49 volumio dhcpd[1266]: DHCPDISCOVER from b8:27:eb:9b:e4:3d (volumio) via wlan0 Nov 24 13:32:49 volumio dhcpd[1266]: DHCPOFFER on 192.168.211.5 to b8:27:eb:9b:e4:3d (volumio) via wlan0 Nov 24 13:32:50 volumio volumio[1249]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Nov 24 13:32:50 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 24 13:32:50 volumio volumio[1249]: [SpotifyConnect] Closing metadata listener Nov 24 13:32:50 volumio volumio[1249]: info: Disabling plugin spop Nov 24 13:32:50 volumio volumio[1249]: info: Done. Nov 24 13:32:50 volumio sudo[1810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect.service Nov 24 13:32:50 volumio sudo[1810]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:50 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Nov 24 13:32:50 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Nov 24 13:32:50 volumio sudo[1810]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:50 volumio volumio[1249]: [SpotifyConnect] Vollibrespot Daemon service stoped! Nov 24 13:32:51 volumio volumio[1249]: [SpotifyConnect] Stopping Vollibrespot daemon Nov 24 13:32:51 volumio volumio[1249]: [SpotifyConnect] Closing metadata listener Nov 24 13:32:51 volumio volumio[1249]: info: Disabling plugin volspotconnect2 Nov 24 13:32:51 volumio volumio[1249]: info: Done. Nov 24 13:32:51 volumio sudo[1822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volspotconnect2.service Nov 24 13:32:51 volumio sudo[1822]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:51 volumio systemd[1]: Stopping Volspotconnect2 Daemon... Nov 24 13:32:51 volumio systemd[1]: Stopped Volspotconnect2 Daemon. Nov 24 13:32:51 volumio sudo[1822]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:51 volumio volumio[1249]: [SpotifyConnect] Vollibrespot Daemon service stoped! Nov 24 13:32:53 volumio volumio[1249]: info: Enabling plugin volspotconnect2 Nov 24 13:32:53 volumio volumio[1249]: info: Loading plugin "volspotconnect2"... Nov 24 13:32:53 volumio volumio[1249]: info: PLUGIN START: volspotconnect2 Nov 24 13:32:53 volumio volumio[1249]: [SpotifyConnect] Creating VLS config file Nov 24 13:32:53 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:53 volumio volumio[1249]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:32:53 volumio volumio[1249]: [SpotifyConnect] Unimplemented debug mode!! Nov 24 13:32:53 volumio volumio[1249]: [SpotifyConnect] Starting metadata listener Nov 24 13:32:53 volumio sudo[1834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Nov 24 13:32:53 volumio sudo[1834]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:32:53 volumio systemd[1]: Starting Volspotconnect2 Daemon... Nov 24 13:32:53 volumio systemd[1]: Started Volspotconnect2 Daemon. Nov 24 13:32:53 volumio sudo[1834]: pam_unix(sudo:session): session closed for user root Nov 24 13:32:53 volumio volumio[1839]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Nov 24 13:32:53 volumio volumio[1839]: Reading Config from "volspotify.toml" Nov 24 13:32:53 volumio volumio[1839]: [Vollibrespot] : Using Alsa backend with device: plughw:Loopback Nov 24 13:32:53 volumio volumio[1249]: [SpotifyConnect] Vollibrespot Daemon service started! Nov 24 13:32:53 volumio volumio[1249]: [Metrics] SpotifyConnect: 0s 66.48ms Nov 24 13:32:53 volumio volumio[1249]: info: Done. Nov 24 13:32:53 volumio volumio[1839]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Nov 24 13:32:53 volumio volumio[1249]: info: Checking Spotify Web API Nov 24 13:32:53 volumio volumio[1249]: info: Checking Spotify Web API Nov 24 13:32:53 volumio volumio[1249]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 13:32:53 volumio volumio[1249]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Nov 24 13:32:53 volumio volumio[1249]: at Socket._healthCheck (dgram.js:638:11) Nov 24 13:32:53 volumio volumio[1249]: at Socket.send (dgram.js:435:8) Nov 24 13:32:53 volumio volumio[1249]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:106:21) Nov 24 13:32:53 volumio volumio[1249]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2318:23) Nov 24 13:32:53 volumio volumio[1249]: at Timeout.setTimeout [as _onTimeout] (/data/plugins/music_service/spop/index.js:2410:18) Nov 24 13:32:53 volumio volumio[1249]: at ontimeout (timers.js:482:11) Nov 24 13:32:53 volumio volumio[1249]: at tryOnTimeout (timers.js:317:5) Nov 24 13:32:53 volumio volumio[1249]: at Timer.listOnTimeout (timers.js:277:5) Nov 24 13:32:53 volumio volumio[1249]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 13:32:54 volumio sudo[1854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-24 13:31 Nov 24 13:32:54 volumio sudo[1854]: 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="74e4cc9de715c64d553d35948d017f973a622b6d" VOLUMIO_FE_VERSION="2be6c28eb9de74ec1f9662ca333f7bd51a232c33" VOLUMIO_BE_VERSION="259a7f2894e1376413ffac331be84e0e9a6173dd" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 24 22:42:43 CEST 2020" VOLUMIO_VERSION="2.834" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="e41ef0f29aa50c1af109d3064a380c69"