-- Logs begin at Sun 2025-01-12 19:07:59 AWST, end at Sun 2025-01-12 19:09:03 AWST. -- Jan 12 19:08:00 volumio kernel: videodev: Linux video capture interface: v2.00 Jan 12 19:08:00 volumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 12 19:08:00 volumio kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jan 12 19:08:00 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jan 12 19:08:00 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jan 12 19:08:00 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jan 12 19:08:00 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jan 12 19:08:00 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jan 12 19:08:00 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jan 12 19:08:00 volumio kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jan 12 19:08:00 volumio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jan 12 19:08:00 volumio kernel: snd_bcm2835: unknown parameter 'index' ignored Jan 12 19:08:00 volumio kernel: bcm2835_audio soc:audio: card created with 8 channels Jan 12 19:08:00 volumio systemd[1]: Found device /dev/mmcblk0p1. Jan 12 19:08:00 volumio systemd[1]: Starting Sound Card. Jan 12 19:08:00 volumio systemd[1]: Reached target Sound Card. Jan 12 19:08:00 volumio systemd[1]: Starting File System Check on /dev/mmcblk0p1... Jan 12 19:08:00 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 12 19:08:00 volumio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 12 19:08:00 volumio kernel: platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 Jan 12 19:08:00 volumio kernel: cfg80211: failed to load regulatory.db Jan 12 19:08:00 volumio kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jan 12 19:08:00 volumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jan 12 19:08:00 volumio kernel: usbcore: registered new interface driver brcmfmac Jan 12 19:08:00 volumio systemd-fsck[289]: fsck.fat 3.0.27 (2014-11-12) Jan 12 19:08:00 volumio systemd-fsck[289]: /dev/mmcblk0p1: 223 files, 118527/123044 clusters Jan 12 19:08:00 volumio systemd[1]: Started File System Check on /dev/mmcblk0p1. Jan 12 19:08:00 volumio systemd[1]: Mounting /boot... Jan 12 19:08:01 volumio systemd[1]: Mounted /boot. Jan 12 19:08:01 volumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jan 12 19:08:01 volumio systemd[1]: Starting Local File Systems. Jan 12 19:08:01 volumio systemd[1]: Reached target Local File Systems. Jan 12 19:08:01 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 12 19:08:01 volumio systemd[1]: Starting Remote File Systems. Jan 12 19:08:01 volumio systemd[1]: Reached target Remote File Systems. Jan 12 19:08:01 volumio systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... Jan 12 19:08:01 volumio systemd[1]: Starting Create Volatile Files and Directories... Jan 12 19:08:01 volumio systemd[1]: Starting LSB: Raise network interfaces.... Jan 12 19:08:01 volumio kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Feb 27 2018 03:15:32 version 7.45.154 (r684107 CY) FWID 01-4fbe0b04 Jan 12 19:08:01 volumio systemd[1]: Started Create Volatile Files and Directories. Jan 12 19:08:01 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 12 19:08:01 volumio systemd[1]: Starting system-systemd\x2drfkill.slice. Jan 12 19:08:01 volumio systemd[1]: Created slice system-systemd\x2drfkill.slice. Jan 12 19:08:01 volumio systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill0... Jan 12 19:08:01 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 12 19:08:01 volumio systemd[1]: Started Load/Save RF Kill Switch Status of rfkill0. Jan 12 19:08:01 volumio systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. Jan 12 19:08:01 volumio systemd[1]: Starting system-ifup.slice. Jan 12 19:08:01 volumio systemd[1]: Created slice system-ifup.slice. Jan 12 19:08:02 volumio systemd[1]: Received SIGRTMIN+20 from PID 240 (plymouthd). Jan 12 19:08:02 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 12 19:08:02 volumio kernel: brcmfmac: power management disabled Jan 12 19:08:02 volumio networking[315]: Configuring network interfaces...python3: can't open file '~/Scripts/subs.py': [Errno 2] No such file or directory Jan 12 19:08:02 volumio networking[315]: Failed to bring up wlan0. Jan 12 19:08:03 volumio kernel: brcmfmac: power management disabled Jan 12 19:08:03 volumio networking[315]: done. Jan 12 19:08:03 volumio systemd[1]: Started LSB: Raise network interfaces.. Jan 12 19:08:03 volumio systemd[1]: Starting ifup for wlan0... Jan 12 19:08:03 volumio systemd[1]: Started ifup for wlan0. Jan 12 19:08:03 volumio systemd[1]: Starting Network. Jan 12 19:08:03 volumio systemd[1]: Reached target Network. Jan 12 19:08:03 volumio systemd[1]: Starting Network is Online. Jan 12 19:08:03 volumio systemd[1]: Reached target Network is Online. Jan 12 19:08:03 volumio systemd[1]: Starting LSB: RPC portmapper replacement... Jan 12 19:08:03 volumio ifup[431]: python3: can't open file '~/Scripts/subs.py': [Errno 2] No such file or directory Jan 12 19:08:03 volumio ifup[431]: Failed to bring up wlan0. Jan 12 19:08:03 volumio rpcbind[432]: Starting rpcbind daemon.... Jan 12 19:08:03 volumio systemd[1]: Started LSB: RPC portmapper replacement. Jan 12 19:08:03 volumio systemd[1]: Starting RPC Port Mapper. Jan 12 19:08:03 volumio systemd[1]: Reached target RPC Port Mapper. Jan 12 19:08:03 volumio systemd[1]: Starting LSB: NFS support files common to client and server... Jan 12 19:08:03 volumio rpc.statd[466]: Version 1.2.8 starting Jan 12 19:08:03 volumio sm-notify[467]: Version 1.2.8 starting Jan 12 19:08:04 volumio kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Jan 12 19:08:04 volumio nfs-common[460]: Starting NFS common utilities: statd idmapd. Jan 12 19:08:04 volumio systemd[1]: Started LSB: NFS support files common to client and server. Jan 12 19:08:04 volumio systemd[1]: Starting System Initialization. Jan 12 19:08:04 volumio systemd[1]: Reached target System Initialization. Jan 12 19:08:04 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. Jan 12 19:08:04 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 12 19:08:04 volumio systemd[1]: Starting mpd.socket. Jan 12 19:08:04 volumio systemd[1]: Listening on mpd.socket. Jan 12 19:08:04 volumio systemd[1]: Starting D-Bus System Message Bus Socket. Jan 12 19:08:04 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 12 19:08:04 volumio systemd[1]: Starting Sockets. Jan 12 19:08:04 volumio systemd[1]: Reached target Sockets. Jan 12 19:08:04 volumio systemd[1]: Starting Daily Cleanup of Temporary Directories. Jan 12 19:08:04 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 12 19:08:04 volumio systemd[1]: Starting Timers. Jan 12 19:08:04 volumio systemd[1]: Reached target Timers. Jan 12 19:08:04 volumio systemd[1]: Started Manage Sound Card State (restore and store). Jan 12 19:08:04 volumio systemd[1]: Starting Restore Sound Card State... Jan 12 19:08:04 volumio systemd[1]: Starting Basic System. Jan 12 19:08:04 volumio systemd[1]: Reached target Basic System. Jan 12 19:08:04 volumio systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... Jan 12 19:08:04 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 12 19:08:04 volumio systemd[1]: Starting cd.service... Jan 12 19:08:04 volumio systemd[1]: Started cd.service. Jan 12 19:08:04 volumio systemd[1]: Starting Volumio Iptables Module... Jan 12 19:08:04 volumio systemd[1]: Started Volumio Iptables Module. Jan 12 19:08:04 volumio systemd[1]: Starting mount disks automatically with standby... Jan 12 19:08:04 volumio systemd[1]: Started mount disks automatically with standby. Jan 12 19:08:04 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Jan 12 19:08:04 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 12 19:08:04 volumio systemd[1]: Starting volumio-remote-updater.service... Jan 12 19:08:04 volumio systemd[1]: Started volumio-remote-updater.service. Jan 12 19:08:04 volumio systemd[1]: Starting Volumio SSH enabler... Jan 12 19:08:04 volumio systemd[1]: Started Volumio SSH enabler. Jan 12 19:08:04 volumio systemd[1]: Starting Wireless Services... Jan 12 19:08:04 volumio systemd[1]: Starting Xbindrc... Jan 12 19:08:04 volumio systemd[1]: Started Xbindrc. Jan 12 19:08:04 volumio systemd[1]: Starting OpenBSD Secure Shell server... Jan 12 19:08:04 volumio systemd[1]: Starting Regular background program processing daemon... Jan 12 19:08:04 volumio volumio[481]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Jan 12 19:08:04 volumio systemd[1]: Started Regular background program processing daemon. Jan 12 19:08:04 volumio systemd[1]: Starting /etc/rc.local Compatibility... Jan 12 19:08:04 volumio systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. Jan 12 19:08:04 volumio systemd[1]: Starting Login Service... Jan 12 19:08:04 volumio systemd[1]: Starting LSB: mosquitto MQTT v3.1 message broker... Jan 12 19:08:04 volumio systemd[1]: Starting LSB: exim Mail Transport Agent... Jan 12 19:08:04 volumio systemd[1]: Starting LSB: start Winbind daemon... Jan 12 19:08:04 volumio cron[493]: (CRON) INFO (pidfile fd = 3) Jan 12 19:08:04 volumio systemd[1]: Starting LSB: start Samba daemons for the AD DC... Jan 12 19:08:04 volumio systemd[1]: Starting LSB: Start NTP daemon... Jan 12 19:08:04 volumio systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... Jan 12 19:08:04 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 12 19:08:04 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Jan 12 19:08:04 volumio systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... Jan 12 19:08:04 volumio cron[493]: (CRON) INFO (Running @reboot jobs) Jan 12 19:08:04 volumio systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... Jan 12 19:08:04 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 12 19:08:04 volumio systemd[1]: Starting Music Player Daemon... Jan 12 19:08:04 volumio mosquitto[496]: Starting network daemon:: mosquitto. Jan 12 19:08:04 volumio systemd[1]: Starting D-Bus System Message Bus... Jan 12 19:08:04 volumio systemd[1]: Started D-Bus System Message Bus. Jan 12 19:08:04 volumio ifplugd[514]: Network Interface Plugging Daemon...skip eth0...done. Jan 12 19:08:04 volumio loadcpufreq[509]: Loading cpufreq kernel modules...done (none). Jan 12 19:08:04 volumio avahi-daemon[535]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). Jan 12 19:08:04 volumio avahi-daemon[535]: Successfully dropped root privileges. Jan 12 19:08:04 volumio avahi-daemon[535]: avahi-daemon 0.6.31 starting up. Jan 12 19:08:04 volumio dhcpcd[525]: Not running dhcpcd because /etc/network/interfaces ... failed! Jan 12 19:08:05 volumio dhcpcd[525]: defines some interfaces that will use a DHCP client ... failed! Jan 12 19:08:05 volumio ntpdate[418]: Can't find host 0.debian.pool.ntp.org: Name or service not known (-2) Jan 12 19:08:05 volumio ntpdate[418]: Can't find host 1.debian.pool.ntp.org: Name or service not known (-2) Jan 12 19:08:05 volumio ntpdate[418]: Can't find host 2.debian.pool.ntp.org: Name or service not known (-2) Jan 12 19:08:05 volumio ntpdate[418]: Can't find host 3.debian.pool.ntp.org: Name or service not known (-2) Jan 12 19:08:05 volumio ntpdate[418]: no servers can be used, exiting Jan 12 19:08:05 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 12 19:08:05 volumio systemd[1]: Starting Permit User Sessions... Jan 12 19:08:05 volumio avahi-daemon[535]: Successfully called chroot(). Jan 12 19:08:05 volumio avahi-daemon[535]: Successfully dropped remaining capabilities. Jan 12 19:08:05 volumio avahi-daemon[535]: Loading service file /services/volumio.service. Jan 12 19:08:05 volumio systemd[1]: Started Restore Sound Card State. Jan 12 19:08:05 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Jan 12 19:08:05 volumio systemd[1]: Unit cd.service entered failed state. Jan 12 19:08:05 volumio avahi-daemon[535]: Network interface enumeration completed. Jan 12 19:08:05 volumio avahi-daemon[535]: Registering HINFO record with values 'ARMV7L'/'LINUX'. Jan 12 19:08:05 volumio systemd[1]: Started OpenBSD Secure Shell server. Jan 12 19:08:05 volumio avahi-daemon[535]: Server startup complete. Host name is volumio.local. Local service cookie is 3712397102. Jan 12 19:08:05 volumio avahi-daemon[535]: Service "volumio" (/services/volumio.service) successfully established. Jan 12 19:08:05 volumio systemd[1]: Started /etc/rc.local Compatibility. Jan 12 19:08:05 volumio systemd[1]: Started LSB: mosquitto MQTT v3.1 message broker. Jan 12 19:08:05 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 12 19:08:05 volumio systemd[1]: Started LSB: Brings up/down network automatically. Jan 12 19:08:05 volumio systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. Jan 12 19:08:05 volumio systemd[1]: dhcpcd.service: control process exited, code=exited status=6 Jan 12 19:08:05 volumio systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. Jan 12 19:08:05 volumio systemd[1]: Unit dhcpcd.service entered failed state. Jan 12 19:08:05 volumio systemd[1]: Started Permit User Sessions. Jan 12 19:08:05 volumio sshd[808]: Server listening on 0.0.0.0 port 22. Jan 12 19:08:05 volumio sshd[808]: Server listening on :: port 22. Jan 12 19:08:05 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Jan 12 19:08:05 volumio dbus[544]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' Jan 12 19:08:05 volumio systemd-logind[495]: New seat seat0. Jan 12 19:08:05 volumio systemd[1]: Started Login Service. Jan 12 19:08:05 volumio systemd[1]: Starting Disk Manager (legacy version)... Jan 12 19:08:05 volumio systemd[1]: Stopping cd.service... Jan 12 19:08:05 volumio systemd[1]: Starting cd.service... Jan 12 19:08:05 volumio systemd[1]: Started cd.service. Jan 12 19:08:05 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 12 19:08:05 volumio systemd[1]: Starting Hold until boot process finishes up... Jan 12 19:08:05 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 12 19:08:06 volumio volumio[837]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Jan 12 19:08:06 volumio volumio-remote-updater[486]: Error: No active session Jan 12 19:08:06 volumio volumio-remote-updater[486]: [2025-01-12 19:08:06] [info] asio async_connect error: system:111 (Connection refused) Jan 12 19:08:06 volumio volumio-remote-updater[486]: [2025-01-12 19:08:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 12 19:08:06 volumio volumio-remote-updater[486]: [2025-01-12 19:08:06] [error] handle_connect error: Underlying Transport Error Jan 12 19:08:06 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Jan 12 19:08:06 volumio systemd[1]: Unit cd.service entered failed state. Jan 12 19:08:06 volumio systemd[1]: Received SIGRTMIN+21 from PID 240 (plymouthd). Jan 12 19:08:06 volumio sudo[491]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Jan 12 19:08:06 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Jan 12 19:08:06 volumio systemd[1]: Started Hold until boot process finishes up. Jan 12 19:08:06 volumio dbus[544]: [system] Successfully activated service 'org.freedesktop.UDisks' Jan 12 19:08:06 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Jan 12 19:08:06 volumio systemd[1]: Started Disk Manager (legacy version). Jan 12 19:08:06 volumio sudo[491]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:06 volumio systemd[1]: Stopping cd.service... Jan 12 19:08:06 volumio systemd[1]: Starting cd.service... Jan 12 19:08:06 volumio systemd[1]: Started cd.service. Jan 12 19:08:06 volumio systemd[1]: Starting Getty on tty1... Jan 12 19:08:06 volumio systemd[1]: Started Getty on tty1. Jan 12 19:08:06 volumio systemd[1]: Starting Login Prompts. Jan 12 19:08:06 volumio systemd[1]: Reached target Login Prompts. Jan 12 19:08:06 volumio sudo[491]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:06 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Jan 12 19:08:06 volumio volumio[858]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Jan 12 19:08:06 volumio systemd[1]: Unit cd.service entered failed state. Jan 12 19:08:06 volumio cpufrequtils[838]: CPUFreq Utilities: Setting performance CPUFreq governor...error: unexpectedly disconnected from boot status daemon Jan 12 19:08:06 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Jan 12 19:08:06 volumio systemd[1]: Stopping cd.service... Jan 12 19:08:06 volumio systemd[1]: Starting cd.service... Jan 12 19:08:06 volumio systemd[1]: Started cd.service. Jan 12 19:08:06 volumio volumio[485]: Could not open config: /tmp/upmpdcli.conf Jan 12 19:08:06 volumio volumio[871]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Jan 12 19:08:06 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Jan 12 19:08:06 volumio systemd[1]: Unit cd.service entered failed state. Jan 12 19:08:06 volumio cpufrequtils[838]: CPU0...CPU1...CPU2...CPU3...done. Jan 12 19:08:06 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 12 19:08:06 volumio systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE Jan 12 19:08:06 volumio systemd[1]: Unit upmpdcli.service entered failed state. Jan 12 19:08:06 volumio dbus[544]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' Jan 12 19:08:06 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Jan 12 19:08:06 volumio systemd[1]: Stopping cd.service... Jan 12 19:08:06 volumio systemd[1]: Starting cd.service... Jan 12 19:08:06 volumio systemd[1]: Started cd.service. Jan 12 19:08:06 volumio systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... Jan 12 19:08:06 volumio volumio[884]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Jan 12 19:08:06 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Jan 12 19:08:06 volumio systemd[1]: Unit cd.service entered failed state. Jan 12 19:08:06 volumio polkitd[885]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 12 19:08:06 volumio dbus[544]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 12 19:08:06 volumio systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. Jan 12 19:08:06 volumio udisks-glue[484]: Device file /dev/mmcblk0 inserted Jan 12 19:08:06 volumio udisks-glue[484]: Device file /dev/mmcblk0p3 inserted Jan 12 19:08:06 volumio exim4[498]: Starting MTA: exim4. Jan 12 19:08:06 volumio systemd[1]: Started LSB: exim Mail Transport Agent. Jan 12 19:08:06 volumio systemd[1]: cd.service holdoff time over, scheduling restart. Jan 12 19:08:06 volumio systemd[1]: Stopping cd.service... Jan 12 19:08:06 volumio systemd[1]: Starting cd.service... Jan 12 19:08:06 volumio systemd[1]: cd.service start request repeated too quickly, refusing to start. Jan 12 19:08:06 volumio systemd[1]: Failed to start cd.service. Jan 12 19:08:06 volumio systemd[1]: Unit cd.service entered failed state. Jan 12 19:08:06 volumio exim[894]: 2025-01-12 19:08:06 socket bind() to port 25 for address ::1 failed: Cannot assign requested address: waiting 30s before trying again (9 more tries) Jan 12 19:08:06 volumio exim[894]: 2025-01-12 19:08:06 Cannot open main log file "/var/log/exim4/mainlog": No such file or directory: euid=0 egid=116 Jan 12 19:08:06 volumio exim[894]: exim: could not open panic log - aborting: see message(s) above Jan 12 19:08:06 volumio haveged[480]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K Jan 12 19:08:06 volumio haveged[480]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244 Jan 12 19:08:06 volumio haveged[480]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99712 Jan 12 19:08:06 volumio haveged[480]: haveged: fills: 0, generated: 0 Jan 12 19:08:06 volumio kernel: random: crng init done Jan 12 19:08:06 volumio kernel: random: 7 urandom warning(s) missed due to ratelimiting Jan 12 19:08:07 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jan 12 19:08:07 volumio systemd[1]: Stopping Xbindrc... Jan 12 19:08:07 volumio systemd[1]: Starting Xbindrc... Jan 12 19:08:07 volumio systemd[1]: Started Xbindrc. Jan 12 19:08:07 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jan 12 19:08:07 volumio systemd[1]: Stopping Xbindrc... Jan 12 19:08:07 volumio systemd[1]: Starting Xbindrc... Jan 12 19:08:07 volumio systemd[1]: Started Xbindrc. Jan 12 19:08:08 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jan 12 19:08:08 volumio systemd[1]: Stopping Xbindrc... Jan 12 19:08:08 volumio systemd[1]: Starting Xbindrc... Jan 12 19:08:08 volumio systemd[1]: Started Xbindrc. Jan 12 19:08:08 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jan 12 19:08:08 volumio systemd[1]: Stopping Xbindrc... Jan 12 19:08:08 volumio systemd[1]: Starting Xbindrc... Jan 12 19:08:08 volumio systemd[1]: Started Xbindrc. Jan 12 19:08:08 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Jan 12 19:08:08 volumio systemd[1]: Stopping Xbindrc... Jan 12 19:08:08 volumio systemd[1]: Starting Xbindrc... Jan 12 19:08:08 volumio systemd[1]: xbindkeysrc.service start request repeated too quickly, refusing to start. Jan 12 19:08:08 volumio systemd[1]: Failed to start Xbindrc. Jan 12 19:08:08 volumio systemd[1]: Unit xbindkeysrc.service entered failed state. Jan 12 19:08:09 volumio systemd[1]: Started LSB: start Samba daemons for the AD DC. Jan 12 19:08:09 volumio wireless.js[867]: WIRELESS DAEMON: start Jan 12 19:08:09 volumio wireless.js[867]: WIRELESS: Loaded configuration Jan 12 19:08:09 volumio wireless.js[867]: Cleaning previous... Jan 12 19:08:09 volumio systemd[1]: Stopped hotspot.service. Jan 12 19:08:09 volumio ntpd[938]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) Jan 12 19:08:09 volumio ntp[504]: Starting NTP server: ntpd. Jan 12 19:08:09 volumio ntpd[944]: proto: precision = 0.468 usec Jan 12 19:08:09 volumio ntpd[944]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Jan 12 19:08:09 volumio systemd[1]: Started LSB: Start NTP daemon. Jan 12 19:08:09 volumio ntpd[944]: Listen and drop on 1 v6wildcard :: UDP 123 Jan 12 19:08:09 volumio ntpd[944]: Listen normally on 2 lo 127.0.0.1 UDP 123 Jan 12 19:08:09 volumio ntpd[944]: peers refreshed Jan 12 19:08:09 volumio ntpd[944]: Listening on routing socket on fd #19 for interface updates Jan 12 19:08:09 volumio sudo[947]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 12 19:08:09 volumio nmbd[505]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Jan 12 19:08:09 volumio nmbd[505]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Jan 12 19:08:09 volumio nmbd[505]: Unable to setup corepath for nmbd: No such file or directory Jan 12 19:08:09 volumio nmbd[505]: [2025/01/12 19:08:09, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:09 volumio nmbd[505]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jan 12 19:08:09 volumio nmbd[505]: [2025/01/12 19:08:09, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:09 volumio nmbd[505]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jan 12 19:08:09 volumio nmbd[505]: [2025/01/12 19:08:09, 0] ../source3/nmbd/nmbd.c:908(main) Jan 12 19:08:09 volumio nmbd[505]: nmbd version 4.2.14-Debian started. Jan 12 19:08:09 volumio nmbd[941]: [2025/01/12 19:08:09.818642, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:09 volumio nmbd[941]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jan 12 19:08:09 volumio nmbd[505]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Jan 12 19:08:09 volumio nmbd[505]: [2025/01/12 19:08:09.818642, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:09 volumio nmbd[505]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jan 12 19:08:09 volumio sudo[947]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:09 volumio nmbd[941]: [2025/01/12 19:08:09.823874, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:09 volumio nmbd[941]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jan 12 19:08:09 volumio nmbd[951]: [2025/01/12 19:08:09.827400, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) Jan 12 19:08:09 volumio nmbd[951]: started asyncdns process 952 Jan 12 19:08:09 volumio nmbd[951]: [2025/01/12 19:08:09.830544, 0] ../lib/util/become_daemon.c:135(daemon_status) Jan 12 19:08:09 volumio nmbd[951]: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 12 19:08:09 volumio systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Jan 12 19:08:09 volumio systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Jan 12 19:08:09 volumio sudo[947]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:09 volumio nmbd[505]: [2025/01/12 19:08:09.823874, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:09 volumio nmbd[505]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Jan 12 19:08:09 volumio nmbd[505]: . Jan 12 19:08:09 volumio ntpd[944]: Deferring DNS for 0.debian.pool.ntp.org 1 Jan 12 19:08:09 volumio ntpd[944]: Deferring DNS for 1.debian.pool.ntp.org 1 Jan 12 19:08:09 volumio ntpd[944]: Deferring DNS for 2.debian.pool.ntp.org 1 Jan 12 19:08:09 volumio ntpd[944]: Deferring DNS for 3.debian.pool.ntp.org 1 Jan 12 19:08:09 volumio ntpd[964]: signal_no_reset: signal 17 had flags 4000000 Jan 12 19:08:09 volumio sudo[963]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 12 19:08:09 volumio sudo[963]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:09 volumio winbind[500]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Jan 12 19:08:09 volumio winbind[500]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Jan 12 19:08:09 volumio winbind[500]: Unable to setup corepath for winbindd: No such file or directory Jan 12 19:08:09 volumio winbind[500]: mkdir failed on directory /var/log/samba/cores: No such file or directory Jan 12 19:08:09 volumio winbind[500]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Jan 12 19:08:09 volumio winbind[500]: Unable to setup corepath for winbindd: No such file or directory Jan 12 19:08:09 volumio winbind[500]: [2025/01/12 19:08:09, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:09 volumio winbind[500]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jan 12 19:08:09 volumio winbind[500]: [2025/01/12 19:08:09, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:09 volumio winbind[500]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jan 12 19:08:09 volumio winbind[500]: [2025/01/12 19:08:09, 0] ../source3/winbindd/winbindd.c:1549(main) Jan 12 19:08:09 volumio winbind[500]: winbindd version 4.2.14-Debian started. Jan 12 19:08:09 volumio winbind[500]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Jan 12 19:08:09 volumio winbind[500]: [2025/01/12 19:08:09, 0] ../lib/util/util.c:220(directory_create_or_exist) Jan 12 19:08:09 volumio winbindd[936]: [2025/01/12 19:08:09.922159, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:09 volumio winbindd[936]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jan 12 19:08:09 volumio winbindd[936]: [2025/01/12 19:08:09.923169, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:09 volumio winbindd[936]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jan 12 19:08:09 volumio winbind[500]: mkdir failed on directory /var/log/samba/cores: No such file or directory Jan 12 19:08:09 volumio winbind[500]: [2025/01/12 19:08:09, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Jan 12 19:08:09 volumio winbind[500]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Jan 12 19:08:09 volumio winbind[500]: [2025/01/12 19:08:09, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Jan 12 19:08:09 volumio winbind[500]: Unable to setup corepath for winbindd: No such file or directory Jan 12 19:08:09 volumio winbind[500]: [2025/01/12 19:08:09.922159, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:09 volumio winbind[500]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jan 12 19:08:09 volumio winbind[500]: [2025/01/12 19:08:09.923169, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:09 volumio winbind[500]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Jan 12 19:08:10 volumio sudo[963]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:10 volumio winbind[500]: . Jan 12 19:08:10 volumio winbindd[980]: [2025/01/12 19:08:10.448542, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Jan 12 19:08:10 volumio winbindd[980]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 12 19:08:10 volumio systemd[1]: Started LSB: start Winbind daemon. Jan 12 19:08:10 volumio systemd[1]: Started Music Player Daemon. Jan 12 19:08:10 volumio wireless.js[867]: Stopped aP Jan 12 19:08:10 volumio wireless.js[867]: Start wireless flow Jan 12 19:08:10 volumio wireless.js[867]: Stopped hotspot (if there).. Jan 12 19:08:10 volumio winbindd[980]: [2025/01/12 19:08:10.531455, 0] ../lib/util/become_daemon.c:124(daemon_ready) Jan 12 19:08:10 volumio winbindd[980]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 12 19:08:10 volumio winbindd[986]: [2025/01/12 19:08:10.536058, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:10 volumio winbindd[986]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO': No such file or directory Jan 12 19:08:10 volumio sudo[987]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 12 19:08:10 volumio sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:10 volumio sudo[987]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:10 volumio sudo[995]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 12 19:08:10 volumio sudo[995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:10 volumio sudo[995]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:10 volumio smbd[954]: Starting SMB/CIFS daemon: smbd[2025/01/12 19:08:10, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:10 volumio smbd[954]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 12 19:08:10 volumio smbd[954]: [2025/01/12 19:08:10, 0] ../lib/util/util.c:220(directory_create_or_exist) Jan 12 19:08:10 volumio smbd[954]: mkdir failed on directory /var/log/samba/cores: No such file or directory Jan 12 19:08:10 volumio smbd[954]: [2025/01/12 19:08:10, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Jan 12 19:08:10 volumio smbd[954]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Jan 12 19:08:10 volumio smbd[954]: [2025/01/12 19:08:10, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Jan 12 19:08:10 volumio smbd[954]: Unable to setup corepath for smbd: No such file or directory Jan 12 19:08:10 volumio smbd[954]: [2025/01/12 19:08:10, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:10 volumio smbd[954]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 12 19:08:10 volumio smbd[954]: [2025/01/12 19:08:10, 0] ../source3/smbd/server.c:1241(main) Jan 12 19:08:10 volumio smbd[954]: smbd version 4.2.14-Debian started. Jan 12 19:08:10 volumio smbd[954]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Jan 12 19:08:10 volumio wireless.js[867]: DHCP IP Jan 12 19:08:10 volumio wireless.js[867]: Start ap Jan 12 19:08:10 volumio sudo[1004]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jan 12 19:08:10 volumio sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:10 volumio smbd[977]: [2025/01/12 19:08:10.690510, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:10 volumio smbd[977]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 12 19:08:10 volumio dhcpcd[1008]: version 6.0.5 starting Jan 12 19:08:10 volumio smbd[977]: [2025/01/12 19:08:10.691971, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:10 volumio smbd[977]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 12 19:08:10 volumio smbd[977]: [2025/01/12 19:08:10.692254, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:10 volumio smbd[977]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 12 19:08:10 volumio smbd[954]: [2025/01/12 19:08:10.690510, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:10 volumio smbd[954]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 12 19:08:10 volumio smbd[954]: [2025/01/12 19:08:10.691971, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:10 volumio smbd[954]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 12 19:08:10 volumio smbd[954]: [2025/01/12 19:08:10.692254, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:10 volumio smbd[954]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 12 19:08:10 volumio kernel: brcmfmac: power management disabled Jan 12 19:08:10 volumio smbd[954]: . Jan 12 19:08:10 volumio systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Jan 12 19:08:10 volumio wpa_supplicant[1003]: Successfully initialized wpa_supplicant Jan 12 19:08:10 volumio dhcpcd[1008]: wlan0: waiting for carrier Jan 12 19:08:10 volumio winbindd[1025]: [2025/01/12 19:08:10.788146, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:10 volumio winbindd[1025]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Jan 12 19:08:10 volumio winbindd[1026]: [2025/01/12 19:08:10.869041, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:10 volumio winbindd[1026]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory Jan 12 19:08:10 volumio smbd[1010]: [2025/01/12 19:08:10.900717, 0] ../lib/util/become_daemon.c:124(daemon_ready) Jan 12 19:08:10 volumio smbd[1010]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 12 19:08:10 volumio smbd[1027]: [2025/01/12 19:08:10.907674, 0] ../lib/util/debug.c:603(reopen_logs_internal) Jan 12 19:08:10 volumio smbd[1027]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Jan 12 19:08:11 volumio volumio-remote-updater[486]: [2025-01-12 19:08:11] [info] asio async_connect error: system:111 (Connection refused) Jan 12 19:08:11 volumio volumio-remote-updater[486]: [2025-01-12 19:08:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 12 19:08:11 volumio volumio-remote-updater[486]: [2025-01-12 19:08:11] [error] handle_connect error: Underlying Transport Error Jan 12 19:08:11 volumio login[859]: pam_lastlog(login:session): file /var/log/lastlog created Jan 12 19:08:11 volumio login[859]: pam_unix(login:session): session opened for user volumio by LOGIN(uid=0) Jan 12 19:08:11 volumio systemd[1]: Starting user-1000.slice. Jan 12 19:08:11 volumio systemd[1]: Created slice user-1000.slice. Jan 12 19:08:11 volumio systemd[1]: Starting User Manager for UID 1000... Jan 12 19:08:11 volumio systemd[1]: Starting Session c1 of user volumio. Jan 12 19:08:11 volumio systemd-logind[495]: New session c1 of user volumio. Jan 12 19:08:11 volumio systemd[1]: Started Session c1 of user volumio. Jan 12 19:08:11 volumio systemd[1033]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Jan 12 19:08:11 volumio systemd[1033]: Starting Paths. Jan 12 19:08:11 volumio systemd[1033]: Reached target Paths. Jan 12 19:08:11 volumio systemd[1033]: Starting Timers. Jan 12 19:08:11 volumio systemd[1033]: Reached target Timers. Jan 12 19:08:11 volumio systemd[1033]: Starting Sockets. Jan 12 19:08:11 volumio systemd[1033]: Reached target Sockets. Jan 12 19:08:11 volumio systemd[1033]: Starting Basic System. Jan 12 19:08:11 volumio systemd[1033]: Reached target Basic System. Jan 12 19:08:11 volumio systemd[1033]: Starting Default. Jan 12 19:08:11 volumio systemd[1033]: Reached target Default. Jan 12 19:08:11 volumio systemd[1033]: Startup finished in 41ms. Jan 12 19:08:11 volumio systemd[1]: Started User Manager for UID 1000. Jan 12 19:08:11 volumio wireless.js[867]: trying... Jan 12 19:08:11 volumio sudo[1067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 19:08:11 volumio sudo[1067]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:11 volumio sudo[1067]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:11 volumio ntpd_intres[964]: host name not found: 0.debian.pool.ntp.org Jan 12 19:08:11 volumio ntpd_intres[964]: host name not found: 1.debian.pool.ntp.org Jan 12 19:08:11 volumio ntpd_intres[964]: host name not found: 2.debian.pool.ntp.org Jan 12 19:08:11 volumio ntpd_intres[964]: host name not found: 3.debian.pool.ntp.org Jan 12 19:08:12 volumio wireless.js[867]: trying... Jan 12 19:08:12 volumio sudo[1077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 19:08:12 volumio sudo[1077]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:12 volumio sudo[1077]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:13 volumio wireless.js[867]: trying... Jan 12 19:08:13 volumio sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 19:08:13 volumio sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:13 volumio sudo[1086]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:13 volumio wpa_supplicant[1029]: wlan0: Trying to associate with SSID 'LovisaWireless' Jan 12 19:08:14 volumio wireless.js[867]: trying... Jan 12 19:08:14 volumio sudo[1095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 19:08:14 volumio sudo[1095]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:14 volumio sudo[1095]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:15 volumio wireless.js[867]: trying... Jan 12 19:08:15 volumio sudo[1104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 19:08:15 volumio sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:15 volumio sudo[1104]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:16 volumio wpa_supplicant[1029]: wlan0: Associated with 6a:3a:5e:77:03:81 Jan 12 19:08:16 volumio wpa_supplicant[1029]: wlan0: WPA: Key negotiation completed with 6a:3a:5e:77:03:81 [PTK=CCMP GTK=CCMP] Jan 12 19:08:16 volumio wpa_supplicant[1029]: wlan0: CTRL-EVENT-CONNECTED - Connection to 6a:3a:5e:77:03:81 completed [id=1 id_str=] Jan 12 19:08:16 volumio dhcpcd[1008]: wlan0: carrier acquired Jan 12 19:08:16 volumio wpa_supplicant[1029]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=AU Jan 12 19:08:16 volumio dhcpcd[1008]: wlan0: soliciting an IPv6 router Jan 12 19:08:16 volumio wireless.js[867]: trying... Jan 12 19:08:16 volumio sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 19:08:16 volumio sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:16 volumio sudo[1126]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:17 volumio wireless.js[867]: trying... Jan 12 19:08:17 volumio sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 19:08:17 volumio sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:17 volumio sudo[1135]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:18 volumio volumio-remote-updater[486]: [2025-01-12 19:08:18] [info] asio async_connect error: system:111 (Connection refused) Jan 12 19:08:18 volumio volumio-remote-updater[486]: [2025-01-12 19:08:18] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 12 19:08:18 volumio volumio-remote-updater[486]: [2025-01-12 19:08:18] [error] handle_connect error: Underlying Transport Error Jan 12 19:08:18 volumio wireless.js[867]: trying... Jan 12 19:08:18 volumio sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 19:08:18 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:18 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:19 volumio wireless.js[867]: trying... Jan 12 19:08:20 volumio sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 19:08:20 volumio sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:20 volumio sudo[1153]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:21 volumio wireless.js[867]: trying... Jan 12 19:08:21 volumio sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 19:08:21 volumio sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:21 volumio sudo[1162]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:22 volumio wireless.js[867]: trying... Jan 12 19:08:22 volumio sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 19:08:22 volumio sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:22 volumio sudo[1171]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:22 volumio dhcpcd[1008]: wlan0: using IPv4LL address 169.254.4.37 Jan 12 19:08:22 volumio avahi-daemon[535]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.4.37. Jan 12 19:08:22 volumio avahi-daemon[535]: New relevant interface wlan0.IPv4 for mDNS. Jan 12 19:08:22 volumio dhcpcd[1008]: wlan0: adding host route to 169.254.4.37 via 127.0.0.1 Jan 12 19:08:22 volumio dhcpcd[1008]: wlan0: adding route to 169.254.0.0/16 Jan 12 19:08:22 volumio avahi-daemon[535]: Registering new address record for 169.254.4.37 on wlan0.IPv4. Jan 12 19:08:22 volumio dhcpcd[1008]: forked to background, child pid 1192 Jan 12 19:08:22 volumio sudo[1004]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:23 volumio wireless.js[867]: trying... Jan 12 19:08:23 volumio sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jan 12 19:08:23 volumio sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:23 volumio sudo[1197]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:23 volumio wireless.js[867]: Connected to: ----LovisaWireless Jan 12 19:08:23 volumio wireless.js[867]: ---- Jan 12 19:08:23 volumio sudo[1206]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 19:08:23 volumio sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:23 volumio sudo[1206]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:23 volumio wireless.js[867]: ... joined AP, wlan0 IPv4 is 169.254.4.37, ipV6 is undefined Jan 12 19:08:23 volumio wireless.js[867]: It's done! AP Jan 12 19:08:23 volumio systemd[1]: Started Wireless Services. Jan 12 19:08:23 volumio systemd[1]: Starting Volumio Backend Module... Jan 12 19:08:23 volumio systemd[1]: Started Volumio Backend Module. Jan 12 19:08:23 volumio systemd[1]: Starting Volumio Streaming Daemon... Jan 12 19:08:23 volumio systemd[1]: Started Volumio Streaming Daemon. Jan 12 19:08:23 volumio systemd[1]: Starting Multi-User System. Jan 12 19:08:23 volumio systemd[1]: Reached target Multi-User System. Jan 12 19:08:23 volumio systemd[1]: Starting Graphical Interface. Jan 12 19:08:23 volumio systemd[1]: Reached target Graphical Interface. Jan 12 19:08:23 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 12 19:08:23 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 12 19:08:23 volumio systemd[1]: Startup finished in 9.230s (kernel) + 25.217s (userspace) = 34.447s. Jan 12 19:08:23 volumio volumio-streaming-daemon[1216]: ############################ Jan 12 19:08:23 volumio volumio-streaming-daemon[1216]: # Volumio Streaming Daemon # Jan 12 19:08:23 volumio volumio-streaming-daemon[1216]: # Running on port 7777 # Jan 12 19:08:23 volumio volumio-streaming-daemon[1216]: ############################ Jan 12 19:08:23 volumio ntpd[944]: Listen normally on 3 wlan0 169.254.4.37 UDP 123 Jan 12 19:08:23 volumio ntpd[944]: peers refreshed Jan 12 19:08:24 volumio nmbd[951]: [2025/01/12 19:08:24.837150, 0] ../lib/util/become_daemon.c:124(daemon_ready) Jan 12 19:08:24 volumio nmbd[951]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 12 19:08:25 volumio volumio[1215]: info: ------------------------------------------- Jan 12 19:08:25 volumio volumio[1215]: info: ----- Volumio2 ---- Jan 12 19:08:25 volumio volumio[1215]: info: ------------------------------------------- Jan 12 19:08:25 volumio volumio[1215]: info: ----- System startup ---- Jan 12 19:08:25 volumio volumio[1215]: info: ------------------------------------------- Jan 12 19:08:25 volumio volumio[1215]: info: MYVOLUMIO Environment detected Jan 12 19:08:25 volumio ntpd_intres[964]: host name not found: 0.debian.pool.ntp.org Jan 12 19:08:25 volumio ntpd_intres[964]: host name not found: 1.debian.pool.ntp.org Jan 12 19:08:25 volumio ntpd_intres[964]: host name not found: 2.debian.pool.ntp.org Jan 12 19:08:25 volumio ntpd_intres[964]: host name not found: 3.debian.pool.ntp.org Jan 12 19:08:25 volumio volumio[1215]: info: Plugin folders cleanup Jan 12 19:08:25 volumio volumio[1215]: info: Scanning into folder /volumio/app/plugins/ Jan 12 19:08:25 volumio volumio[1215]: info: Scanning category audio_interface Jan 12 19:08:25 volumio volumio[1215]: info: Scanning category miscellanea Jan 12 19:08:25 volumio volumio[1215]: info: Scanning category music_service Jan 12 19:08:25 volumio volumio[1215]: info: Scanning category plugins.json Jan 12 19:08:25 volumio volumio[1215]: info: Scanning category system_controller Jan 12 19:08:25 volumio volumio[1215]: info: Scanning category user_interface Jan 12 19:08:25 volumio volumio[1215]: info: Scanning into folder /data/plugins/ Jan 12 19:08:25 volumio volumio[1215]: info: Scanning category miscellanea Jan 12 19:08:25 volumio volumio[1215]: info: Plugin folders cleanup completed Jan 12 19:08:25 volumio volumio[1215]: info: ------------------------------------------- Jan 12 19:08:25 volumio volumio[1215]: info: ----- Core plugins startup ---- Jan 12 19:08:25 volumio volumio[1215]: info: ------------------------------------------- Jan 12 19:08:25 volumio volumio[1215]: info: Loading plugins from folder /volumio/app/plugins/ Jan 12 19:08:25 volumio volumio[1215]: info: Adding plugin upnp to MyMusic Plugins Jan 12 19:08:25 volumio volumio[1215]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 12 19:08:25 volumio volumio[1215]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 12 19:08:25 volumio volumio[1215]: info: Loading plugins from folder /data/plugins/ Jan 12 19:08:25 volumio volumio[1215]: info: Loading plugin "system"... Jan 12 19:08:25 volumio volumio[1215]: { Error: ENOENT: no such file or directory, open '/sys/class/net/eth0/address' Jan 12 19:08:25 volumio volumio[1215]: at Object.fs.openSync (fs.js:646:18) Jan 12 19:08:25 volumio volumio[1215]: at Object.fs.readFileSync (fs.js:551:33) Jan 12 19:08:25 volumio volumio[1215]: at ControllerSystem.callHome (/volumio/app/plugins/system_controller/system/index.js:578:20) Jan 12 19:08:25 volumio volumio[1215]: at ControllerSystem.onVolumioStart (/volumio/app/plugins/system_controller/system/index.js:51:7) Jan 12 19:08:25 volumio volumio[1215]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:169:35) Jan 12 19:08:25 volumio volumio[1215]: at /volumio/app/pluginmanager.js:261:30 Jan 12 19:08:25 volumio volumio[1215]: at Array.forEach () Jan 12 19:08:25 volumio volumio[1215]: at HashMap. (/volumio/app/pluginmanager.js:260:17) Jan 12 19:08:25 volumio volumio[1215]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:172:10) Jan 12 19:08:25 volumio volumio[1215]: at HashMap.proto.(anonymous function) [as forEach] (/volumio/node_modules/hashmap/hashmap.js:184:7) Jan 12 19:08:25 volumio volumio[1215]: errno: -2, Jan 12 19:08:25 volumio volumio[1215]: code: 'ENOENT', Jan 12 19:08:25 volumio volumio[1215]: syscall: 'open', Jan 12 19:08:25 volumio volumio[1215]: path: '/sys/class/net/eth0/address' } Jan 12 19:08:25 volumio volumio[1215]: info: Loading plugin "appearance"... Jan 12 19:08:27 volumio dhcpcd[1192]: wlan0: soliciting a DHCP lease Jan 12 19:08:27 volumio volumio[1215]: info: Loading plugin "network"... Jan 12 19:08:27 volumio volumio[1215]: info: Loading plugin "services"... Jan 12 19:08:27 volumio volumio[1215]: info: Loading plugin "alsa_controller"... Jan 12 19:08:27 volumio volumio[1215]: info: Loading plugin "wizard"... Jan 12 19:08:27 volumio volumio[1215]: info: Loading plugin "volumio_command_line_client"... Jan 12 19:08:27 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 19:08:27 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 19:08:27 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 19:08:27 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 19:08:27 volumio volumio[1215]: info: Loading plugin "upnp"... Jan 12 19:08:27 volumio volumio[1215]: info: [1736680107826] Starting Upmpd Daemon Jan 12 19:08:27 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 19:08:27 volumio volumio[1215]: info: Loading plugin "my_music"... Jan 12 19:08:27 volumio volumio[1215]: info: Loading plugin "mpd"... Jan 12 19:08:28 volumio dhcpcd[1192]: wlan0: offered 172.16.99.113 from 172.16.99.1 Jan 12 19:08:28 volumio volumio[1215]: info: Loading plugin "upnp_browser"... Jan 12 19:08:28 volumio volumio-remote-updater[486]: [2025-01-12 19:08:28] [connect] Successful connection Jan 12 19:08:29 volumio volumio[1215]: info: Loading plugin "networkfs"... Jan 12 19:08:29 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 19:08:29 volumio volumio[1215]: info: Loading plugin "alarm-clock"... Jan 12 19:08:29 volumio volumio[1215]: info: Loading plugin "airplay_emulation"... Jan 12 19:08:29 volumio volumio[1215]: info: Starting Shairport Sync Jan 12 19:08:29 volumio volumio[1215]: info: Loading plugin "last_100"... Jan 12 19:08:29 volumio volumio[1215]: info: Loading plugin "webradio"... Jan 12 19:08:29 volumio volumio[1215]: info: Loading plugin "i2s_dacs"... Jan 12 19:08:30 volumio volumio[1215]: info: I2S DAC not set, start Auto-detection Jan 12 19:08:30 volumio volumio[1215]: info: Loading plugin "volumiodiscovery"... Jan 12 19:08:30 volumio volumio[1215]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 12 19:08:30 volumio node[1215]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 12 19:08:30 volumio node[1215]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 19:08:30 volumio node[1215]: *** WARNING *** For more information see Jan 12 19:08:30 volumio node[1215]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 12 19:08:30 volumio node[1215]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 19:08:30 volumio node[1215]: *** WARNING *** For more information see Jan 12 19:08:30 volumio volumio[1215]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 19:08:30 volumio volumio[1215]: *** WARNING *** For more information see Jan 12 19:08:30 volumio volumio[1215]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 12 19:08:30 volumio volumio[1215]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 12 19:08:30 volumio volumio[1215]: *** WARNING *** For more information see Jan 12 19:08:30 volumio volumio[1215]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 12 19:08:30 volumio volumio[1215]: Discovery: StartAdv! undefined Jan 12 19:08:30 volumio volumio[1215]: Discovery: Started advertising... Volumio - undefined Jan 12 19:08:30 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 12 19:08:30 volumio volumio[1215]: info: Loading plugin "auto_play"... Jan 12 19:08:30 volumio volumio[1215]: info: ControllerAutoPlay - connecting mpd on host: localhost; port: 6600 Jan 12 19:08:30 volumio volumio[1215]: info: Loading plugin "outputs"... Jan 12 19:08:30 volumio volumio[1215]: info: Loading plugin "albumart"... Jan 12 19:08:30 volumio volumio[1215]: info: Plugin example_plugin is not enabled Jan 12 19:08:30 volumio volumio[1215]: info: Loading plugin "inputs"... Jan 12 19:08:30 volumio volumio[1215]: info: Loading plugin "updater_comm"... Jan 12 19:08:30 volumio volumio[1215]: info: Plugin mpdemulation is not enabled Jan 12 19:08:30 volumio volumio[1215]: info: Loading plugin "rest_api"... Jan 12 19:08:30 volumio volumio[1215]: info: Loading plugin "websocket"... Jan 12 19:08:30 volumio volumio[1215]: info: ___________ START PLUGINS ___________ Jan 12 19:08:30 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 19:08:30 volumio volumio[1215]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 19:08:30 volumio volumio[1215]: info: [1736680110497] CoreMusicLibrary::Adding element Media Servers Jan 12 19:08:30 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 19:08:30 volumio volumio[1215]: Forking 3 albumart workers Jan 12 19:08:30 volumio volumio[1215]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 19:08:30 volumio volumio[1215]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 12 19:08:30 volumio volumio[1215]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 19:08:30 volumio volumio[1215]: info: [1736680110602] CoreMusicLibrary::Adding element Last_100 Jan 12 19:08:30 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 19:08:30 volumio volumio[1215]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 12 19:08:30 volumio volumio[1215]: info: [1736680110606] CoreMusicLibrary::Adding element Webradio Jan 12 19:08:30 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 19:08:30 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 12 19:08:30 volumio volumio[1215]: info: ------------------------------------------- Jan 12 19:08:30 volumio volumio[1215]: info: ----- MyVolumio plugins startup ---- Jan 12 19:08:30 volumio volumio[1215]: info: ------------------------------------------- Jan 12 19:08:30 volumio volumio[1215]: info: Loading plugins from folder /myvolumio/plugins Jan 12 19:08:30 volumio volumio[1215]: info: Loading plugins from folder /data/myvolumio/plugins Jan 12 19:08:30 volumio volumio[1215]: info: Loading plugin "cd_controller"... Jan 12 19:08:30 volumio volumio[1215]: info: Loading plugin "streaming_services"... Jan 12 19:08:31 volumio volumio[1215]: info: Loading plugin "my_volumio"... Jan 12 19:08:31 volumio volumio[1215]: Starting albumart workers Jan 12 19:08:31 volumio volumio[1215]: Starting albumart workers Jan 12 19:08:31 volumio volumio[1215]: Starting albumart workers Jan 12 19:08:31 volumio volumio[1215]: info: Adding CD REST API Endpoints Jan 12 19:08:31 volumio volumio[1215]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller Jan 12 19:08:31 volumio volumio[1215]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller Jan 12 19:08:31 volumio volumio[1215]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller Jan 12 19:08:31 volumio volumio[1215]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 12 19:08:31 volumio volumio[1215]: info: Detecting CD presence Jan 12 19:08:31 volumio volumio[1215]: info: Streaming services startup Jan 12 19:08:31 volumio volumio[1215]: info: Starting Streaming Daemon Jan 12 19:08:31 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 19:08:31 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 19:08:31 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 19:08:31 volumio volumio[1215]: info: Starting MyVolumio Remote Streaming Endpoints Jan 12 19:08:31 volumio sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 12 19:08:31 volumio sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:31 volumio systemd[1]: Stopping Volumio Streaming Daemon... Jan 12 19:08:31 volumio volumio[1215]: info: Loading i18n strings for locale en Jan 12 19:08:31 volumio systemd[1]: Starting Volumio Streaming Daemon... Jan 12 19:08:31 volumio systemd[1]: Started Volumio Streaming Daemon. Jan 12 19:08:31 volumio volumio[1215]: Updating browse sources language Jan 12 19:08:31 volumio volumio[1215]: Cannot find translation for sourceMedia Servers Jan 12 19:08:31 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 19:08:31 volumio sudo[1283]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:31 volumio volumio-streaming-daemon[1289]: ############################ Jan 12 19:08:31 volumio volumio-streaming-daemon[1289]: # Volumio Streaming Daemon # Jan 12 19:08:31 volumio volumio-streaming-daemon[1289]: # Running on port 7777 # Jan 12 19:08:31 volumio volumio-streaming-daemon[1289]: ############################ Jan 12 19:08:31 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 19:08:31 volumio volumio[1215]: info: CoreCommandRouter::initPlayerControls Jan 12 19:08:31 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 19:08:31 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jan 12 19:08:32 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 19:08:32 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 19:08:32 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 19:08:32 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 19:08:32 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 12 19:08:32 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 12 19:08:32 volumio volumio[1215]: info: BOOT COMPLETED Jan 12 19:08:32 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 19:08:33 volumio dhcpcd[1192]: wlan0: leased 172.16.99.113 for 86400 seconds Jan 12 19:08:33 volumio avahi-daemon[535]: Registering new address record for 172.16.99.113 on wlan0.IPv4. Jan 12 19:08:33 volumio avahi-daemon[535]: Withdrawing address record for 169.254.4.37 on wlan0. Jan 12 19:08:33 volumio avahi-daemon[535]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.4.37. Jan 12 19:08:33 volumio avahi-daemon[535]: Joining mDNS multicast group on interface wlan0.IPv4 with address 172.16.99.113. Jan 12 19:08:33 volumio dhcpcd[1192]: wlan0: adding host route to 172.16.99.113 via 127.0.0.1 Jan 12 19:08:33 volumio dhcpcd[1192]: wlan0: adding route to 172.16.99.0/24 Jan 12 19:08:33 volumio dhcpcd[1192]: wlan0: adding default route via 172.16.99.1 Jan 12 19:08:33 volumio dhcpcd[1192]: wlan0: deleting host route to 169.254.4.37 via 127.0.0.1 Jan 12 19:08:33 volumio dhcpcd[1192]: wlan0: deleting route to 169.254.0.0/16 Jan 12 19:08:33 volumio systemd[1]: Stopping LSB: Start NTP daemon... Jan 12 19:08:33 volumio ntpd[944]: ntpd exiting on signal 15 Jan 12 19:08:33 volumio ntp[1402]: Stopping NTP server: ntpd. Jan 12 19:08:33 volumio systemd[1]: Starting LSB: Start NTP daemon... Jan 12 19:08:33 volumio ntpd[1421]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) Jan 12 19:08:33 volumio ntpd[1422]: proto: precision = 0.469 usec Jan 12 19:08:33 volumio ntpd[1422]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Jan 12 19:08:33 volumio ntp[1412]: Starting NTP server: ntpd. Jan 12 19:08:33 volumio systemd[1]: Started LSB: Start NTP daemon. Jan 12 19:08:33 volumio ntpd[1422]: Listen and drop on 1 v6wildcard :: UDP 123 Jan 12 19:08:33 volumio ntpd[1422]: Listen normally on 2 lo 127.0.0.1 UDP 123 Jan 12 19:08:33 volumio ntpd[1422]: Listen normally on 3 wlan0 172.16.99.113 UDP 123 Jan 12 19:08:33 volumio ntpd[1422]: peers refreshed Jan 12 19:08:33 volumio ntpd[1422]: Listening on routing socket on fd #20 for interface updates Jan 12 19:08:36 volumio volumio[1215]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jan 12 19:08:36 volumio volumio[1215]: info: CoreCommandRouter::Close All Modals sent Jan 12 19:08:36 volumio volumio[1215]: info: CoreCommandRouter::Close All Modals sent Jan 12 19:08:36 volumio volumio[1215]: Express server listening on port 3000 Jan 12 19:08:37 volumio volumio[1215]: Volumio Calling Home Jan 12 19:08:37 volumio volumio[1215]: info: MyVolumio not started Jan 12 19:08:37 volumio volumio[1215]: info: Initializing device activation check Jan 12 19:08:37 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 19:08:37 volumio volumio[1215]: info: CoreStateMachine::resetVolumioState Jan 12 19:08:37 volumio volumio[1215]: info: CoreStateMachine::getcurrentVolume Jan 12 19:08:37 volumio volumio[1215]: info: CoreCommandRouter::volumioRetrievevolume Jan 12 19:08:37 volumio volumio[1215]: info: Setting Device type: Raspberry PI Jan 12 19:08:37 volumio volumio[1215]: info: MPD running with PID542 ,establishing connection Jan 12 19:08:37 volumio sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Jan 12 19:08:37 volumio sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:37 volumio sudo[1436]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:37 volumio volumio[1215]: info: VolumeController:: Volume=100 Mute =false Jan 12 19:08:37 volumio volumio[1215]: info: CoreStateMachine::pushState Jan 12 19:08:37 volumio volumio[1215]: info: CoreStateMachine::getState Jan 12 19:08:37 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Jan 12 19:08:37 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Jan 12 19:08:37 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 19:08:37 volumio volumio[1215]: info: Jan 12 19:08:37 volumio volumio[1215]: ---------------------------- Client requests Volume 100 Jan 12 19:08:37 volumio volumio[1215]: info: VolumeController::SetAlsaVolume100 Jan 12 19:08:37 volumio volumio[1215]: info: CoreStateMachine::pushState Jan 12 19:08:37 volumio volumio[1215]: info: CoreStateMachine::getState Jan 12 19:08:37 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Jan 12 19:08:37 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Jan 12 19:08:37 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 19:08:37 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 12 19:08:37 volumio volumio[1215]: info: Reloading queue from file Jan 12 19:08:37 volumio volumio[1215]: info: Jan 12 19:08:37 volumio volumio[1215]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Jan 12 19:08:37 volumio volumio[1215]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Jan 12 19:08:37 volumio volumio[1215]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Jan 12 19:08:37 volumio volumio[1215]: at emitOne (events.js:116:13) Jan 12 19:08:37 volumio volumio[1215]: at Socket.emit (events.js:211:7) Jan 12 19:08:37 volumio volumio[1215]: at addChunk (_stream_readable.js:263:12) Jan 12 19:08:37 volumio volumio[1215]: at readableAddChunk (_stream_readable.js:246:13) Jan 12 19:08:37 volumio volumio[1215]: at Socket.Readable.push (_stream_readable.js:208:10) Jan 12 19:08:37 volumio volumio[1215]: at Pipe.onread (net.js:607:20) Jan 12 19:08:37 volumio volumio[1215]: info: Setting volume on startup at 100 Jan 12 19:08:37 volumio volumio[1215]: info: CoreStateMachine::setRepeat on single undefined Jan 12 19:08:37 volumio volumio[1215]: info: CoreStateMachine::pushState Jan 12 19:08:37 volumio volumio[1215]: info: CoreStateMachine::getState Jan 12 19:08:37 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Jan 12 19:08:37 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Jan 12 19:08:37 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 19:08:37 volumio volumio[1215]: info: CoreStateMachine::setRandom on Jan 12 19:08:37 volumio volumio[1215]: info: CoreStateMachine::pushState Jan 12 19:08:37 volumio volumio[1215]: info: CoreStateMachine::getState Jan 12 19:08:37 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Jan 12 19:08:37 volumio volumio[1215]: info: CoreCommandRouter::volumioPushState Jan 12 19:08:37 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 12 19:08:37 volumio sudo[1447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 19:08:37 volumio sudo[1447]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:37 volumio sudo[1447]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:37 volumio sudo[1453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Jan 12 19:08:37 volumio sudo[1453]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:37 volumio sudo[1458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Jan 12 19:08:37 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Jan 12 19:08:37 volumio systemd[1]: Started ShairportSync AirTunes receiver. Jan 12 19:08:37 volumio sudo[1458]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:37 volumio sudo[1453]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:37 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Jan 12 19:08:37 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 12 19:08:37 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Jan 12 19:08:37 volumio systemd[1]: Started ShairportSync AirTunes receiver. Jan 12 19:08:38 volumio sudo[1458]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:38 volumio shairport-sync[1473]: Successful Startup Jan 12 19:08:38 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 12 19:08:38 volumio volumio[1215]: Discovery: adding 004d4c5c-ee31-43a1-bcb1-ca4d7ffc5cc7 Jan 12 19:08:38 volumio volumio[1215]: info: mDNS: Found device Volumio Jan 12 19:08:38 volumio sudo[1481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 19:08:38 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:38 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:38 volumio volumio[1215]: info: Shairport-Sync Started Jan 12 19:08:38 volumio volumio[1215]: Error adding Membership: Error: addMembership EINVAL Jan 12 19:08:38 volumio volumio[1215]: info: ControllerAutoPlay - mpd ready Jan 12 19:08:38 volumio volumio[1215]: info: Shairport-Sync Started Jan 12 19:08:38 volumio volumio[1215]: info: CoreCommandRouter::volumioGetState Jan 12 19:08:38 volumio volumio[1215]: info: CoreStateMachine::getState Jan 12 19:08:38 volumio volumio[1215]: info: CorePlayQueue::getTrack 0 Jan 12 19:08:38 volumio sudo[1490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 12 19:08:38 volumio sudo[1490]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:38 volumio sudo[1490]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:38 volumio sudo[1499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 12 19:08:38 volumio sudo[1499]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 12 19:08:38 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Jan 12 19:08:38 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 12 19:08:38 volumio sudo[1499]: pam_unix(sudo:session): session closed for user root Jan 12 19:08:38 volumio volumio[1215]: info: Upmpdcli Daemon Started Jan 12 19:08:40 volumio volumio[1215]: info: Fetching Streaming Services browse cache Jan 12 19:08:40 volumio volumio[1215]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Jan 12 19:08:40 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 19:08:40 volumio volumio[1215]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Jan 12 19:08:40 volumio volumio[1215]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 12 19:09:02 volumio systemd[1]: Time has been changed Jan 12 19:09:02 volumio systemd[1033]: Time has been changed Jan 12 19:09:02 volumio volumio[1215]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 19:09:02 volumio volumio[1215]: { Error: addMembership ENODEV Jan 12 19:09:02 volumio volumio[1215]: at _errnoException (util.js:1022:11) Jan 12 19:09:02 volumio volumio[1215]: at Socket.addMembership (dgram.js:615:11) Jan 12 19:09:02 volumio volumio[1215]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:261:16) Jan 12 19:09:02 volumio volumio[1215]: at ontimeout (timers.js:482:11) Jan 12 19:09:02 volumio volumio[1215]: at tryOnTimeout (timers.js:317:5) Jan 12 19:09:02 volumio volumio[1215]: at Timer.listOnTimeout (timers.js:277:5) code: 'ENODEV', errno: 'ENODEV', syscall: 'addMembership' } Jan 12 19:09:02 volumio volumio[1215]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 19:09:03 volumio sudo[1563]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-12 19:08 Jan 12 19:09:03 volumio sudo[1563]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f1d44ae5428081b5b8ca49f419b134ef83e234df" VOLUMIO_FE_VERSION="021732c5252c1ad4fb85955339e1f032fe4ae9f0" VOLUMIO_BE_VERSION="caf6c0f9a8e3cd984391aa77621b50a9247127b8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Aug 2 17:17:17 CEST 2019" VOLUMIO_VERSION="2.599" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="c28fac21d698202aa4e6e014f85b1eaf"