-- Logs begin at Sun 2023-11-12 10:40:55 GMT, end at Sun 2023-11-12 10:42:36 GMT. -- Nov 12 10:41:00 chris-volumio dhcpcd[529]: Not running dhcpcd because /etc/network/interfaces Nov 12 10:41:00 chris-volumio dhcpcd[529]: defines some interfaces that will use a Nov 12 10:41:00 chris-volumio dhcpcd[529]: DHCP client or static address Nov 12 10:41:00 chris-volumio thd[528]: Unable to parse trigger line: Nov 12 10:41:00 chris-volumio thd[528]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Nov 12 10:41:00 chris-volumio thd[528]: Unable to parse trigger line: Nov 12 10:41:00 chris-volumio thd[528]: Found socket passed from systemd Nov 12 10:41:01 chris-volumio systemd-logind[521]: New seat seat0. Nov 12 10:41:01 chris-volumio sh[419]: eth0: waiting for carrier Nov 12 10:41:01 chris-volumio dhcpcd[465]: eth0: waiting for carrier Nov 12 10:41:01 chris-volumio kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Nov 12 10:41:01 chris-volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Nov 12 10:41:01 chris-volumio avahi-daemon[532]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Nov 12 10:41:01 chris-volumio avahi-daemon[532]: Successfully dropped root privileges. Nov 12 10:41:01 chris-volumio avahi-daemon[532]: avahi-daemon 0.7 starting up. Nov 12 10:41:01 chris-volumio systemd[1]: Started Login Service. Nov 12 10:41:01 chris-volumio avahi-daemon[532]: Successfully called chroot(). Nov 12 10:41:01 chris-volumio avahi-daemon[532]: Successfully dropped remaining capabilities. Nov 12 10:41:01 chris-volumio systemd[1]: Started triggerhappy global hotkey daemon. Nov 12 10:41:01 chris-volumio systemd[1]: hciuart.service: Succeeded. Nov 12 10:41:01 chris-volumio systemd[1]: Started Configure Bluetooth Modems connected by UART. Nov 12 10:41:01 chris-volumio systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Nov 12 10:41:01 chris-volumio systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Nov 12 10:41:01 chris-volumio systemd[1]: Failed to start dhcpcd on all interfaces. Nov 12 10:41:01 chris-volumio wpa_supplicant[519]: Successfully initialized wpa_supplicant Nov 12 10:41:01 chris-volumio systemd[1]: Started Save/Restore Sound Card State. Nov 12 10:41:01 chris-volumio avahi-daemon[532]: Loading service file /services/volumio.service. Nov 12 10:41:01 chris-volumio avahi-daemon[532]: Network interface enumeration completed. Nov 12 10:41:01 chris-volumio avahi-daemon[532]: Server startup complete. Host name is chris-volumio.local. Local service cookie is 2529062155. Nov 12 10:41:01 chris-volumio avahi-daemon[532]: Service "Chris-Volumio" (/services/volumio.service) successfully established. Nov 12 10:41:02 chris-volumio systemd[1]: Started Raise network interfaces. Nov 12 10:41:02 chris-volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Nov 12 10:41:02 chris-volumio systemd[1]: Started WPA supplicant. Nov 12 10:41:02 chris-volumio systemd[1]: Reached target Sound Card. Nov 12 10:41:02 chris-volumio systemd[1]: Reached target Network. Nov 12 10:41:02 chris-volumio volumio-remote-updater[523]: Error: No active session Nov 12 10:41:02 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:41:02] [info] asio async_connect error: system:111 (Connection refused) Nov 12 10:41:02 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:41:02] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 12 10:41:02 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:41:02] [error] handle_connect error: Underlying Transport Error Nov 12 10:41:02 chris-volumio systemd[1]: Starting Network Time Service... Nov 12 10:41:02 chris-volumio systemd[1]: Starting Permit User Sessions... Nov 12 10:41:02 chris-volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Nov 12 10:41:02 chris-volumio systemd[1]: Reached target Network is Online. Nov 12 10:41:02 chris-volumio systemd[1]: Starting /etc/rc.local Compatibility... Nov 12 10:41:02 chris-volumio systemd[1]: Starting LSB: Brings up/down network automatically... Nov 12 10:41:02 chris-volumio volumio[515]: Could not open config: /tmp/upmpdcli.conf Nov 12 10:41:02 chris-volumio systemd[1]: Started AdGuard Home: Network-level blocker. Nov 12 10:41:02 chris-volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 12 10:41:02 chris-volumio haveged[454]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Nov 12 10:41:02 chris-volumio haveged[454]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Nov 12 10:41:02 chris-volumio haveged[454]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00202 Nov 12 10:41:02 chris-volumio haveged[454]: haveged: fills: 0, generated: 0 Nov 12 10:41:02 chris-volumio systemd[1]: Starting OpenBSD Secure Shell server... Nov 12 10:41:02 chris-volumio systemd[1]: Starting Samba NMB Daemon... Nov 12 10:41:02 chris-volumio loadcpufreq[536]: Loading cpufreq kernel modules...done (none). Nov 12 10:41:02 chris-volumio ntpd[585]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 12 10:41:02 chris-volumio ntpd[585]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Nov 12 10:41:02 chris-volumio systemd[1]: Starting Music Player Daemon... Nov 12 10:41:02 chris-volumio systemd[1]: Starting Raspberry Pi bluetooth helper... Nov 12 10:41:02 chris-volumio ntpd[633]: proto: precision = 2.148 usec (-19) Nov 12 10:41:02 chris-volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Nov 12 10:41:02 chris-volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Nov 12 10:41:02 chris-volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Nov 12 10:41:02 chris-volumio systemd[1]: Started Network Time Service. Nov 12 10:41:02 chris-volumio ntpd[633]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 12 10:41:02 chris-volumio ntpd[633]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 12 10:41:02 chris-volumio systemd[1]: Started Permit User Sessions. Nov 12 10:41:02 chris-volumio systemd[1]: Started /etc/rc.local Compatibility. Nov 12 10:41:02 chris-volumio systemd[1]: iptables.service: Succeeded. Nov 12 10:41:02 chris-volumio systemd[1]: Starting Hold until boot process finishes up... Nov 12 10:41:02 chris-volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Nov 12 10:41:02 chris-volumio ntpd[633]: Listen and drop on 0 v6wildcard [::]:123 Nov 12 10:41:02 chris-volumio ntpd[633]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 12 10:41:02 chris-volumio ntpd[633]: Listen normally on 2 lo 127.0.0.1:123 Nov 12 10:41:02 chris-volumio ntpd[633]: Listening on routing socket on fd #19 for interface updates Nov 12 10:41:02 chris-volumio ntpd[633]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 10:41:02 chris-volumio ntpd[633]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 12 10:41:02 chris-volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Nov 12 10:41:03 chris-volumio systemd[1]: plymouth-start.service: Succeeded. Nov 12 10:41:03 chris-volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Nov 12 10:41:03 chris-volumio systemd[1]: Started Hold until boot process finishes up. Nov 12 10:41:03 chris-volumio systemd[1]: plymouth-quit.service: Succeeded. Nov 12 10:41:03 chris-volumio systemd[1]: Started Terminate Plymouth Boot Screen. Nov 12 10:41:03 chris-volumio systemd[1]: Received SIGRTMIN+21 from PID 265 (n/a). Nov 12 10:41:03 chris-volumio systemd[1]: Received SIGRTMIN+21 from PID 265 (n/a). Nov 12 10:41:03 chris-volumio bthelper[634]: Raspberry Pi BDADDR already set Nov 12 10:41:03 chris-volumio ifplugd(eth0)[668]: ifplugd 0.28 initializing. Nov 12 10:41:03 chris-volumio ifplugd(eth0)[668]: Using interface eth0/E4:5F:01:84:B2:57 with driver (version: 6.1.58-v7l+) Nov 12 10:41:03 chris-volumio ifplugd(eth0)[668]: Using detection mode: SIOCETHTOOL Nov 12 10:41:03 chris-volumio ifplugd(eth0)[668]: Initialization complete, link beat not detected. Nov 12 10:41:03 chris-volumio systemd[1]: Started Raspberry Pi bluetooth helper. Nov 12 10:41:03 chris-volumio AdGuardHome[597]: 2023/11/12 10:41:03 [info] service: AdGuard Home, version v0.107.71 Nov 12 10:41:03 chris-volumio AdGuardHome[597]: 2023/11/12 10:41:03 [info] service: control action=run Nov 12 10:41:03 chris-volumio AdGuardHome[597]: 2023/11/12 10:41:03.275296 [info] service: starting adguard home version="AdGuard Home, version v0.107.71" Nov 12 10:41:03 chris-volumio AdGuardHome[597]: 2023/11/12 10:41:03.275933 [info] service: adguard home is running as a service Nov 12 10:41:03 chris-volumio ifplugd[594]: Network Interface Plugging Daemon...start eth0...done. Nov 12 10:41:03 chris-volumio AdGuardHome[597]: 2023/11/12 10:41:03.290803 [info] service: this is the first time adguard home has been launched Nov 12 10:41:03 chris-volumio AdGuardHome[597]: 2023/11/12 10:41:03.290942 [info] service: checking if adguard home has the necessary permissions Nov 12 10:41:03 chris-volumio sshd[670]: Server listening on 0.0.0.0 port 22. Nov 12 10:41:03 chris-volumio systemd[1]: Started OpenBSD Secure Shell server. Nov 12 10:41:03 chris-volumio sshd[670]: Server listening on :: port 22. Nov 12 10:41:03 chris-volumio systemd[1]: Started LSB: Brings up/down network automatically. Nov 12 10:41:03 chris-volumio AdGuardHome[597]: 2023/11/12 10:41:03.371495 [info] service: adguard home can bind to port 53 Nov 12 10:41:03 chris-volumio AdGuardHome[597]: 2023/11/12 10:41:03.391629 [info] dhcpd: warning: creating dhcpv4 server err="dhcpv4: invalid IP is not an IPv4 address" Nov 12 10:41:03 chris-volumio systemd[1]: Starting Bluetooth service... Nov 12 10:41:03 chris-volumio systemd[1]: Started Getty on tty1. Nov 12 10:41:03 chris-volumio systemd[1]: Reached target Login Prompts. Nov 12 10:41:03 chris-volumio AdGuardHome[597]: 2023/11/12 10:41:03.425961 [info] tls_manager: using default ciphers Nov 12 10:41:03 chris-volumio AdGuardHome[597]: 2023/11/12 10:41:03.441346 [info] webapi: initializing Nov 12 10:41:03 chris-volumio AdGuardHome[597]: 2023/11/12 10:41:03.441636 [info] webapi: This is the first launch of AdGuard Home, redirecting everything to /install.html Nov 12 10:41:03 chris-volumio AdGuardHome[597]: 2023/11/12 10:41:03.444150 [info] permcheck: warning: found unexpected permissions type=directory path=/home/volumio/AdGuardHome perm=0755 want=0700 Nov 12 10:41:03 chris-volumio AdGuardHome[597]: 2023/11/12 10:41:03.445065 [info] webapi: AdGuard Home is available at the following addresses: Nov 12 10:41:03 chris-volumio AdGuardHome[597]: 2023/11/12 10:41:03.446500 [info] go to http://127.0.0.1:3000 Nov 12 10:41:03 chris-volumio AdGuardHome[597]: 2023/11/12 10:41:03.446815 [info] service: starting plain server server=plain addr=0.0.0.0:3000 Nov 12 10:41:03 chris-volumio cpufrequtils[642]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Nov 12 10:41:03 chris-volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Nov 12 10:41:03 chris-volumio bluetoothd[679]: Bluetooth daemon 5.50 Nov 12 10:41:03 chris-volumio bluetoothd[679]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Nov 12 10:41:03 chris-volumio systemd[1]: Started Bluetooth service. Nov 12 10:41:03 chris-volumio systemd[1]: Reached target Bluetooth. Nov 12 10:41:03 chris-volumio bluetoothd[679]: Starting SDP server Nov 12 10:41:03 chris-volumio bluetoothd[679]: Excluding (cli) sap Nov 12 10:41:03 chris-volumio systemd[1]: systemd-rfkill.service: Succeeded. Nov 12 10:41:03 chris-volumio bluetoothd[679]: Bluetooth management interface 1.22 initialized Nov 12 10:41:03 chris-volumio dbus-daemon[516]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=679 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Nov 12 10:41:03 chris-volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Nov 12 10:41:03 chris-volumio kernel: Bluetooth: BNEP filters: protocol multicast Nov 12 10:41:03 chris-volumio kernel: Bluetooth: BNEP socket layer initialized Nov 12 10:41:03 chris-volumio kernel: Bluetooth: MGMT ver 1.22 Nov 12 10:41:03 chris-volumio bluetoothd[679]: Failed to set privacy: Rejected (0x0b) Nov 12 10:41:03 chris-volumio systemd[1]: Starting Hostname Service... Nov 12 10:41:03 chris-volumio systemd[1]: Reloading. Nov 12 10:41:03 chris-volumio ntpd[633]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 12 10:41:04 chris-volumio dbus-daemon[516]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 12 10:41:04 chris-volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 12 10:41:04 chris-volumio nmbd[637]: [2023/11/12 10:41:04.201558, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Nov 12 10:41:04 chris-volumio nmbd[637]: started asyncdns process 704 Nov 12 10:41:04 chris-volumio nmbd[637]: [2023/11/12 10:41:04.204491, 0] ../lib/util/become_daemon.c:149(daemon_status) Nov 12 10:41:04 chris-volumio nmbd[637]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Nov 12 10:41:04 chris-volumio nmbd[637]: [2023/11/12 10:41:04.204942, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Nov 12 10:41:04 chris-volumio nmbd[637]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Nov 12 10:41:04 chris-volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 12 10:41:04 chris-volumio systemd[1]: volumiossh.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 12 10:41:04 chris-volumio systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 12 10:41:04 chris-volumio systemd[1]: Started Hostname Service. Nov 12 10:41:04 chris-volumio wireless.js[530]: Cleaning previous... Nov 12 10:41:04 chris-volumio sudo[714]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 12 10:41:04 chris-volumio sudo[714]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:04 chris-volumio sudo[714]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:04 chris-volumio systemd[1]: Reloading. Nov 12 10:41:04 chris-volumio sudo[717]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 12 10:41:04 chris-volumio sudo[717]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:04 chris-volumio ntpd[633]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 12 10:41:04 chris-volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 12 10:41:05 chris-volumio sudo[717]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:05 chris-volumio mpd[683]: Nov 12 10:41 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 12 10:41:05 chris-volumio systemd[1]: Reloading. Nov 12 10:41:05 chris-volumio wireless.js[530]: Stopped aP Nov 12 10:41:05 chris-volumio sudo[737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 12 10:41:05 chris-volumio sudo[737]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:05 chris-volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 12 10:41:05 chris-volumio dhcpcd[465]: eth0: carrier acquired Nov 12 10:41:05 chris-volumio sh[419]: eth0: carrier acquired Nov 12 10:41:05 chris-volumio sudo[737]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:05 chris-volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Nov 12 10:41:05 chris-volumio sh[419]: DUID 00:01:00:01:29:d8:61:9d:e4:5f:01:84:b2:57 Nov 12 10:41:05 chris-volumio sh[419]: eth0: IAID 01:84:b2:57 Nov 12 10:41:05 chris-volumio sh[419]: eth0: adding address fe80::6ab1:1634:d088:29ad Nov 12 10:41:05 chris-volumio sh[419]: ipv6_addaddr1: Permission denied Nov 12 10:41:05 chris-volumio dhcpcd[465]: DUID 00:01:00:01:29:d8:61:9d:e4:5f:01:84:b2:57 Nov 12 10:41:05 chris-volumio dhcpcd[465]: eth0: IAID 01:84:b2:57 Nov 12 10:41:05 chris-volumio dhcpcd[465]: eth0: adding address fe80::6ab1:1634:d088:29ad Nov 12 10:41:05 chris-volumio dhcpcd[465]: ipv6_addaddr1: Permission denied Nov 12 10:41:05 chris-volumio sudo[753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Nov 12 10:41:05 chris-volumio sudo[753]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:05 chris-volumio sudo[753]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:05 chris-volumio sudo[760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 12 10:41:05 chris-volumio sudo[760]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:05 chris-volumio mpd[683]: Nov 12 10:41 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 12 10:41:05 chris-volumio mpd[683]: Nov 12 10:41 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 12 10:41:05 chris-volumio sudo[760]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:05 chris-volumio sudo[762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Nov 12 10:41:05 chris-volumio sudo[762]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:05 chris-volumio sh[419]: eth0: soliciting an IPv6 router Nov 12 10:41:05 chris-volumio dhcpcd[465]: eth0: soliciting an IPv6 router Nov 12 10:41:05 chris-volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Nov 12 10:41:05 chris-volumio sh[419]: eth0: soliciting a DHCP lease Nov 12 10:41:05 chris-volumio dhcpcd[465]: eth0: soliciting a DHCP lease Nov 12 10:41:05 chris-volumio systemd[1]: Started Music Player Daemon. Nov 12 10:41:05 chris-volumio systemd[1]: volumiossh.service: Succeeded. Nov 12 10:41:05 chris-volumio systemd[1]: Started Volumio SSH enabler. Nov 12 10:41:05 chris-volumio ntpd[633]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 12 10:41:06 chris-volumio ifplugd(eth0)[668]: Link beat detected. Nov 12 10:41:06 chris-volumio ifplugd(eth0)[668]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Nov 12 10:41:06 chris-volumio ifplugd(eth0)[668]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Nov 12 10:41:06 chris-volumio ntpd[633]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 12 10:41:07 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:41:07] [connect] Successful connection Nov 12 10:41:07 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:41:07] [error] Server handshake response error: websocketpp.processor:20 (Invalid HTTP status.) Nov 12 10:41:08 chris-volumio sudo[762]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:08 chris-volumio wireless.js[530]: SETTING APPROPRIATE REG DOMAIN: GB Nov 12 10:41:08 chris-volumio sudo[774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Nov 12 10:41:08 chris-volumio sudo[774]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:08 chris-volumio sudo[774]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:08 chris-volumio sudo[776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set GB Nov 12 10:41:08 chris-volumio sudo[776]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:08 chris-volumio sudo[776]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:08 chris-volumio wireless.js[530]: SUCCESSFULLY SET NEW REGDOMAIN: GB Nov 12 10:41:08 chris-volumio wireless.js[530]: Start wireless flow Nov 12 10:41:08 chris-volumio wireless.js[530]: Stopped hotspot (if there).. Nov 12 10:41:08 chris-volumio sudo[781]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 12 10:41:08 chris-volumio sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:08 chris-volumio sudo[781]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:08 chris-volumio sudo[783]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 12 10:41:08 chris-volumio sudo[783]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:08 chris-volumio bthelper[634]: Changing power off succeeded Nov 12 10:41:08 chris-volumio bthelper[634]: [CHG] Controller E4:5F:01:84:B2:59 Class: 0x0000041c Nov 12 10:41:08 chris-volumio bthelper[634]: Changing power on succeeded Nov 12 10:41:08 chris-volumio sudo[783]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:08 chris-volumio wireless.js[530]: DHCP IP Nov 12 10:41:08 chris-volumio wireless.js[530]: Start ap Nov 12 10:41:08 chris-volumio wpa_supplicant[792]: Successfully initialized wpa_supplicant Nov 12 10:41:08 chris-volumio sudo[793]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Nov 12 10:41:08 chris-volumio sudo[793]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:08 chris-volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 12 10:41:08 chris-volumio dhcpcd[794]: dev: loaded udev Nov 12 10:41:08 chris-volumio dhcpcd-run-hooks[803]: wlan0: starting wpa_supplicant Nov 12 10:41:09 chris-volumio dhcpcd-run-hooks[808]: wlan0: failed to start wpa_supplicant Nov 12 10:41:09 chris-volumio dhcpcd-run-hooks[809]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Nov 12 10:41:09 chris-volumio dhcpcd[794]: wlan0: connected to Access Point `' Nov 12 10:41:09 chris-volumio dhcpcd[794]: DUID 00:01:00:01:29:d8:61:9d:e4:5f:01:84:b2:57 Nov 12 10:41:09 chris-volumio dhcpcd[794]: eth0: IAID 01:84:b2:57 Nov 12 10:41:09 chris-volumio dhcpcd[794]: eth0: adding address fe80::6ab1:1634:d088:29ad Nov 12 10:41:09 chris-volumio dhcpcd[794]: ipv6_addaddr1: Permission denied Nov 12 10:41:09 chris-volumio dhcpcd[794]: wlan0: waiting for carrier Nov 12 10:41:09 chris-volumio dhcpcd[794]: eth0: soliciting an IPv6 router Nov 12 10:41:09 chris-volumio dhcpcd[794]: eth0: soliciting a DHCP lease Nov 12 10:41:09 chris-volumio wireless.js[530]: trying... Nov 12 10:41:09 chris-volumio sudo[819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:09 chris-volumio sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:09 chris-volumio sudo[819]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:10 chris-volumio sh[419]: eth0: probing for an IPv4LL address Nov 12 10:41:10 chris-volumio dhcpcd[465]: eth0: probing for an IPv4LL address Nov 12 10:41:10 chris-volumio wireless.js[530]: trying... Nov 12 10:41:10 chris-volumio sudo[822]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:10 chris-volumio sudo[822]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:10 chris-volumio sudo[822]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:11 chris-volumio wireless.js[530]: trying... Nov 12 10:41:11 chris-volumio sudo[825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:11 chris-volumio sudo[825]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:11 chris-volumio sudo[825]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:12 chris-volumio wireless.js[530]: trying... Nov 12 10:41:12 chris-volumio sudo[828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:12 chris-volumio sudo[828]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:12 chris-volumio sudo[828]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:13 chris-volumio wireless.js[530]: trying... Nov 12 10:41:13 chris-volumio sudo[831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:13 chris-volumio sudo[831]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:13 chris-volumio sudo[831]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:14 chris-volumio dhcpcd[794]: eth0: probing for an IPv4LL address Nov 12 10:41:14 chris-volumio wireless.js[530]: trying... Nov 12 10:41:14 chris-volumio sudo[834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:14 chris-volumio sudo[834]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:14 chris-volumio sudo[834]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:14 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:41:14] [connect] Successful connection Nov 12 10:41:14 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:41:14] [error] Server handshake response error: websocketpp.processor:20 (Invalid HTTP status.) Nov 12 10:41:15 chris-volumio wireless.js[530]: trying... Nov 12 10:41:15 chris-volumio sudo[837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:15 chris-volumio sudo[837]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:15 chris-volumio sudo[837]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:15 chris-volumio sh[419]: eth0: using IPv4LL address 169.254.179.144 Nov 12 10:41:15 chris-volumio sh[419]: eth0: adding route to 169.254.0.0/16 Nov 12 10:41:15 chris-volumio sh[419]: eth0: adding default route Nov 12 10:41:15 chris-volumio dhcpcd[465]: eth0: using IPv4LL address 169.254.179.144 Nov 12 10:41:15 chris-volumio avahi-daemon[532]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.179.144. Nov 12 10:41:15 chris-volumio avahi-daemon[532]: New relevant interface eth0.IPv4 for mDNS. Nov 12 10:41:15 chris-volumio dhcpcd[465]: eth0: adding route to 169.254.0.0/16 Nov 12 10:41:15 chris-volumio avahi-daemon[532]: Registering new address record for 169.254.179.144 on eth0.IPv4. Nov 12 10:41:15 chris-volumio dhcpcd[465]: eth0: adding default route Nov 12 10:41:15 chris-volumio sh[419]: forked to background, child pid 849 Nov 12 10:41:15 chris-volumio dhcpcd[465]: forked to background, child pid 849 Nov 12 10:41:16 chris-volumio ifplugd(eth0)[668]: client: ifup: interface eth0 already configured Nov 12 10:41:16 chris-volumio sh[419]: eth0=eth0 Nov 12 10:41:16 chris-volumio ifplugd(eth0)[668]: Program executed successfully. Nov 12 10:41:16 chris-volumio wireless.js[530]: trying... Nov 12 10:41:16 chris-volumio sudo[876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:16 chris-volumio sudo[876]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:16 chris-volumio sudo[876]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:17 chris-volumio wireless.js[530]: trying... Nov 12 10:41:17 chris-volumio sudo[879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:17 chris-volumio sudo[879]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:17 chris-volumio sudo[879]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:17 chris-volumio ntpd[633]: Listen normally on 3 eth0 169.254.179.144:123 Nov 12 10:41:17 chris-volumio ntpd[633]: new interface(s) found: waking up resolver Nov 12 10:41:18 chris-volumio wireless.js[530]: trying... Nov 12 10:41:18 chris-volumio sudo[882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:18 chris-volumio sudo[882]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:18 chris-volumio sudo[882]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:19 chris-volumio nmbd[637]: [2023/11/12 10:41:19.211059, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 12 10:41:19 chris-volumio nmbd[637]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 12 10:41:19 chris-volumio systemd[1]: Started Samba NMB Daemon. Nov 12 10:41:19 chris-volumio systemd[1]: Starting Samba Winbind Daemon... Nov 12 10:41:19 chris-volumio winbindd[884]: [2023/11/12 10:41:19.452799, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 12 10:41:19 chris-volumio winbindd[884]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 12 10:41:19 chris-volumio winbindd[884]: [2023/11/12 10:41:19.466290, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 12 10:41:19 chris-volumio winbindd[884]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 12 10:41:19 chris-volumio systemd[1]: Started Samba Winbind Daemon. Nov 12 10:41:19 chris-volumio systemd[1]: Starting Samba SMB Daemon... Nov 12 10:41:19 chris-volumio wireless.js[530]: trying... Nov 12 10:41:19 chris-volumio sudo[891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:19 chris-volumio sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:19 chris-volumio sudo[891]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:19 chris-volumio dhcpcd[794]: eth0: using IPv4LL address 169.254.179.144 Nov 12 10:41:19 chris-volumio dhcpcd[794]: eth0: adding route to 169.254.0.0/16 Nov 12 10:41:19 chris-volumio dhcpcd[794]: eth0: adding default route Nov 12 10:41:19 chris-volumio winbindd[884]: [2023/11/12 10:41:19.794245, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Nov 12 10:41:19 chris-volumio winbindd[884]: res_names->count = 3, expected 4 Nov 12 10:41:19 chris-volumio smbd[889]: [2023/11/12 10:41:19.819655, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 12 10:41:19 chris-volumio smbd[889]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 12 10:41:19 chris-volumio systemd[1]: Started Samba SMB Daemon. Nov 12 10:41:19 chris-volumio dhcpcd[794]: forked to background, child pid 910 Nov 12 10:41:19 chris-volumio sudo[793]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:20 chris-volumio wireless.js[530]: trying... Nov 12 10:41:20 chris-volumio sudo[913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:20 chris-volumio sudo[913]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:20 chris-volumio sudo[913]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:21 chris-volumio wireless.js[530]: trying... Nov 12 10:41:21 chris-volumio sudo[916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:21 chris-volumio sudo[916]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:21 chris-volumio sudo[916]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:22 chris-volumio wireless.js[530]: trying... Nov 12 10:41:22 chris-volumio sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:22 chris-volumio sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:22 chris-volumio sudo[919]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:23 chris-volumio wireless.js[530]: trying... Nov 12 10:41:23 chris-volumio sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:23 chris-volumio sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:23 chris-volumio sudo[922]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:24 chris-volumio wireless.js[530]: trying... Nov 12 10:41:24 chris-volumio sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:24 chris-volumio sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:24 chris-volumio sudo[925]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:24 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:41:24] [connect] Successful connection Nov 12 10:41:24 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:41:24] [error] Server handshake response error: websocketpp.processor:20 (Invalid HTTP status.) Nov 12 10:41:25 chris-volumio wireless.js[530]: trying... Nov 12 10:41:25 chris-volumio sudo[928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:25 chris-volumio sudo[928]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:25 chris-volumio sudo[928]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:26 chris-volumio wpa_supplicant[807]: wlan0: Failed to initiate sched scan Nov 12 10:41:26 chris-volumio wireless.js[530]: trying... Nov 12 10:41:26 chris-volumio sudo[931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:26 chris-volumio sudo[931]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:26 chris-volumio sudo[931]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:27 chris-volumio wireless.js[530]: trying... Nov 12 10:41:27 chris-volumio sudo[934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:27 chris-volumio sudo[934]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:27 chris-volumio sudo[934]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:28 chris-volumio wireless.js[530]: trying... Nov 12 10:41:28 chris-volumio systemd[1]: systemd-fsckd.service: Succeeded. Nov 12 10:41:28 chris-volumio sudo[937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 12 10:41:28 chris-volumio sudo[937]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:28 chris-volumio sudo[937]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:29 chris-volumio wireless.js[530]: Overtime, starting plan B Nov 12 10:41:29 chris-volumio wireless.js[530]: STARTING HOTSPOT Nov 12 10:41:29 chris-volumio wpa_supplicant[807]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Nov 12 10:41:29 chris-volumio wpa_supplicant[807]: nl80211: Failed to set IPv4 unicast in multicast filter Nov 12 10:41:29 chris-volumio wpa_supplicant[807]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Nov 12 10:41:29 chris-volumio wpa_supplicant[807]: nl80211: Failed to set IPv4 unicast in multicast filter Nov 12 10:41:29 chris-volumio wpa_supplicant[807]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Nov 12 10:41:29 chris-volumio wpa_supplicant[807]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Nov 12 10:41:29 chris-volumio wpa_supplicant[807]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Nov 12 10:41:30 chris-volumio wpa_supplicant[807]: wlan0: CTRL-EVENT-TERMINATING Nov 12 10:41:32 chris-volumio sudo[946]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 12 10:41:32 chris-volumio sudo[946]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:32 chris-volumio sudo[946]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:32 chris-volumio sudo[948]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 12 10:41:32 chris-volumio sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:32 chris-volumio sudo[948]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:32 chris-volumio wireless.js[530]: Hotspot is disabled, not starting it Nov 12 10:41:32 chris-volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 12 10:41:32 chris-volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 12 10:41:32 chris-volumio systemd[1]: Started Wireless Services. Nov 12 10:41:32 chris-volumio systemd[1]: Started Volumio Backend Module. Nov 12 10:41:32 chris-volumio systemd[1]: Started Volumio Cpu Tweaker. Nov 12 10:41:32 chris-volumio systemd[1]: Reached target Multi-User System. Nov 12 10:41:32 chris-volumio systemd[1]: Reached target Graphical Interface. Nov 12 10:41:32 chris-volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 12 10:41:33 chris-volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 12 10:41:33 chris-volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 12 10:41:33 chris-volumio systemd[1]: Startup finished in 11.862s (kernel) + 39.721s (userspace) = 51.583s. Nov 12 10:41:33 chris-volumio volumio-cpu-tweak[955]: Setting RT Priority for mpd Nov 12 10:41:33 chris-volumio volumio-cpu-tweak[955]: Setting MPD Affinity Nov 12 10:41:33 chris-volumio volumio-cpu-tweak[955]: pid 683's current affinity mask: f Nov 12 10:41:33 chris-volumio volumio-cpu-tweak[955]: pid 683's new affinity mask: 3 Nov 12 10:41:33 chris-volumio volumio-cpu-tweak[955]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Nov 12 10:41:33 chris-volumio volumio-cpu-tweak[955]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Nov 12 10:41:33 chris-volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Nov 12 10:41:34 chris-volumio systemd[1]: systemd-hostnamed.service: Succeeded. Nov 12 10:41:34 chris-volumio volumio[954]: info: ------------------------------------------- Nov 12 10:41:34 chris-volumio volumio[954]: info: ----- Volumio3 ---- Nov 12 10:41:34 chris-volumio volumio[954]: info: ------------------------------------------- Nov 12 10:41:34 chris-volumio volumio[954]: info: ----- System startup ---- Nov 12 10:41:34 chris-volumio volumio[954]: info: ------------------------------------------- Nov 12 10:41:34 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:41:34] [connect] Successful connection Nov 12 10:41:34 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:41:34] [error] Server handshake response error: websocketpp.processor:20 (Invalid HTTP status.) Nov 12 10:41:35 chris-volumio volumio[954]: info: MYVOLUMIO Environment detected Nov 12 10:41:35 chris-volumio volumio[954]: info: Plugin folders cleanup Nov 12 10:41:35 chris-volumio volumio[954]: info: Scanning into folder /volumio/app/plugins/ Nov 12 10:41:35 chris-volumio volumio[954]: info: Scanning category audio_interface Nov 12 10:41:35 chris-volumio volumio[954]: info: Scanning category miscellanea Nov 12 10:41:35 chris-volumio volumio[954]: info: Scanning category music_service Nov 12 10:41:35 chris-volumio volumio[954]: info: Scanning category plugins.json Nov 12 10:41:35 chris-volumio volumio[954]: info: Scanning category system_controller Nov 12 10:41:35 chris-volumio volumio[954]: info: Scanning category user_interface Nov 12 10:41:35 chris-volumio volumio[954]: info: Scanning into folder /data/plugins/ Nov 12 10:41:35 chris-volumio volumio[954]: info: Scanning category audio_interface Nov 12 10:41:35 chris-volumio volumio[954]: info: Scanning category music_service Nov 12 10:41:35 chris-volumio volumio[954]: info: Scanning category user_interface Nov 12 10:41:35 chris-volumio volumio[954]: info: Plugin folders cleanup completed Nov 12 10:41:35 chris-volumio volumio[954]: info: ------------------------------------------- Nov 12 10:41:35 chris-volumio volumio[954]: info: ----- Core plugins startup ---- Nov 12 10:41:35 chris-volumio volumio[954]: info: ------------------------------------------- Nov 12 10:41:35 chris-volumio volumio[954]: info: Loading plugins from folder /volumio/app/plugins/ Nov 12 10:41:35 chris-volumio volumio[954]: info: Adding plugin upnp to MyMusic Plugins Nov 12 10:41:35 chris-volumio volumio[954]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 12 10:41:35 chris-volumio volumio[954]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 12 10:41:35 chris-volumio volumio[954]: info: Loading plugins from folder /data/plugins/ Nov 12 10:41:35 chris-volumio volumio[954]: info: Loading plugin "system"... Nov 12 10:41:35 chris-volumio volumio[954]: info: Loading plugin "appearance"... Nov 12 10:41:36 chris-volumio volumio[954]: info: Loading plugin "network"... Nov 12 10:41:36 chris-volumio volumio[954]: info: Refreshing Cached IP Addresses Nov 12 10:41:36 chris-volumio sudo[985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 12 10:41:36 chris-volumio sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:36 chris-volumio sudo[985]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:36 chris-volumio sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 12 10:41:36 chris-volumio sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:36 chris-volumio volumio[954]: info: Loading plugin "services"... Nov 12 10:41:36 chris-volumio sudo[987]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:36 chris-volumio volumio[954]: info: Loading plugin "alsa_controller"... Nov 12 10:41:36 chris-volumio sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 12 10:41:36 chris-volumio sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:36 chris-volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 12 10:41:36 chris-volumio volumio[954]: info: Loading plugin "wizard"... Nov 12 10:41:36 chris-volumio volumio[954]: info: Loading plugin "volumio_command_line_client"... Nov 12 10:41:36 chris-volumio volumio[954]: info: Loading plugin "upnp"... Nov 12 10:41:36 chris-volumio volumio[954]: info: [1699785696629] Starting Upmpd Daemon Nov 12 10:41:36 chris-volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:41:36 chris-volumio volumio[954]: info: Loading plugin "my_music"... Nov 12 10:41:36 chris-volumio volumio[954]: info: Loading plugin "mpd"... Nov 12 10:41:36 chris-volumio volumio[954]: info: Creating MPD Configuration file Nov 12 10:41:36 chris-volumio volumio[954]: info: Loading plugin "upnp_browser"... Nov 12 10:41:36 chris-volumio sudo[996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 12 10:41:36 chris-volumio sudo[996]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:36 chris-volumio sudo[996]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:36 chris-volumio sudo[998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 12 10:41:36 chris-volumio sudo[998]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:36 chris-volumio systemd[1]: Stopping Music Player Daemon... Nov 12 10:41:37 chris-volumio systemd[1]: mpd.service: Succeeded. Nov 12 10:41:37 chris-volumio systemd[1]: Stopped Music Player Daemon. Nov 12 10:41:37 chris-volumio systemd[1]: Starting Music Player Daemon... Nov 12 10:41:37 chris-volumio volumio[954]: info: Loading plugin "networkfs"... Nov 12 10:41:37 chris-volumio volumio[954]: info: Starting Udev Watcher for removable devices Nov 12 10:41:37 chris-volumio sudo[1007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=chrisfox@ymail.com,password=cx7euaIfF3V8,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.22/Music /mnt/NAS/Music Nov 12 10:41:37 chris-volumio sudo[1007]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:37 chris-volumio kernel: Key type cifs.spnego registered Nov 12 10:41:37 chris-volumio kernel: Key type cifs.idmap registered Nov 12 10:41:37 chris-volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Nov 12 10:41:37 chris-volumio kernel: CIFS: Attempting to mount \\192.168.1.22\Music Nov 12 10:41:37 chris-volumio volumio[954]: info: Ignoring mount for partition: boot Nov 12 10:41:37 chris-volumio volumio[954]: info: Ignoring mount for partition: volumio Nov 12 10:41:37 chris-volumio volumio[954]: info: Ignoring mount for partition: volumio_data Nov 12 10:41:37 chris-volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:41:37 chris-volumio volumio[954]: info: Loading plugin "alarm-clock"... Nov 12 10:41:37 chris-volumio mpd[1004]: Nov 12 10:41 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 12 10:41:37 chris-volumio volumio[954]: info: Loading plugin "airplay_emulation"... Nov 12 10:41:37 chris-volumio volumio[954]: info: Starting Shairport Sync Nov 12 10:41:37 chris-volumio volumio[954]: info: Loading plugin "last_100"... Nov 12 10:41:37 chris-volumio volumio[954]: info: Loading plugin "webradio"... Nov 12 10:41:37 chris-volumio mpd[1004]: Nov 12 10:41 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 12 10:41:37 chris-volumio mpd[1004]: Nov 12 10:41 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 12 10:41:37 chris-volumio systemd[1]: Started Music Player Daemon. Nov 12 10:41:37 chris-volumio sudo[998]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:38 chris-volumio volumio[954]: info: Loading plugin "i2s_dacs"... Nov 12 10:41:38 chris-volumio volumio[954]: info: Loading plugin "volumiodiscovery"... Nov 12 10:41:38 chris-volumio volumio[954]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 12 10:41:38 chris-volumio volumio[954]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:41:38 chris-volumio volumio[954]: *** WARNING *** For more information see Nov 12 10:41:38 chris-volumio node[954]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 12 10:41:38 chris-volumio volumio[954]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 12 10:41:38 chris-volumio volumio[954]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:41:38 chris-volumio volumio[954]: *** WARNING *** For more information see Nov 12 10:41:38 chris-volumio node[954]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:41:38 chris-volumio node[954]: *** WARNING *** For more information see Nov 12 10:41:38 chris-volumio node[954]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 12 10:41:38 chris-volumio node[954]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:41:38 chris-volumio node[954]: *** WARNING *** For more information see Nov 12 10:41:38 chris-volumio volumio[954]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 12 10:41:38 chris-volumio volumio[954]: info: Discovery: Started advertising with name: Chris-Volumio Nov 12 10:41:38 chris-volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:41:38 chris-volumio volumio[954]: info: Loading plugin "ytcr"... Nov 12 10:41:39 chris-volumio sudo[990]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:41 chris-volumio volumio[954]: info: Loading plugin "now_playing"... Nov 12 10:41:41 chris-volumio volumio[954]: info: Loading plugin "outputs"... Nov 12 10:41:41 chris-volumio volumio[954]: info: Loading plugin "albumart"... Nov 12 10:41:41 chris-volumio volumio[954]: info: Plugin example_plugin is not enabled Nov 12 10:41:41 chris-volumio volumio[954]: info: Loading plugin "inputs"... Nov 12 10:41:41 chris-volumio volumio[954]: info: Loading plugin "updater_comm"... Nov 12 10:41:41 chris-volumio volumio[954]: info: Plugin mpdemulation is not enabled Nov 12 10:41:41 chris-volumio volumio[954]: info: Loading plugin "rest_api"... Nov 12 10:41:41 chris-volumio volumio[954]: info: Loading plugin "websocket"... Nov 12 10:41:41 chris-volumio volumio[954]: info: Loading plugin "fusiondsp"... Nov 12 10:41:41 chris-volumio volumio[954]: Forking 3 albumart workers Nov 12 10:41:42 chris-volumio volumio[954]: info: Applying required configuration parameters for plugin fusiondsp Nov 12 10:41:42 chris-volumio volumio[954]: Starting albumart workers Nov 12 10:41:42 chris-volumio volumio[954]: info: Loading plugin "RoonBridge"... Nov 12 10:41:42 chris-volumio volumio[954]: Starting albumart workers Nov 12 10:41:42 chris-volumio volumio[954]: Starting albumart workers Nov 12 10:41:42 chris-volumio volumio[954]: info: Applying required configuration parameters for plugin RoonBridge Nov 12 10:41:42 chris-volumio volumio[954]: info: Loading plugin "radio_paradise"... Nov 12 10:41:43 chris-volumio volumio[954]: info: Applying required configuration parameters for plugin radio_paradise Nov 12 10:41:43 chris-volumio volumio[954]: info: [1699785703010] [RadioParadise] API delay: 5 Nov 12 10:41:43 chris-volumio volumio[954]: info: Loading plugin "lastfm"... Nov 12 10:41:43 chris-volumio sudo[1007]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:43 chris-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 12 10:41:43 chris-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Nov 12 10:41:44 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:41:44] [connect] Successful connection Nov 12 10:41:44 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:41:44] [error] Server handshake response error: websocketpp.processor:20 (Invalid HTTP status.) Nov 12 10:41:45 chris-volumio volumio[954]: info: Loading i18n strings for locale en Nov 12 10:41:45 chris-volumio volumio[954]: Updating browse sources language Nov 12 10:41:45 chris-volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 12 10:41:45 chris-volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 12 10:41:45 chris-volumio volumio[954]: info: CoreCommandRouter::initPlayerControls Nov 12 10:41:45 chris-volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:45 chris-volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:45 chris-volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:45 chris-volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:45 chris-volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:45 chris-volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:45 chris-volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:45 chris-volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:45 chris-volumio volumio[954]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 12 10:41:45 chris-volumio volumio[954]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 12 10:41:45 chris-volumio volumio[954]: Error: listen EADDRINUSE: address already in use :::3000 Nov 12 10:41:45 chris-volumio volumio[954]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Nov 12 10:41:45 chris-volumio volumio[954]: at listenInCluster (net.js:1366:12) Nov 12 10:41:45 chris-volumio volumio[954]: at Server.listen (net.js:1452:7) Nov 12 10:41:45 chris-volumio volumio[954]: at Function.listen (/volumio/node_modules/express/lib/application.js:618:24) Nov 12 10:41:45 chris-volumio volumio[954]: at Object. (/volumio/index.js:26:29) Nov 12 10:41:45 chris-volumio volumio[954]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Nov 12 10:41:45 chris-volumio volumio[954]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Nov 12 10:41:45 chris-volumio volumio[954]: at Module.load (internal/modules/cjs/loader.js:928:32) Nov 12 10:41:45 chris-volumio volumio[954]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Nov 12 10:41:45 chris-volumio volumio[954]: at Function.executeUserEntryPoint [as runMain] (internal/modules/run_main.js:72:12) { Nov 12 10:41:45 chris-volumio volumio[954]: code: 'EADDRINUSE', Nov 12 10:41:45 chris-volumio volumio[954]: errno: -98, Nov 12 10:41:45 chris-volumio volumio[954]: syscall: 'listen', Nov 12 10:41:45 chris-volumio volumio[954]: address: '::', Nov 12 10:41:45 chris-volumio volumio[954]: port: 3000 Nov 12 10:41:45 chris-volumio volumio[954]: } Nov 12 10:41:45 chris-volumio volumio[954]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 12 10:41:45 chris-volumio sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-11-12 10:40 Nov 12 10:41:45 chris-volumio sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:45 chris-volumio sudo[1085]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:45 chris-volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 12 10:41:45 chris-volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 12 10:41:45 chris-volumio systemd[1]: Started dynamicswap service. Nov 12 10:41:45 chris-volumio systemd[1]: dynamicswap.service: Succeeded. Nov 12 10:41:46 chris-volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 12 10:41:46 chris-volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Nov 12 10:41:46 chris-volumio systemd[1]: Stopped Volumio Backend Module. Nov 12 10:41:46 chris-volumio systemd[1]: Started Volumio Backend Module. Nov 12 10:41:46 chris-volumio systemd[1]: Started dynamicswap service. Nov 12 10:41:46 chris-volumio systemd[1]: dynamicswap.service: Succeeded. Nov 12 10:41:47 chris-volumio volumio[1101]: info: ------------------------------------------- Nov 12 10:41:47 chris-volumio volumio[1101]: info: ----- Volumio3 ---- Nov 12 10:41:47 chris-volumio volumio[1101]: info: ------------------------------------------- Nov 12 10:41:47 chris-volumio volumio[1101]: info: ----- System startup ---- Nov 12 10:41:47 chris-volumio volumio[1101]: info: ------------------------------------------- Nov 12 10:41:47 chris-volumio volumio[1101]: info: MYVOLUMIO Environment detected Nov 12 10:41:47 chris-volumio volumio[1101]: info: Plugin folders cleanup Nov 12 10:41:47 chris-volumio volumio[1101]: info: Scanning into folder /volumio/app/plugins/ Nov 12 10:41:47 chris-volumio volumio[1101]: info: Scanning category audio_interface Nov 12 10:41:47 chris-volumio volumio[1101]: info: Scanning category miscellanea Nov 12 10:41:47 chris-volumio volumio[1101]: info: Scanning category music_service Nov 12 10:41:47 chris-volumio volumio[1101]: info: Scanning category plugins.json Nov 12 10:41:47 chris-volumio volumio[1101]: info: Scanning category system_controller Nov 12 10:41:47 chris-volumio volumio[1101]: info: Scanning category user_interface Nov 12 10:41:47 chris-volumio volumio[1101]: info: Scanning into folder /data/plugins/ Nov 12 10:41:47 chris-volumio volumio[1101]: info: Scanning category audio_interface Nov 12 10:41:47 chris-volumio volumio[1101]: info: Scanning category music_service Nov 12 10:41:47 chris-volumio volumio[1101]: info: Scanning category user_interface Nov 12 10:41:47 chris-volumio volumio[1101]: info: Plugin folders cleanup completed Nov 12 10:41:47 chris-volumio volumio[1101]: info: ------------------------------------------- Nov 12 10:41:47 chris-volumio volumio[1101]: info: ----- Core plugins startup ---- Nov 12 10:41:47 chris-volumio volumio[1101]: info: ------------------------------------------- Nov 12 10:41:47 chris-volumio volumio[1101]: info: Loading plugins from folder /volumio/app/plugins/ Nov 12 10:41:47 chris-volumio volumio[1101]: info: Adding plugin upnp to MyMusic Plugins Nov 12 10:41:47 chris-volumio volumio[1101]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 12 10:41:47 chris-volumio volumio[1101]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 12 10:41:47 chris-volumio volumio[1101]: info: Loading plugins from folder /data/plugins/ Nov 12 10:41:47 chris-volumio volumio[1101]: info: Loading plugin "system"... Nov 12 10:41:47 chris-volumio volumio[1101]: info: Loading plugin "appearance"... Nov 12 10:41:48 chris-volumio volumio[1101]: info: Loading plugin "network"... Nov 12 10:41:48 chris-volumio volumio[1101]: info: Refreshing Cached IP Addresses Nov 12 10:41:48 chris-volumio sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 12 10:41:48 chris-volumio sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:48 chris-volumio sudo[1125]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:48 chris-volumio volumio[1101]: info: Loading plugin "services"... Nov 12 10:41:48 chris-volumio sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 12 10:41:48 chris-volumio sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:48 chris-volumio volumio[1101]: info: Loading plugin "alsa_controller"... Nov 12 10:41:48 chris-volumio sudo[1127]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:48 chris-volumio sudo[1130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 12 10:41:48 chris-volumio sudo[1130]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:48 chris-volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 12 10:41:48 chris-volumio volumio[1101]: info: Loading plugin "wizard"... Nov 12 10:41:48 chris-volumio volumio[1101]: info: Loading plugin "volumio_command_line_client"... Nov 12 10:41:48 chris-volumio volumio[1101]: info: Loading plugin "upnp"... Nov 12 10:41:48 chris-volumio volumio[1101]: info: [1699785708222] Starting Upmpd Daemon Nov 12 10:41:48 chris-volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:41:48 chris-volumio volumio[1101]: info: Loading plugin "my_music"... Nov 12 10:41:48 chris-volumio volumio[1101]: info: Loading plugin "mpd"... Nov 12 10:41:48 chris-volumio volumio[1101]: info: Creating MPD Configuration file Nov 12 10:41:48 chris-volumio volumio[1101]: info: Loading plugin "upnp_browser"... Nov 12 10:41:48 chris-volumio sudo[1136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 12 10:41:48 chris-volumio sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:48 chris-volumio sudo[1136]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:48 chris-volumio sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 12 10:41:48 chris-volumio sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:48 chris-volumio systemd[1]: Stopping Music Player Daemon... Nov 12 10:41:48 chris-volumio systemd[1]: mpd.service: Succeeded. Nov 12 10:41:48 chris-volumio systemd[1]: Stopped Music Player Daemon. Nov 12 10:41:48 chris-volumio systemd[1]: Starting Music Player Daemon... Nov 12 10:41:48 chris-volumio volumio[1101]: info: Loading plugin "networkfs"... Nov 12 10:41:48 chris-volumio volumio[1101]: info: Starting Udev Watcher for removable devices Nov 12 10:41:48 chris-volumio sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=chrisfox@ymail.com,password=cx7euaIfF3V8,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.22/Music /mnt/NAS/Music Nov 12 10:41:48 chris-volumio sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:48 chris-volumio kernel: CIFS: Attempting to mount \\192.168.1.22\Music Nov 12 10:41:48 chris-volumio volumio[1101]: info: Ignoring mount for partition: boot Nov 12 10:41:48 chris-volumio volumio[1101]: info: Ignoring mount for partition: volumio Nov 12 10:41:48 chris-volumio volumio[1101]: info: Ignoring mount for partition: volumio_data Nov 12 10:41:48 chris-volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:41:48 chris-volumio volumio[1101]: info: Loading plugin "alarm-clock"... Nov 12 10:41:49 chris-volumio volumio[1101]: info: Loading plugin "airplay_emulation"... Nov 12 10:41:49 chris-volumio volumio[1101]: info: Starting Shairport Sync Nov 12 10:41:49 chris-volumio volumio[1101]: info: Loading plugin "last_100"... Nov 12 10:41:49 chris-volumio volumio[1101]: info: Loading plugin "webradio"... Nov 12 10:41:49 chris-volumio volumio[1101]: info: Loading plugin "i2s_dacs"... Nov 12 10:41:49 chris-volumio volumio[1101]: info: Loading plugin "volumiodiscovery"... Nov 12 10:41:49 chris-volumio volumio[1101]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 12 10:41:49 chris-volumio volumio[1101]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:41:49 chris-volumio node[1101]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 12 10:41:49 chris-volumio volumio[1101]: *** WARNING *** For more information see Nov 12 10:41:49 chris-volumio volumio[1101]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 12 10:41:49 chris-volumio volumio[1101]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:41:49 chris-volumio volumio[1101]: *** WARNING *** For more information see Nov 12 10:41:49 chris-volumio node[1101]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:41:49 chris-volumio node[1101]: *** WARNING *** For more information see Nov 12 10:41:49 chris-volumio node[1101]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 12 10:41:49 chris-volumio node[1101]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:41:49 chris-volumio node[1101]: *** WARNING *** For more information see Nov 12 10:41:49 chris-volumio volumio[1101]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 12 10:41:49 chris-volumio volumio[1101]: info: Discovery: Started advertising with name: Chris-Volumio Nov 12 10:41:49 chris-volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:41:49 chris-volumio volumio[1101]: info: Loading plugin "ytcr"... Nov 12 10:41:49 chris-volumio mpd[1143]: Nov 12 10:41 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 12 10:41:49 chris-volumio mpd[1143]: Nov 12 10:41 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 12 10:41:49 chris-volumio mpd[1143]: Nov 12 10:41 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 12 10:41:49 chris-volumio systemd[1]: Started Music Player Daemon. Nov 12 10:41:49 chris-volumio sudo[1138]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:50 chris-volumio volumio[1101]: info: Loading plugin "now_playing"... Nov 12 10:41:50 chris-volumio volumio[1101]: info: Loading plugin "outputs"... Nov 12 10:41:50 chris-volumio volumio[1101]: info: Loading plugin "albumart"... Nov 12 10:41:50 chris-volumio volumio[1101]: info: Plugin example_plugin is not enabled Nov 12 10:41:50 chris-volumio volumio[1101]: info: Loading plugin "inputs"... Nov 12 10:41:50 chris-volumio volumio[1101]: info: Loading plugin "updater_comm"... Nov 12 10:41:50 chris-volumio volumio[1101]: info: Plugin mpdemulation is not enabled Nov 12 10:41:50 chris-volumio volumio[1101]: info: Loading plugin "rest_api"... Nov 12 10:41:50 chris-volumio volumio[1101]: info: Loading plugin "websocket"... Nov 12 10:41:50 chris-volumio volumio[1101]: info: Loading plugin "fusiondsp"... Nov 12 10:41:50 chris-volumio volumio[1101]: Forking 3 albumart workers Nov 12 10:41:50 chris-volumio volumio[1101]: info: Applying required configuration parameters for plugin fusiondsp Nov 12 10:41:50 chris-volumio sudo[1130]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:50 chris-volumio volumio[1101]: info: Loading plugin "RoonBridge"... Nov 12 10:41:51 chris-volumio volumio[1101]: info: Applying required configuration parameters for plugin RoonBridge Nov 12 10:41:51 chris-volumio volumio[1101]: info: Loading plugin "radio_paradise"... Nov 12 10:41:51 chris-volumio volumio[1101]: info: Applying required configuration parameters for plugin radio_paradise Nov 12 10:41:51 chris-volumio volumio[1101]: info: [1699785711199] [RadioParadise] API delay: 5 Nov 12 10:41:51 chris-volumio volumio[1101]: info: Loading plugin "lastfm"... Nov 12 10:41:51 chris-volumio volumio[1101]: Starting albumart workers Nov 12 10:41:51 chris-volumio volumio[1101]: Starting albumart workers Nov 12 10:41:51 chris-volumio volumio[1101]: Starting albumart workers Nov 12 10:41:51 chris-volumio volumio[1101]: info: Loading i18n strings for locale en Nov 12 10:41:51 chris-volumio volumio[1101]: Updating browse sources language Nov 12 10:41:51 chris-volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 12 10:41:51 chris-volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 12 10:41:51 chris-volumio volumio[1101]: info: CoreCommandRouter::initPlayerControls Nov 12 10:41:51 chris-volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:51 chris-volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:51 chris-volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:51 chris-volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:51 chris-volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:51 chris-volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:51 chris-volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:51 chris-volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:51 chris-volumio volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 12 10:41:51 chris-volumio volumio[1101]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 12 10:41:51 chris-volumio volumio[1101]: Error: listen EADDRINUSE: address already in use :::3000 Nov 12 10:41:51 chris-volumio volumio[1101]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Nov 12 10:41:51 chris-volumio volumio[1101]: at listenInCluster (net.js:1366:12) Nov 12 10:41:51 chris-volumio volumio[1101]: at Server.listen (net.js:1452:7) Nov 12 10:41:51 chris-volumio volumio[1101]: at Function.listen (/volumio/node_modules/express/lib/application.js:618:24) Nov 12 10:41:51 chris-volumio volumio[1101]: at Object. (/volumio/index.js:26:29) Nov 12 10:41:51 chris-volumio volumio[1101]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Nov 12 10:41:51 chris-volumio volumio[1101]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Nov 12 10:41:51 chris-volumio volumio[1101]: at Module.load (internal/modules/cjs/loader.js:928:32) Nov 12 10:41:51 chris-volumio volumio[1101]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Nov 12 10:41:51 chris-volumio volumio[1101]: at Function.executeUserEntryPoint [as runMain] (internal/modules/run_main.js:72:12) { Nov 12 10:41:51 chris-volumio volumio[1101]: code: 'EADDRINUSE', Nov 12 10:41:51 chris-volumio volumio[1101]: errno: -98, Nov 12 10:41:51 chris-volumio volumio[1101]: syscall: 'listen', Nov 12 10:41:51 chris-volumio volumio[1101]: address: '::', Nov 12 10:41:51 chris-volumio volumio[1101]: port: 3000 Nov 12 10:41:51 chris-volumio volumio[1101]: } Nov 12 10:41:51 chris-volumio volumio[1101]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 12 10:41:52 chris-volumio sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-11-12 10:40 Nov 12 10:41:52 chris-volumio sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:52 chris-volumio sudo[1215]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:52 chris-volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 12 10:41:52 chris-volumio sudo[1146]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:52 chris-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 12 10:41:52 chris-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -4 Nov 12 10:41:52 chris-volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 12 10:41:52 chris-volumio systemd[1]: Started dynamicswap service. Nov 12 10:41:52 chris-volumio systemd[1]: dynamicswap.service: Succeeded. Nov 12 10:41:52 chris-volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 12 10:41:52 chris-volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Nov 12 10:41:52 chris-volumio systemd[1]: Stopped Volumio Backend Module. Nov 12 10:41:52 chris-volumio systemd[1]: Started Volumio Backend Module. Nov 12 10:41:52 chris-volumio systemd[1]: Started dynamicswap service. Nov 12 10:41:52 chris-volumio systemd[1]: dynamicswap.service: Succeeded. Nov 12 10:41:53 chris-volumio volumio[1228]: info: ------------------------------------------- Nov 12 10:41:53 chris-volumio volumio[1228]: info: ----- Volumio3 ---- Nov 12 10:41:53 chris-volumio volumio[1228]: info: ------------------------------------------- Nov 12 10:41:53 chris-volumio volumio[1228]: info: ----- System startup ---- Nov 12 10:41:53 chris-volumio volumio[1228]: info: ------------------------------------------- Nov 12 10:41:53 chris-volumio volumio[1228]: info: MYVOLUMIO Environment detected Nov 12 10:41:54 chris-volumio volumio[1228]: info: Plugin folders cleanup Nov 12 10:41:54 chris-volumio volumio[1228]: info: Scanning into folder /volumio/app/plugins/ Nov 12 10:41:54 chris-volumio volumio[1228]: info: Scanning category audio_interface Nov 12 10:41:54 chris-volumio volumio[1228]: info: Scanning category miscellanea Nov 12 10:41:54 chris-volumio volumio[1228]: info: Scanning category music_service Nov 12 10:41:54 chris-volumio volumio[1228]: info: Scanning category plugins.json Nov 12 10:41:54 chris-volumio volumio[1228]: info: Scanning category system_controller Nov 12 10:41:54 chris-volumio volumio[1228]: info: Scanning category user_interface Nov 12 10:41:54 chris-volumio volumio[1228]: info: Scanning into folder /data/plugins/ Nov 12 10:41:54 chris-volumio volumio[1228]: info: Scanning category audio_interface Nov 12 10:41:54 chris-volumio volumio[1228]: info: Scanning category music_service Nov 12 10:41:54 chris-volumio volumio[1228]: info: Scanning category user_interface Nov 12 10:41:54 chris-volumio volumio[1228]: info: Plugin folders cleanup completed Nov 12 10:41:54 chris-volumio volumio[1228]: info: ------------------------------------------- Nov 12 10:41:54 chris-volumio volumio[1228]: info: ----- Core plugins startup ---- Nov 12 10:41:54 chris-volumio volumio[1228]: info: ------------------------------------------- Nov 12 10:41:54 chris-volumio volumio[1228]: info: Loading plugins from folder /volumio/app/plugins/ Nov 12 10:41:54 chris-volumio volumio[1228]: info: Adding plugin upnp to MyMusic Plugins Nov 12 10:41:54 chris-volumio volumio[1228]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 12 10:41:54 chris-volumio volumio[1228]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 12 10:41:54 chris-volumio volumio[1228]: info: Loading plugins from folder /data/plugins/ Nov 12 10:41:54 chris-volumio volumio[1228]: info: Loading plugin "system"... Nov 12 10:41:54 chris-volumio volumio[1228]: info: Loading plugin "appearance"... Nov 12 10:41:54 chris-volumio volumio[1228]: info: Loading plugin "network"... Nov 12 10:41:54 chris-volumio volumio[1228]: info: Refreshing Cached IP Addresses Nov 12 10:41:54 chris-volumio sudo[1252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 12 10:41:54 chris-volumio sudo[1252]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:54 chris-volumio sudo[1252]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:54 chris-volumio sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 12 10:41:54 chris-volumio sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:54 chris-volumio volumio[1228]: info: Loading plugin "services"... Nov 12 10:41:54 chris-volumio volumio[1228]: info: Loading plugin "alsa_controller"... Nov 12 10:41:54 chris-volumio sudo[1254]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:54 chris-volumio sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 12 10:41:54 chris-volumio sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:54 chris-volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 12 10:41:54 chris-volumio volumio[1228]: info: Loading plugin "wizard"... Nov 12 10:41:54 chris-volumio volumio[1228]: info: Loading plugin "volumio_command_line_client"... Nov 12 10:41:54 chris-volumio volumio[1228]: info: Loading plugin "upnp"... Nov 12 10:41:54 chris-volumio volumio[1228]: info: [1699785714694] Starting Upmpd Daemon Nov 12 10:41:54 chris-volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:41:54 chris-volumio volumio[1228]: info: Loading plugin "my_music"... Nov 12 10:41:54 chris-volumio volumio[1228]: info: Loading plugin "mpd"... Nov 12 10:41:54 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:41:54] [connect] Successful connection Nov 12 10:41:54 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:41:54] [error] Server handshake response error: websocketpp.processor:20 (Invalid HTTP status.) Nov 12 10:41:54 chris-volumio volumio[1228]: info: Creating MPD Configuration file Nov 12 10:41:54 chris-volumio volumio[1228]: info: Loading plugin "upnp_browser"... Nov 12 10:41:54 chris-volumio sudo[1263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 12 10:41:54 chris-volumio sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:54 chris-volumio sudo[1263]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:54 chris-volumio sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 12 10:41:54 chris-volumio sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:54 chris-volumio systemd[1]: Stopping Music Player Daemon... Nov 12 10:41:54 chris-volumio systemd[1]: mpd.service: Succeeded. Nov 12 10:41:54 chris-volumio systemd[1]: Stopped Music Player Daemon. Nov 12 10:41:55 chris-volumio systemd[1]: Starting Music Player Daemon... Nov 12 10:41:55 chris-volumio volumio[1228]: info: Loading plugin "networkfs"... Nov 12 10:41:55 chris-volumio volumio[1228]: info: Starting Udev Watcher for removable devices Nov 12 10:41:55 chris-volumio sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=chrisfox@ymail.com,password=cx7euaIfF3V8,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.22/Music /mnt/NAS/Music Nov 12 10:41:55 chris-volumio sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:55 chris-volumio kernel: CIFS: Attempting to mount \\192.168.1.22\Music Nov 12 10:41:55 chris-volumio volumio[1228]: info: Ignoring mount for partition: boot Nov 12 10:41:55 chris-volumio volumio[1228]: info: Ignoring mount for partition: volumio Nov 12 10:41:55 chris-volumio volumio[1228]: info: Ignoring mount for partition: volumio_data Nov 12 10:41:55 chris-volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:41:55 chris-volumio volumio[1228]: info: Loading plugin "alarm-clock"... Nov 12 10:41:55 chris-volumio volumio[1228]: info: Loading plugin "airplay_emulation"... Nov 12 10:41:55 chris-volumio volumio[1228]: info: Starting Shairport Sync Nov 12 10:41:55 chris-volumio volumio[1228]: info: Loading plugin "last_100"... Nov 12 10:41:55 chris-volumio volumio[1228]: info: Loading plugin "webradio"... Nov 12 10:41:55 chris-volumio volumio[1228]: info: Loading plugin "i2s_dacs"... Nov 12 10:41:55 chris-volumio volumio[1228]: info: Loading plugin "volumiodiscovery"... Nov 12 10:41:55 chris-volumio volumio[1228]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 12 10:41:55 chris-volumio volumio[1228]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:41:55 chris-volumio volumio[1228]: *** WARNING *** For more information see Nov 12 10:41:55 chris-volumio volumio[1228]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 12 10:41:55 chris-volumio node[1228]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 12 10:41:55 chris-volumio volumio[1228]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:41:55 chris-volumio volumio[1228]: *** WARNING *** For more information see Nov 12 10:41:55 chris-volumio node[1228]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:41:55 chris-volumio node[1228]: *** WARNING *** For more information see Nov 12 10:41:55 chris-volumio node[1228]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 12 10:41:55 chris-volumio node[1228]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:41:55 chris-volumio node[1228]: *** WARNING *** For more information see Nov 12 10:41:55 chris-volumio volumio[1228]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 12 10:41:55 chris-volumio volumio[1228]: info: Discovery: Started advertising with name: Chris-Volumio Nov 12 10:41:55 chris-volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:41:55 chris-volumio volumio[1228]: info: Loading plugin "ytcr"... Nov 12 10:41:55 chris-volumio mpd[1270]: Nov 12 10:41 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 12 10:41:55 chris-volumio mpd[1270]: Nov 12 10:41 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 12 10:41:55 chris-volumio mpd[1270]: Nov 12 10:41 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 12 10:41:55 chris-volumio systemd[1]: Started Music Player Daemon. Nov 12 10:41:55 chris-volumio sudo[1265]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:56 chris-volumio volumio[1228]: info: Loading plugin "now_playing"... Nov 12 10:41:56 chris-volumio volumio[1228]: info: Loading plugin "outputs"... Nov 12 10:41:56 chris-volumio volumio[1228]: info: Loading plugin "albumart"... Nov 12 10:41:56 chris-volumio volumio[1228]: info: Plugin example_plugin is not enabled Nov 12 10:41:56 chris-volumio volumio[1228]: info: Loading plugin "inputs"... Nov 12 10:41:56 chris-volumio volumio[1228]: info: Loading plugin "updater_comm"... Nov 12 10:41:57 chris-volumio volumio[1228]: info: Plugin mpdemulation is not enabled Nov 12 10:41:57 chris-volumio volumio[1228]: info: Loading plugin "rest_api"... Nov 12 10:41:57 chris-volumio volumio[1228]: info: Loading plugin "websocket"... Nov 12 10:41:57 chris-volumio volumio[1228]: info: Loading plugin "fusiondsp"... Nov 12 10:41:57 chris-volumio volumio[1228]: Forking 3 albumart workers Nov 12 10:41:57 chris-volumio volumio[1228]: info: Applying required configuration parameters for plugin fusiondsp Nov 12 10:41:57 chris-volumio sudo[1257]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:57 chris-volumio volumio[1228]: info: Loading plugin "RoonBridge"... Nov 12 10:41:57 chris-volumio volumio[1228]: info: Applying required configuration parameters for plugin RoonBridge Nov 12 10:41:57 chris-volumio volumio[1228]: info: Loading plugin "radio_paradise"... Nov 12 10:41:57 chris-volumio volumio[1228]: info: Applying required configuration parameters for plugin radio_paradise Nov 12 10:41:57 chris-volumio volumio[1228]: info: [1699785717724] [RadioParadise] API delay: 5 Nov 12 10:41:57 chris-volumio volumio[1228]: Starting albumart workers Nov 12 10:41:57 chris-volumio volumio[1228]: info: Loading plugin "lastfm"... Nov 12 10:41:57 chris-volumio volumio[1228]: Starting albumart workers Nov 12 10:41:57 chris-volumio volumio[1228]: Starting albumart workers Nov 12 10:41:58 chris-volumio volumio[1228]: info: Loading i18n strings for locale en Nov 12 10:41:58 chris-volumio volumio[1228]: Updating browse sources language Nov 12 10:41:58 chris-volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 12 10:41:58 chris-volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 12 10:41:58 chris-volumio volumio[1228]: info: CoreCommandRouter::initPlayerControls Nov 12 10:41:58 chris-volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:58 chris-volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:58 chris-volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:58 chris-volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:58 chris-volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:58 chris-volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:58 chris-volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:58 chris-volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:41:58 chris-volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 12 10:41:58 chris-volumio volumio[1228]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 12 10:41:58 chris-volumio volumio[1228]: Error: listen EADDRINUSE: address already in use :::3000 Nov 12 10:41:58 chris-volumio volumio[1228]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Nov 12 10:41:58 chris-volumio volumio[1228]: at listenInCluster (net.js:1366:12) Nov 12 10:41:58 chris-volumio volumio[1228]: at Server.listen (net.js:1452:7) Nov 12 10:41:58 chris-volumio volumio[1228]: at Function.listen (/volumio/node_modules/express/lib/application.js:618:24) Nov 12 10:41:58 chris-volumio volumio[1228]: at Object. (/volumio/index.js:26:29) Nov 12 10:41:58 chris-volumio volumio[1228]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Nov 12 10:41:58 chris-volumio volumio[1228]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Nov 12 10:41:58 chris-volumio volumio[1228]: at Module.load (internal/modules/cjs/loader.js:928:32) Nov 12 10:41:58 chris-volumio volumio[1228]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Nov 12 10:41:58 chris-volumio volumio[1228]: at Function.executeUserEntryPoint [as runMain] (internal/modules/run_main.js:72:12) { Nov 12 10:41:58 chris-volumio volumio[1228]: code: 'EADDRINUSE', Nov 12 10:41:58 chris-volumio volumio[1228]: errno: -98, Nov 12 10:41:58 chris-volumio volumio[1228]: syscall: 'listen', Nov 12 10:41:58 chris-volumio volumio[1228]: address: '::', Nov 12 10:41:58 chris-volumio volumio[1228]: port: 3000 Nov 12 10:41:58 chris-volumio volumio[1228]: } Nov 12 10:41:58 chris-volumio volumio[1228]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 12 10:41:58 chris-volumio sudo[1342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-11-12 10:40 Nov 12 10:41:58 chris-volumio sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:41:58 chris-volumio sudo[1342]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:58 chris-volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 12 10:41:58 chris-volumio sudo[1273]: pam_unix(sudo:session): session closed for user root Nov 12 10:41:58 chris-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 12 10:41:58 chris-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -4 Nov 12 10:41:58 chris-volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 12 10:41:58 chris-volumio systemd[1]: Started dynamicswap service. Nov 12 10:41:58 chris-volumio systemd[1]: dynamicswap.service: Succeeded. Nov 12 10:41:59 chris-volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 12 10:41:59 chris-volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Nov 12 10:41:59 chris-volumio systemd[1]: Stopped Volumio Backend Module. Nov 12 10:41:59 chris-volumio systemd[1]: Started Volumio Backend Module. Nov 12 10:41:59 chris-volumio systemd[1]: Started dynamicswap service. Nov 12 10:41:59 chris-volumio systemd[1]: dynamicswap.service: Succeeded. Nov 12 10:42:00 chris-volumio volumio[1355]: info: ------------------------------------------- Nov 12 10:42:00 chris-volumio volumio[1355]: info: ----- Volumio3 ---- Nov 12 10:42:00 chris-volumio volumio[1355]: info: ------------------------------------------- Nov 12 10:42:00 chris-volumio volumio[1355]: info: ----- System startup ---- Nov 12 10:42:00 chris-volumio volumio[1355]: info: ------------------------------------------- Nov 12 10:42:00 chris-volumio volumio[1355]: info: MYVOLUMIO Environment detected Nov 12 10:42:00 chris-volumio volumio[1355]: info: Plugin folders cleanup Nov 12 10:42:00 chris-volumio volumio[1355]: info: Scanning into folder /volumio/app/plugins/ Nov 12 10:42:00 chris-volumio volumio[1355]: info: Scanning category audio_interface Nov 12 10:42:00 chris-volumio volumio[1355]: info: Scanning category miscellanea Nov 12 10:42:00 chris-volumio volumio[1355]: info: Scanning category music_service Nov 12 10:42:00 chris-volumio volumio[1355]: info: Scanning category plugins.json Nov 12 10:42:00 chris-volumio volumio[1355]: info: Scanning category system_controller Nov 12 10:42:00 chris-volumio volumio[1355]: info: Scanning category user_interface Nov 12 10:42:00 chris-volumio volumio[1355]: info: Scanning into folder /data/plugins/ Nov 12 10:42:00 chris-volumio volumio[1355]: info: Scanning category audio_interface Nov 12 10:42:00 chris-volumio volumio[1355]: info: Scanning category music_service Nov 12 10:42:00 chris-volumio volumio[1355]: info: Scanning category user_interface Nov 12 10:42:00 chris-volumio volumio[1355]: info: Plugin folders cleanup completed Nov 12 10:42:00 chris-volumio volumio[1355]: info: ------------------------------------------- Nov 12 10:42:00 chris-volumio volumio[1355]: info: ----- Core plugins startup ---- Nov 12 10:42:00 chris-volumio volumio[1355]: info: ------------------------------------------- Nov 12 10:42:00 chris-volumio volumio[1355]: info: Loading plugins from folder /volumio/app/plugins/ Nov 12 10:42:00 chris-volumio volumio[1355]: info: Adding plugin upnp to MyMusic Plugins Nov 12 10:42:00 chris-volumio volumio[1355]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 12 10:42:00 chris-volumio volumio[1355]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 12 10:42:00 chris-volumio volumio[1355]: info: Loading plugins from folder /data/plugins/ Nov 12 10:42:00 chris-volumio volumio[1355]: info: Loading plugin "system"... Nov 12 10:42:00 chris-volumio volumio[1355]: info: Loading plugin "appearance"... Nov 12 10:42:01 chris-volumio volumio[1355]: info: Loading plugin "network"... Nov 12 10:42:01 chris-volumio volumio[1355]: info: Refreshing Cached IP Addresses Nov 12 10:42:01 chris-volumio sudo[1379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 12 10:42:01 chris-volumio sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:01 chris-volumio sudo[1379]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:01 chris-volumio sudo[1381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 12 10:42:01 chris-volumio volumio[1355]: info: Loading plugin "services"... Nov 12 10:42:01 chris-volumio sudo[1381]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:01 chris-volumio volumio[1355]: info: Loading plugin "alsa_controller"... Nov 12 10:42:01 chris-volumio sudo[1381]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:01 chris-volumio sudo[1384]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 12 10:42:01 chris-volumio sudo[1384]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:01 chris-volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 12 10:42:01 chris-volumio volumio[1355]: info: Loading plugin "wizard"... Nov 12 10:42:01 chris-volumio volumio[1355]: info: Loading plugin "volumio_command_line_client"... Nov 12 10:42:01 chris-volumio volumio[1355]: info: Loading plugin "upnp"... Nov 12 10:42:01 chris-volumio volumio[1355]: info: [1699785721238] Starting Upmpd Daemon Nov 12 10:42:01 chris-volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:42:01 chris-volumio volumio[1355]: info: Loading plugin "my_music"... Nov 12 10:42:01 chris-volumio volumio[1355]: info: Loading plugin "mpd"... Nov 12 10:42:01 chris-volumio volumio[1355]: info: Creating MPD Configuration file Nov 12 10:42:01 chris-volumio volumio[1355]: info: Loading plugin "upnp_browser"... Nov 12 10:42:01 chris-volumio sudo[1390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 12 10:42:01 chris-volumio sudo[1390]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:01 chris-volumio sudo[1390]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:01 chris-volumio sudo[1392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 12 10:42:01 chris-volumio sudo[1392]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:01 chris-volumio systemd[1]: Stopping Music Player Daemon... Nov 12 10:42:01 chris-volumio systemd[1]: mpd.service: Succeeded. Nov 12 10:42:01 chris-volumio systemd[1]: Stopped Music Player Daemon. Nov 12 10:42:01 chris-volumio systemd[1]: Starting Music Player Daemon... Nov 12 10:42:01 chris-volumio volumio[1355]: info: Loading plugin "networkfs"... Nov 12 10:42:01 chris-volumio volumio[1355]: info: Starting Udev Watcher for removable devices Nov 12 10:42:01 chris-volumio sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=chrisfox@ymail.com,password=cx7euaIfF3V8,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.22/Music /mnt/NAS/Music Nov 12 10:42:01 chris-volumio sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:01 chris-volumio kernel: CIFS: Attempting to mount \\192.168.1.22\Music Nov 12 10:42:02 chris-volumio volumio[1355]: info: Ignoring mount for partition: boot Nov 12 10:42:02 chris-volumio volumio[1355]: info: Ignoring mount for partition: volumio Nov 12 10:42:02 chris-volumio volumio[1355]: info: Ignoring mount for partition: volumio_data Nov 12 10:42:02 chris-volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:42:02 chris-volumio volumio[1355]: info: Loading plugin "alarm-clock"... Nov 12 10:42:02 chris-volumio volumio[1355]: info: Loading plugin "airplay_emulation"... Nov 12 10:42:02 chris-volumio volumio[1355]: info: Starting Shairport Sync Nov 12 10:42:02 chris-volumio volumio[1355]: info: Loading plugin "last_100"... Nov 12 10:42:02 chris-volumio volumio[1355]: info: Loading plugin "webradio"... Nov 12 10:42:02 chris-volumio volumio[1355]: info: Loading plugin "i2s_dacs"... Nov 12 10:42:02 chris-volumio volumio[1355]: info: Loading plugin "volumiodiscovery"... Nov 12 10:42:02 chris-volumio volumio[1355]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:02 chris-volumio node[1355]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:02 chris-volumio node[1355]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:02 chris-volumio volumio[1355]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:02 chris-volumio volumio[1355]: *** WARNING *** For more information see Nov 12 10:42:02 chris-volumio volumio[1355]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:02 chris-volumio volumio[1355]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:02 chris-volumio volumio[1355]: *** WARNING *** For more information see Nov 12 10:42:02 chris-volumio node[1355]: *** WARNING *** For more information see Nov 12 10:42:02 chris-volumio node[1355]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:02 chris-volumio node[1355]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:02 chris-volumio node[1355]: *** WARNING *** For more information see Nov 12 10:42:02 chris-volumio volumio[1355]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 12 10:42:02 chris-volumio volumio[1355]: info: Discovery: Started advertising with name: Chris-Volumio Nov 12 10:42:02 chris-volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:42:02 chris-volumio volumio[1355]: info: Loading plugin "ytcr"... Nov 12 10:42:02 chris-volumio mpd[1397]: Nov 12 10:42 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 12 10:42:02 chris-volumio mpd[1397]: Nov 12 10:42 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 12 10:42:02 chris-volumio mpd[1397]: Nov 12 10:42 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 12 10:42:02 chris-volumio systemd[1]: Started Music Player Daemon. Nov 12 10:42:02 chris-volumio sudo[1392]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:03 chris-volumio volumio[1355]: info: Loading plugin "now_playing"... Nov 12 10:42:03 chris-volumio volumio[1355]: info: Loading plugin "outputs"... Nov 12 10:42:03 chris-volumio volumio[1355]: info: Loading plugin "albumart"... Nov 12 10:42:03 chris-volumio volumio[1355]: info: Plugin example_plugin is not enabled Nov 12 10:42:03 chris-volumio volumio[1355]: info: Loading plugin "inputs"... Nov 12 10:42:03 chris-volumio volumio[1355]: info: Loading plugin "updater_comm"... Nov 12 10:42:03 chris-volumio volumio[1355]: info: Plugin mpdemulation is not enabled Nov 12 10:42:03 chris-volumio volumio[1355]: info: Loading plugin "rest_api"... Nov 12 10:42:03 chris-volumio volumio[1355]: info: Loading plugin "websocket"... Nov 12 10:42:03 chris-volumio volumio[1355]: info: Loading plugin "fusiondsp"... Nov 12 10:42:03 chris-volumio volumio[1355]: Forking 3 albumart workers Nov 12 10:42:03 chris-volumio volumio[1355]: info: Applying required configuration parameters for plugin fusiondsp Nov 12 10:42:03 chris-volumio sudo[1384]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:03 chris-volumio volumio[1355]: info: Loading plugin "RoonBridge"... Nov 12 10:42:04 chris-volumio volumio[1355]: info: Applying required configuration parameters for plugin RoonBridge Nov 12 10:42:04 chris-volumio volumio[1355]: info: Loading plugin "radio_paradise"... Nov 12 10:42:04 chris-volumio volumio[1355]: Starting albumart workers Nov 12 10:42:04 chris-volumio volumio[1355]: info: Applying required configuration parameters for plugin radio_paradise Nov 12 10:42:04 chris-volumio volumio[1355]: info: [1699785724247] [RadioParadise] API delay: 5 Nov 12 10:42:04 chris-volumio volumio[1355]: info: Loading plugin "lastfm"... Nov 12 10:42:04 chris-volumio volumio[1355]: Starting albumart workers Nov 12 10:42:04 chris-volumio volumio[1355]: Starting albumart workers Nov 12 10:42:04 chris-volumio volumio[1355]: info: Loading i18n strings for locale en Nov 12 10:42:04 chris-volumio volumio[1355]: Updating browse sources language Nov 12 10:42:04 chris-volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 12 10:42:04 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:42:04] [connect] Successful connection Nov 12 10:42:04 chris-volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 12 10:42:04 chris-volumio volumio[1355]: info: CoreCommandRouter::initPlayerControls Nov 12 10:42:04 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:42:04] [error] Server handshake response error: websocketpp.processor:20 (Invalid HTTP status.) Nov 12 10:42:04 chris-volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:04 chris-volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:04 chris-volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:04 chris-volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:04 chris-volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:04 chris-volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:04 chris-volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:04 chris-volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:04 chris-volumio volumio[1355]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 12 10:42:04 chris-volumio volumio[1355]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 12 10:42:04 chris-volumio volumio[1355]: Error: listen EADDRINUSE: address already in use :::3000 Nov 12 10:42:04 chris-volumio volumio[1355]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Nov 12 10:42:04 chris-volumio volumio[1355]: at listenInCluster (net.js:1366:12) Nov 12 10:42:04 chris-volumio volumio[1355]: at Server.listen (net.js:1452:7) Nov 12 10:42:04 chris-volumio volumio[1355]: at Function.listen (/volumio/node_modules/express/lib/application.js:618:24) Nov 12 10:42:04 chris-volumio volumio[1355]: at Object. (/volumio/index.js:26:29) Nov 12 10:42:04 chris-volumio volumio[1355]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Nov 12 10:42:04 chris-volumio volumio[1355]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Nov 12 10:42:04 chris-volumio volumio[1355]: at Module.load (internal/modules/cjs/loader.js:928:32) Nov 12 10:42:04 chris-volumio volumio[1355]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Nov 12 10:42:04 chris-volumio volumio[1355]: at Function.executeUserEntryPoint [as runMain] (internal/modules/run_main.js:72:12) { Nov 12 10:42:04 chris-volumio volumio[1355]: code: 'EADDRINUSE', Nov 12 10:42:04 chris-volumio volumio[1355]: errno: -98, Nov 12 10:42:04 chris-volumio volumio[1355]: syscall: 'listen', Nov 12 10:42:04 chris-volumio volumio[1355]: address: '::', Nov 12 10:42:04 chris-volumio volumio[1355]: port: 3000 Nov 12 10:42:04 chris-volumio volumio[1355]: } Nov 12 10:42:04 chris-volumio volumio[1355]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 12 10:42:05 chris-volumio sudo[1469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-11-12 10:41 Nov 12 10:42:05 chris-volumio sudo[1469]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:05 chris-volumio sudo[1469]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:05 chris-volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 12 10:42:05 chris-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 12 10:42:05 chris-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -4 Nov 12 10:42:05 chris-volumio sudo[1400]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:05 chris-volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 12 10:42:05 chris-volumio systemd[1]: Started dynamicswap service. Nov 12 10:42:05 chris-volumio systemd[1]: dynamicswap.service: Succeeded. Nov 12 10:42:05 chris-volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 12 10:42:05 chris-volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Nov 12 10:42:05 chris-volumio systemd[1]: Started dynamicswap service. Nov 12 10:42:05 chris-volumio systemd[1]: Stopped Volumio Backend Module. Nov 12 10:42:05 chris-volumio systemd[1]: Started Volumio Backend Module. Nov 12 10:42:05 chris-volumio systemd[1]: dynamicswap.service: Succeeded. Nov 12 10:42:06 chris-volumio volumio[1484]: info: ------------------------------------------- Nov 12 10:42:06 chris-volumio volumio[1484]: info: ----- Volumio3 ---- Nov 12 10:42:06 chris-volumio volumio[1484]: info: ------------------------------------------- Nov 12 10:42:06 chris-volumio volumio[1484]: info: ----- System startup ---- Nov 12 10:42:06 chris-volumio volumio[1484]: info: ------------------------------------------- Nov 12 10:42:07 chris-volumio volumio[1484]: info: MYVOLUMIO Environment detected Nov 12 10:42:07 chris-volumio volumio[1484]: info: Plugin folders cleanup Nov 12 10:42:07 chris-volumio volumio[1484]: info: Scanning into folder /volumio/app/plugins/ Nov 12 10:42:07 chris-volumio volumio[1484]: info: Scanning category audio_interface Nov 12 10:42:07 chris-volumio volumio[1484]: info: Scanning category miscellanea Nov 12 10:42:07 chris-volumio volumio[1484]: info: Scanning category music_service Nov 12 10:42:07 chris-volumio volumio[1484]: info: Scanning category plugins.json Nov 12 10:42:07 chris-volumio volumio[1484]: info: Scanning category system_controller Nov 12 10:42:07 chris-volumio volumio[1484]: info: Scanning category user_interface Nov 12 10:42:07 chris-volumio volumio[1484]: info: Scanning into folder /data/plugins/ Nov 12 10:42:07 chris-volumio volumio[1484]: info: Scanning category audio_interface Nov 12 10:42:07 chris-volumio volumio[1484]: info: Scanning category music_service Nov 12 10:42:07 chris-volumio volumio[1484]: info: Scanning category user_interface Nov 12 10:42:07 chris-volumio volumio[1484]: info: Plugin folders cleanup completed Nov 12 10:42:07 chris-volumio volumio[1484]: info: ------------------------------------------- Nov 12 10:42:07 chris-volumio volumio[1484]: info: ----- Core plugins startup ---- Nov 12 10:42:07 chris-volumio volumio[1484]: info: ------------------------------------------- Nov 12 10:42:07 chris-volumio volumio[1484]: info: Loading plugins from folder /volumio/app/plugins/ Nov 12 10:42:07 chris-volumio volumio[1484]: info: Adding plugin upnp to MyMusic Plugins Nov 12 10:42:07 chris-volumio volumio[1484]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 12 10:42:07 chris-volumio volumio[1484]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 12 10:42:07 chris-volumio volumio[1484]: info: Loading plugins from folder /data/plugins/ Nov 12 10:42:07 chris-volumio volumio[1484]: info: Loading plugin "system"... Nov 12 10:42:07 chris-volumio volumio[1484]: info: Loading plugin "appearance"... Nov 12 10:42:07 chris-volumio dhcpcd[849]: eth0: offered 192.168.68.56 from 192.168.68.1 Nov 12 10:42:07 chris-volumio dhcpcd[849]: eth0: probing address 192.168.68.56/22 Nov 12 10:42:07 chris-volumio volumio[1484]: info: Loading plugin "network"... Nov 12 10:42:07 chris-volumio volumio[1484]: info: Refreshing Cached IP Addresses Nov 12 10:42:07 chris-volumio sudo[1507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 12 10:42:07 chris-volumio sudo[1507]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:07 chris-volumio sudo[1507]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:07 chris-volumio sudo[1509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 12 10:42:07 chris-volumio sudo[1509]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:07 chris-volumio volumio[1484]: info: Loading plugin "services"... Nov 12 10:42:07 chris-volumio volumio[1484]: info: Loading plugin "alsa_controller"... Nov 12 10:42:07 chris-volumio sudo[1509]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:07 chris-volumio sudo[1512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 12 10:42:07 chris-volumio sudo[1512]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:07 chris-volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 12 10:42:07 chris-volumio volumio[1484]: info: Loading plugin "wizard"... Nov 12 10:42:07 chris-volumio volumio[1484]: info: Loading plugin "volumio_command_line_client"... Nov 12 10:42:07 chris-volumio volumio[1484]: info: Loading plugin "upnp"... Nov 12 10:42:07 chris-volumio volumio[1484]: info: [1699785727752] Starting Upmpd Daemon Nov 12 10:42:07 chris-volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:42:07 chris-volumio volumio[1484]: info: Loading plugin "my_music"... Nov 12 10:42:07 chris-volumio volumio[1484]: info: Loading plugin "mpd"... Nov 12 10:42:07 chris-volumio ntpd[633]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 12 10:42:07 chris-volumio volumio[1484]: info: Creating MPD Configuration file Nov 12 10:42:07 chris-volumio volumio[1484]: info: Loading plugin "upnp_browser"... Nov 12 10:42:07 chris-volumio sudo[1518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 12 10:42:07 chris-volumio sudo[1518]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:07 chris-volumio sudo[1518]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:07 chris-volumio sudo[1520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 12 10:42:07 chris-volumio sudo[1520]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:08 chris-volumio systemd[1]: Stopping Music Player Daemon... Nov 12 10:42:08 chris-volumio systemd[1]: mpd.service: Succeeded. Nov 12 10:42:08 chris-volumio systemd[1]: Stopped Music Player Daemon. Nov 12 10:42:08 chris-volumio systemd[1]: Starting Music Player Daemon... Nov 12 10:42:08 chris-volumio volumio[1484]: info: Loading plugin "networkfs"... Nov 12 10:42:08 chris-volumio volumio[1484]: info: Starting Udev Watcher for removable devices Nov 12 10:42:08 chris-volumio sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=chrisfox@ymail.com,password=cx7euaIfF3V8,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.22/Music /mnt/NAS/Music Nov 12 10:42:08 chris-volumio sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:08 chris-volumio kernel: CIFS: Attempting to mount \\192.168.1.22\Music Nov 12 10:42:08 chris-volumio volumio[1484]: info: Ignoring mount for partition: boot Nov 12 10:42:08 chris-volumio volumio[1484]: info: Ignoring mount for partition: volumio Nov 12 10:42:08 chris-volumio volumio[1484]: info: Ignoring mount for partition: volumio_data Nov 12 10:42:08 chris-volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:42:08 chris-volumio volumio[1484]: info: Loading plugin "alarm-clock"... Nov 12 10:42:08 chris-volumio volumio[1484]: info: Loading plugin "airplay_emulation"... Nov 12 10:42:08 chris-volumio volumio[1484]: info: Starting Shairport Sync Nov 12 10:42:08 chris-volumio volumio[1484]: info: Loading plugin "last_100"... Nov 12 10:42:08 chris-volumio volumio[1484]: info: Loading plugin "webradio"... Nov 12 10:42:08 chris-volumio volumio[1484]: info: Loading plugin "i2s_dacs"... Nov 12 10:42:08 chris-volumio volumio[1484]: info: Loading plugin "volumiodiscovery"... Nov 12 10:42:08 chris-volumio volumio[1484]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:08 chris-volumio node[1484]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:08 chris-volumio node[1484]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:08 chris-volumio volumio[1484]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:08 chris-volumio volumio[1484]: *** WARNING *** For more information see Nov 12 10:42:08 chris-volumio volumio[1484]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:08 chris-volumio volumio[1484]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:08 chris-volumio volumio[1484]: *** WARNING *** For more information see Nov 12 10:42:08 chris-volumio node[1484]: *** WARNING *** For more information see Nov 12 10:42:08 chris-volumio node[1484]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:08 chris-volumio node[1484]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:08 chris-volumio node[1484]: *** WARNING *** For more information see Nov 12 10:42:08 chris-volumio volumio[1484]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 12 10:42:08 chris-volumio volumio[1484]: info: Discovery: Started advertising with name: Chris-Volumio Nov 12 10:42:08 chris-volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:42:08 chris-volumio volumio[1484]: info: Loading plugin "ytcr"... Nov 12 10:42:08 chris-volumio mpd[1526]: Nov 12 10:42 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 12 10:42:08 chris-volumio mpd[1526]: Nov 12 10:42 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 12 10:42:08 chris-volumio mpd[1526]: Nov 12 10:42 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 12 10:42:08 chris-volumio systemd[1]: Started Music Player Daemon. Nov 12 10:42:09 chris-volumio sudo[1520]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:09 chris-volumio volumio[1484]: info: Loading plugin "now_playing"... Nov 12 10:42:09 chris-volumio ntpd[633]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 12 10:42:09 chris-volumio ntpd[633]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 12 10:42:09 chris-volumio volumio[1484]: info: Loading plugin "outputs"... Nov 12 10:42:09 chris-volumio volumio[1484]: info: Loading plugin "albumart"... Nov 12 10:42:09 chris-volumio volumio[1484]: info: Plugin example_plugin is not enabled Nov 12 10:42:09 chris-volumio volumio[1484]: info: Loading plugin "inputs"... Nov 12 10:42:09 chris-volumio volumio[1484]: info: Loading plugin "updater_comm"... Nov 12 10:42:10 chris-volumio volumio[1484]: info: Plugin mpdemulation is not enabled Nov 12 10:42:10 chris-volumio volumio[1484]: info: Loading plugin "rest_api"... Nov 12 10:42:10 chris-volumio volumio[1484]: info: Loading plugin "websocket"... Nov 12 10:42:10 chris-volumio volumio[1484]: info: Loading plugin "fusiondsp"... Nov 12 10:42:10 chris-volumio volumio[1484]: Forking 3 albumart workers Nov 12 10:42:10 chris-volumio sudo[1512]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:10 chris-volumio volumio[1484]: info: Applying required configuration parameters for plugin fusiondsp Nov 12 10:42:10 chris-volumio volumio[1484]: info: Loading plugin "RoonBridge"... Nov 12 10:42:10 chris-volumio volumio[1484]: info: Applying required configuration parameters for plugin RoonBridge Nov 12 10:42:10 chris-volumio volumio[1484]: info: Loading plugin "radio_paradise"... Nov 12 10:42:10 chris-volumio volumio[1484]: info: Applying required configuration parameters for plugin radio_paradise Nov 12 10:42:10 chris-volumio volumio[1484]: info: [1699785730738] [RadioParadise] API delay: 5 Nov 12 10:42:10 chris-volumio volumio[1484]: info: Loading plugin "lastfm"... Nov 12 10:42:10 chris-volumio volumio[1484]: Starting albumart workers Nov 12 10:42:10 chris-volumio volumio[1484]: Starting albumart workers Nov 12 10:42:10 chris-volumio volumio[1484]: Starting albumart workers Nov 12 10:42:11 chris-volumio volumio[1484]: info: Loading i18n strings for locale en Nov 12 10:42:11 chris-volumio volumio[1484]: Updating browse sources language Nov 12 10:42:11 chris-volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 12 10:42:11 chris-volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 12 10:42:11 chris-volumio volumio[1484]: info: CoreCommandRouter::initPlayerControls Nov 12 10:42:11 chris-volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:11 chris-volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:11 chris-volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:11 chris-volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:11 chris-volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:11 chris-volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:11 chris-volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:11 chris-volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:11 chris-volumio volumio[1484]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 12 10:42:11 chris-volumio volumio[1484]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 12 10:42:11 chris-volumio volumio[1484]: Error: listen EADDRINUSE: address already in use :::3000 Nov 12 10:42:11 chris-volumio volumio[1484]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Nov 12 10:42:11 chris-volumio volumio[1484]: at listenInCluster (net.js:1366:12) Nov 12 10:42:11 chris-volumio volumio[1484]: at Server.listen (net.js:1452:7) Nov 12 10:42:11 chris-volumio volumio[1484]: at Function.listen (/volumio/node_modules/express/lib/application.js:618:24) Nov 12 10:42:11 chris-volumio volumio[1484]: at Object. (/volumio/index.js:26:29) Nov 12 10:42:11 chris-volumio volumio[1484]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Nov 12 10:42:11 chris-volumio volumio[1484]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Nov 12 10:42:11 chris-volumio volumio[1484]: at Module.load (internal/modules/cjs/loader.js:928:32) Nov 12 10:42:11 chris-volumio volumio[1484]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Nov 12 10:42:11 chris-volumio volumio[1484]: at Function.executeUserEntryPoint [as runMain] (internal/modules/run_main.js:72:12) { Nov 12 10:42:11 chris-volumio volumio[1484]: code: 'EADDRINUSE', Nov 12 10:42:11 chris-volumio volumio[1484]: errno: -98, Nov 12 10:42:11 chris-volumio volumio[1484]: syscall: 'listen', Nov 12 10:42:11 chris-volumio volumio[1484]: address: '::', Nov 12 10:42:11 chris-volumio volumio[1484]: port: 3000 Nov 12 10:42:11 chris-volumio volumio[1484]: } Nov 12 10:42:11 chris-volumio volumio[1484]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 12 10:42:11 chris-volumio dhcpcd[910]: eth0: offered 192.168.68.56 from 192.168.68.1 Nov 12 10:42:11 chris-volumio dhcpcd[910]: eth0: probing address 192.168.68.56/22 Nov 12 10:42:11 chris-volumio sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-11-12 10:41 Nov 12 10:42:11 chris-volumio sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:11 chris-volumio sudo[1598]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:11 chris-volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 12 10:42:11 chris-volumio sudo[1529]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:11 chris-volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 12 10:42:11 chris-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 12 10:42:11 chris-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -4 Nov 12 10:42:11 chris-volumio systemd[1]: Started dynamicswap service. Nov 12 10:42:11 chris-volumio systemd[1]: dynamicswap.service: Succeeded. Nov 12 10:42:12 chris-volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 12 10:42:12 chris-volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Nov 12 10:42:12 chris-volumio systemd[1]: Stopped Volumio Backend Module. Nov 12 10:42:12 chris-volumio systemd[1]: Started Volumio Backend Module. Nov 12 10:42:12 chris-volumio systemd[1]: Started dynamicswap service. Nov 12 10:42:12 chris-volumio systemd[1]: dynamicswap.service: Succeeded. Nov 12 10:42:12 chris-volumio dhcpcd[849]: eth0: leased 192.168.68.56 for 7200 seconds Nov 12 10:42:12 chris-volumio avahi-daemon[532]: Registering new address record for 192.168.68.56 on eth0.IPv4. Nov 12 10:42:12 chris-volumio dhcpcd[849]: eth0: adding route to 192.168.68.0/22 Nov 12 10:42:12 chris-volumio dhcpcd[849]: eth0: changing default route via 192.168.68.1 Nov 12 10:42:12 chris-volumio dhcpcd[910]: eth0: pid 849 deleted default route Nov 12 10:42:12 chris-volumio avahi-daemon[532]: Withdrawing address record for 169.254.179.144 on eth0. Nov 12 10:42:12 chris-volumio dhcpcd[910]: eth0: pid 849 deleted IP address 169.254.179.144/16 Nov 12 10:42:12 chris-volumio avahi-daemon[532]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.179.144. Nov 12 10:42:12 chris-volumio avahi-daemon[532]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.68.56. Nov 12 10:42:12 chris-volumio dhcpcd[910]: eth0: deleting route to 169.254.0.0/16 Nov 12 10:42:12 chris-volumio dhcpcd[849]: eth0: deleting route to 169.254.0.0/16 Nov 12 10:42:12 chris-volumio dhcpcd[910]: eth0: probing for an IPv4LL address Nov 12 10:42:12 chris-volumio dhcpcd[849]: eth0: pid 849 deleted default route via 192.168.68.1 Nov 12 10:42:13 chris-volumio volumio[1612]: info: ------------------------------------------- Nov 12 10:42:13 chris-volumio volumio[1612]: info: ----- Volumio3 ---- Nov 12 10:42:13 chris-volumio volumio[1612]: info: ------------------------------------------- Nov 12 10:42:13 chris-volumio volumio[1612]: info: ----- System startup ---- Nov 12 10:42:13 chris-volumio volumio[1612]: info: ------------------------------------------- Nov 12 10:42:13 chris-volumio volumio[1612]: info: MYVOLUMIO Environment detected Nov 12 10:42:13 chris-volumio volumio[1612]: info: Plugin folders cleanup Nov 12 10:42:13 chris-volumio volumio[1612]: info: Scanning into folder /volumio/app/plugins/ Nov 12 10:42:13 chris-volumio volumio[1612]: info: Scanning category audio_interface Nov 12 10:42:13 chris-volumio volumio[1612]: info: Scanning category miscellanea Nov 12 10:42:13 chris-volumio volumio[1612]: info: Scanning category music_service Nov 12 10:42:13 chris-volumio volumio[1612]: info: Scanning category plugins.json Nov 12 10:42:13 chris-volumio volumio[1612]: info: Scanning category system_controller Nov 12 10:42:13 chris-volumio volumio[1612]: info: Scanning category user_interface Nov 12 10:42:13 chris-volumio volumio[1612]: info: Scanning into folder /data/plugins/ Nov 12 10:42:13 chris-volumio volumio[1612]: info: Scanning category audio_interface Nov 12 10:42:13 chris-volumio volumio[1612]: info: Scanning category music_service Nov 12 10:42:13 chris-volumio volumio[1612]: info: Scanning category user_interface Nov 12 10:42:13 chris-volumio volumio[1612]: info: Plugin folders cleanup completed Nov 12 10:42:13 chris-volumio volumio[1612]: info: ------------------------------------------- Nov 12 10:42:13 chris-volumio volumio[1612]: info: ----- Core plugins startup ---- Nov 12 10:42:13 chris-volumio volumio[1612]: info: ------------------------------------------- Nov 12 10:42:13 chris-volumio volumio[1612]: info: Loading plugins from folder /volumio/app/plugins/ Nov 12 10:42:13 chris-volumio volumio[1612]: info: Adding plugin upnp to MyMusic Plugins Nov 12 10:42:13 chris-volumio volumio[1612]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 12 10:42:13 chris-volumio volumio[1612]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 12 10:42:13 chris-volumio volumio[1612]: info: Loading plugins from folder /data/plugins/ Nov 12 10:42:13 chris-volumio volumio[1612]: info: Loading plugin "system"... Nov 12 10:42:13 chris-volumio volumio[1612]: info: Loading plugin "appearance"... Nov 12 10:42:14 chris-volumio volumio[1612]: info: Loading plugin "network"... Nov 12 10:42:14 chris-volumio volumio[1612]: info: Refreshing Cached IP Addresses Nov 12 10:42:14 chris-volumio sudo[1691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 12 10:42:14 chris-volumio sudo[1691]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:14 chris-volumio sudo[1691]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:14 chris-volumio sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 12 10:42:14 chris-volumio sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:14 chris-volumio volumio[1612]: info: Loading plugin "services"... Nov 12 10:42:14 chris-volumio volumio[1612]: info: Loading plugin "alsa_controller"... Nov 12 10:42:14 chris-volumio sudo[1693]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:14 chris-volumio sudo[1696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 12 10:42:14 chris-volumio sudo[1696]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:14 chris-volumio volumio[1612]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 12 10:42:14 chris-volumio volumio[1612]: info: Loading plugin "wizard"... Nov 12 10:42:14 chris-volumio volumio[1612]: info: Loading plugin "volumio_command_line_client"... Nov 12 10:42:14 chris-volumio volumio[1612]: info: Loading plugin "upnp"... Nov 12 10:42:14 chris-volumio volumio[1612]: info: [1699785734269] Starting Upmpd Daemon Nov 12 10:42:14 chris-volumio volumio[1612]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:42:14 chris-volumio volumio[1612]: info: Loading plugin "my_music"... Nov 12 10:42:14 chris-volumio volumio[1612]: info: Loading plugin "mpd"... Nov 12 10:42:14 chris-volumio volumio[1612]: info: Creating MPD Configuration file Nov 12 10:42:14 chris-volumio sudo[1702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 12 10:42:14 chris-volumio volumio[1612]: info: Loading plugin "upnp_browser"... Nov 12 10:42:14 chris-volumio sudo[1702]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:14 chris-volumio sudo[1702]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:14 chris-volumio sudo[1704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 12 10:42:14 chris-volumio sudo[1704]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:14 chris-volumio systemd[1]: Stopping Music Player Daemon... Nov 12 10:42:14 chris-volumio systemd[1]: mpd.service: Succeeded. Nov 12 10:42:14 chris-volumio systemd[1]: Stopped Music Player Daemon. Nov 12 10:42:14 chris-volumio systemd[1]: Starting Music Player Daemon... Nov 12 10:42:14 chris-volumio volumio[1612]: info: Loading plugin "networkfs"... Nov 12 10:42:14 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:42:14] [connect] Successful connection Nov 12 10:42:14 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:42:14] [error] Server handshake response error: websocketpp.processor:20 (Invalid HTTP status.) Nov 12 10:42:14 chris-volumio ntpd[633]: Listen normally on 4 eth0 192.168.68.56:123 Nov 12 10:42:14 chris-volumio ntpd[633]: Deleting interface #3 eth0, 169.254.179.144#123, interface stats: received=0, sent=0, dropped=0, active_time=57 secs Nov 12 10:42:14 chris-volumio ntpd[633]: new interface(s) found: waking up resolver Nov 12 10:42:14 chris-volumio volumio[1612]: info: Starting Udev Watcher for removable devices Nov 12 10:42:14 chris-volumio sudo[1713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=chrisfox@ymail.com,password=cx7euaIfF3V8,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.22/Music /mnt/NAS/Music Nov 12 10:42:14 chris-volumio sudo[1713]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:14 chris-volumio kernel: CIFS: Attempting to mount \\192.168.1.22\Music Nov 12 10:42:15 chris-volumio volumio[1612]: info: Ignoring mount for partition: boot Nov 12 10:42:15 chris-volumio volumio[1612]: info: Ignoring mount for partition: volumio Nov 12 10:42:15 chris-volumio volumio[1612]: info: Ignoring mount for partition: volumio_data Nov 12 10:42:15 chris-volumio volumio[1612]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:42:15 chris-volumio volumio[1612]: info: Loading plugin "alarm-clock"... Nov 12 10:42:15 chris-volumio volumio[1612]: info: Loading plugin "airplay_emulation"... Nov 12 10:42:15 chris-volumio volumio[1612]: info: Starting Shairport Sync Nov 12 10:42:15 chris-volumio volumio[1612]: info: Loading plugin "last_100"... Nov 12 10:42:15 chris-volumio volumio[1612]: info: Loading plugin "webradio"... Nov 12 10:42:15 chris-volumio volumio[1612]: info: Loading plugin "i2s_dacs"... Nov 12 10:42:15 chris-volumio volumio[1612]: info: Loading plugin "volumiodiscovery"... Nov 12 10:42:15 chris-volumio volumio[1612]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:15 chris-volumio node[1612]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:15 chris-volumio node[1612]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:15 chris-volumio volumio[1612]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:15 chris-volumio volumio[1612]: *** WARNING *** For more information see Nov 12 10:42:15 chris-volumio volumio[1612]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:15 chris-volumio volumio[1612]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:15 chris-volumio volumio[1612]: *** WARNING *** For more information see Nov 12 10:42:15 chris-volumio node[1612]: *** WARNING *** For more information see Nov 12 10:42:15 chris-volumio node[1612]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:15 chris-volumio node[1612]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:15 chris-volumio node[1612]: *** WARNING *** For more information see Nov 12 10:42:15 chris-volumio volumio[1612]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 12 10:42:15 chris-volumio volumio[1612]: info: Discovery: Started advertising with name: Chris-Volumio Nov 12 10:42:15 chris-volumio volumio[1612]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:42:15 chris-volumio volumio[1612]: info: Loading plugin "ytcr"... Nov 12 10:42:15 chris-volumio mpd[1710]: Nov 12 10:42 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 12 10:42:15 chris-volumio mpd[1710]: Nov 12 10:42 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 12 10:42:15 chris-volumio mpd[1710]: Nov 12 10:42 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 12 10:42:15 chris-volumio systemd[1]: Started Music Player Daemon. Nov 12 10:42:15 chris-volumio sudo[1704]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:15 chris-volumio dhcpcd[910]: eth0: leased 192.168.68.56 for 7200 seconds Nov 12 10:42:15 chris-volumio dhcpcd[910]: eth0: adding route to 192.168.68.0/22 Nov 12 10:42:15 chris-volumio dhcpcd[910]: eth0: adding default route via 192.168.68.1 Nov 12 10:42:16 chris-volumio volumio[1612]: info: Loading plugin "now_playing"... Nov 12 10:42:16 chris-volumio volumio[1612]: info: Loading plugin "outputs"... Nov 12 10:42:16 chris-volumio volumio[1612]: info: Loading plugin "albumart"... Nov 12 10:42:16 chris-volumio volumio[1612]: info: Plugin example_plugin is not enabled Nov 12 10:42:16 chris-volumio volumio[1612]: info: Loading plugin "inputs"... Nov 12 10:42:16 chris-volumio volumio[1612]: info: Loading plugin "updater_comm"... Nov 12 10:42:16 chris-volumio volumio[1612]: info: Plugin mpdemulation is not enabled Nov 12 10:42:16 chris-volumio volumio[1612]: info: Loading plugin "rest_api"... Nov 12 10:42:16 chris-volumio volumio[1612]: info: Loading plugin "websocket"... Nov 12 10:42:16 chris-volumio volumio[1612]: info: Loading plugin "fusiondsp"... Nov 12 10:42:16 chris-volumio volumio[1612]: Forking 3 albumart workers Nov 12 10:42:16 chris-volumio sudo[1696]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:16 chris-volumio volumio[1612]: info: Applying required configuration parameters for plugin fusiondsp Nov 12 10:42:16 chris-volumio volumio[1612]: info: Loading plugin "RoonBridge"... Nov 12 10:42:17 chris-volumio volumio[1612]: info: Applying required configuration parameters for plugin RoonBridge Nov 12 10:42:17 chris-volumio volumio[1612]: info: Loading plugin "radio_paradise"... Nov 12 10:42:17 chris-volumio volumio[1612]: info: Applying required configuration parameters for plugin radio_paradise Nov 12 10:42:17 chris-volumio volumio[1612]: info: [1699785737264] [RadioParadise] API delay: 5 Nov 12 10:42:17 chris-volumio volumio[1612]: info: Loading plugin "lastfm"... Nov 12 10:42:17 chris-volumio volumio[1612]: Starting albumart workers Nov 12 10:42:17 chris-volumio volumio[1612]: Starting albumart workers Nov 12 10:42:17 chris-volumio volumio[1612]: Starting albumart workers Nov 12 10:42:17 chris-volumio volumio[1612]: info: Loading i18n strings for locale en Nov 12 10:42:17 chris-volumio volumio[1612]: Updating browse sources language Nov 12 10:42:17 chris-volumio volumio[1612]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 12 10:42:17 chris-volumio volumio[1612]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 12 10:42:17 chris-volumio volumio[1612]: info: CoreCommandRouter::initPlayerControls Nov 12 10:42:17 chris-volumio volumio[1612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:17 chris-volumio volumio[1612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:17 chris-volumio volumio[1612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:17 chris-volumio volumio[1612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:17 chris-volumio volumio[1612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:17 chris-volumio volumio[1612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:17 chris-volumio volumio[1612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:17 chris-volumio volumio[1612]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:17 chris-volumio volumio[1612]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 12 10:42:17 chris-volumio volumio[1612]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 12 10:42:17 chris-volumio volumio[1612]: Error: listen EADDRINUSE: address already in use :::3000 Nov 12 10:42:17 chris-volumio volumio[1612]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Nov 12 10:42:17 chris-volumio volumio[1612]: at listenInCluster (net.js:1366:12) Nov 12 10:42:17 chris-volumio volumio[1612]: at Server.listen (net.js:1452:7) Nov 12 10:42:17 chris-volumio volumio[1612]: at Function.listen (/volumio/node_modules/express/lib/application.js:618:24) Nov 12 10:42:17 chris-volumio volumio[1612]: at Object. (/volumio/index.js:26:29) Nov 12 10:42:17 chris-volumio volumio[1612]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Nov 12 10:42:17 chris-volumio volumio[1612]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Nov 12 10:42:17 chris-volumio volumio[1612]: at Module.load (internal/modules/cjs/loader.js:928:32) Nov 12 10:42:17 chris-volumio volumio[1612]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Nov 12 10:42:17 chris-volumio volumio[1612]: at Function.executeUserEntryPoint [as runMain] (internal/modules/run_main.js:72:12) { Nov 12 10:42:17 chris-volumio volumio[1612]: code: 'EADDRINUSE', Nov 12 10:42:17 chris-volumio volumio[1612]: errno: -98, Nov 12 10:42:17 chris-volumio volumio[1612]: syscall: 'listen', Nov 12 10:42:17 chris-volumio volumio[1612]: address: '::', Nov 12 10:42:17 chris-volumio volumio[1612]: port: 3000 Nov 12 10:42:17 chris-volumio volumio[1612]: } Nov 12 10:42:17 chris-volumio volumio[1612]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 12 10:42:18 chris-volumio sudo[1803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-11-12 10:41 Nov 12 10:42:18 chris-volumio sudo[1803]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:18 chris-volumio sudo[1803]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:22 chris-volumio nmbd[637]: [2023/11/12 10:42:22.090757, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 12 10:42:22 chris-volumio nmbd[637]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Nov 12 10:42:24 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:42:24] [connect] Successful connection Nov 12 10:42:24 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:42:24] [error] Server handshake response error: websocketpp.processor:20 (Invalid HTTP status.) Nov 12 10:42:24 chris-volumio ntpd[633]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 12 10:42:24 chris-volumio sudo[1713]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:24 chris-volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 12 10:42:24 chris-volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Nov 12 10:42:30 chris-volumio nmbd[637]: [2023/11/12 10:42:30.169731, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 12 10:42:30 chris-volumio nmbd[637]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Nov 12 10:42:30 chris-volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 12 10:42:30 chris-volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 12 10:42:30 chris-volumio systemd[1]: Started dynamicswap service. Nov 12 10:42:30 chris-volumio systemd[1]: dynamicswap.service: Succeeded. Nov 12 10:42:30 chris-volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 12 10:42:30 chris-volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Nov 12 10:42:30 chris-volumio systemd[1]: Started dynamicswap service. Nov 12 10:42:30 chris-volumio systemd[1]: Stopped Volumio Backend Module. Nov 12 10:42:30 chris-volumio systemd[1]: Started Volumio Backend Module. Nov 12 10:42:30 chris-volumio systemd[1]: dynamicswap.service: Succeeded. Nov 12 10:42:31 chris-volumio volumio[1817]: info: ------------------------------------------- Nov 12 10:42:31 chris-volumio volumio[1817]: info: ----- Volumio3 ---- Nov 12 10:42:31 chris-volumio volumio[1817]: info: ------------------------------------------- Nov 12 10:42:31 chris-volumio volumio[1817]: info: ----- System startup ---- Nov 12 10:42:31 chris-volumio volumio[1817]: info: ------------------------------------------- Nov 12 10:42:31 chris-volumio volumio[1817]: info: MYVOLUMIO Environment detected Nov 12 10:42:32 chris-volumio volumio[1817]: info: Plugin folders cleanup Nov 12 10:42:32 chris-volumio volumio[1817]: info: Scanning into folder /volumio/app/plugins/ Nov 12 10:42:32 chris-volumio volumio[1817]: info: Scanning category audio_interface Nov 12 10:42:32 chris-volumio volumio[1817]: info: Scanning category miscellanea Nov 12 10:42:32 chris-volumio volumio[1817]: info: Scanning category music_service Nov 12 10:42:32 chris-volumio volumio[1817]: info: Scanning category plugins.json Nov 12 10:42:32 chris-volumio volumio[1817]: info: Scanning category system_controller Nov 12 10:42:32 chris-volumio volumio[1817]: info: Scanning category user_interface Nov 12 10:42:32 chris-volumio volumio[1817]: info: Scanning into folder /data/plugins/ Nov 12 10:42:32 chris-volumio volumio[1817]: info: Scanning category audio_interface Nov 12 10:42:32 chris-volumio volumio[1817]: info: Scanning category music_service Nov 12 10:42:32 chris-volumio volumio[1817]: info: Scanning category user_interface Nov 12 10:42:32 chris-volumio volumio[1817]: info: Plugin folders cleanup completed Nov 12 10:42:32 chris-volumio volumio[1817]: info: ------------------------------------------- Nov 12 10:42:32 chris-volumio volumio[1817]: info: ----- Core plugins startup ---- Nov 12 10:42:32 chris-volumio volumio[1817]: info: ------------------------------------------- Nov 12 10:42:32 chris-volumio volumio[1817]: info: Loading plugins from folder /volumio/app/plugins/ Nov 12 10:42:32 chris-volumio volumio[1817]: info: Adding plugin upnp to MyMusic Plugins Nov 12 10:42:32 chris-volumio volumio[1817]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 12 10:42:32 chris-volumio volumio[1817]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 12 10:42:32 chris-volumio volumio[1817]: info: Loading plugins from folder /data/plugins/ Nov 12 10:42:32 chris-volumio volumio[1817]: info: Loading plugin "system"... Nov 12 10:42:32 chris-volumio volumio[1817]: info: Loading plugin "appearance"... Nov 12 10:42:32 chris-volumio volumio[1817]: info: Loading plugin "network"... Nov 12 10:42:32 chris-volumio volumio[1817]: info: Refreshing Cached IP Addresses Nov 12 10:42:32 chris-volumio sudo[1840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 12 10:42:32 chris-volumio sudo[1840]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:32 chris-volumio sudo[1840]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:32 chris-volumio sudo[1842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 12 10:42:32 chris-volumio sudo[1842]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:32 chris-volumio volumio[1817]: info: Loading plugin "services"... Nov 12 10:42:32 chris-volumio volumio[1817]: info: Loading plugin "alsa_controller"... Nov 12 10:42:32 chris-volumio sudo[1842]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:32 chris-volumio sudo[1845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 12 10:42:32 chris-volumio sudo[1845]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:32 chris-volumio volumio[1817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 12 10:42:32 chris-volumio volumio[1817]: info: Loading plugin "wizard"... Nov 12 10:42:32 chris-volumio volumio[1817]: info: Loading plugin "volumio_command_line_client"... Nov 12 10:42:32 chris-volumio volumio[1817]: info: Loading plugin "upnp"... Nov 12 10:42:32 chris-volumio volumio[1817]: info: [1699785752730] Starting Upmpd Daemon Nov 12 10:42:32 chris-volumio volumio[1817]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:42:32 chris-volumio volumio[1817]: info: Loading plugin "my_music"... Nov 12 10:42:32 chris-volumio volumio[1817]: info: Loading plugin "mpd"... Nov 12 10:42:32 chris-volumio volumio[1817]: info: Creating MPD Configuration file Nov 12 10:42:32 chris-volumio sudo[1851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 12 10:42:32 chris-volumio volumio[1817]: info: Loading plugin "upnp_browser"... Nov 12 10:42:32 chris-volumio sudo[1851]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:32 chris-volumio sudo[1851]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:32 chris-volumio sudo[1853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 12 10:42:32 chris-volumio sudo[1853]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:32 chris-volumio systemd[1]: Stopping Music Player Daemon... Nov 12 10:42:33 chris-volumio systemd[1]: mpd.service: Succeeded. Nov 12 10:42:33 chris-volumio systemd[1]: Stopped Music Player Daemon. Nov 12 10:42:33 chris-volumio systemd[1]: Starting Music Player Daemon... Nov 12 10:42:33 chris-volumio volumio[1817]: info: Loading plugin "networkfs"... Nov 12 10:42:33 chris-volumio volumio[1817]: info: Starting Udev Watcher for removable devices Nov 12 10:42:33 chris-volumio sudo[1861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=chrisfox@ymail.com,password=cx7euaIfF3V8,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.22/Music /mnt/NAS/Music Nov 12 10:42:33 chris-volumio sudo[1861]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 12 10:42:33 chris-volumio kernel: CIFS: Attempting to mount \\192.168.1.22\Music Nov 12 10:42:33 chris-volumio volumio[1817]: info: Ignoring mount for partition: boot Nov 12 10:42:33 chris-volumio volumio[1817]: info: Ignoring mount for partition: volumio Nov 12 10:42:33 chris-volumio volumio[1817]: info: Ignoring mount for partition: volumio_data Nov 12 10:42:33 chris-volumio volumio[1817]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:42:33 chris-volumio volumio[1817]: info: Loading plugin "alarm-clock"... Nov 12 10:42:33 chris-volumio volumio[1817]: info: Loading plugin "airplay_emulation"... Nov 12 10:42:33 chris-volumio volumio[1817]: info: Starting Shairport Sync Nov 12 10:42:33 chris-volumio volumio[1817]: info: Loading plugin "last_100"... Nov 12 10:42:33 chris-volumio volumio[1817]: info: Loading plugin "webradio"... Nov 12 10:42:33 chris-volumio volumio[1817]: info: Loading plugin "i2s_dacs"... Nov 12 10:42:33 chris-volumio volumio[1817]: info: Loading plugin "volumiodiscovery"... Nov 12 10:42:33 chris-volumio volumio[1817]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:33 chris-volumio node[1817]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:33 chris-volumio volumio[1817]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:33 chris-volumio node[1817]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:33 chris-volumio volumio[1817]: *** WARNING *** For more information see Nov 12 10:42:33 chris-volumio node[1817]: *** WARNING *** For more information see Nov 12 10:42:33 chris-volumio volumio[1817]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:33 chris-volumio node[1817]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 12 10:42:33 chris-volumio volumio[1817]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:33 chris-volumio node[1817]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 12 10:42:33 chris-volumio volumio[1817]: *** WARNING *** For more information see Nov 12 10:42:33 chris-volumio node[1817]: *** WARNING *** For more information see Nov 12 10:42:33 chris-volumio volumio[1817]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 12 10:42:33 chris-volumio volumio[1817]: info: Discovery: Started advertising with name: Chris-Volumio Nov 12 10:42:33 chris-volumio volumio[1817]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 12 10:42:33 chris-volumio volumio[1817]: info: Loading plugin "ytcr"... Nov 12 10:42:33 chris-volumio mpd[1858]: Nov 12 10:42 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 12 10:42:33 chris-volumio mpd[1858]: Nov 12 10:42 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 12 10:42:33 chris-volumio mpd[1858]: Nov 12 10:42 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 12 10:42:33 chris-volumio systemd[1]: Started Music Player Daemon. Nov 12 10:42:33 chris-volumio sudo[1853]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:34 chris-volumio volumio[1817]: info: Loading plugin "now_playing"... Nov 12 10:42:34 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:42:34] [connect] Successful connection Nov 12 10:42:34 chris-volumio volumio-remote-updater[523]: [2023-11-12 10:42:34] [error] Server handshake response error: websocketpp.processor:20 (Invalid HTTP status.) Nov 12 10:42:34 chris-volumio volumio[1817]: info: Loading plugin "outputs"... Nov 12 10:42:34 chris-volumio volumio[1817]: info: Loading plugin "albumart"... Nov 12 10:42:34 chris-volumio volumio[1817]: info: Plugin example_plugin is not enabled Nov 12 10:42:34 chris-volumio volumio[1817]: info: Loading plugin "inputs"... Nov 12 10:42:34 chris-volumio volumio[1817]: info: Loading plugin "updater_comm"... Nov 12 10:42:35 chris-volumio volumio[1817]: info: Plugin mpdemulation is not enabled Nov 12 10:42:35 chris-volumio volumio[1817]: info: Loading plugin "rest_api"... Nov 12 10:42:35 chris-volumio volumio[1817]: info: Loading plugin "websocket"... Nov 12 10:42:35 chris-volumio volumio[1817]: info: Loading plugin "fusiondsp"... Nov 12 10:42:35 chris-volumio volumio[1817]: Forking 3 albumart workers Nov 12 10:42:35 chris-volumio volumio[1817]: info: Applying required configuration parameters for plugin fusiondsp Nov 12 10:42:35 chris-volumio sudo[1845]: pam_unix(sudo:session): session closed for user root Nov 12 10:42:35 chris-volumio volumio[1817]: info: Loading plugin "RoonBridge"... Nov 12 10:42:35 chris-volumio volumio[1817]: info: Applying required configuration parameters for plugin RoonBridge Nov 12 10:42:35 chris-volumio volumio[1817]: info: Loading plugin "radio_paradise"... Nov 12 10:42:35 chris-volumio volumio[1817]: info: Applying required configuration parameters for plugin radio_paradise Nov 12 10:42:35 chris-volumio volumio[1817]: info: [1699785755724] [RadioParadise] API delay: 5 Nov 12 10:42:35 chris-volumio volumio[1817]: info: Loading plugin "lastfm"... Nov 12 10:42:35 chris-volumio volumio[1817]: Starting albumart workers Nov 12 10:42:35 chris-volumio volumio[1817]: Starting albumart workers Nov 12 10:42:35 chris-volumio volumio[1817]: Starting albumart workers Nov 12 10:42:36 chris-volumio volumio[1817]: info: Loading i18n strings for locale en Nov 12 10:42:36 chris-volumio volumio[1817]: Updating browse sources language Nov 12 10:42:36 chris-volumio volumio[1817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 12 10:42:36 chris-volumio volumio[1817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 12 10:42:36 chris-volumio volumio[1817]: info: CoreCommandRouter::initPlayerControls Nov 12 10:42:36 chris-volumio volumio[1817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:36 chris-volumio volumio[1817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:36 chris-volumio volumio[1817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:36 chris-volumio volumio[1817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:36 chris-volumio volumio[1817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:36 chris-volumio volumio[1817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:36 chris-volumio volumio[1817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:36 chris-volumio volumio[1817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 12 10:42:36 chris-volumio volumio[1817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 12 10:42:36 chris-volumio volumio[1817]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 12 10:42:36 chris-volumio volumio[1817]: Error: listen EADDRINUSE: address already in use :::3000 Nov 12 10:42:36 chris-volumio volumio[1817]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Nov 12 10:42:36 chris-volumio volumio[1817]: at listenInCluster (net.js:1366:12) Nov 12 10:42:36 chris-volumio volumio[1817]: at Server.listen (net.js:1452:7) Nov 12 10:42:36 chris-volumio volumio[1817]: at Function.listen (/volumio/node_modules/express/lib/application.js:618:24) Nov 12 10:42:36 chris-volumio volumio[1817]: at Object. (/volumio/index.js:26:29) Nov 12 10:42:36 chris-volumio volumio[1817]: at Module._compile (internal/modules/cjs/loader.js:1063:30) Nov 12 10:42:36 chris-volumio volumio[1817]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1092:10) Nov 12 10:42:36 chris-volumio volumio[1817]: at Module.load (internal/modules/cjs/loader.js:928:32) Nov 12 10:42:36 chris-volumio volumio[1817]: at Function.Module._load (internal/modules/cjs/loader.js:769:14) Nov 12 10:42:36 chris-volumio volumio[1817]: at Function.executeUserEntryPoint [as runMain] (internal/modules/run_main.js:72:12) { Nov 12 10:42:36 chris-volumio volumio[1817]: code: 'EADDRINUSE', Nov 12 10:42:36 chris-volumio volumio[1817]: errno: -98, Nov 12 10:42:36 chris-volumio volumio[1817]: syscall: 'listen', Nov 12 10:42:36 chris-volumio volumio[1817]: address: '::', Nov 12 10:42:36 chris-volumio volumio[1817]: port: 3000 Nov 12 10:42:36 chris-volumio volumio[1817]: } Nov 12 10:42:36 chris-volumio volumio[1817]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 12 10:42:36 chris-volumio sudo[1930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2023-11-12 10:41 Nov 12 10:42:36 chris-volumio sudo[1930]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"