-- Logs begin at Tue 2024-05-14 21:36:54 SAST, end at Tue 2024-05-14 21:38:02 SAST. -- May 14 21:36:59 volumio systemd[1]: Started Restore Sound Card State. May 14 21:37:00 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 14 21:37:00 volumio dbus[577]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' May 14 21:37:00 volumio systemd[1]: Unit cd.service entered failed state. May 14 21:37:00 volumio sudo[529]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off May 14 21:37:00 volumio systemd[1]: Started OpenBSD Secure Shell server. May 14 21:37:00 volumio systemd[1]: Started /etc/rc.local Compatibility. May 14 21:37:00 volumio systemd[1]: Started LSB: mosquitto MQTT v3.1 message broker. May 14 21:37:00 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 14 21:37:00 volumio systemd[1]: Started LSB: Brings up/down network automatically. May 14 21:37:00 volumio systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. May 14 21:37:00 volumio systemd[1]: dhcpcd.service: control process exited, code=exited status=6 May 14 21:37:00 volumio systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. May 14 21:37:00 volumio systemd[1]: Unit dhcpcd.service entered failed state. May 14 21:37:00 volumio systemd[1]: Started Permit User Sessions. May 14 21:37:00 volumio sudo[529]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:00 volumio systemd[1]: cd.service holdoff time over, scheduling restart. May 14 21:37:00 volumio sudo[529]: pam_unix(sudo:session): session closed for user root May 14 21:37:00 volumio sshd[790]: Server listening on 0.0.0.0 port 22. May 14 21:37:00 volumio sshd[790]: Server listening on :: port 22. May 14 21:37:00 volumio volumio-remote-updater[523]: Error: No active session May 14 21:37:00 volumio volumio-remote-updater[523]: [2024-05-14 21:37:00] [info] asio async_connect error: system:111 (Connection refused) May 14 21:37:00 volumio volumio-remote-updater[523]: [2024-05-14 21:37:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 14 21:37:00 volumio volumio-remote-updater[523]: [2024-05-14 21:37:00] [error] handle_connect error: Underlying Transport Error May 14 21:37:00 volumio systemd-logind[533]: New seat seat0. May 14 21:37:00 volumio systemd[1]: Started Login Service. May 14 21:37:00 volumio systemd[1]: Starting Disk Manager (legacy version)... May 14 21:37:00 volumio systemd[1]: Stopping cd.service... May 14 21:37:00 volumio systemd[1]: Starting cd.service... May 14 21:37:00 volumio systemd[1]: Started cd.service. May 14 21:37:00 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 14 21:37:00 volumio systemd[1]: Starting Hold until boot process finishes up... May 14 21:37:00 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... May 14 21:37:00 volumio volumio[881]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 14 21:37:00 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 14 21:37:00 volumio systemd[1]: Unit cd.service entered failed state. May 14 21:37:00 volumio volumio[522]: Could not open config: /tmp/upmpdcli.conf May 14 21:37:00 volumio systemd[1]: Received SIGRTMIN+21 from PID 248 (plymouthd). May 14 21:37:00 volumio systemd[1]: Started Hold until boot process finishes up. May 14 21:37:00 volumio systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE May 14 21:37:00 volumio systemd[1]: Unit upmpdcli.service entered failed state. May 14 21:37:00 volumio systemd[1]: Started Terminate Plymouth Boot Screen. May 14 21:37:00 volumio systemd[1]: cd.service holdoff time over, scheduling restart. May 14 21:37:00 volumio systemd[1]: Stopping cd.service... May 14 21:37:00 volumio systemd[1]: Starting cd.service... May 14 21:37:00 volumio systemd[1]: Started cd.service. May 14 21:37:00 volumio systemd[1]: Starting Getty on tty1... May 14 21:37:00 volumio cpufrequtils[882]: CPUFreq Utilities: Setting performance CPUFreq governor...error: unexpectedly disconnected from boot status daemon May 14 21:37:00 volumio dbus[577]: [system] Successfully activated service 'org.freedesktop.UDisks' May 14 21:37:00 volumio volumio[908]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 14 21:37:00 volumio systemd[1]: Started Getty on tty1. May 14 21:37:00 volumio systemd[1]: Starting Login Prompts. May 14 21:37:00 volumio systemd[1]: Reached target Login Prompts. May 14 21:37:00 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 14 21:37:00 volumio systemd[1]: Unit cd.service entered failed state. May 14 21:37:00 volumio systemd[1]: Started Disk Manager (legacy version). May 14 21:37:00 volumio cpufrequtils[882]: CPU0...CPU1...CPU2...CPU3...done. May 14 21:37:00 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. May 14 21:37:01 volumio dbus[577]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' May 14 21:37:01 volumio systemd[1]: cd.service holdoff time over, scheduling restart. May 14 21:37:01 volumio systemd[1]: Stopping cd.service... May 14 21:37:01 volumio systemd[1]: Starting cd.service... May 14 21:37:01 volumio systemd[1]: Started cd.service. May 14 21:37:01 volumio systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... May 14 21:37:01 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 14 21:37:01 volumio volumio[922]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 14 21:37:01 volumio systemd[1]: Unit cd.service entered failed state. May 14 21:37:01 volumio polkitd[923]: started daemon version 0.105 using authority implementation `local' version `0.105' May 14 21:37:01 volumio dbus[577]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 14 21:37:01 volumio systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. May 14 21:37:01 volumio udisks-glue[521]: Device file /dev/mmcblk0p3 inserted May 14 21:37:01 volumio udisks-glue[521]: Device file /dev/mmcblk0 inserted May 14 21:37:01 volumio haveged[517]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K May 14 21:37:01 volumio haveged[517]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244 May 14 21:37:01 volumio haveged[517]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00053 May 14 21:37:01 volumio haveged[517]: haveged: fills: 0, generated: 0 May 14 21:37:01 volumio systemd[1]: cd.service holdoff time over, scheduling restart. May 14 21:37:01 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 14 21:37:01 volumio systemd[1]: Stopping Xbindrc... May 14 21:37:01 volumio systemd[1]: Starting Xbindrc... May 14 21:37:01 volumio systemd[1]: Started Xbindrc. May 14 21:37:01 volumio systemd[1]: Stopping cd.service... May 14 21:37:01 volumio systemd[1]: Starting cd.service... May 14 21:37:01 volumio systemd[1]: Started cd.service. May 14 21:37:01 volumio volumio[937]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 14 21:37:01 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 14 21:37:01 volumio systemd[1]: Unit cd.service entered failed state. May 14 21:37:01 volumio exim[940]: 2024-05-14 21:37:01 socket bind() to port 25 for address ::1 failed: Cannot assign requested address: waiting 30s before trying again (9 more tries) May 14 21:37:01 volumio exim[940]: 2024-05-14 21:37:01 Cannot open main log file "/var/log/exim4/mainlog": No such file or directory: euid=0 egid=116 May 14 21:37:01 volumio exim[940]: exim: could not open panic log - aborting: see message(s) above May 14 21:37:01 volumio exim4[536]: Starting MTA: exim4. May 14 21:37:01 volumio systemd[1]: Started LSB: exim Mail Transport Agent. May 14 21:37:01 volumio systemd[1]: cd.service holdoff time over, scheduling restart. May 14 21:37:01 volumio systemd[1]: Stopping cd.service... May 14 21:37:01 volumio systemd[1]: Starting cd.service... May 14 21:37:01 volumio systemd[1]: cd.service start request repeated too quickly, refusing to start. May 14 21:37:01 volumio systemd[1]: Failed to start cd.service. May 14 21:37:01 volumio systemd[1]: Unit cd.service entered failed state. May 14 21:37:02 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 14 21:37:02 volumio systemd[1]: Stopping Xbindrc... May 14 21:37:02 volumio systemd[1]: Starting Xbindrc... May 14 21:37:02 volumio systemd[1]: Started Xbindrc. May 14 21:37:02 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 14 21:37:02 volumio systemd[1]: Stopping Xbindrc... May 14 21:37:02 volumio systemd[1]: Starting Xbindrc... May 14 21:37:02 volumio systemd[1]: Started Xbindrc. May 14 21:37:02 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 14 21:37:02 volumio systemd[1]: Stopping Xbindrc... May 14 21:37:02 volumio systemd[1]: Starting Xbindrc... May 14 21:37:02 volumio systemd[1]: Started Xbindrc. May 14 21:37:03 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 14 21:37:03 volumio systemd[1]: Stopping Xbindrc... May 14 21:37:03 volumio systemd[1]: Starting Xbindrc... May 14 21:37:03 volumio systemd[1]: xbindkeysrc.service start request repeated too quickly, refusing to start. May 14 21:37:03 volumio systemd[1]: Failed to start Xbindrc. May 14 21:37:03 volumio systemd[1]: Unit xbindkeysrc.service entered failed state. May 14 21:37:03 volumio systemd[1]: Started Music Player Daemon. May 14 21:37:03 volumio wireless.js[830]: WIRELESS DAEMON: start May 14 21:37:03 volumio wireless.js[830]: WIRELESS: Loaded configuration May 14 21:37:03 volumio wireless.js[830]: Cleaning previous... May 14 21:37:03 volumio systemd[1]: Started LSB: start Samba daemons for the AD DC. May 14 21:37:03 volumio systemd[1]: Stopped hotspot.service. May 14 21:37:03 volumio sudo[984]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 14 21:37:03 volumio sudo[984]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:03 volumio sudo[984]: pam_unix(sudo:session): session closed for user root May 14 21:37:03 volumio sudo[992]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 14 21:37:03 volumio sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:03 volumio winbind[539]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory May 14 21:37:03 volumio winbind[539]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 14 21:37:03 volumio winbind[539]: Unable to setup corepath for winbindd: No such file or directory May 14 21:37:03 volumio winbind[539]: mkdir failed on directory /var/log/samba/cores: No such file or directory May 14 21:37:03 volumio winbind[539]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 14 21:37:03 volumio winbind[539]: Unable to setup corepath for winbindd: No such file or directory May 14 21:37:03 volumio winbind[539]: [2024/05/14 21:37:03, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:03 volumio winbind[539]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 14 21:37:03 volumio winbind[539]: [2024/05/14 21:37:03, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:03 volumio winbind[539]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 14 21:37:03 volumio winbind[539]: [2024/05/14 21:37:03, 0] ../source3/winbindd/winbindd.c:1549(main) May 14 21:37:03 volumio winbind[539]: winbindd version 4.2.14-Debian started. May 14 21:37:03 volumio winbind[539]: Copyright Andrew Tridgell and the Samba Team 1992-2014 May 14 21:37:03 volumio winbind[539]: [2024/05/14 21:37:03, 0] ../lib/util/util.c:220(directory_create_or_exist) May 14 21:37:03 volumio winbind[539]: mkdir failed on directory /var/log/samba/cores: No such file or directory May 14 21:37:03 volumio winbind[539]: [2024/05/14 21:37:03, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) May 14 21:37:03 volumio winbind[539]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 14 21:37:03 volumio winbind[539]: [2024/05/14 21:37:03, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) May 14 21:37:03 volumio winbind[539]: Unable to setup corepath for winbindd: No such file or directory May 14 21:37:03 volumio winbindd[982]: [2024/05/14 21:37:03.846463, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:03 volumio winbindd[982]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 14 21:37:03 volumio winbindd[982]: [2024/05/14 21:37:03.847454, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:03 volumio winbindd[982]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 14 21:37:03 volumio winbind[539]: [2024/05/14 21:37:03.846463, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:03 volumio winbind[539]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 14 21:37:03 volumio winbind[539]: [2024/05/14 21:37:03.847454, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:03 volumio winbind[539]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 14 21:37:03 volumio nmbd[549]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory May 14 21:37:03 volumio nmbd[549]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 14 21:37:03 volumio nmbd[549]: Unable to setup corepath for nmbd: No such file or directory May 14 21:37:03 volumio nmbd[999]: [2024/05/14 21:37:03.863302, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:03 volumio nmbd[999]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 14 21:37:03 volumio nmbd[999]: [2024/05/14 21:37:03.864808, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:03 volumio nmbd[999]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 14 21:37:03 volumio nmbd[549]: [2024/05/14 21:37:03, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:03 volumio nmbd[549]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 14 21:37:03 volumio nmbd[549]: [2024/05/14 21:37:03, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:03 volumio nmbd[549]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 14 21:37:03 volumio nmbd[549]: [2024/05/14 21:37:03, 0] ../source3/nmbd/nmbd.c:908(main) May 14 21:37:03 volumio nmbd[549]: nmbd version 4.2.14-Debian started. May 14 21:37:03 volumio nmbd[549]: Copyright Andrew Tridgell and the Samba Team 1992-2014 May 14 21:37:03 volumio nmbd[549]: [2024/05/14 21:37:03.863302, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:03 volumio nmbd[549]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 14 21:37:03 volumio nmbd[549]: [2024/05/14 21:37:03.864808, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:03 volumio nmbd[549]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 14 21:37:03 volumio nmbd[1000]: [2024/05/14 21:37:03.868184, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) May 14 21:37:03 volumio nmbd[1000]: started asyncdns process 1001 May 14 21:37:03 volumio systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). May 14 21:37:03 volumio systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... May 14 21:37:03 volumio nmbd[549]: . May 14 21:37:04 volumio ntpd[1009]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) May 14 21:37:04 volumio ntpd[1012]: proto: precision = 0.573 usec May 14 21:37:04 volumio systemd[1]: Started LSB: Start NTP daemon. May 14 21:37:04 volumio ntp[542]: Starting NTP server: ntpd. May 14 21:37:04 volumio ntpd[1012]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 May 14 21:37:04 volumio ntpd[1012]: Listen and drop on 1 v6wildcard :: UDP 123 May 14 21:37:04 volumio nmbd[1000]: [2024/05/14 21:37:04.277327, 0] ../lib/util/become_daemon.c:135(daemon_status) May 14 21:37:04 volumio nmbd[1000]: 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). May 14 21:37:04 volumio sudo[992]: pam_unix(sudo:session): session closed for user root May 14 21:37:04 volumio ntpd[1012]: Listen normally on 2 lo 127.0.0.1 UDP 123 May 14 21:37:04 volumio ntpd[1012]: peers refreshed May 14 21:37:04 volumio ntpd[1012]: Listening on routing socket on fd #19 for interface updates May 14 21:37:04 volumio winbindd[1017]: [2024/05/14 21:37:04.295432, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) May 14 21:37:04 volumio winbindd[1017]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 14 21:37:04 volumio systemd[1]: Started LSB: start Winbind daemon. May 14 21:37:04 volumio winbind[539]: . May 14 21:37:04 volumio winbindd[1017]: [2024/05/14 21:37:04.341796, 0] ../lib/util/become_daemon.c:124(daemon_ready) May 14 21:37:04 volumio winbindd[1017]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 14 21:37:04 volumio winbindd[1020]: [2024/05/14 21:37:04.349481, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:04 volumio winbindd[1020]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 14 21:37:04 volumio wireless.js[830]: Stopped aP May 14 21:37:04 volumio wireless.js[830]: Start wireless flow May 14 21:37:04 volumio wireless.js[830]: Stopped hotspot (if there).. May 14 21:37:04 volumio ntpd[1012]: Deferring DNS for 0.debian.pool.ntp.org 1 May 14 21:37:04 volumio ntpd[1012]: Deferring DNS for 1.debian.pool.ntp.org 1 May 14 21:37:04 volumio ntpd[1012]: Deferring DNS for 2.debian.pool.ntp.org 1 May 14 21:37:04 volumio ntpd[1012]: Deferring DNS for 3.debian.pool.ntp.org 1 May 14 21:37:04 volumio ntpd[1024]: signal_no_reset: signal 17 had flags 4000000 May 14 21:37:04 volumio sudo[1025]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 14 21:37:04 volumio sudo[1025]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:04 volumio sudo[1025]: pam_unix(sudo:session): session closed for user root May 14 21:37:04 volumio sudo[1033]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 14 21:37:04 volumio sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:04 volumio sudo[1033]: pam_unix(sudo:session): session closed for user root May 14 21:37:04 volumio wireless.js[830]: DHCP IP May 14 21:37:04 volumio wireless.js[830]: Start ap May 14 21:37:04 volumio sudo[1042]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 14 21:37:04 volumio sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:04 volumio dhcpcd[1046]: version 6.0.5 starting May 14 21:37:04 volumio kernel: brcmfmac: power management disabled May 14 21:37:04 volumio wpa_supplicant[1041]: Successfully initialized wpa_supplicant May 14 21:37:04 volumio dhcpcd[1046]: eth0: waiting for carrier May 14 21:37:04 volumio dhcpcd[1046]: wlan0: waiting for carrier May 14 21:37:04 volumio smbd[1002]: Starting SMB/CIFS daemon: smbd[2024/05/14 21:37:04, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:04 volumio smbd[1002]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 14 21:37:04 volumio smbd[1002]: [2024/05/14 21:37:04, 0] ../lib/util/util.c:220(directory_create_or_exist) May 14 21:37:04 volumio smbd[1002]: mkdir failed on directory /var/log/samba/cores: No such file or directory May 14 21:37:04 volumio smbd[1002]: [2024/05/14 21:37:04, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) May 14 21:37:04 volumio smbd[1002]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 14 21:37:04 volumio smbd[1002]: [2024/05/14 21:37:04, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) May 14 21:37:04 volumio smbd[1002]: Unable to setup corepath for smbd: No such file or directory May 14 21:37:04 volumio smbd[1002]: [2024/05/14 21:37:04, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:04 volumio smbd[1002]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 14 21:37:04 volumio smbd[1002]: [2024/05/14 21:37:04, 0] ../source3/smbd/server.c:1241(main) May 14 21:37:04 volumio smbd[1002]: smbd version 4.2.14-Debian started. May 14 21:37:04 volumio smbd[1002]: Copyright Andrew Tridgell and the Samba Team 1992-2014 May 14 21:37:04 volumio smbd[1065]: [2024/05/14 21:37:04.808021, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:04 volumio smbd[1065]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 14 21:37:04 volumio smbd[1065]: [2024/05/14 21:37:04.809513, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:04 volumio smbd[1065]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 14 21:37:04 volumio smbd[1065]: [2024/05/14 21:37:04.809716, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:04 volumio smbd[1065]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 14 21:37:04 volumio smbd[1002]: [2024/05/14 21:37:04.808021, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:04 volumio smbd[1002]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 14 21:37:04 volumio smbd[1002]: [2024/05/14 21:37:04.809513, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:04 volumio smbd[1002]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 14 21:37:04 volumio smbd[1002]: [2024/05/14 21:37:04.809716, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:04 volumio smbd[1002]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 14 21:37:04 volumio smbd[1002]: . May 14 21:37:04 volumio systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). May 14 21:37:04 volumio winbindd[1074]: [2024/05/14 21:37:04.861911, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:04 volumio winbindd[1074]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory May 14 21:37:04 volumio winbindd[1076]: [2024/05/14 21:37:04.923001, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:04 volumio winbindd[1076]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory May 14 21:37:04 volumio smbd[1071]: [2024/05/14 21:37:04.958619, 0] ../lib/util/become_daemon.c:124(daemon_ready) May 14 21:37:04 volumio smbd[1071]: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 14 21:37:04 volumio smbd[1077]: [2024/05/14 21:37:04.964454, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 14 21:37:04 volumio smbd[1077]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 14 21:37:05 volumio volumio-remote-updater[523]: [2024-05-14 21:37:05] [info] asio async_connect error: system:111 (Connection refused) May 14 21:37:05 volumio volumio-remote-updater[523]: [2024-05-14 21:37:05] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 14 21:37:05 volumio volumio-remote-updater[523]: [2024-05-14 21:37:05] [error] handle_connect error: Underlying Transport Error May 14 21:37:05 volumio wireless.js[830]: trying... May 14 21:37:05 volumio sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:37:05 volumio sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:05 volumio sudo[1080]: pam_unix(sudo:session): session closed for user root May 14 21:37:05 volumio login[909]: pam_lastlog(login:session): file /var/log/lastlog created May 14 21:37:05 volumio login[909]: pam_unix(login:session): session opened for user volumio by LOGIN(uid=0) May 14 21:37:05 volumio systemd[1]: Starting user-1000.slice. May 14 21:37:05 volumio systemd[1]: Created slice user-1000.slice. May 14 21:37:05 volumio systemd[1]: Starting User Manager for UID 1000... May 14 21:37:05 volumio systemd[1]: Starting Session c1 of user volumio. May 14 21:37:05 volumio systemd-logind[533]: New session c1 of user volumio. May 14 21:37:05 volumio systemd[1]: Started Session c1 of user volumio. May 14 21:37:05 volumio systemd[1091]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) May 14 21:37:06 volumio systemd[1091]: Starting Paths. May 14 21:37:06 volumio systemd[1091]: Reached target Paths. May 14 21:37:06 volumio systemd[1091]: Starting Timers. May 14 21:37:06 volumio systemd[1091]: Reached target Timers. May 14 21:37:06 volumio systemd[1091]: Starting Sockets. May 14 21:37:06 volumio systemd[1091]: Reached target Sockets. May 14 21:37:06 volumio systemd[1091]: Starting Basic System. May 14 21:37:06 volumio systemd[1091]: Reached target Basic System. May 14 21:37:06 volumio systemd[1091]: Starting Default. May 14 21:37:06 volumio systemd[1091]: Reached target Default. May 14 21:37:06 volumio systemd[1091]: Startup finished in 36ms. May 14 21:37:06 volumio systemd[1]: Started User Manager for UID 1000. May 14 21:37:06 volumio ntpd_intres[1024]: host name not found: 0.debian.pool.ntp.org May 14 21:37:06 volumio ntpd_intres[1024]: host name not found: 1.debian.pool.ntp.org May 14 21:37:06 volumio ntpd_intres[1024]: host name not found: 2.debian.pool.ntp.org May 14 21:37:06 volumio ntpd_intres[1024]: host name not found: 3.debian.pool.ntp.org May 14 21:37:06 volumio wireless.js[830]: trying... May 14 21:37:06 volumio sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:37:06 volumio sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:06 volumio sudo[1125]: pam_unix(sudo:session): session closed for user root May 14 21:37:07 volumio wireless.js[830]: trying... May 14 21:37:07 volumio sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:37:07 volumio sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:07 volumio sudo[1135]: pam_unix(sudo:session): session closed for user root May 14 21:37:07 volumio wpa_supplicant[1075]: wlan0: Trying to associate with SSID 'LovisaWireless' May 14 21:37:08 volumio wireless.js[830]: trying... May 14 21:37:08 volumio sudo[1144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:37:08 volumio sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:08 volumio sudo[1144]: pam_unix(sudo:session): session closed for user root May 14 21:37:09 volumio wireless.js[830]: trying... May 14 21:37:09 volumio sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:37:09 volumio sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:09 volumio sudo[1153]: pam_unix(sudo:session): session closed for user root May 14 21:37:10 volumio wpa_supplicant[1075]: wlan0: Associated with ba:db:19:ce:4e:5e May 14 21:37:10 volumio wpa_supplicant[1075]: wlan0: WPA: Key negotiation completed with ba:db:19:ce:4e:5e [PTK=CCMP GTK=CCMP] May 14 21:37:10 volumio wpa_supplicant[1075]: wlan0: CTRL-EVENT-CONNECTED - Connection to ba:db:19:ce:4e:5e completed [id=1 id_str=] May 14 21:37:10 volumio dhcpcd[1046]: wlan0: carrier acquired May 14 21:37:10 volumio wpa_supplicant[1075]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE May 14 21:37:10 volumio dhcpcd[1046]: wlan0: soliciting an IPv6 router May 14 21:37:10 volumio wireless.js[830]: trying... May 14 21:37:10 volumio sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:37:10 volumio sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:10 volumio sudo[1175]: pam_unix(sudo:session): session closed for user root May 14 21:37:11 volumio wireless.js[830]: trying... May 14 21:37:11 volumio sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:37:11 volumio sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:11 volumio sudo[1184]: pam_unix(sudo:session): session closed for user root May 14 21:37:12 volumio wireless.js[830]: trying... May 14 21:37:12 volumio sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:37:12 volumio sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:12 volumio sudo[1193]: pam_unix(sudo:session): session closed for user root May 14 21:37:12 volumio volumio-remote-updater[523]: [2024-05-14 21:37:12] [info] asio async_connect error: system:111 (Connection refused) May 14 21:37:12 volumio volumio-remote-updater[523]: [2024-05-14 21:37:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 14 21:37:12 volumio volumio-remote-updater[523]: [2024-05-14 21:37:12] [error] handle_connect error: Underlying Transport Error May 14 21:37:13 volumio wireless.js[830]: trying... May 14 21:37:13 volumio sudo[1202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:37:13 volumio sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:13 volumio sudo[1202]: pam_unix(sudo:session): session closed for user root May 14 21:37:14 volumio wireless.js[830]: trying... May 14 21:37:14 volumio sudo[1211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:37:14 volumio sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:14 volumio sudo[1211]: pam_unix(sudo:session): session closed for user root May 14 21:37:15 volumio wireless.js[830]: trying... May 14 21:37:15 volumio sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:37:15 volumio sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:15 volumio sudo[1220]: pam_unix(sudo:session): session closed for user root May 14 21:37:16 volumio dhcpcd[1046]: wlan0: using IPv4LL address 169.254.62.227 May 14 21:37:16 volumio avahi-daemon[568]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.62.227. May 14 21:37:16 volumio avahi-daemon[568]: New relevant interface wlan0.IPv4 for mDNS. May 14 21:37:16 volumio dhcpcd[1046]: wlan0: adding host route to 169.254.62.227 via 127.0.0.1 May 14 21:37:16 volumio dhcpcd[1046]: wlan0: adding route to 169.254.0.0/16 May 14 21:37:16 volumio avahi-daemon[568]: Registering new address record for 169.254.62.227 on wlan0.IPv4. May 14 21:37:16 volumio dhcpcd[1046]: forked to background, child pid 1241 May 14 21:37:16 volumio sudo[1042]: pam_unix(sudo:session): session closed for user root May 14 21:37:17 volumio wireless.js[830]: trying... May 14 21:37:17 volumio sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 14 21:37:17 volumio sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:17 volumio sudo[1246]: pam_unix(sudo:session): session closed for user root May 14 21:37:17 volumio wireless.js[830]: Connected to: ----LovisaWireless May 14 21:37:17 volumio wireless.js[830]: ---- May 14 21:37:17 volumio sudo[1255]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 21:37:17 volumio sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:17 volumio sudo[1255]: pam_unix(sudo:session): session closed for user root May 14 21:37:17 volumio wireless.js[830]: ... joined AP, wlan0 IPv4 is 169.254.62.227, ipV6 is undefined May 14 21:37:17 volumio wireless.js[830]: It's done! AP May 14 21:37:17 volumio systemd[1]: Started Wireless Services. May 14 21:37:17 volumio systemd[1]: Starting Volumio Backend Module... May 14 21:37:17 volumio systemd[1]: Started Volumio Backend Module. May 14 21:37:17 volumio systemd[1]: Starting Volumio Streaming Daemon... May 14 21:37:17 volumio systemd[1]: Started Volumio Streaming Daemon. May 14 21:37:17 volumio systemd[1]: Starting Multi-User System. May 14 21:37:17 volumio systemd[1]: Reached target Multi-User System. May 14 21:37:17 volumio systemd[1]: Starting Graphical Interface. May 14 21:37:17 volumio systemd[1]: Reached target Graphical Interface. May 14 21:37:17 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 14 21:37:17 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 14 21:37:17 volumio systemd[1]: Startup finished in 9.736s (kernel) + 23.972s (userspace) = 33.708s. May 14 21:37:17 volumio ntpd[1012]: Listen normally on 3 wlan0 169.254.62.227 UDP 123 May 14 21:37:17 volumio ntpd[1012]: peers refreshed May 14 21:37:17 volumio volumio-streaming-daemon[1265]: ############################ May 14 21:37:17 volumio volumio-streaming-daemon[1265]: # Volumio Streaming Daemon # May 14 21:37:17 volumio volumio-streaming-daemon[1265]: # Running on port 7777 # May 14 21:37:17 volumio volumio-streaming-daemon[1265]: ############################ May 14 21:37:19 volumio ntpd_intres[1024]: host name not found: 0.debian.pool.ntp.org May 14 21:37:19 volumio ntpd_intres[1024]: host name not found: 1.debian.pool.ntp.org May 14 21:37:19 volumio ntpd_intres[1024]: host name not found: 2.debian.pool.ntp.org May 14 21:37:19 volumio ntpd_intres[1024]: host name not found: 3.debian.pool.ntp.org May 14 21:37:19 volumio nmbd[1000]: [2024/05/14 21:37:19.283559, 0] ../lib/util/become_daemon.c:124(daemon_ready) May 14 21:37:19 volumio nmbd[1000]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 14 21:37:19 volumio volumio[1264]: info: ------------------------------------------- May 14 21:37:19 volumio volumio[1264]: info: ----- Volumio2 ---- May 14 21:37:19 volumio volumio[1264]: info: ------------------------------------------- May 14 21:37:19 volumio volumio[1264]: info: ----- System startup ---- May 14 21:37:19 volumio volumio[1264]: info: ------------------------------------------- May 14 21:37:19 volumio volumio[1264]: info: MYVOLUMIO Environment detected May 14 21:37:19 volumio volumio[1264]: info: Plugin folders cleanup May 14 21:37:19 volumio volumio[1264]: info: Scanning into folder /volumio/app/plugins/ May 14 21:37:19 volumio volumio[1264]: info: Scanning category audio_interface May 14 21:37:19 volumio volumio[1264]: info: Scanning category miscellanea May 14 21:37:19 volumio volumio[1264]: info: Scanning category music_service May 14 21:37:19 volumio volumio[1264]: info: Scanning category plugins.json May 14 21:37:19 volumio volumio[1264]: info: Scanning category system_controller May 14 21:37:19 volumio volumio[1264]: info: Scanning category user_interface May 14 21:37:19 volumio volumio[1264]: info: Scanning into folder /data/plugins/ May 14 21:37:19 volumio volumio[1264]: info: Scanning category miscellanea May 14 21:37:19 volumio volumio[1264]: info: Plugin folders cleanup completed May 14 21:37:19 volumio volumio[1264]: info: ------------------------------------------- May 14 21:37:19 volumio volumio[1264]: info: ----- Core plugins startup ---- May 14 21:37:19 volumio volumio[1264]: info: ------------------------------------------- May 14 21:37:19 volumio volumio[1264]: info: Loading plugins from folder /volumio/app/plugins/ May 14 21:37:19 volumio volumio[1264]: info: Adding plugin upnp to MyMusic Plugins May 14 21:37:19 volumio volumio[1264]: info: Adding plugin airplay_emulation to MyMusic Plugins May 14 21:37:19 volumio volumio[1264]: info: Adding plugin upnp_browser to MyMusic Plugins May 14 21:37:19 volumio volumio[1264]: info: Loading plugins from folder /data/plugins/ May 14 21:37:19 volumio volumio[1264]: info: Loading plugin "system"... May 14 21:37:19 volumio dhcpcd[1241]: wlan0: soliciting a DHCP lease May 14 21:37:19 volumio volumio[1264]: info: Loading plugin "appearance"... May 14 21:37:20 volumio dhcpcd[1241]: wlan0: offered 172.16.99.220 from 172.16.99.1 May 14 21:37:21 volumio volumio[1264]: info: Loading plugin "network"... May 14 21:37:21 volumio volumio[1264]: info: Loading plugin "services"... May 14 21:37:21 volumio volumio[1264]: info: Loading plugin "alsa_controller"... May 14 21:37:21 volumio volumio[1264]: info: Loading plugin "wizard"... May 14 21:37:21 volumio volumio[1264]: info: Loading plugin "volumio_command_line_client"... May 14 21:37:21 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:37:21 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:37:21 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:37:21 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:37:21 volumio volumio[1264]: info: Loading plugin "upnp"... May 14 21:37:21 volumio volumio[1264]: info: [1715715441959] Starting Upmpd Daemon May 14 21:37:21 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 14 21:37:21 volumio volumio[1264]: info: Loading plugin "my_music"... May 14 21:37:21 volumio volumio[1264]: info: Loading plugin "mpd"... May 14 21:37:22 volumio volumio[1264]: info: Loading plugin "upnp_browser"... May 14 21:37:22 volumio volumio-remote-updater[523]: [2024-05-14 21:37:22] [connect] Successful connection May 14 21:37:23 volumio volumio[1264]: info: Loading plugin "networkfs"... May 14 21:37:23 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 14 21:37:23 volumio volumio[1264]: info: Loading plugin "alarm-clock"... May 14 21:37:24 volumio volumio[1264]: info: Loading plugin "airplay_emulation"... May 14 21:37:24 volumio volumio[1264]: info: Starting Shairport Sync May 14 21:37:24 volumio volumio[1264]: info: Loading plugin "last_100"... May 14 21:37:24 volumio volumio[1264]: info: Loading plugin "webradio"... May 14 21:37:24 volumio volumio[1264]: info: Loading plugin "i2s_dacs"... May 14 21:37:24 volumio volumio[1264]: info: I2S DAC not set, start Auto-detection May 14 21:37:24 volumio volumio[1264]: info: Loading plugin "volumiodiscovery"... May 14 21:37:24 volumio volumio[1264]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 14 21:37:24 volumio node[1264]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 14 21:37:24 volumio node[1264]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 21:37:24 volumio node[1264]: *** WARNING *** For more information see May 14 21:37:24 volumio node[1264]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 14 21:37:24 volumio node[1264]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 21:37:24 volumio node[1264]: *** WARNING *** For more information see May 14 21:37:24 volumio volumio[1264]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 21:37:24 volumio volumio[1264]: *** WARNING *** For more information see May 14 21:37:24 volumio volumio[1264]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 14 21:37:24 volumio volumio[1264]: *** WARNING *** Please fix your application to use the native API of Avahi! May 14 21:37:24 volumio volumio[1264]: *** WARNING *** For more information see May 14 21:37:24 volumio volumio[1264]: info: Applying required configuration parameters for plugin volumiodiscovery May 14 21:37:24 volumio volumio[1264]: Discovery: StartAdv! undefined May 14 21:37:24 volumio volumio[1264]: Discovery: Started advertising... Volumio - undefined May 14 21:37:24 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 14 21:37:24 volumio volumio[1264]: info: Loading plugin "auto_play"... May 14 21:37:24 volumio volumio[1264]: info: ControllerAutoPlay - connecting mpd on host: localhost; port: 6600 May 14 21:37:24 volumio volumio[1264]: info: Loading plugin "outputs"... May 14 21:37:24 volumio volumio[1264]: info: Loading plugin "albumart"... May 14 21:37:24 volumio volumio[1264]: info: Plugin example_plugin is not enabled May 14 21:37:24 volumio volumio[1264]: info: Loading plugin "inputs"... May 14 21:37:24 volumio volumio[1264]: info: Loading plugin "updater_comm"... May 14 21:37:24 volumio volumio[1264]: info: Plugin mpdemulation is not enabled May 14 21:37:24 volumio volumio[1264]: info: Loading plugin "rest_api"... May 14 21:37:24 volumio volumio[1264]: info: Loading plugin "websocket"... May 14 21:37:24 volumio volumio[1264]: info: ___________ START PLUGINS ___________ May 14 21:37:24 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 21:37:24 volumio volumio[1264]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 14 21:37:24 volumio volumio[1264]: info: [1715715444799] CoreMusicLibrary::Adding element Media Servers May 14 21:37:24 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 21:37:24 volumio volumio[1264]: Forking 3 albumart workers May 14 21:37:24 volumio volumio[1264]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 21:37:24 volumio volumio[1264]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 14 21:37:24 volumio volumio[1264]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 14 21:37:24 volumio volumio[1264]: info: [1715715444900] CoreMusicLibrary::Adding element Last_100 May 14 21:37:24 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 21:37:24 volumio volumio[1264]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 14 21:37:24 volumio volumio[1264]: info: [1715715444904] CoreMusicLibrary::Adding element Webradio May 14 21:37:24 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 21:37:24 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 14 21:37:24 volumio volumio[1264]: info: ------------------------------------------- May 14 21:37:24 volumio volumio[1264]: info: ----- MyVolumio plugins startup ---- May 14 21:37:24 volumio volumio[1264]: info: ------------------------------------------- May 14 21:37:24 volumio volumio[1264]: info: Loading plugins from folder /myvolumio/plugins May 14 21:37:24 volumio volumio[1264]: info: Loading plugins from folder /data/myvolumio/plugins May 14 21:37:24 volumio volumio[1264]: info: Loading plugin "cd_controller"... May 14 21:37:24 volumio volumio[1264]: info: Loading plugin "streaming_services"... May 14 21:37:25 volumio volumio[1264]: info: Loading plugin "my_volumio"... May 14 21:37:25 volumio dhcpcd[1241]: wlan0: leased 172.16.99.220 for 86400 seconds May 14 21:37:25 volumio avahi-daemon[568]: Registering new address record for 172.16.99.220 on wlan0.IPv4. May 14 21:37:25 volumio avahi-daemon[568]: Withdrawing address record for 169.254.62.227 on wlan0. May 14 21:37:25 volumio avahi-daemon[568]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.62.227. May 14 21:37:25 volumio avahi-daemon[568]: Joining mDNS multicast group on interface wlan0.IPv4 with address 172.16.99.220. May 14 21:37:25 volumio dhcpcd[1241]: wlan0: adding host route to 172.16.99.220 via 127.0.0.1 May 14 21:37:25 volumio dhcpcd[1241]: wlan0: adding route to 172.16.99.0/24 May 14 21:37:25 volumio dhcpcd[1241]: wlan0: adding default route via 172.16.99.1 May 14 21:37:25 volumio dhcpcd[1241]: wlan0: deleting host route to 169.254.62.227 via 127.0.0.1 May 14 21:37:25 volumio dhcpcd[1241]: wlan0: deleting route to 169.254.0.0/16 May 14 21:37:25 volumio volumio[1264]: Starting albumart workers May 14 21:37:25 volumio volumio[1264]: Starting albumart workers May 14 21:37:25 volumio volumio[1264]: Starting albumart workers May 14 21:37:26 volumio systemd[1]: Stopping LSB: Start NTP daemon... May 14 21:37:26 volumio ntpd[1012]: ntpd exiting on signal 15 May 14 21:37:26 volumio ntp[1430]: Stopping NTP server: ntpd. May 14 21:37:26 volumio systemd[1]: Starting LSB: Start NTP daemon... May 14 21:37:26 volumio ntpd[1449]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) May 14 21:37:26 volumio ntpd[1450]: proto: precision = 0.520 usec May 14 21:37:26 volumio ntpd[1450]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 May 14 21:37:26 volumio ntp[1440]: Starting NTP server: ntpd. May 14 21:37:26 volumio systemd[1]: Started LSB: Start NTP daemon. May 14 21:37:26 volumio ntpd[1450]: Listen and drop on 1 v6wildcard :: UDP 123 May 14 21:37:26 volumio ntpd[1450]: Listen normally on 2 lo 127.0.0.1 UDP 123 May 14 21:37:26 volumio ntpd[1450]: Listen normally on 3 wlan0 172.16.99.220 UDP 123 May 14 21:37:26 volumio ntpd[1450]: peers refreshed May 14 21:37:26 volumio ntpd[1450]: Listening on routing socket on fd #20 for interface updates May 14 21:37:26 volumio volumio[1264]: info: Adding CD REST API Endpoints May 14 21:37:26 volumio volumio[1264]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller May 14 21:37:26 volumio volumio[1264]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller May 14 21:37:26 volumio volumio[1264]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller May 14 21:37:26 volumio volumio[1264]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 14 21:37:26 volumio volumio[1264]: info: Detecting CD presence May 14 21:37:26 volumio volumio[1264]: info: Streaming services startup May 14 21:37:26 volumio volumio[1264]: info: Starting Streaming Daemon May 14 21:37:26 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 21:37:26 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 21:37:26 volumio volumio[1264]: info: Starting MyVolumio Remote Streaming Endpoints May 14 21:37:26 volumio sudo[1453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 14 21:37:26 volumio sudo[1453]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:26 volumio systemd[1]: Stopping Volumio Streaming Daemon... May 14 21:37:26 volumio systemd[1]: Starting Volumio Streaming Daemon... May 14 21:37:26 volumio systemd[1]: Started Volumio Streaming Daemon. May 14 21:37:26 volumio sudo[1453]: pam_unix(sudo:session): session closed for user root May 14 21:37:26 volumio volumio[1264]: info: Loading i18n strings for locale pl May 14 21:37:26 volumio volumio-streaming-daemon[1459]: ############################ May 14 21:37:26 volumio volumio-streaming-daemon[1459]: # Volumio Streaming Daemon # May 14 21:37:26 volumio volumio-streaming-daemon[1459]: # Running on port 7777 # May 14 21:37:26 volumio volumio-streaming-daemon[1459]: ############################ May 14 21:37:26 volumio volumio[1264]: Updating browse sources language May 14 21:37:26 volumio volumio[1264]: Cannot find translation for sourceMedia Servers May 14 21:37:26 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 21:37:26 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 21:37:26 volumio volumio[1264]: info: CoreCommandRouter::initPlayerControls May 14 21:37:26 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:37:26 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards May 14 21:37:26 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:37:26 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:37:26 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:37:26 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:37:26 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 14 21:37:26 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 14 21:37:26 volumio volumio[1264]: info: BOOT COMPLETED May 14 21:37:26 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 21:37:27 volumio dhcpcd[472]: timed out May 14 21:37:27 volumio dhcpcd[472]: allowing 8 seconds for IPv4LL timeout May 14 21:37:27 volumio ifup[443]: dhcpcd[472]: timed out May 14 21:37:27 volumio ifup[443]: dhcpcd[472]: allowing 8 seconds for IPv4LL timeout May 14 21:37:31 volumio volumio[1264]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo May 14 21:37:31 volumio volumio[1264]: info: CoreCommandRouter::Close All Modals sent May 14 21:37:31 volumio volumio[1264]: info: CoreCommandRouter::Close All Modals sent May 14 21:37:31 volumio volumio[1264]: Express server listening on port 3000 May 14 21:37:31 volumio volumio[1264]: Volumio Calling Home May 14 21:37:31 volumio volumio[1264]: info: MyVolumio not started May 14 21:37:31 volumio volumio[1264]: info: Initializing device activation check May 14 21:37:31 volumio volumio[1264]: info: CoreStateMachine::resetVolumioState May 14 21:37:31 volumio volumio[1264]: info: CoreStateMachine::getcurrentVolume May 14 21:37:31 volumio volumio[1264]: info: CoreCommandRouter::volumioRetrievevolume May 14 21:37:32 volumio volumio[1264]: info: Setting Device type: Raspberry PI May 14 21:37:32 volumio volumio[1264]: info: MPD running with PID570 ,establishing connection May 14 21:37:32 volumio sudo[1484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 May 14 21:37:32 volumio sudo[1484]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:32 volumio sudo[1488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 21:37:32 volumio sudo[1488]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:32 volumio sudo[1484]: pam_unix(sudo:session): session closed for user root May 14 21:37:32 volumio sudo[1488]: pam_unix(sudo:session): session closed for user root May 14 21:37:32 volumio volumio[1264]: info: VolumeController:: Volume=100 Mute =false May 14 21:37:32 volumio volumio[1264]: info: CoreStateMachine::pushState May 14 21:37:32 volumio volumio[1264]: info: CoreStateMachine::getState May 14 21:37:32 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 May 14 21:37:32 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState May 14 21:37:32 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 21:37:32 volumio volumio[1264]: info: May 14 21:37:32 volumio volumio[1264]: ---------------------------- Client requests Volume 100 May 14 21:37:32 volumio volumio[1264]: info: VolumeController::SetAlsaVolume100 May 14 21:37:32 volumio volumio[1264]: info: CoreStateMachine::pushState May 14 21:37:32 volumio volumio[1264]: info: CoreStateMachine::getState May 14 21:37:32 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 May 14 21:37:32 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState May 14 21:37:32 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 21:37:32 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 14 21:37:32 volumio volumio[1264]: info: Reloading queue from file May 14 21:37:32 volumio volumio[1264]: info: May 14 21:37:32 volumio volumio[1264]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory May 14 21:37:32 volumio volumio[1264]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) May 14 21:37:32 volumio volumio[1264]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) May 14 21:37:32 volumio volumio[1264]: at emitOne (events.js:116:13) May 14 21:37:32 volumio volumio[1264]: at Socket.emit (events.js:211:7) May 14 21:37:32 volumio volumio[1264]: at addChunk (_stream_readable.js:263:12) May 14 21:37:32 volumio volumio[1264]: at readableAddChunk (_stream_readable.js:246:13) May 14 21:37:32 volumio volumio[1264]: at Socket.Readable.push (_stream_readable.js:208:10) May 14 21:37:32 volumio volumio[1264]: at Pipe.onread (net.js:607:20) May 14 21:37:32 volumio volumio[1264]: info: Setting volume on startup at 100 May 14 21:37:32 volumio volumio[1264]: info: CoreStateMachine::setRepeat on single undefined May 14 21:37:32 volumio volumio[1264]: info: CoreStateMachine::pushState May 14 21:37:32 volumio volumio[1264]: info: CoreStateMachine::getState May 14 21:37:32 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 May 14 21:37:32 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState May 14 21:37:32 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 21:37:32 volumio volumio[1264]: info: CoreStateMachine::setRandom on May 14 21:37:32 volumio volumio[1264]: info: CoreStateMachine::pushState May 14 21:37:32 volumio volumio[1264]: info: CoreStateMachine::getState May 14 21:37:32 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 May 14 21:37:32 volumio volumio[1264]: info: CoreCommandRouter::volumioPushState May 14 21:37:32 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 14 21:37:32 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 14 21:37:32 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 14 21:37:32 volumio sudo[1506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay May 14 21:37:32 volumio sudo[1506]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:32 volumio sudo[1508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay May 14 21:37:32 volumio sudo[1508]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:32 volumio systemd[1]: Starting ShairportSync AirTunes receiver... May 14 21:37:32 volumio systemd[1]: Started ShairportSync AirTunes receiver. May 14 21:37:32 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... May 14 21:37:32 volumio sudo[1506]: pam_unix(sudo:session): session closed for user root May 14 21:37:32 volumio systemd[1]: Starting ShairportSync AirTunes receiver... May 14 21:37:32 volumio systemd[1]: Started ShairportSync AirTunes receiver. May 14 21:37:32 volumio sudo[1508]: pam_unix(sudo:session): session closed for user root May 14 21:37:32 volumio shairport-sync[1522]: Successful Startup May 14 21:37:32 volumio sudo[1526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 14 21:37:32 volumio sudo[1526]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:32 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... May 14 21:37:32 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 14 21:37:32 volumio sudo[1526]: pam_unix(sudo:session): session closed for user root May 14 21:37:32 volumio volumio[1264]: Discovery: adding 004d4c5c-ee31-43a1-bcb1-ca4d7ffc5cc7 May 14 21:37:32 volumio volumio[1264]: info: mDNS: Found device Volumio May 14 21:37:32 volumio sudo[1539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 21:37:32 volumio sudo[1539]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:32 volumio sudo[1539]: pam_unix(sudo:session): session closed for user root May 14 21:37:32 volumio volumio[1264]: info: Upmpdcli Daemon Started May 14 21:37:32 volumio volumio[1264]: info: Shairport-Sync Started May 14 21:37:32 volumio volumio[1264]: Error adding Membership: Error: addMembership EINVAL May 14 21:37:32 volumio volumio[1264]: info: Shairport-Sync Started May 14 21:37:32 volumio volumio[1264]: info: ControllerAutoPlay - mpd ready May 14 21:37:32 volumio volumio[1264]: info: CoreCommandRouter::volumioGetState May 14 21:37:32 volumio volumio[1264]: info: CoreStateMachine::getState May 14 21:37:32 volumio volumio[1264]: info: CorePlayQueue::getTrack 0 May 14 21:37:33 volumio sudo[1548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 14 21:37:33 volumio sudo[1548]: pam_unix(sudo:session): session opened for user root by (uid=0) May 14 21:37:33 volumio sudo[1548]: pam_unix(sudo:session): session closed for user root May 14 21:37:57 volumio systemd[1]: Time has been changed May 14 21:37:57 volumio systemd[1091]: Time has been changed May 14 21:37:59 volumio volumio[1264]: info: Fetching Streaming Services browse cache May 14 21:37:59 volumio volumio[1264]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL May 14 21:37:59 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 21:37:59 volumio volumio[1264]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ May 14 21:37:59 volumio volumio[1264]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 14 21:38:00 volumio dhcpcd[472]: timed out May 14 21:38:00 volumio dhcpcd[472]: exited May 14 21:38:00 volumio ifup[443]: dhcpcd[472]: timed out May 14 21:38:00 volumio ifup[443]: dhcpcd[472]: exited May 14 21:38:00 volumio ifup[443]: Failed to bring up eth0. May 14 21:38:01 volumio volumio[1264]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 21:38:01 volumio volumio[1264]: { Error: addMembership ENODEV May 14 21:38:01 volumio volumio[1264]: at _errnoException (util.js:1022:11) May 14 21:38:01 volumio volumio[1264]: at Socket.addMembership (dgram.js:615:11) May 14 21:38:01 volumio volumio[1264]: at Timeout.addMembership [as _onTimeout] (/volumio/node_modules/node-ssdp/lib/index.js:261:16) May 14 21:38:01 volumio volumio[1264]: at ontimeout (timers.js:482:11) May 14 21:38:01 volumio volumio[1264]: at tryOnTimeout (timers.js:317:5) May 14 21:38:01 volumio volumio[1264]: at Timer.listOnTimeout (timers.js:277:5) code: 'ENODEV', errno: 'ENODEV', syscall: 'addMembership' } May 14 21:38:01 volumio volumio[1264]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 14 21:38:02 volumio sudo[1619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-14 21:37 May 14 21:38:02 volumio sudo[1619]: 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"