Wed Jan 26 19:55:31 UTC 2022 Description="'Trying to access te plugins with DHCp enabled and pihole disabled'" # cat /proc/version --------------- Linux version 5.4.83-v7+ (dom@buildbot) (gcc version 8.4.0 (Ubuntu/Linaro 8.4.0-3ubuntu1)) #1379 SMP Mon Dec 14 13:08:57 GMT 2020 # cat /etc/os-release --------------- PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="682e0b17433cee9f9da31457f545345105e06e63" VOLUMIO_FE_VERSION="fba46add594d630e1b506bc42f1beddaad53fb64" VOLUMIO_FE3_VERSION="c23fef6dba8e02db978bd39bd8d26872e60ada6e" VOLUMIO_BE_VERSION="f7885966dee9f148ddf4f5050103a7d6734e1a10" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 20 Dec 2021 04:57:50 PM CET" VOLUMIO_VERSION="3.179" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6d5d4c489f0b1e608d9cefb791a9102d" # ifconfig --------------- eth0: flags=4163 mtu 1500 inet 192.168.0.243 netmask 255.255.0.0 broadcast 192.168.255.255 ether b8:27:eb:15:2e:b1 txqueuelen 1000 (Ethernet) RX packets 1102 bytes 169951 (165.9 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 804 bytes 133723 (130.5 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 lo: flags=73 mtu 65536 inet 127.0.0.1 netmask 255.0.0.0 loop txqueuelen 1000 (Local Loopback) RX packets 352 bytes 50200 (49.0 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 352 bytes 50200 (49.0 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- eth0 no wireless extensions. lo no wireless extensions. wlan0 IEEE 802.11 ESSID:off/any Mode:Managed Access Point: Not-Associated Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 0: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones] Subdevices: 8/8 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 Subdevice #4: subdevice #4 Subdevice #5: subdevice #5 Subdevice #6: subdevice #6 Subdevice #7: subdevice #7 card 1: sndrpihifiberry [snd_rpi_hifiberry_dacplus], device 0: HiFiBerry DAC+ HiFi pcm512x-hifi-0 [HiFiBerry DAC+ HiFi pcm512x-hifi-0] Subdevices: 1/1 Subdevice #0: subdevice #0 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 3 19:51 ? 00:00:09 /sbin/init root 2 0 0 19:51 ? 00:00:00 [kthreadd] root 3 2 0 19:51 ? 00:00:00 [rcu_gp] root 4 2 0 19:51 ? 00:00:00 [rcu_par_gp] root 5 2 0 19:51 ? 00:00:00 [kworker/0:0-events] root 6 2 0 19:51 ? 00:00:00 [kworker/0:0H-mmc_complete] root 7 2 0 19:51 ? 00:00:00 [kworker/u8:0-events_unbound] root 8 2 0 19:51 ? 00:00:00 [mm_percpu_wq] root 9 2 0 19:51 ? 00:00:00 [ksoftirqd/0] root 10 2 0 19:51 ? 00:00:00 [rcu_sched] root 11 2 0 19:51 ? 00:00:00 [migration/0] root 12 2 0 19:51 ? 00:00:00 [cpuhp/0] root 13 2 0 19:51 ? 00:00:00 [cpuhp/1] root 14 2 0 19:51 ? 00:00:00 [migration/1] root 15 2 0 19:51 ? 00:00:00 [ksoftirqd/1] root 16 2 0 19:51 ? 00:00:00 [kworker/1:0-events_freezable] root 17 2 0 19:51 ? 00:00:00 [kworker/1:0H-kblockd] root 18 2 0 19:51 ? 00:00:00 [cpuhp/2] root 19 2 0 19:51 ? 00:00:00 [migration/2] root 20 2 0 19:51 ? 00:00:00 [ksoftirqd/2] root 21 2 0 19:51 ? 00:00:00 [kworker/2:0-events] root 22 2 0 19:51 ? 00:00:00 [kworker/2:0H-kblockd] root 23 2 0 19:51 ? 00:00:00 [cpuhp/3] root 24 2 0 19:51 ? 00:00:00 [migration/3] root 25 2 0 19:51 ? 00:00:00 [ksoftirqd/3] root 26 2 0 19:51 ? 00:00:00 [kworker/3:0-mm_percpu_wq] root 27 2 0 19:51 ? 00:00:00 [kworker/3:0H-kblockd] root 28 2 0 19:51 ? 00:00:00 [kdevtmpfs] root 29 2 0 19:51 ? 00:00:00 [netns] root 30 2 0 19:51 ? 00:00:00 [kworker/0:1-events] root 31 2 0 19:51 ? 00:00:00 [kworker/1:1-cgroup_destroy] root 32 2 0 19:51 ? 00:00:00 [kauditd] root 33 2 0 19:51 ? 00:00:00 [khungtaskd] root 34 2 0 19:51 ? 00:00:00 [oom_reaper] root 35 2 0 19:51 ? 00:00:00 [writeback] root 36 2 0 19:51 ? 00:00:00 [kcompactd0] root 54 2 0 19:51 ? 00:00:00 [kblockd] root 55 2 0 19:51 ? 00:00:00 [blkcg_punt_bio] root 56 2 0 19:51 ? 00:00:00 [watchdogd] root 57 2 0 19:51 ? 00:00:00 [kworker/2:1-events] root 58 2 0 19:51 ? 00:00:00 [kworker/3:1-events] root 59 2 0 19:51 ? 00:00:00 [rpciod] root 60 2 0 19:51 ? 00:00:00 [kworker/u9:0-hci0] root 61 2 0 19:51 ? 00:00:00 [xprtiod] root 62 2 0 19:51 ? 00:00:00 [kswapd0] root 63 2 0 19:51 ? 00:00:00 [nfsiod] root 64 2 0 19:51 ? 00:00:00 [iscsi_eh] root 65 2 0 19:51 ? 00:00:00 [dwc_otg] root 66 2 0 19:51 ? 00:00:00 [DWC Notificatio] root 67 2 0 19:51 ? 00:00:00 [kworker/1:2-cgroup_destroy] root 68 2 0 19:51 ? 00:00:00 [vchiq-slot/0] root 69 2 0 19:51 ? 00:00:00 [vchiq-recy/0] root 70 2 0 19:51 ? 00:00:00 [vchiq-sync/0] root 71 2 0 19:51 ? 00:00:00 [vchiq-keep/0] root 72 2 0 19:51 ? 00:00:00 [SMIO] root 73 2 0 19:51 ? 00:00:00 [kworker/1:3-events_freezable] root 81 2 0 19:51 ? 00:00:00 [mmc_complete] root 82 2 0 19:51 ? 00:00:00 [kworker/1:1H-kblockd] root 83 2 0 19:51 ? 00:00:00 [kworker/0:1H-kblockd] root 101 2 0 19:51 ? 00:00:00 [kworker/2:2-events] root 104 2 0 19:51 ? 00:00:00 [kworker/3:1H-events_highpri] root 105 2 0 19:51 ? 00:00:00 [kworker/3:2H-kblockd] root 109 2 0 19:51 ? 00:00:00 [kworker/0:2H-kblockd] root 110 2 0 19:51 ? 00:00:00 [jbd2/mmcblk0p2-] root 111 2 0 19:51 ? 00:00:00 [ext4-rsv-conver] root 113 2 0 19:51 ? 00:00:00 [kworker/u8:1-events_unbound] root 119 2 0 19:51 ? 00:00:01 [loop0] root 122 2 0 19:51 ? 00:00:00 [kworker/2:1H-kblockd] root 146 2 0 19:51 ? 00:00:00 [kworker/1:2H-kblockd] root 147 2 0 19:51 ? 00:00:00 [kworker/2:2H-kblockd] root 148 2 0 19:51 ? 00:00:00 [jbd2/mmcblk0p3-] root 149 2 0 19:51 ? 00:00:00 [ext4-rsv-conver] root 156 2 0 19:51 ? 00:00:00 [ipv6_addrconf] root 157 2 0 19:51 ? 00:00:00 [kworker/3:2-events] root 180 1 0 19:51 ? 00:00:00 /lib/systemd/systemd-journald root 191 2 0 19:51 ? 00:00:00 [kworker/0:2-events] root 207 2 0 19:51 ? 00:00:00 [kworker/2:3-events] root 211 1 0 19:51 ? 00:00:01 /lib/systemd/systemd-udevd root 212 2 0 19:51 ? 00:00:00 [kworker/1:4-events] root 245 2 0 19:51 ? 00:00:00 [SMIO] root 270 2 0 19:51 ? 00:00:00 [mmal-vchiq] root 272 2 0 19:51 ? 00:00:00 [mmal-vchiq] root 274 2 0 19:51 ? 00:00:00 [mmal-vchiq] root 275 2 0 19:51 ? 00:00:00 [mmal-vchiq] root 325 2 0 19:51 ? 00:00:00 [kworker/u8:2-events_unbound] root 335 2 0 19:51 ? 00:00:00 [cfg80211] root 336 2 0 19:51 ? 00:00:00 [kworker/0:3-events_power_efficient] root 346 2 0 19:51 ? 00:00:00 [brcmf_wq/mmc1:0] root 348 2 0 19:51 ? 00:00:00 [brcmf_wdog/mmc1] root 421 1 0 19:51 ? 00:00:01 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 _rpc 423 1 0 19:51 ? 00:00:00 /sbin/rpcbind -f -w volumio 458 1 0 19:51 ? 00:00:00 /usr/local/bin/volumio-remote-updater avahi 459 1 0 19:51 ? 00:00:00 avahi-daemon: running [kueche.local] root 461 2 0 19:51 ? 00:00:00 [kworker/0:4-events] root 462 2 0 19:51 ? 00:00:00 [kworker/0:5-cgroup_destroy] nobody 467 1 0 19:51 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* root 470 2 0 19:51 ? 00:00:00 [kworker/u8:3-flush-179:0] message+ 473 1 0 19:51 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 483 1 0 19:51 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon root 491 1 0 19:51 ? 00:00:00 /lib/systemd/systemd-logind root 496 1 0 19:51 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant avahi 508 459 0 19:51 ? 00:00:00 avahi-daemon: chroot helper root 632 1 0 19:51 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 633 2 0 19:51 ? 00:00:00 [kworker/2:4-events] root 644 1 0 19:51 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux root 652 1 0 19:51 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I root 661 1 0 19:51 ? 00:00:00 /usr/sbin/sshd -D root 667 632 0 19:51 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 676 2 0 19:51 ? 00:00:00 [kworker/u9:1-hci0] root 677 1 0 19:51 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 460800 noflow - b8:27:eb:bf:84:1b root 679 2 0 19:51 ? 00:00:00 [kworker/u9:2-hci0] root 689 1 0 19:51 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap volumio 707 1 6 19:51 ? 00:00:15 /usr/bin/node /volumio/index.js volumio 708 1 0 19:51 ? 00:00:00 /bin/streaming-daemon ntp 773 1 0 19:51 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 root 774 1 0 19:51 ? 00:00:00 /sbin/dhcpcd eth0 root 801 1 0 19:51 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 805 801 0 19:51 ? 00:00:00 winbindd: domain child [KUECHE] root 806 1 0 19:51 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 816 806 0 19:51 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 817 806 0 19:51 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 818 801 0 19:51 ? 00:00:00 winbindd: idmap child root 819 801 0 19:51 ? 00:00:00 winbindd: domain child [BUILTIN] root 821 806 0 19:51 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 840 2 0 19:51 ? 00:00:00 [cifsiod] root 841 2 0 19:51 ? 00:00:00 [smb3decryptd] root 842 2 0 19:51 ? 00:00:00 [cifsfileinfoput] root 843 2 0 19:51 ? 00:00:00 [cifsoplockd] root 847 2 0 19:51 ? 00:00:00 [cifsd] volumio 866 707 0 19:51 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 873 866 0 19:51 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 874 866 0 19:51 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 875 866 0 19:51 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart root 980 2 0 19:51 ? 00:00:00 [kworker/3:3] root 981 2 0 19:51 ? 00:00:00 [kworker/2:5-mm_percpu_wq] root 982 2 0 19:51 ? 00:00:00 [kworker/2:6-events_power_efficient] root 983 2 0 19:51 ? 00:00:00 [kworker/2:7-events] root 984 2 0 19:51 ? 00:00:00 [kworker/2:8] mpd 998 1 0 19:51 ? 00:00:01 /usr/bin/mpd --no-daemon shairpo+ 1002 1 0 19:51 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf volumio 1004 1 0 19:51 ? 00:00:00 /bin/sh /usr/bin/startx /etc/X11/Xsession /opt/volumiokiosk.sh -- -nocursor volumio 1031 1004 0 19:51 ? 00:00:00 xinit /etc/X11/Xsession /opt/volumiokiosk.sh -- /usr/bin/X :0 -nocursor -auth /tmp/serverauth.bi9enlsrMQ root 1032 1031 0 19:51 tty2 00:00:00 /usr/lib/xorg/Xorg :0 -nocursor -auth /tmp/serverauth.bi9enlsrMQ volumio 1060 1031 33 19:51 ? 00:01:11 /bin/bash /opt/volumiokiosk.sh volumio 1086 1 0 19:51 ? 00:00:00 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf volumio 1111 1060 0 19:51 ? 00:00:00 /usr/bin/ssh-agent /opt/volumiokiosk.sh volumio 1117 1060 0 19:51 ? 00:00:00 /usr/bin/openbox --startup /usr/lib/arm-linux-gnueabihf/openbox-autostart OPENBOX volumio 2585 707 0 19:55 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'Trying to access te plugins with DHCp enabled and pihole disabled' volumio 2590 2585 48 19:55 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js Trying to access te plugins with DHCp enabled and pihole disabled volumio 2871 2590 0 19:55 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 2874 2871 0 19:55 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.5G 434M 1.9G 19% /imgpart /dev/loop0 375M 375M 0 100% /static overlay 54G 16G 36G 31% / devtmpfs 446M 0 446M 0% /dev tmpfs 485M 0 485M 0% /dev/shm tmpfs 485M 8.9M 476M 2% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 485M 0 485M 0% /sys/fs/cgroup tmpfs 20M 40K 20M 1% /var/log tmpfs 485M 52K 485M 1% /tmp tmpfs 485M 0 485M 0% /var/spool/cups tmpfs 485M 0 485M 0% /var/spool/cups/tmp /dev/mmcblk0p1 92M 53M 40M 58% /boot //192.168.0.163/music 3.5T 1.4T 2.2T 38% /mnt/NAS/NASDaten # mount --------------- /dev/mmcblk0p2 on /imgpart type ext4 (rw,relatime) /dev/loop0 on /static type squashfs (ro,relatime) overlay on / type overlay (rw,relatime,lowerdir=/mnt/static,upperdir=/mnt/ext/dyn,workdir=/mnt/ext/work) sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime) proc on /proc type proc (rw,relatime) devtmpfs on /dev type devtmpfs (rw,nosuid,size=456272k,nr_inodes=114068,mode=755) securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime) tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev,noexec) devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000) tmpfs on /run type tmpfs (rw,nosuid,nodev,mode=755) tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k) tmpfs on /sys/fs/cgroup type tmpfs (ro,nosuid,nodev,noexec,mode=755) cgroup2 on /sys/fs/cgroup/unified type cgroup2 (rw,nosuid,nodev,noexec,relatime,nsdelegate) cgroup on /sys/fs/cgroup/systemd type cgroup (rw,nosuid,nodev,noexec,relatime,xattr,name=systemd) none on /sys/fs/bpf type bpf (rw,nosuid,nodev,noexec,relatime,mode=700) cgroup on /sys/fs/cgroup/net_cls,net_prio type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls,net_prio) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=41,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) debugfs on /sys/kernel/debug type debugfs (rw,relatime) mqueue on /dev/mqueue type mqueue (rw,relatime) sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) configfs on /sys/kernel/config type configfs (rw,relatime) tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /var/spool/cups/tmp type tmpfs (rw,noatime,mode=755) /dev/mmcblk0p1 on /boot type vfat (rw,nosuid,nodev,noexec,relatime,fmask=0111,dmask=0000,allow_utime=0022,codepage=437,iocharset=ascii,shortname=mixed,utf8,errors=remount-ro,user) //192.168.0.163/music on /mnt/NAS/NASDaten type cifs (ro,relatime,vers=3.1.1,cache=strict,username=smbuser,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.0.163,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,echo_interval=60,actimeo=1) # netstat -natp --------------- (Not all processes could be identified, non-owned process info will not be shown, you would have to be root to see it all.) Active Internet connections (servers and established) Proto Recv-Q Send-Q Local Address Foreign Address State PID/Program name tcp 0 0 0.0.0.0:49149 0.0.0.0:* LISTEN 1086/upmpdcli tcp 0 0 0.0.0.0:445 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49152 0.0.0.0:* LISTEN 1086/upmpdcli tcp 0 0 0.0.0.0:5000 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:139 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:111 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:22 0.0.0.0:* LISTEN - tcp 0 0 127.0.0.1:47662 127.0.0.1:6599 ESTABLISHED 1086/upmpdcli tcp 0 0 127.0.0.1:60602 127.0.0.1:3000 ESTABLISHED 707/node tcp 0 0 127.0.0.1:60566 127.0.0.1:3000 ESTABLISHED 458/volumio-remote- tcp 0 0 127.0.0.1:47658 127.0.0.1:6599 ESTABLISHED 1086/upmpdcli tcp 0 0 192.168.0.243:52128 192.168.0.163:445 ESTABLISHED - tcp 0 0 127.0.0.1:59340 127.0.0.1:6600 ESTABLISHED 707/node tcp 0 0 127.0.0.1:60572 127.0.0.1:3000 ESTABLISHED 707/node tcp6 0 0 :::3005 :::* LISTEN 707/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::7777 :::* LISTEN 708/streaming-daemo tcp6 0 0 :::6599 :::* LISTEN 707/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::22 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 707/node tcp6 0 0 :::3001 :::* LISTEN 866/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:60572 ESTABLISHED 707/node tcp6 0 0 192.168.0.243:3000 192.168.0.205:55745 FIN_WAIT2 - tcp6 0 0 192.168.0.243:3000 192.168.0.205:55746 ESTABLISHED 707/node tcp6 0 0 127.0.0.1:6600 127.0.0.1:59340 ESTABLISHED - tcp6 0 0 127.0.0.1:6599 127.0.0.1:47662 ESTABLISHED 707/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:60602 ESTABLISHED 707/node tcp6 0 0 192.168.0.243:3000 192.168.0.205:55735 ESTABLISHED 707/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:47658 ESTABLISHED 707/node tcp6 0 0 192.168.0.243:3000 192.168.0.205:55743 FIN_WAIT2 - tcp6 0 0 192.168.0.243:3000 192.168.0.205:55744 FIN_WAIT2 - tcp6 0 0 127.0.0.1:3000 127.0.0.1:60566 ESTABLISHED 707/node # cat /etc/asound.conf --------------- pcm.!default { type empty slave.pcm "volumio" } pcm.volumio { type empty slave.pcm "softvolume" } # Convert to 24 bit to avoid unnecessary quality loss for 16 bit audio pcm.softvolume { type plug slave { pcm "volumioSoftVol" format "S24_3LE" } } pcm.volumioSoftVol { type softvol slave { pcm "postVolume" } control { name "SoftMaster" card 1 device 0 } max_dB 0.0 min_dB -50.0 resolution 100 } pcm.postVolume { type empty slave.pcm "volumioOutput" } # There is always a plug before the hardware to be safe pcm.volumioOutput { type plug slave.pcm "volumioHw" } pcm.volumioHw { type hw card "sndrpihifiberry" } # cat /var/log/mpd.log --------------- Jan 26 19:51 : zeroconf: No global port, disabling zeroconf Jan 26 19:51 : zeroconf: No global port, disabling zeroconf Jan 26 19:51 : client: [0] opened from local Jan 26 19:51 : exception: No such directory Jan 26 19:51 : client: [1] opened from local Jan 26 19:51 : zeroconf: No global port, disabling zeroconf Jan 26 19:51 : client: [0] opened from local Jan 26 19:51 : client: [1] opened from 127.0.0.1:59336 Jan 26 19:51 : client: [2] opened from 127.0.0.1:59340 Jan 26 19:51 : exception: Failed to read mixer for 'alsa': no such mixer control: PCM Jan 26 19:52 : client: [1] closed # volumio plugin list --------------- This command will list installed plugins on your device ----------------------- Installed Plugins: [ { prettyName: 'Now Playing', name: 'now_playing', category: 'user_interface', version: '0.1.3', icon: 'fa-play-circle', isManuallyInstalled: false, enabled: false, active: false }, { prettyName: 'Touch Display', name: 'touch_display', category: 'user_interface', version: '3.3.1', icon: 'fa-hand-pointer-o', isManuallyInstalled: false, enabled: true, active: true } ] ------------------------------------------ # cat /data/configuration/plugins.json --------------- { "music_service": { "airplay_emulation": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "inputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpd": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "webradio": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "example_plugin": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "last_100": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp_browser": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "streaming_services": { "status": { "type": "string", "value": "STARTING" } } }, "audio_interface": { "outputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "alsa_controller": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "miscellanea": { "alarm-clock": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "albumart": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "appearance": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "wizard": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_music": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "user_interface": { "websocket": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpdemulation": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STARTED" } }, "rest_api": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "now_playing": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "touch_display": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "system_controller": { "updater_comm": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "network": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "networkfs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "services": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "system": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "i2s_dacs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumiodiscovery": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumio_command_line_client": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_volumio": { "status": { "type": "string", "value": "STARTED" } } } } # cat /etc/mpd.conf --------------- # Volumio MPD Configuration File # Files and directories ####################################################### music_directory "/var/lib/mpd/music" playlist_directory "/var/lib/mpd/playlists" db_file "/var/lib/mpd/tag_cache" log_file "/var/log/mpd.log" #pid_file "/var/run/mpd/pid" #state_file "/var/lib/mpd/state" #sticker_file "/var/lib/mpd/sticker.sql" ############################################################################### # General music daemon options ################################################ user "mpd" group "audio" bind_to_address "any" #port "6600" #log_level "default" #save_absolute_paths_in_playlists "no" #metadata_to_use "artist,album,title,track,name,genre,date,composer,performer,disc" auto_update "no" #auto_update_depth "3" ############################################################################### # Symbolic link behavior ###################################################### follow_outside_symlinks "yes" follow_inside_symlinks "yes" ############################################################################### # Input ####################################################################### # input { plugin "curl" # proxy "proxy.isp.com:8080" # proxy_user "user" # proxy_password "password" } ############################################################################### # Decoder ################################################################ decoder { plugin "faad" enabled "no" } ############################################################################### # Audio Output ################################################################ resampler { plugin "soxr" quality "high" threads "1" } audio_output { type "alsa" name "alsa" device "volumio" dop "no" } audio_output { type "fifo" enabled "no" name "multiroom" path "/tmp/snapfifo" format "44100:16:2" } #replaygain "album" #replaygain_preamp "0" volume_normalization "yes" ############################################################################### # MPD Internal Buffering ###################################################### audio_buffer_size "2048" ############################################################################### # Resource Limitations ######################################################## #connection_timeout "60" max_connections "20" max_playlist_length "81920" max_command_list_size "81920" max_output_buffer_size "81920" ############################################################################### # Character Encoding ########################################################## filesystem_charset "UTF-8" ############################################################################### # volumio endpointstest --------------- TESTING REMOTE ENDPOINTS https://google.com, 5187 ms: FAILED https://www.googleapis.com, 5170 ms: FAILED https://securetoken.googleapis.com, 5167 ms: FAILED https://myvolumio.firebaseio.com, 5170 ms: FAILED https://functions.volumio.cloud, 5171 ms: FAILED https://oauth-performer.dfs.volumio.org, 5174 ms: FAILED https://browsing-performer.dfs.volumio.org, 5177 ms: FAILED http://cddb.volumio.org, 5182 ms: FAILED https://functions.volumio.cloud, 5185 ms: FAILED http://pushupdates.volumio.org, 5188 ms: FAILED http://plugins.volumio.org, 5187 ms: FAILED https://database.volumio.cloud, 5190 ms: FAILED https://radio-directory.firebaseapp.com, 5195 ms: FAILED ---------- WARNING!!! Some remote endpoints cannot be reached! Failing endpoints: https://google.com, 5195 ms https://www.googleapis.com, 5195 ms https://securetoken.googleapis.com, 5195 ms https://myvolumio.firebaseio.com, 5195 ms https://functions.volumio.cloud, 5195 ms https://oauth-performer.dfs.volumio.org, 5195 ms https://browsing-performer.dfs.volumio.org, 5195 ms http://cddb.volumio.org, 5195 ms https://functions.volumio.cloud, 5195 ms http://pushupdates.volumio.org, 5195 ms http://plugins.volumio.org, 5195 ms https://database.volumio.cloud, 5195 ms https://radio-directory.firebaseapp.com, 5195 ms ---------- # sudo journalctl -p 7 --------------- -- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Wed 2022-01-26 19:56:33 UTC. -- Feb 14 10:11:59 kueche kernel: Booting Linux on physical CPU 0x0 Feb 14 10:11:59 kueche kernel: Linux version 5.4.83-v7+ (dom@buildbot) (gcc version 8.4.0 (Ubuntu/Linaro 8.4.0-3ubuntu1)) #1379 SMP Mon Dec 14 13:08:57 GMT 2020 Feb 14 10:11:59 kueche kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Feb 14 10:11:59 kueche kernel: CPU: div instructions available: patching division code Feb 14 10:11:59 kueche kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Feb 14 10:11:59 kueche kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2 Feb 14 10:11:59 kueche kernel: Memory policy: Data cache writealloc Feb 14 10:11:59 kueche kernel: Reserved memory: created CMA memory pool at 0x3a000000, size 64 MiB Feb 14 10:11:59 kueche kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 14 10:11:59 kueche kernel: On node 0 totalpages: 253952 Feb 14 10:11:59 kueche kernel: Normal zone: 2232 pages used for memmap Feb 14 10:11:59 kueche kernel: Normal zone: 0 pages reserved Feb 14 10:11:59 kueche kernel: Normal zone: 253952 pages, LIFO batch:63 Feb 14 10:11:59 kueche kernel: percpu: Embedded 20 pages/cpu s49740 r8192 d23988 u81920 Feb 14 10:11:59 kueche kernel: pcpu-alloc: s49740 r8192 d23988 u81920 alloc=20*4096 Feb 14 10:11:59 kueche kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 14 10:11:59 kueche kernel: Built 1 zonelists, mobility grouping on. Total pages: 251720 Feb 14 10:11:59 kueche kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Feb 14 10:11:59 kueche kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Feb 14 10:11:59 kueche kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 10:11:59 kueche kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 14 10:11:59 kueche kernel: Memory: 912544K/1015808K available (9216K kernel code, 698K rwdata, 2608K rodata, 1024K init, 827K bss, 37728K reserved, 65536K cma-reserved) Feb 14 10:11:59 kueche kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 14 10:11:59 kueche kernel: ftrace: allocating 29205 entries in 58 pages Feb 14 10:11:59 kueche kernel: rcu: Hierarchical RCU implementation. Feb 14 10:11:59 kueche kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Feb 14 10:11:59 kueche kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Feb 14 10:11:59 kueche kernel: random: get_random_bytes called from start_kernel+0x324/0x4f8 with crng_init=0 Feb 14 10:11:59 kueche kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Feb 14 10:11:59 kueche kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Feb 14 10:11:59 kueche kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Feb 14 10:11:59 kueche kernel: Switching to timer-based delay loop, resolution 52ns Feb 14 10:11:59 kueche kernel: Console: colour dummy device 80x30 Feb 14 10:11:59 kueche kernel: printk: console [tty1] enabled Feb 14 10:11:59 kueche kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Feb 14 10:11:59 kueche kernel: pid_max: default: 32768 minimum: 301 Feb 14 10:11:59 kueche kernel: LSM: Security Framework initializing Feb 14 10:11:59 kueche kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 kueche kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 kueche kernel: Disabling memory control group subsystem Feb 14 10:11:59 kueche kernel: CPU: Testing write buffer coherency: ok Feb 14 10:11:59 kueche kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Feb 14 10:11:59 kueche kernel: Setting up static identity map for 0x100000 - 0x10003c Feb 14 10:11:59 kueche kernel: rcu: Hierarchical SRCU implementation. Feb 14 10:11:59 kueche kernel: smp: Bringing up secondary CPUs ... Feb 14 10:11:59 kueche kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Feb 14 10:11:59 kueche kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Feb 14 10:11:59 kueche kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Feb 14 10:11:59 kueche kernel: smp: Brought up 1 node, 4 CPUs Feb 14 10:11:59 kueche kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Feb 14 10:11:59 kueche kernel: CPU: All CPU(s) started in HYP mode. Feb 14 10:11:59 kueche kernel: CPU: Virtualization extensions available. Feb 14 10:11:59 kueche kernel: devtmpfs: initialized Feb 14 10:11:59 kueche kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Feb 14 10:11:59 kueche kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 14 10:11:59 kueche kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 14 10:11:59 kueche kernel: pinctrl core: initialized pinctrl subsystem Feb 14 10:11:59 kueche kernel: NET: Registered protocol family 16 Feb 14 10:11:59 kueche kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 14 10:11:59 kueche kernel: audit: initializing netlink subsys (disabled) Feb 14 10:11:59 kueche kernel: audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1 Feb 14 10:11:59 kueche kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 14 10:11:59 kueche kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 14 10:11:59 kueche kernel: Serial: AMBA PL011 UART driver Feb 14 10:11:59 kueche kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Feb 14 10:11:59 kueche kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2021-01-08 14:33, variant start Feb 14 10:11:59 kueche kernel: raspberrypi-firmware soc:firmware: Firmware hash is 194a85abd768c7334bbadc3f1911c10a7d18ed14 Feb 14 10:11:59 kueche kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1 Feb 14 10:11:59 kueche kernel: SCSI subsystem initialized Feb 14 10:11:59 kueche kernel: usbcore: registered new interface driver usbfs Feb 14 10:11:59 kueche kernel: usbcore: registered new interface driver hub Feb 14 10:11:59 kueche kernel: usbcore: registered new device driver usb Feb 14 10:11:59 kueche kernel: clocksource: Switched to clocksource arch_sys_counter Feb 14 10:11:59 kueche kernel: VFS: Disk quotas dquot_6.6.0 Feb 14 10:11:59 kueche kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 14 10:11:59 kueche kernel: FS-Cache: Loaded Feb 14 10:11:59 kueche kernel: CacheFiles: Loaded Feb 14 10:11:59 kueche kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 14 10:11:59 kueche kernel: NET: Registered protocol family 2 Feb 14 10:11:59 kueche kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Feb 14 10:11:59 kueche kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Feb 14 10:11:59 kueche kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 14 10:11:59 kueche kernel: TCP: Hash tables configured (established 8192 bind 8192) Feb 14 10:11:59 kueche kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:11:59 kueche kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:11:59 kueche kernel: NET: Registered protocol family 1 Feb 14 10:11:59 kueche kernel: RPC: Registered named UNIX socket transport module. Feb 14 10:11:59 kueche kernel: RPC: Registered udp transport module. Feb 14 10:11:59 kueche kernel: RPC: Registered tcp transport module. Feb 14 10:11:59 kueche kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 14 10:11:59 kueche kernel: Trying to unpack rootfs image as initramfs... Feb 14 10:11:59 kueche kernel: Freeing initrd memory: 12592K Feb 14 10:11:59 kueche kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Feb 14 10:11:59 kueche kernel: Initialise system trusted keyrings Feb 14 10:11:59 kueche kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Feb 14 10:11:59 kueche kernel: FS-Cache: Netfs 'nfs' registered for caching Feb 14 10:11:59 kueche kernel: NFS: Registering the id_resolver key type Feb 14 10:11:59 kueche kernel: Key type id_resolver registered Feb 14 10:11:59 kueche kernel: Key type id_legacy registered Feb 14 10:11:59 kueche kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 14 10:11:59 kueche kernel: Key type asymmetric registered Feb 14 10:11:59 kueche kernel: Asymmetric key parser 'x509' registered Feb 14 10:11:59 kueche kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Feb 14 10:11:59 kueche kernel: io scheduler mq-deadline registered Feb 14 10:11:59 kueche kernel: io scheduler kyber registered Feb 14 10:11:59 kueche kernel: bcm2708_fb soc:fb: FB found 1 display(s) Feb 14 10:11:59 kueche kernel: Console: switching to colour frame buffer device 100x30 Feb 14 10:11:59 kueche kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 800x480 Feb 14 10:11:59 kueche kernel: bcm2835-rng 3f104000.rng: hwrng registered Feb 14 10:11:59 kueche kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Feb 14 10:11:59 kueche kernel: vc-sm: Videocore shared memory driver Feb 14 10:11:59 kueche kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 Feb 14 10:11:59 kueche kernel: brd: module loaded Feb 14 10:11:59 kueche kernel: loop: module loaded Feb 14 10:11:59 kueche kernel: Loading iSCSI transport class v2.0-870. Feb 14 10:11:59 kueche kernel: libphy: Fixed MDIO Bus: probed Feb 14 10:11:59 kueche kernel: usbcore: registered new interface driver lan78xx Feb 14 10:11:59 kueche kernel: usbcore: registered new interface driver smsc95xx Feb 14 10:11:59 kueche kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 14 10:11:59 kueche kernel: Core Release: 2.80a Feb 14 10:11:59 kueche kernel: Setting default values for core params Feb 14 10:11:59 kueche kernel: Finished setting default values for core params Feb 14 10:11:59 kueche kernel: Using Buffer DMA mode Feb 14 10:11:59 kueche kernel: Periodic Transfer Interrupt Enhancement - disabled Feb 14 10:11:59 kueche kernel: Multiprocessor Interrupt Enhancement - disabled Feb 14 10:11:59 kueche kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Feb 14 10:11:59 kueche kernel: Dedicated Tx FIFOs mode Feb 14 10:11:59 kueche kernel: WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = ba104000 dma = 0xfa104000 len=9024 Feb 14 10:11:59 kueche kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Feb 14 10:11:59 kueche kernel: dwc_otg: Microframe scheduler enabled Feb 14 10:11:59 kueche kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Feb 14 10:11:59 kueche kernel: WARN::hcd_init_fiq:458: FIQ ASM at 8070bf24 length 36 Feb 14 10:11:59 kueche kernel: WARN::hcd_init_fiq:497: MPHI regs_base at be810000 Feb 14 10:11:59 kueche kernel: dwc_otg 3f980000.usb: DWC OTG Controller Feb 14 10:11:59 kueche kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Feb 14 10:11:59 kueche kernel: dwc_otg 3f980000.usb: irq 56, io mem 0x00000000 Feb 14 10:11:59 kueche kernel: Init: Port Power? op_state=1 Feb 14 10:11:59 kueche kernel: Init: Power Port (0) Feb 14 10:11:59 kueche kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04 Feb 14 10:11:59 kueche kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 14 10:11:59 kueche kernel: usb usb1: Product: DWC OTG Controller Feb 14 10:11:59 kueche kernel: usb usb1: Manufacturer: Linux 5.4.83-v7+ dwc_otg_hcd Feb 14 10:11:59 kueche kernel: usb usb1: SerialNumber: 3f980000.usb Feb 14 10:11:59 kueche kernel: hub 1-0:1.0: USB hub found Feb 14 10:11:59 kueche kernel: hub 1-0:1.0: 1 port detected Feb 14 10:11:59 kueche kernel: dwc_otg: FIQ enabled Feb 14 10:11:59 kueche kernel: dwc_otg: NAK holdoff enabled Feb 14 10:11:59 kueche kernel: dwc_otg: FIQ split-transaction FSM enabled Feb 14 10:11:59 kueche kernel: Module dwc_common_port init Feb 14 10:11:59 kueche kernel: usbcore: registered new interface driver usb-storage Feb 14 10:11:59 kueche kernel: mousedev: PS/2 mouse device common for all mice Feb 14 10:11:59 kueche kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Feb 14 10:11:59 kueche kernel: sdhci: Secure Digital Host Controller Interface driver Feb 14 10:11:59 kueche kernel: sdhci: Copyright(c) Pierre Ossman Feb 14 10:11:59 kueche kernel: mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe Feb 14 10:11:59 kueche kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe Feb 14 10:11:59 kueche kernel: sdhci-pltfm: SDHCI platform and OF driver helper Feb 14 10:11:59 kueche kernel: ledtrig-cpu: registered to indicate activity on CPUs Feb 14 10:11:59 kueche kernel: hidraw: raw HID events driver (C) Jiri Kosina Feb 14 10:11:59 kueche kernel: usbcore: registered new interface driver usbhid Feb 14 10:11:59 kueche kernel: usbhid: USB HID core driver Feb 14 10:11:59 kueche kernel: vchiq: vchiq_init_state: slot_zero = (ptrval) Feb 14 10:11:59 kueche kernel: [vc_sm_connected_init]: start Feb 14 10:11:59 kueche kernel: [vc_sm_connected_init]: end - returning 0 Feb 14 10:11:59 kueche kernel: Initializing XFRM netlink socket Feb 14 10:11:59 kueche kernel: NET: Registered protocol family 17 Feb 14 10:11:59 kueche kernel: Key type dns_resolver registered Feb 14 10:11:59 kueche kernel: Registering SWP/SWPB emulation handler Feb 14 10:11:59 kueche kernel: registered taskstats version 1 Feb 14 10:11:59 kueche kernel: Loading compiled-in X.509 certificates Feb 14 10:11:59 kueche kernel: Key type ._fscrypt registered Feb 14 10:11:59 kueche kernel: Key type .fscrypt registered Feb 14 10:11:59 kueche kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Feb 14 10:11:59 kueche kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2 Feb 14 10:11:59 kueche kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Feb 14 10:11:59 kueche kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Feb 14 10:11:59 kueche kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Feb 14 10:11:59 kueche kernel: sdhost: log_buf @ (ptrval) (fa107000) Feb 14 10:11:59 kueche kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Feb 14 10:11:59 kueche kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Feb 14 10:11:59 kueche kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Feb 14 10:11:59 kueche kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Feb 14 10:11:59 kueche kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Feb 14 10:11:59 kueche kernel: of_cfs_init Feb 14 10:11:59 kueche kernel: of_cfs_init: OK Feb 14 10:11:59 kueche kernel: Freeing unused kernel memory: 1024K Feb 14 10:11:59 kueche kernel: Run /init as init process Feb 14 10:11:59 kueche kernel: mmc0: host does not support reading read-only switch, assuming write-enable Feb 14 10:11:59 kueche kernel: random: fast init done Feb 14 10:11:59 kueche kernel: mmc0: new high speed SDXC card at address 1234 Feb 14 10:11:59 kueche kernel: mmcblk0: mmc0:1234 SA64G 57.7 GiB Feb 14 10:11:59 kueche kernel: mmcblk0: p1 p2 p3 Feb 14 10:11:59 kueche kernel: Indeed it is in host mode hprt0 = 00021501 Feb 14 10:11:59 kueche kernel: mmc1: new high speed SDIO card at address 0001 Feb 14 10:11:59 kueche initramfs: Booting Volumio for BCM2835 Feb 14 10:11:59 kueche initramfs: This script mounts rootfs RO with an overlay RW layer. Feb 14 10:11:59 kueche kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 14 10:11:59 kueche initramfs: IMGPART=/dev/mmcblk0p2 Feb 14 10:11:59 kueche initramfs: IMGFILE=/volumio_current.sqsh Feb 14 10:11:59 kueche initramfs: Boot delay (except first time) will be 5 seconds Feb 14 10:11:59 kueche kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Feb 14 10:11:59 kueche kernel: Indeed it is in host mode hprt0 = 00001101 Feb 14 10:11:59 kueche initramfs: /dev/mmcblk0p2: Feb 14 10:11:59 kueche kernel: EXT4-fs (mmcblk0p2): recovery complete Feb 14 10:11:59 kueche kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Feb 14 10:11:59 kueche kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Feb 14 10:11:59 kueche kernel: hub 1-1:1.0: USB hub found Feb 14 10:11:59 kueche kernel: hub 1-1:1.0: 5 ports detected Feb 14 10:11:59 kueche kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Feb 14 10:11:59 kueche initramfs: Doing a 5 second delay here to give kernel load a headstart Feb 14 10:11:59 kueche kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Feb 14 10:11:59 kueche kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Feb 14 10:11:59 kueche kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Feb 14 10:11:59 kueche kernel: smsc95xx v1.0.6 Feb 14 10:11:59 kueche kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:15:2e:b1 Feb 14 10:11:59 kueche initramfs: Checking for USB updates Feb 14 10:11:59 kueche initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Feb 14 10:11:59 kueche kernel: EXT4-fs (mmcblk0p3): recovery complete Feb 14 10:11:59 kueche kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Feb 14 10:11:59 kueche initramfs: With Option: Feb 14 10:11:59 kueche initramfs: VOLUMIO_VERSION="3.179" Feb 14 10:11:59 kueche initramfs: Finish initramfs, continue booting Volumio Feb 14 10:11:59 kueche systemd[1]: System time before build time, advancing clock. Feb 14 10:11:59 kueche kernel: NET: Registered protocol family 10 Feb 14 10:11:59 kueche kernel: Segment Routing with IPv6 Feb 14 10:11:59 kueche systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Feb 14 10:11:59 kueche systemd[1]: Detected architecture arm. Feb 14 10:11:59 kueche systemd[1]: Set hostname to . Feb 14 10:11:59 kueche systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 14 10:11:59 kueche kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 14 10:11:59 kueche kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 14 10:11:59 kueche kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 14 10:11:59 kueche systemd[1]: Reached target System Time Synchronized. Feb 14 10:11:59 kueche systemd[1]: Listening on initctl Compatibility Named Pipe. Feb 14 10:11:59 kueche systemd[1]: Listening on udev Kernel Socket. Feb 14 10:11:59 kueche systemd[1]: Created slice User and Session Slice. Feb 14 10:11:59 kueche systemd[1]: Reached target Slices. Feb 14 10:11:59 kueche systemd[1]: Started Forward Password Requests to Wall Directory Watch. Feb 14 10:11:59 kueche systemd-journald[180]: Journal started Feb 14 10:11:59 kueche systemd-journald[180]: Runtime journal (/run/log/journal/03837a839dbf02e377dd5ba761c0a77e) is 3.7M, max 30.0M, 26.2M free. Feb 14 10:11:59 kueche systemd-modules-load[186]: Inserted module 'i2c_dev' Feb 14 10:11:59 kueche kernel: i2c /dev entries driver Feb 14 10:11:59 kueche systemd[1]: Started Load Kernel Modules. Feb 14 10:11:59 kueche systemd[1]: Condition check resulted in FUSE Control File System being skipped. Feb 14 10:11:59 kueche systemd[1]: Starting Apply Kernel Variables... Feb 14 10:11:59 kueche systemd[1]: Mounting Kernel Configuration File System... Jan 26 19:51:09 kueche fake-hwclock[175]: Wed Jan 26 19:51:09 UTC 2022 Jan 26 19:51:09 kueche systemd[1]: Started Restore / save the current clock. Jan 26 19:51:09 kueche systemd[1]: Mounted RPC Pipe File System. Jan 26 19:51:09 kueche systemd[1]: Started Remount Root and Kernel File Systems. Jan 26 19:51:09 kueche systemd[1]: Mounted POSIX Message Queue File System. Jan 26 19:51:09 kueche systemd[1]: Mounted Kernel Debug File System. Jan 26 19:51:09 kueche systemd[1]: Mounted Kernel Configuration File System. Jan 26 19:51:09 kueche systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 26 19:51:09 kueche systemd[1]: Starting Load/Save Random Seed... Jan 26 19:51:09 kueche systemd[1]: Starting Create System Users... Jan 26 19:51:09 kueche systemd[1]: Started Apply Kernel Variables. Jan 26 19:51:09 kueche systemd[1]: Started Load/Save Random Seed. Jan 26 19:51:09 kueche systemd[1]: Started udev Coldplug all Devices. Jan 26 19:51:09 kueche systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jan 26 19:51:09 kueche systemd[1]: dynamicswap.service: Succeeded. Jan 26 19:51:09 kueche systemd[1]: Started Create System Users. Jan 26 19:51:09 kueche systemd[1]: Starting Create Static Device Nodes in /dev... Jan 26 19:51:09 kueche systemd[1]: Started Create Static Device Nodes in /dev. Jan 26 19:51:09 kueche systemd[1]: Reached target Local File Systems (Pre). Jan 26 19:51:09 kueche systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 26 19:51:09 kueche systemd[1]: Mounting /var/log... Jan 26 19:51:09 kueche systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 26 19:51:09 kueche systemd[1]: Mounting /tmp... Jan 26 19:51:09 kueche systemd[1]: Mounting /var/spool/cups... Jan 26 19:51:09 kueche systemd[1]: Starting udev Kernel Device Manager... Jan 26 19:51:09 kueche systemd[1]: Mounted /var/log. Jan 26 19:51:09 kueche systemd[1]: Mounted /tmp. Jan 26 19:51:09 kueche systemd[1]: Mounted /var/spool/cups. Jan 26 19:51:09 kueche systemd[1]: Mounting /var/spool/cups/tmp... Jan 26 19:51:09 kueche systemd[1]: Starting Flush Journal to Persistent Storage... Jan 26 19:51:09 kueche systemd[1]: Mounted /var/spool/cups/tmp. Jan 26 19:51:09 kueche systemd-udevd[211]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jan 26 19:51:09 kueche systemd-journald[180]: Runtime journal (/run/log/journal/03837a839dbf02e377dd5ba761c0a77e) is 7.5M, max 30.0M, 22.5M free. Jan 26 19:51:09 kueche systemd[1]: Started Flush Journal to Persistent Storage. Jan 26 19:51:09 kueche systemd[1]: Started udev Kernel Device Manager. Jan 26 19:51:09 kueche systemd[1]: Starting Show Plymouth Boot Screen... Jan 26 19:51:09 kueche systemd[1]: Received SIGRTMIN+20 from PID 226 (plymouthd). Jan 26 19:51:09 kueche systemd[1]: Started Show Plymouth Boot Screen. Jan 26 19:51:09 kueche systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jan 26 19:51:09 kueche systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 26 19:51:09 kueche systemd[1]: Reached target Paths. Jan 26 19:51:09 kueche systemd[1]: Reached target Local Encrypted Volumes. Jan 26 19:51:09 kueche kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 26 19:51:09 kueche kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jan 26 19:51:09 kueche kernel: [vc_sm_connected_init]: start Jan 26 19:51:09 kueche kernel: [vc_sm_connected_init]: installed successfully Jan 26 19:51:09 kueche kernel: mc: Linux media interface: v0.10 Jan 26 19:51:10 kueche kernel: videodev: Linux video capture interface: v2.00 Jan 26 19:51:10 kueche kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 26 19:51:10 kueche kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 26 19:51:10 kueche kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 26 19:51:10 kueche kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jan 26 19:51:10 kueche kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jan 26 19:51:10 kueche kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jan 26 19:51:10 kueche kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jan 26 19:51:10 kueche kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jan 26 19:51:10 kueche kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jan 26 19:51:10 kueche kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jan 26 19:51:10 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jan 26 19:51:10 kueche kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 26 19:51:10 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 26 19:51:10 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jan 26 19:51:10 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 26 19:51:10 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 26 19:51:10 kueche kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jan 26 19:51:10 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jan 26 19:51:10 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jan 26 19:51:10 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jan 26 19:51:10 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jan 26 19:51:10 kueche kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jan 26 19:51:10 kueche kernel: bcm2835_audio bcm2835_audio: card created with 8 channels Jan 26 19:51:10 kueche kernel: input: raspberrypi-ts as /devices/platform/soc/soc:firmware/soc:firmware:touchscreen/input/input0 Jan 26 19:51:10 kueche kernel: snd-rpi-hifiberry-dacplus soc:sound: ASoC: failed to init link HiFiBerry DAC+: -517 Jan 26 19:51:10 kueche kernel: snd-rpi-hifiberry-dacplus soc:sound: ASoC: failed to init link HiFiBerry DAC+: -517 Jan 26 19:51:11 kueche systemd-udevd[224]: Using default interface naming scheme 'v240'. Jan 26 19:51:11 kueche systemd-udevd[224]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 26 19:51:11 kueche kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 26 19:51:11 kueche systemd-udevd[222]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 26 19:51:11 kueche systemd[1]: Found device /dev/mmcblk0p1. Jan 26 19:51:11 kueche kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 26 19:51:11 kueche kernel: snd-rpi-hifiberry-dacplus soc:sound: ASoC: failed to init link HiFiBerry DAC+: -517 Jan 26 19:51:11 kueche kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Jan 26 19:51:11 kueche kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Jan 26 19:51:11 kueche kernel: usbcore: registered new interface driver brcmfmac Jan 26 19:51:11 kueche kernel: snd-rpi-hifiberry-dacplus soc:sound: ASoC: failed to init link HiFiBerry DAC+: -517 Jan 26 19:51:11 kueche kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43430-sdio.raspberrypi,3-model-b.txt failed with error -2 Jan 26 19:51:11 kueche kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Jan 26 19:51:11 kueche kernel: brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available Jan 26 19:51:11 kueche kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Oct 22 2019 01:59:28 version 7.45.98.94 (r723000 CY) FWID 01-3b33decd Jan 26 19:51:12 kueche systemd-udevd[220]: Using default interface naming scheme 'v240'. Jan 26 19:51:12 kueche systemd-udevd[220]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 26 19:51:12 kueche systemd-udevd[219]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 26 19:51:12 kueche kernel: snd-rpi-hifiberry-dacplus soc:sound: pcm512x-hifi <-> 3f203000.i2s mapping ok Jan 26 19:51:12 kueche systemd-udevd[216]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 26 19:51:12 kueche systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 26 19:51:12 kueche systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 26 19:51:12 kueche systemd[1]: Condition check resulted in FUSE Control File System being skipped. Jan 26 19:51:12 kueche systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 26 19:51:12 kueche systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 26 19:51:12 kueche systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jan 26 19:51:12 kueche systemd[1]: Created slice system-systemd\x2dbacklight.slice. Jan 26 19:51:12 kueche systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:rpi_backlight... Jan 26 19:51:12 kueche systemd[1]: Starting File System Check on /dev/mmcblk0p1... Jan 26 19:51:12 kueche systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Jan 26 19:51:12 kueche systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:rpi_backlight. Jan 26 19:51:12 kueche systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jan 26 19:51:12 kueche systemd[1]: Started Helper to synchronize boot up for ifupdown. Jan 26 19:51:12 kueche systemd[1]: Started File System Check Daemon to report status. Jan 26 19:51:12 kueche systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 26 19:51:13 kueche systemd[1]: Started Load/Save RF Kill Switch Status. Jan 26 19:51:13 kueche systemd-fsck[379]: fsck.fat 4.1 (2017-01-24) Jan 26 19:51:13 kueche systemd-fsck[379]: /dev/mmcblk0p1: 332 files, 26663/46774 clusters Jan 26 19:51:13 kueche systemd[1]: Started File System Check on /dev/mmcblk0p1. Jan 26 19:51:13 kueche systemd[1]: Mounting /boot... Jan 26 19:51:13 kueche systemd[1]: Mounted /boot. Jan 26 19:51:13 kueche systemd[1]: Reached target Local File Systems. Jan 26 19:51:13 kueche systemd[1]: Starting Create Volatile Files and Directories... Jan 26 19:51:13 kueche systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jan 26 19:51:13 kueche systemd[1]: Starting Raise network interfaces... Jan 26 19:51:13 kueche systemd[1]: Started ifup for eth0. Jan 26 19:51:13 kueche systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 26 19:51:13 kueche systemd[1]: Starting Preprocess NFS configuration... Jan 26 19:51:13 kueche systemd[1]: Started ifup for wlan0. Jan 26 19:51:13 kueche systemd[1]: Received SIGRTMIN+20 from PID 226 (plymouthd). Jan 26 19:51:13 kueche systemd[1]: plymouth-read-write.service: Succeeded. Jan 26 19:51:13 kueche systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 26 19:51:13 kueche systemd[1]: nfs-config.service: Succeeded. Jan 26 19:51:13 kueche systemd[1]: Started Preprocess NFS configuration. Jan 26 19:51:13 kueche systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jan 26 19:51:13 kueche systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 26 19:51:13 kueche systemd[1]: Reached target NFS client services. Jan 26 19:51:13 kueche systemd[1]: Started Create Volatile Files and Directories. Jan 26 19:51:13 kueche systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 26 19:51:13 kueche systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 26 19:51:13 kueche systemd[1]: Starting RPC bind portmap service... Jan 26 19:51:13 kueche systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jan 26 19:51:13 kueche systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 26 19:51:13 kueche systemd[1]: Reached target System Initialization. Jan 26 19:51:13 kueche systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 26 19:51:13 kueche systemd[1]: Started Daily apt download activities. Jan 26 19:51:13 kueche systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 26 19:51:13 kueche systemd[1]: Listening on triggerhappy.socket. Jan 26 19:51:13 kueche systemd[1]: Started Daily man-db regeneration. Jan 26 19:51:13 kueche systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 26 19:51:13 kueche systemd[1]: Started Daily apt upgrade and clean activities. Jan 26 19:51:13 kueche systemd[1]: Reached target Timers. Jan 26 19:51:13 kueche systemd[1]: Listening on mpd.socket. Jan 26 19:51:13 kueche systemd[1]: Reached target Sockets. Jan 26 19:51:13 kueche systemd[1]: Reached target Basic System. Jan 26 19:51:13 kueche systemd[1]: Started UPnP Renderer front-end to MPD. Jan 26 19:51:13 kueche systemd[1]: Started volumio-remote-updater.service. Jan 26 19:51:13 kueche systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 26 19:51:13 kueche kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 26 19:51:13 kueche systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jan 26 19:51:13 kueche systemd[1]: Starting triggerhappy global hotkey daemon... Jan 26 19:51:13 kueche systemd[1]: Started Volumio Iptables Module. Jan 26 19:51:13 kueche systemd[1]: Started D-Bus System Message Bus. Jan 26 19:51:13 kueche thd[467]: Unable to parse trigger line: Jan 26 19:51:13 kueche thd[467]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 26 19:51:13 kueche thd[467]: Unable to parse trigger line: Jan 26 19:51:13 kueche kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 26 19:51:13 kueche systemd[1]: Started Manage Sound Card State (restore and store). Jan 26 19:51:14 kueche systemd[1]: Starting Save/Restore Sound Card State... Jan 26 19:51:14 kueche systemd[1]: Starting Wireless Services... Jan 26 19:51:14 kueche systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 26 19:51:14 kueche systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jan 26 19:51:14 kueche systemd[1]: Starting dhcpcd on all interfaces... Jan 26 19:51:14 kueche alsactl[483]: alsactl 1.1.8 daemon started Jan 26 19:51:14 kueche systemd[1]: Starting Login Service... Jan 26 19:51:14 kueche systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jan 26 19:51:14 kueche thd[467]: Found socket passed from systemd Jan 26 19:51:14 kueche systemd[1]: Starting WPA supplicant... Jan 26 19:51:14 kueche systemd[1]: Started triggerhappy global hotkey daemon. Jan 26 19:51:14 kueche avahi-daemon[459]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jan 26 19:51:14 kueche avahi-daemon[459]: Successfully dropped root privileges. Jan 26 19:51:14 kueche avahi-daemon[459]: avahi-daemon 0.7 starting up. Jan 26 19:51:14 kueche dhcpcd[488]: Not running dhcpcd because /etc/network/interfaces Jan 26 19:51:14 kueche dhcpcd[488]: defines some interfaces that will use a Jan 26 19:51:14 kueche dhcpcd[488]: DHCP client or static address Jan 26 19:51:14 kueche systemd[1]: Started RPC bind portmap service. Jan 26 19:51:14 kueche systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jan 26 19:51:14 kueche systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jan 26 19:51:14 kueche systemd[1]: Failed to start dhcpcd on all interfaces. Jan 26 19:51:14 kueche systemd[1]: Started Save/Restore Sound Card State. Jan 26 19:51:14 kueche systemd[1]: Reached target Sound Card. Jan 26 19:51:14 kueche systemd[1]: Reached target RPC Port Mapper. Jan 26 19:51:14 kueche systemd[1]: Reached target Remote File Systems (Pre). Jan 26 19:51:14 kueche systemd[1]: Reached target Remote File Systems. Jan 26 19:51:14 kueche ifup[386]: ifup: waiting for lock on /run/network/ifstate.wlan0 Jan 26 19:51:14 kueche systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 26 19:51:14 kueche sh[393]: wlan0=wlan0 Jan 26 19:51:14 kueche avahi-daemon[459]: Successfully called chroot(). Jan 26 19:51:14 kueche avahi-daemon[459]: Successfully dropped remaining capabilities. Jan 26 19:51:14 kueche avahi-daemon[459]: Loading service file /services/volumio.service. Jan 26 19:51:14 kueche avahi-daemon[459]: Network interface enumeration completed. Jan 26 19:51:14 kueche avahi-daemon[459]: Server startup complete. Host name is kueche.local. Local service cookie is 3549509824. Jan 26 19:51:14 kueche avahi-daemon[459]: Service "Kueche" (/services/volumio.service) successfully established. Jan 26 19:51:14 kueche systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 26 19:51:14 kueche kernel: uart-pl011 3f201000.serial: no DMA platform data Jan 26 19:51:14 kueche systemd-logind[491]: New seat seat0. Jan 26 19:51:14 kueche systemd[1]: Started Login Service. Jan 26 19:51:14 kueche sh[387]: eth0: waiting for carrier Jan 26 19:51:14 kueche sh[387]: eth0: carrier acquired Jan 26 19:51:14 kueche dhcpcd[438]: eth0: waiting for carrier Jan 26 19:51:14 kueche dhcpcd[438]: eth0: carrier acquired Jan 26 19:51:14 kueche kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jan 26 19:51:14 kueche systemd[1]: Started Raise network interfaces. Jan 26 19:51:14 kueche sh[387]: DUID 00:01:00:01:29:53:65:b7:b8:27:eb:15:2e:b1 Jan 26 19:51:14 kueche sh[387]: eth0: IAID eb:15:2e:b1 Jan 26 19:51:14 kueche sh[387]: eth0: adding address fe80::ba27:ebff:fe15:2eb1 Jan 26 19:51:14 kueche sh[387]: ipv6_addaddr1: Permission denied Jan 26 19:51:14 kueche dhcpcd[438]: DUID 00:01:00:01:29:53:65:b7:b8:27:eb:15:2e:b1 Jan 26 19:51:14 kueche dhcpcd[438]: eth0: IAID eb:15:2e:b1 Jan 26 19:51:14 kueche dhcpcd[438]: eth0: adding address fe80::ba27:ebff:fe15:2eb1 Jan 26 19:51:14 kueche dhcpcd[438]: ipv6_addaddr1: Permission denied Jan 26 19:51:14 kueche sh[387]: eth0: soliciting an IPv6 router Jan 26 19:51:14 kueche dhcpcd[438]: eth0: soliciting an IPv6 router Jan 26 19:51:14 kueche loadcpufreq[525]: Loading cpufreq kernel modules...done (none). Jan 26 19:51:14 kueche systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 26 19:51:14 kueche systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 26 19:51:15 kueche sh[387]: eth0: carrier lost Jan 26 19:51:15 kueche dhcpcd[438]: eth0: carrier lost Jan 26 19:51:15 kueche cpufrequtils[591]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 26 19:51:15 kueche systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 26 19:51:15 kueche systemd[1]: Started WPA supplicant. Jan 26 19:51:15 kueche wpa_supplicant[496]: Successfully initialized wpa_supplicant Jan 26 19:51:15 kueche systemd[1]: Reached target Network. Jan 26 19:51:15 kueche systemd[1]: Starting Music Player Daemon... Jan 26 19:51:15 kueche systemd[1]: Starting Permit User Sessions... Jan 26 19:51:15 kueche systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 26 19:51:15 kueche systemd[1]: Reached target Network is Online. Jan 26 19:51:15 kueche systemd[1]: Starting LSB: Brings up/down network automatically... Jan 26 19:51:15 kueche systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 26 19:51:15 kueche systemd[1]: Starting /etc/rc.local Compatibility... Jan 26 19:51:15 kueche systemd[1]: Starting Samba NMB Daemon... Jan 26 19:51:15 kueche systemd[1]: Starting Network Time Service... Jan 26 19:51:15 kueche systemd[1]: Starting OpenBSD Secure Shell server... Jan 26 19:51:15 kueche systemd[1]: Started Permit User Sessions. Jan 26 19:51:15 kueche systemd[1]: Started /etc/rc.local Compatibility. Jan 26 19:51:15 kueche systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 26 19:51:15 kueche systemd[1]: Starting Hold until boot process finishes up... Jan 26 19:51:15 kueche systemd[1]: Received SIGRTMIN+21 from PID 226 (plymouthd). Jan 26 19:51:15 kueche systemd[1]: plymouth-start.service: Succeeded. Jan 26 19:51:15 kueche systemd[1]: plymouth-quit.service: Succeeded. Jan 26 19:51:15 kueche systemd[1]: Started Terminate Plymouth Boot Screen. Jan 26 19:51:15 kueche systemd[1]: plymouth-quit-wait.service: Succeeded. Jan 26 19:51:15 kueche systemd[1]: Started Hold until boot process finishes up. Jan 26 19:51:15 kueche systemd[1]: Received SIGRTMIN+21 from PID 226 (n/a). Jan 26 19:51:15 kueche systemd[1]: iptables.service: Succeeded. Jan 26 19:51:15 kueche systemd[1]: Started Getty on tty1. Jan 26 19:51:15 kueche systemd[1]: Reached target Login Prompts. Jan 26 19:51:15 kueche haveged[421]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jan 26 19:51:15 kueche haveged[421]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jan 26 19:51:15 kueche haveged[421]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99916 Jan 26 19:51:15 kueche haveged[421]: haveged: fills: 0, generated: 0 Jan 26 19:51:15 kueche ifplugd(eth0)[652]: ifplugd 0.28 initializing. Jan 26 19:51:15 kueche ifplugd(eth0)[652]: Using interface eth0/B8:27:EB:15:2E:B1 with driver (version: 22-Aug-2005) Jan 26 19:51:15 kueche ifplugd(eth0)[652]: Using detection mode: SIOCETHTOOL Jan 26 19:51:15 kueche ifplugd(eth0)[652]: Initialization complete, link beat not detected. Jan 26 19:51:15 kueche kernel: random: crng init done Jan 26 19:51:15 kueche kernel: random: 7 urandom warning(s) missed due to ratelimiting Jan 26 19:51:16 kueche ntpd[624]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 26 19:51:16 kueche ntpd[624]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jan 26 19:51:16 kueche systemd[1]: Started Network Time Service. Jan 26 19:51:16 kueche ifplugd[618]: Network Interface Plugging Daemon...start eth0...done. Jan 26 19:51:16 kueche systemd[1]: Started LSB: Brings up/down network automatically. Jan 26 19:51:16 kueche kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xCDE1 Jan 26 19:51:16 kueche sh[387]: eth0: carrier acquired Jan 26 19:51:16 kueche dhcpcd[438]: eth0: carrier acquired Jan 26 19:51:16 kueche sh[387]: eth0: IAID eb:15:2e:b1 Jan 26 19:51:16 kueche dhcpcd[438]: eth0: IAID eb:15:2e:b1 Jan 26 19:51:16 kueche ntpd[656]: proto: precision = 0.729 usec (-20) Jan 26 19:51:16 kueche ntpd[656]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 26 19:51:16 kueche ntpd[656]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 26 19:51:16 kueche ntpd[656]: Listen and drop on 0 v6wildcard [::]:123 Jan 26 19:51:16 kueche ntpd[656]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 26 19:51:16 kueche ntpd[656]: Listen normally on 2 lo 127.0.0.1:123 Jan 26 19:51:16 kueche ntpd[656]: Listening on routing socket on fd #19 for interface updates Jan 26 19:51:16 kueche ntpd[656]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 26 19:51:16 kueche ntpd[656]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 26 19:51:16 kueche volumio-remote-updater[458]: Error: No active session Jan 26 19:51:16 kueche volumio-remote-updater[458]: [2022-01-26 19:51:16] [info] asio async_connect error: system:111 (Connection refused) Jan 26 19:51:16 kueche volumio-remote-updater[458]: [2022-01-26 19:51:16] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 26 19:51:16 kueche volumio-remote-updater[458]: [2022-01-26 19:51:16] [error] handle_connect error: Underlying Transport Error Jan 26 19:51:16 kueche sshd[661]: Server listening on 0.0.0.0 port 22. Jan 26 19:51:16 kueche sshd[661]: Server listening on :: port 22. Jan 26 19:51:16 kueche systemd[1]: Started OpenBSD Secure Shell server. Jan 26 19:51:16 kueche sh[387]: eth0: soliciting a DHCP lease Jan 26 19:51:16 kueche dhcpcd[438]: eth0: soliciting a DHCP lease Jan 26 19:51:16 kueche sh[387]: eth0: soliciting an IPv6 router Jan 26 19:51:16 kueche dhcpcd[438]: eth0: soliciting an IPv6 router Jan 26 19:51:16 kueche volumio[456]: Could not open config: /tmp/upmpdcli.conf Jan 26 19:51:16 kueche systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 26 19:51:16 kueche systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 26 19:51:17 kueche ifplugd(eth0)[652]: Link beat detected. Jan 26 19:51:17 kueche ntpd[656]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 26 19:51:17 kueche ifplugd(eth0)[652]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 26 19:51:17 kueche ifplugd(eth0)[652]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Jan 26 19:51:17 kueche nmbd[632]: [2022/01/26 19:51:17.317107, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 26 19:51:17 kueche nmbd[632]: started asyncdns process 667 Jan 26 19:51:17 kueche nmbd[632]: [2022/01/26 19:51:17.320702, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 26 19:51:17 kueche nmbd[632]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 26 19:51:17 kueche nmbd[632]: [2022/01/26 19:51:17.320967, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 26 19:51:17 kueche nmbd[632]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 26 19:51:18 kueche systemd[1]: systemd-rfkill.service: Succeeded. Jan 26 19:51:18 kueche ntpd[656]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 26 19:51:18 kueche kernel: Bluetooth: Core ver 2.22 Jan 26 19:51:18 kueche kernel: NET: Registered protocol family 31 Jan 26 19:51:18 kueche kernel: Bluetooth: HCI device and connection manager initialized Jan 26 19:51:18 kueche kernel: Bluetooth: HCI socket layer initialized Jan 26 19:51:18 kueche kernel: Bluetooth: L2CAP socket layer initialized Jan 26 19:51:18 kueche kernel: Bluetooth: SCO socket layer initialized Jan 26 19:51:18 kueche kernel: Bluetooth: HCI UART driver ver 2.3 Jan 26 19:51:18 kueche kernel: Bluetooth: HCI UART protocol H4 registered Jan 26 19:51:18 kueche kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 26 19:51:18 kueche kernel: Bluetooth: HCI UART protocol Broadcom registered Jan 26 19:51:18 kueche btuart[460]: bcm43xx_init Jan 26 19:51:18 kueche btuart[460]: Flash firmware /lib/firmware/brcm/BCM43430A1.hcd Jan 26 19:51:18 kueche btuart[460]: Set BDADDR UART: b8:27:eb:bf:84:1b Jan 26 19:51:18 kueche btuart[460]: Set Controller UART speed to 460800 bit/s Jan 26 19:51:18 kueche btuart[460]: Device setup complete Jan 26 19:51:18 kueche systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 26 19:51:18 kueche systemd[1]: Started Configure Bluetooth Modems connected by UART. Jan 26 19:51:18 kueche systemd[1]: Created slice system-bthelper.slice. Jan 26 19:51:18 kueche systemd[1]: Starting Raspberry Pi bluetooth helper... Jan 26 19:51:19 kueche ntpd[656]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 26 19:51:20 kueche ntpd[656]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 26 19:51:20 kueche systemd[1]: Started Load/Save RF Kill Switch Status. Jan 26 19:51:20 kueche bthelper[680]: Raspberry Pi BDADDR already set Jan 26 19:51:20 kueche systemd[1]: Started Raspberry Pi bluetooth helper. Jan 26 19:51:20 kueche systemd[1]: Starting Bluetooth service... Jan 26 19:51:20 kueche bluetoothd[689]: Bluetooth daemon 5.50 Jan 26 19:51:20 kueche bluetoothd[689]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 26 19:51:20 kueche systemd[1]: Started Bluetooth service. Jan 26 19:51:20 kueche systemd[1]: Reached target Bluetooth. Jan 26 19:51:20 kueche bluetoothd[689]: Starting SDP server Jan 26 19:51:20 kueche bluetoothd[689]: Excluding (cli) sap Jan 26 19:51:20 kueche kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 26 19:51:20 kueche kernel: Bluetooth: BNEP filters: protocol multicast Jan 26 19:51:20 kueche kernel: Bluetooth: BNEP socket layer initialized Jan 26 19:51:20 kueche dbus-daemon[473]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=689 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 26 19:51:20 kueche bluetoothd[689]: Bluetooth management interface 1.14 initialized Jan 26 19:51:20 kueche systemd[1]: Starting Hostname Service... Jan 26 19:51:20 kueche bluetoothd[689]: Failed to set privacy: Rejected (0x0b) Jan 26 19:51:20 kueche wireless.js[487]: WIRELESS DAEMON: start Jan 26 19:51:20 kueche sh[387]: eth0: offered 192.168.0.243 from 192.168.0.10 Jan 26 19:51:20 kueche dhcpcd[438]: eth0: offered 192.168.0.243 from 192.168.0.10 Jan 26 19:51:20 kueche sh[387]: eth0: probing address 192.168.0.243/16 Jan 26 19:51:20 kueche dhcpcd[438]: eth0: probing address 192.168.0.243/16 Jan 26 19:51:20 kueche wireless.js[487]: WIRELESS: Loaded configuration Jan 26 19:51:20 kueche wireless.js[487]: Cleaning previous... Jan 26 19:51:20 kueche dbus-daemon[473]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 26 19:51:20 kueche systemd[1]: Started Hostname Service. Jan 26 19:51:20 kueche sudo[696]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 26 19:51:20 kueche sudo[696]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:20 kueche sudo[696]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:20 kueche sudo[698]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 26 19:51:20 kueche sudo[698]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:21 kueche mpd[645]: Jan 26 19:51 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 19:51:21 kueche mpd[645]: Jan 26 19:51 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 26 19:51:21 kueche mpd[645]: Jan 26 19:51 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 26 19:51:21 kueche volumio-remote-updater[458]: [2022-01-26 19:51:21] [info] asio async_connect error: system:111 (Connection refused) Jan 26 19:51:21 kueche volumio-remote-updater[458]: [2022-01-26 19:51:21] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 26 19:51:21 kueche volumio-remote-updater[458]: [2022-01-26 19:51:21] [error] handle_connect error: Underlying Transport Error Jan 26 19:51:21 kueche systemd[1]: Started Music Player Daemon. Jan 26 19:51:21 kueche sudo[698]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:21 kueche wireless.js[487]: Stopped aP Jan 26 19:51:21 kueche wireless.js[487]: Wireless Networking DISABLED, not starting wireless flow Jan 26 19:51:21 kueche systemd[1]: wireless.service: Succeeded. Jan 26 19:51:21 kueche systemd[1]: Started Wireless Services. Jan 26 19:51:21 kueche systemd[1]: Started Volumio Backend Module. Jan 26 19:51:21 kueche systemd[1]: Started Volumio Streaming Daemon. Jan 26 19:51:21 kueche volumio-streaming-daemon[708]: ############################ Jan 26 19:51:21 kueche volumio-streaming-daemon[708]: # Volumio Streaming Daemon # Jan 26 19:51:21 kueche volumio-streaming-daemon[708]: # Running on port 7777 # Jan 26 19:51:21 kueche volumio-streaming-daemon[708]: ############################ Jan 26 19:51:21 kueche volumio-streaming-daemon[708]: Environment: production Jan 26 19:51:24 kueche volumio[707]: info: ------------------------------------------- Jan 26 19:51:24 kueche volumio[707]: info: ----- Volumio3 ---- Jan 26 19:51:24 kueche volumio[707]: info: ------------------------------------------- Jan 26 19:51:24 kueche volumio[707]: info: ----- System startup ---- Jan 26 19:51:24 kueche volumio[707]: info: ------------------------------------------- Jan 26 19:51:25 kueche systemd[1]: systemd-rfkill.service: Succeeded. Jan 26 19:51:25 kueche bthelper[680]: Changing power off succeeded Jan 26 19:51:25 kueche bthelper[680]: [CHG] Controller B8:27:EB:BF:84:1B Class: 0x0000041c Jan 26 19:51:25 kueche bthelper[680]: Changing power on succeeded Jan 26 19:51:25 kueche volumio[707]: info: MYVOLUMIO Environment detected Jan 26 19:51:26 kueche volumio[707]: info: Plugin folders cleanup Jan 26 19:51:26 kueche volumio[707]: info: Scanning into folder /volumio/app/plugins/ Jan 26 19:51:26 kueche volumio[707]: info: Scanning category audio_interface Jan 26 19:51:26 kueche volumio[707]: info: Scanning category miscellanea Jan 26 19:51:26 kueche volumio[707]: info: Scanning category music_service Jan 26 19:51:26 kueche volumio[707]: info: Scanning category plugins.json Jan 26 19:51:26 kueche volumio[707]: info: Scanning category system_controller Jan 26 19:51:26 kueche volumio[707]: info: Scanning category user_interface Jan 26 19:51:26 kueche volumio[707]: info: Scanning into folder /data/plugins/ Jan 26 19:51:26 kueche volumio[707]: info: Scanning category user_interface Jan 26 19:51:26 kueche volumio[707]: info: Plugin folders cleanup completed Jan 26 19:51:26 kueche volumio[707]: info: ------------------------------------------- Jan 26 19:51:26 kueche volumio[707]: info: ----- Core plugins startup ---- Jan 26 19:51:26 kueche volumio[707]: info: ------------------------------------------- Jan 26 19:51:26 kueche volumio[707]: info: Loading plugins from folder /volumio/app/plugins/ Jan 26 19:51:26 kueche volumio[707]: info: Adding plugin upnp to MyMusic Plugins Jan 26 19:51:26 kueche volumio[707]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 26 19:51:26 kueche volumio[707]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 26 19:51:26 kueche volumio[707]: info: Loading plugins from folder /data/plugins/ Jan 26 19:51:26 kueche volumio[707]: info: Loading plugin "system"... Jan 26 19:51:26 kueche volumio[707]: info: Loading plugin "appearance"... Jan 26 19:51:26 kueche sh[387]: eth0: leased 192.168.0.243 for 86400 seconds Jan 26 19:51:26 kueche sh[387]: eth0: adding route to 192.168.0.0/16 Jan 26 19:51:26 kueche sh[387]: eth0: adding default route via 192.168.0.10 Jan 26 19:51:26 kueche dhcpcd[438]: eth0: leased 192.168.0.243 for 86400 seconds Jan 26 19:51:26 kueche dhcpcd[438]: eth0: adding route to 192.168.0.0/16 Jan 26 19:51:26 kueche dhcpcd[438]: eth0: adding default route via 192.168.0.10 Jan 26 19:51:26 kueche avahi-daemon[459]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.243. Jan 26 19:51:26 kueche avahi-daemon[459]: New relevant interface eth0.IPv4 for mDNS. Jan 26 19:51:26 kueche avahi-daemon[459]: Registering new address record for 192.168.0.243 on eth0.IPv4. Jan 26 19:51:26 kueche ntpd[656]: ntpd exiting on signal 15 (Terminated) Jan 26 19:51:26 kueche systemd[1]: Stopping Network Time Service... Jan 26 19:51:26 kueche systemd[1]: ntp.service: Succeeded. Jan 26 19:51:26 kueche systemd[1]: Stopped Network Time Service. Jan 26 19:51:26 kueche systemd[1]: Starting Network Time Service... Jan 26 19:51:26 kueche ntpd[767]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 26 19:51:26 kueche ntpd[767]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Jan 26 19:51:26 kueche systemd[1]: Started Network Time Service. Jan 26 19:51:26 kueche ntpd[773]: proto: precision = 0.729 usec (-20) Jan 26 19:51:26 kueche sh[387]: forked to background, child pid 774 Jan 26 19:51:26 kueche dhcpcd[438]: forked to background, child pid 774 Jan 26 19:51:26 kueche ntpd[773]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 26 19:51:26 kueche ntpd[773]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 26 19:51:26 kueche ntpd[773]: Listen and drop on 0 v6wildcard [::]:123 Jan 26 19:51:26 kueche ntpd[773]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 26 19:51:26 kueche ntpd[773]: Listen normally on 2 lo 127.0.0.1:123 Jan 26 19:51:26 kueche ntpd[773]: Listen normally on 3 eth0 192.168.0.243:123 Jan 26 19:51:26 kueche ntpd[773]: Listening on routing socket on fd #20 for interface updates Jan 26 19:51:26 kueche ntpd[773]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 26 19:51:26 kueche ntpd[773]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 26 19:51:26 kueche ifplugd(eth0)[652]: client: ifup: interface eth0 already configured Jan 26 19:51:26 kueche sh[387]: eth0=eth0 Jan 26 19:51:26 kueche ifplugd(eth0)[652]: Program executed successfully. Jan 26 19:51:27 kueche nmbd[632]: [2022/01/26 19:51:27.329724, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 26 19:51:27 kueche nmbd[632]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 26 19:51:27 kueche systemd[1]: Started Samba NMB Daemon. Jan 26 19:51:27 kueche systemd[1]: Starting Samba Winbind Daemon... Jan 26 19:51:27 kueche winbindd[801]: [2022/01/26 19:51:27.707643, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 26 19:51:27 kueche winbindd[801]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 26 19:51:27 kueche systemd[1]: Started Samba Winbind Daemon. Jan 26 19:51:27 kueche winbindd[801]: [2022/01/26 19:51:27.753689, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 26 19:51:27 kueche systemd[1]: Starting Samba SMB Daemon... Jan 26 19:51:27 kueche winbindd[801]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 26 19:51:27 kueche volumio[707]: info: Loading plugin "network"... Jan 26 19:51:27 kueche volumio[707]: info: Refreshing Cached IP Addresses Jan 26 19:51:27 kueche sudo[808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 19:51:27 kueche sudo[808]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:27 kueche volumio[707]: info: Loading plugin "services"... Jan 26 19:51:27 kueche sudo[808]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:27 kueche volumio[707]: info: Loading plugin "alsa_controller"... Jan 26 19:51:27 kueche sudo[810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 19:51:27 kueche sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:27 kueche sudo[810]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:28 kueche volumio[707]: info: Loading plugin "wizard"... Jan 26 19:51:28 kueche volumio[707]: info: Loading plugin "volumio_command_line_client"... Jan 26 19:51:28 kueche volumio[707]: info: Loading plugin "upnp"... Jan 26 19:51:28 kueche volumio[707]: info: [1643226688076] Starting Upmpd Daemon Jan 26 19:51:28 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 19:51:28 kueche volumio[707]: info: Loading plugin "my_music"... Jan 26 19:51:28 kueche volumio[707]: info: Loading plugin "mpd"... Jan 26 19:51:28 kueche winbindd[801]: [2022/01/26 19:51:28.329939, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jan 26 19:51:28 kueche winbindd[801]: res_names->count = 3, expected 4 Jan 26 19:51:28 kueche smbd[806]: [2022/01/26 19:51:28.411436, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 26 19:51:28 kueche systemd[1]: Started Samba SMB Daemon. Jan 26 19:51:28 kueche systemd[1]: Reached target Multi-User System. Jan 26 19:51:28 kueche systemd[1]: Reached target Graphical Interface. Jan 26 19:51:28 kueche smbd[806]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 26 19:51:28 kueche systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 26 19:51:28 kueche systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 26 19:51:28 kueche systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 26 19:51:28 kueche systemd[1]: Startup finished in 11.258s (kernel) + 21.416s (userspace) = 32.675s. Jan 26 19:51:28 kueche volumio[707]: info: Creating MPD Configuration file Jan 26 19:51:28 kueche volumio[707]: info: Loading plugin "upnp_browser"... Jan 26 19:51:28 kueche sudo[824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 19:51:28 kueche sudo[824]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:28 kueche sudo[824]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:28 kueche sudo[826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 19:51:28 kueche sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:28 kueche systemd[1]: Stopping Music Player Daemon... Jan 26 19:51:28 kueche systemd[1]: mpd.service: Succeeded. Jan 26 19:51:28 kueche systemd[1]: Stopped Music Player Daemon. Jan 26 19:51:28 kueche systemd[1]: Starting Music Player Daemon... Jan 26 19:51:28 kueche volumio-remote-updater[458]: [2022-01-26 19:51:28] [connect] Successful connection Jan 26 19:51:29 kueche volumio[707]: info: Loading plugin "networkfs"... Jan 26 19:51:29 kueche volumio[707]: info: Starting Udev Watcher for removable devices Jan 26 19:51:29 kueche sudo[834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.163/music /mnt/NAS/NASDaten Jan 26 19:51:29 kueche sudo[834]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:29 kueche kernel: FS-Cache: Netfs 'cifs' registered for caching Jan 26 19:51:29 kueche kernel: Key type cifs.spnego registered Jan 26 19:51:29 kueche kernel: Key type cifs.idmap registered Jan 26 19:51:29 kueche kernel: CIFS: Attempting to mount //192.168.0.163/music Jan 26 19:51:29 kueche kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Jan 26 19:51:30 kueche volumio[707]: info: Ignoring mount for partition: boot Jan 26 19:51:30 kueche volumio[707]: info: Ignoring mount for partition: volumio Jan 26 19:51:30 kueche volumio[707]: info: Ignoring mount for partition: volumio_data Jan 26 19:51:30 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 19:51:30 kueche volumio[707]: info: Loading plugin "alarm-clock"... Jan 26 19:51:30 kueche sudo[834]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:30 kueche volumio[707]: info: Loading plugin "airplay_emulation"... Jan 26 19:51:30 kueche mpd[831]: Jan 26 19:51 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 19:51:30 kueche volumio[707]: info: Starting Shairport Sync Jan 26 19:51:30 kueche volumio[707]: info: Loading plugin "last_100"... Jan 26 19:51:30 kueche volumio[707]: info: Loading plugin "webradio"... Jan 26 19:51:30 kueche mpd[831]: Jan 26 19:51 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 26 19:51:30 kueche mpd[831]: Jan 26 19:51 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 26 19:51:30 kueche systemd[1]: Started Music Player Daemon. Jan 26 19:51:30 kueche volumio[707]: info: Loading plugin "i2s_dacs"... Jan 26 19:51:30 kueche sudo[826]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:30 kueche volumio[707]: info: Loading plugin "volumiodiscovery"... Jan 26 19:51:30 kueche volumio[707]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 19:51:30 kueche volumio[707]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 19:51:30 kueche volumio[707]: *** WARNING *** For more information see Jan 26 19:51:30 kueche volumio[707]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 19:51:30 kueche node[707]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 26 19:51:30 kueche volumio[707]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 19:51:30 kueche volumio[707]: *** WARNING *** For more information see Jan 26 19:51:30 kueche node[707]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 19:51:30 kueche node[707]: *** WARNING *** For more information see Jan 26 19:51:30 kueche node[707]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 26 19:51:30 kueche node[707]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 26 19:51:30 kueche node[707]: *** WARNING *** For more information see Jan 26 19:51:30 kueche volumio[707]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 26 19:51:30 kueche volumio[707]: info: Discovery: Started advertising with name: Kueche Jan 26 19:51:30 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 26 19:51:30 kueche volumio[707]: info: Plugin now_playing is not enabled Jan 26 19:51:30 kueche volumio[707]: info: Loading plugin "outputs"... Jan 26 19:51:30 kueche volumio[707]: info: Loading plugin "albumart"... Jan 26 19:51:30 kueche volumio[707]: info: Plugin example_plugin is not enabled Jan 26 19:51:30 kueche volumio[707]: info: Loading plugin "inputs"... Jan 26 19:51:30 kueche volumio[707]: info: Loading plugin "updater_comm"... Jan 26 19:51:31 kueche volumio[707]: info: Plugin mpdemulation is not enabled Jan 26 19:51:31 kueche volumio[707]: info: Loading plugin "rest_api"... Jan 26 19:51:31 kueche volumio[707]: info: Loading plugin "websocket"... Jan 26 19:51:31 kueche volumio[707]: info: Loading plugin "touch_display"... Jan 26 19:51:31 kueche volumio[707]: Forking 3 albumart workers Jan 26 19:51:31 kueche volumio[707]: info: Applying required configuration parameters for plugin touch_display Jan 26 19:51:31 kueche volumio[707]: info: Loading i18n strings for locale de Jan 26 19:51:31 kueche volumio[707]: Updating browse sources language Jan 26 19:51:31 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 19:51:31 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:51:31 kueche volumio[707]: info: CoreCommandRouter::initPlayerControls Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:51:32 kueche volumio[707]: Express server listening on port 3000 Jan 26 19:51:32 kueche volumio[707]: [Metrics] WebUI: 8s 431.73ms Jan 26 19:51:32 kueche volumio[707]: info: CoreStateMachine::resetVolumioState Jan 26 19:51:32 kueche volumio[707]: info: CoreStateMachine::getcurrentVolume Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 19:51:32 kueche volumio[707]: Starting albumart workers Jan 26 19:51:32 kueche volumio[707]: Starting albumart workers Jan 26 19:51:32 kueche volumio[707]: Starting albumart workers Jan 26 19:51:32 kueche volumio[707]: info: MPD Permissions set Jan 26 19:51:32 kueche volumio-remote-updater[458]: [2022-01-26 19:51:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1643226688 101 Jan 26 19:51:32 kueche volumio[707]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jan 26 19:51:32 kueche volumio[707]: verbose: New Socket.io Connection to 192.168.0.243 from 192.168.0.205 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/97.0.4692.71 Safari/537.36 Total Clients: 2 Jan 26 19:51:32 kueche volumio[707]: info: CoreStateMachine::pushState Jan 26 19:51:32 kueche volumio[707]: info: CorePlayQueue::getTrack 0 Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::volumioPushState Jan 26 19:51:32 kueche volumio[707]: info: CoreStateMachine::updateTrackBlock Jan 26 19:51:32 kueche volumio[707]: info: CorePlayQueue::getTrackBlock Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::volumioRetrievevolume Jan 26 19:51:32 kueche volumio[707]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Jan 26 19:51:32 kueche volumio[707]: info: MPD running with PID831 Jan 26 19:51:32 kueche volumio[707]: ,establishing connection Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:51:32 kueche volumio[707]: info: Reloading queue from file Jan 26 19:51:32 kueche volumio[707]: error: updateQueue error: null Jan 26 19:51:32 kueche volumio[707]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 26 19:51:32 kueche volumio[707]: info: Setting Device type: Raspberry PI Jan 26 19:51:32 kueche volumio[707]: info: CoreStateMachine::setRepeat null single undefined Jan 26 19:51:32 kueche volumio[707]: info: CoreStateMachine::pushState Jan 26 19:51:32 kueche volumio[707]: info: CorePlayQueue::getTrack 0 Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::volumioPushState Jan 26 19:51:32 kueche volumio[707]: info: CoreStateMachine::setRandom null Jan 26 19:51:32 kueche volumio[707]: info: CoreStateMachine::pushState Jan 26 19:51:32 kueche volumio[707]: info: CorePlayQueue::getTrack 0 Jan 26 19:51:32 kueche volumio[707]: info: CoreCommandRouter::volumioPushState Jan 26 19:51:33 kueche volumio[707]: info: Completed loading Core Plugins Jan 26 19:51:33 kueche volumio[707]: info: Preparing to generate the ALSA configuration file Jan 26 19:51:33 kueche volumio[707]: info: CoreStateMachine::pushState Jan 26 19:51:33 kueche volumio[707]: info: CorePlayQueue::getTrack 0 Jan 26 19:51:33 kueche volumio[707]: info: CoreCommandRouter::volumioPushState Jan 26 19:51:33 kueche volumio[707]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jan 26 19:51:33 kueche volumio[707]: info: Reading ALSA contributions from plugins. Jan 26 19:51:33 kueche volumio[707]: error: updateQueue error: null Jan 26 19:51:34 kueche volumio[707]: info: Asound.conf file unchanged, so no further update is needed Jan 26 19:51:34 kueche volumio[707]: info: Output device has changed, restarting MPD Jan 26 19:51:34 kueche volumio[707]: info: Output device has changed, restarting Shairport Sync Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:51:34 kueche sudo[945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 26 19:51:34 kueche sudo[945]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:34 kueche sudo[945]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:34 kueche sudo[947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 26 19:51:34 kueche sudo[947]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:34 kueche systemd[1]: Stopping Music Player Daemon... Jan 26 19:51:34 kueche volumio[707]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 19:51:34 kueche volumio[707]: info: ___________ START PLUGINS ___________ Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 26 19:51:34 kueche systemd[1]: mpd.service: Succeeded. Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 19:51:34 kueche volumio[707]: info: [1643226694144] CoreMusicLibrary::Adding element Media Servers Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 19:51:34 kueche volumio[707]: Cannot find translation for sourceMedia Servers Jan 26 19:51:34 kueche systemd[1]: Stopped Music Player Daemon. Jan 26 19:51:34 kueche systemd[1]: Starting Music Player Daemon... Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:51:34 kueche volumio[707]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:51:34 kueche volumio[707]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 19:51:34 kueche volumio[707]: info: [1643226694277] CoreMusicLibrary::Adding element Last_100 Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 19:51:34 kueche volumio[707]: Cannot find translation for sourceMedia Servers Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 26 19:51:34 kueche volumio[707]: info: [1643226694283] CoreMusicLibrary::Adding element Webradio Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 19:51:34 kueche volumio[707]: Cannot find translation for sourceMedia Servers Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 19:51:34 kueche volumio[707]: info: Loading i18n strings for locale de Jan 26 19:51:34 kueche volumio[707]: info: Volumio Calling Home Jan 26 19:51:34 kueche sudo[962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 26 19:51:34 kueche sudo[962]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:34 kueche systemd[1]: Reloading. Jan 26 19:51:34 kueche volumio[707]: info: MPD Permissions set Jan 26 19:51:34 kueche volumio[707]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:51:34 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:51:34 kueche systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 26 19:51:34 kueche volumio[707]: info: Starting Shairport Sync Jan 26 19:51:34 kueche volumio[707]: info: Starting Shairport Sync Jan 26 19:51:34 kueche volumio[707]: info: Starting Shairport Sync Jan 26 19:51:34 kueche sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 19:51:34 kueche sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:34 kueche sudo[992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 19:51:34 kueche sudo[992]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:34 kueche sudo[995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 26 19:51:34 kueche sudo[995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:34 kueche volumio[707]: verbose: New Socket.io Connection to 192.168.0.243 from 192.168.0.205 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/97.0.4692.71 Safari/537.36 Total Clients: 5 Jan 26 19:51:35 kueche systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jan 26 19:51:35 kueche sudo[962]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:35 kueche volumio[707]: info: touch_display: systemctl daemon-reload succeeded. Jan 26 19:51:35 kueche sudo[1000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 26 19:51:35 kueche sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:35 kueche systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 26 19:51:35 kueche systemd[1]: shairport-sync.service: Succeeded. Jan 26 19:51:35 kueche systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 26 19:51:35 kueche systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 26 19:51:35 kueche sudo[995]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:35 kueche sudo[992]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:35 kueche sudo[990]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:35 kueche volumio[707]: info: Shairport-Sync Started Jan 26 19:51:35 kueche volumio[707]: Error adding Membership: Error: addMembership EINVAL Jan 26 19:51:35 kueche volumio[707]: info: Shairport-Sync Started Jan 26 19:51:35 kueche volumio[707]: info: Shairport-Sync Started Jan 26 19:51:35 kueche systemd[1]: Started Volumio Kiosk. Jan 26 19:51:35 kueche sudo[1000]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:35 kueche volumio[707]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jan 26 19:51:35 kueche volumio[707]: info: touch_display: Volumio Kiosk started Jan 26 19:51:35 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 19:51:35 kueche volumio[707]: info: CoreCommandRouter::volumioGetState Jan 26 19:51:35 kueche volumio[707]: info: CorePlayQueue::getTrack 0 Jan 26 19:51:35 kueche volumio[707]: info: Completed starting Core Plugins Jan 26 19:51:35 kueche volumio[707]: info: ------------------------------------------- Jan 26 19:51:35 kueche volumio[707]: info: ----- MyVolumio plugins startup ---- Jan 26 19:51:35 kueche volumio[707]: info: ------------------------------------------- Jan 26 19:51:35 kueche volumio[707]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 26 19:51:35 kueche volumio[707]: info: CoreCommandRouter::volumioGetState Jan 26 19:51:35 kueche volumio[707]: info: CorePlayQueue::getTrack 0 Jan 26 19:51:35 kueche volumio[707]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jan 26 19:51:35 kueche volumio[707]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. Jan 26 19:51:35 kueche volumio[707]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Jan 26 19:51:35 kueche sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness Jan 26 19:51:35 kueche sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:35 kueche sudo[1034]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:35 kueche volumio[707]: info: touch_display: File permissions for backlight brightness control set. Jan 26 19:51:35 kueche sudo[1029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Option "TransformationMatrix"/d /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jan 26 19:51:35 kueche sudo[1029]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:35 kueche volumio[707]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 26 19:51:35 kueche sudo[1029]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:35 kueche volumio[707]: info: touch_display: Touchscreen transformation matrix removed. Jan 26 19:51:35 kueche ntpd[773]: Soliciting pool server 188.174.253.163 Jan 26 19:51:35 kueche startx[1004]: X.Org X Server 1.20.4 Jan 26 19:51:35 kueche startx[1004]: X Protocol Version 11, Revision 0 Jan 26 19:51:35 kueche startx[1004]: Build Operating System: Linux 5.10.17-v8+ aarch64 Raspbian Jan 26 19:51:35 kueche startx[1004]: Current Operating System: Linux kueche 5.4.83-v7+ #1379 SMP Mon Dec 14 13:08:57 GMT 2020 armv7l Jan 26 19:51:35 kueche startx[1004]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Jan 26 19:51:35 kueche startx[1004]: Build Date: 19 April 2021 03:23:36PM Jan 26 19:51:35 kueche startx[1004]: xorg-server 2:1.20.4-1+rpt3+deb10u3 (https://www.debian.org/support) Jan 26 19:51:35 kueche startx[1004]: Current version of pixman: 0.36.0 Jan 26 19:51:35 kueche startx[1004]: Before reporting problems, check http://wiki.x.org Jan 26 19:51:35 kueche startx[1004]: to make sure that you have the latest version. Jan 26 19:51:35 kueche startx[1004]: Markers: (--) probed, (**) from config file, (==) default setting, Jan 26 19:51:35 kueche startx[1004]: (++) from command line, (!!) notice, (II) informational, Jan 26 19:51:35 kueche startx[1004]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jan 26 19:51:35 kueche startx[1004]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Jan 26 19:51:35 2022 Jan 26 19:51:35 kueche startx[1004]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jan 26 19:51:35 kueche startx[1004]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jan 26 19:51:35 kueche volumio[707]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 26 19:51:36 kueche mpd[998]: Jan 26 19:51 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 26 19:51:36 kueche mpd[998]: Jan 26 19:51 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 26 19:51:36 kueche mpd[998]: Jan 26 19:51 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 26 19:51:36 kueche systemd[1]: Started Music Player Daemon. Jan 26 19:51:36 kueche sudo[947]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:36 kueche volumio[707]: error: updateQueue error: null Jan 26 19:51:36 kueche ntpd[773]: Soliciting pool server 167.86.115.96 Jan 26 19:51:37 kueche ntpd[773]: Soliciting pool server 138.201.90.189 Jan 26 19:51:37 kueche volumio[707]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 26 19:51:38 kueche sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 19:51:38 kueche sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:38 kueche sudo[1078]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:38 kueche sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 19:51:38 kueche sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:38 kueche sudo[1080]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:38 kueche sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 26 19:51:38 kueche sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:51:38 kueche systemd[1]: Started UPnP Renderer front-end to MPD. Jan 26 19:51:38 kueche sudo[1084]: pam_unix(sudo:session): session closed for user root Jan 26 19:51:38 kueche volumio[707]: info: Upmpdcli Daemon Started Jan 26 19:51:38 kueche volumio[1086]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 26 19:51:38 kueche ntpd[773]: Soliciting pool server 65.21.190.104 Jan 26 19:51:40 kueche volumio[707]: verbose: New Socket.io Connection to 192.168.0.243 from 192.168.0.205 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/97.0.4692.71 Safari/537.36 Total Clients: 6 Jan 26 19:51:40 kueche volumio[707]: info: CoreCommandRouter::volumioGetState Jan 26 19:51:40 kueche volumio[707]: info: CorePlayQueue::getTrack 0 Jan 26 19:51:40 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 26 19:51:40 kueche volumio[707]: info: CoreCommandRouter::volumioGetVisibleSources Jan 26 19:51:40 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 26 19:51:42 kueche volumio[707]: info: Volumio called home Jan 26 19:52:02 kueche ntpd[773]: receive: Unexpected origin timestamp 0xe59c24ce.9f2fa405 does not match aorg 0000000000.00000000 from server@138.201.90.189 xmt 0xe59c24e2.57c36ee5 Jan 26 19:52:02 kueche ntpd[773]: receive: Unexpected origin timestamp 0xe59c24ce.9f258bec does not match aorg 0000000000.00000000 from server@65.21.190.104 xmt 0xe59c24e2.58aa3e81 Jan 26 19:52:02 kueche systemd[1]: systemd-fsckd.service: Succeeded. Jan 26 19:52:03 kueche volumio[1086]: ..............................++++ Jan 26 19:52:03 kueche ntpd[773]: Soliciting pool server 176.9.42.91 Jan 26 19:52:04 kueche ntpd[773]: Soliciting pool server 172.105.75.114 Jan 26 19:52:05 kueche ntpd[773]: Soliciting pool server 141.98.136.83 Jan 26 19:52:06 kueche ntpd[773]: Soliciting pool server 85.10.240.253 Jan 26 19:52:09 kueche volumio[707]: info: Discovery: adding 39f22b47-6286-4923-88c5-e5cbe79911b2 Jan 26 19:52:09 kueche volumio[707]: info: mDNS: Found device Kueche Jan 26 19:52:09 kueche volumio[707]: info: CoreCommandRouter::volumioGetState Jan 26 19:52:09 kueche volumio[707]: info: CorePlayQueue::getTrack 0 Jan 26 19:52:10 kueche volumio[707]: info: CoreCommandRouter::volumioGetState Jan 26 19:52:10 kueche volumio[707]: info: CorePlayQueue::getTrack 0 Jan 26 19:52:10 kueche systemd[1]: systemd-hostnamed.service: Succeeded. Jan 26 19:52:11 kueche ntpd[773]: Soliciting pool server 185.11.138.90 Jan 26 19:52:12 kueche ntpd[773]: Soliciting pool server 131.234.220.232 Jan 26 19:52:13 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 26 19:52:15 kueche volumio[707]: error: MyVolumio Plugin failed to start in a timely fashion Jan 26 19:52:15 kueche volumio[707]: info: BOOT COMPLETED Jan 26 19:52:15 kueche volumio[707]: [Metrics] CommandRouter: 30s 794.86ms Jan 26 19:52:15 kueche volumio[707]: info: CoreCommandRouter::volumiosetStartupVolume Jan 26 19:52:15 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 26 19:52:15 kueche volumio[707]: info: VolumeController:: Setting startup Volume 40 Jan 26 19:52:15 kueche volumio[707]: info: VolumeController::SetAlsaVolume40 Jan 26 19:52:15 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:52:15 kueche volumio[707]: info: CoreCommandRouter::Close All Modals sent Jan 26 19:52:15 kueche volumio[707]: info: CoreCommandRouter::Close All Modals sent Jan 26 19:52:15 kueche volumio[707]: info: CoreStateMachine::pushState Jan 26 19:52:15 kueche volumio[707]: info: CorePlayQueue::getTrack 0 Jan 26 19:52:15 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 26 19:52:15 kueche volumio[707]: info: CoreCommandRouter::volumioPushState Jan 26 19:52:15 kueche kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 26 19:52:16 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 26 19:52:16 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 26 19:52:16 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 26 19:52:19 kueche ntpd[773]: Soliciting pool server 80.151.186.5 Jan 26 19:52:19 kueche volumio[707]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 26 19:52:19 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 26 19:52:19 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 26 19:52:19 kueche sudo[10856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 26 19:52:19 kueche sudo[10856]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:52:19 kueche sudo[10856]: pam_unix(sudo:session): session closed for user root Jan 26 19:52:19 kueche sudo[10868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 26 19:52:19 kueche sudo[10868]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:52:19 kueche sudo[10868]: pam_unix(sudo:session): session closed for user root Jan 26 19:52:19 kueche sudo[10899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 26 19:52:19 kueche sudo[10899]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:52:19 kueche sudo[10893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 26 19:52:19 kueche sudo[10893]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:52:19 kueche sudo[10923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 26 19:52:19 kueche sudo[10923]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:52:19 kueche sudo[10899]: pam_unix(sudo:session): session closed for user root Jan 26 19:52:19 kueche sudo[10893]: pam_unix(sudo:session): session closed for user root Jan 26 19:52:19 kueche sudo[10923]: pam_unix(sudo:session): session closed for user root Jan 26 19:52:19 kueche sudo[10950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 26 19:52:19 kueche sudo[10950]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:52:19 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jan 26 19:52:19 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jan 26 19:52:19 kueche sudo[10950]: pam_unix(sudo:session): session closed for user root Jan 26 19:52:21 kueche volumio[1086]: ......................................................................................................................++++ Jan 26 19:52:21 kueche volumio[1086]: e is 65537 (0x010001) Jan 26 19:52:21 kueche volumio[1086]: writing RSA key Jan 26 19:52:24 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 26 19:52:24 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 26 19:52:27 kueche ntpd[773]: Soliciting pool server 195.50.171.101 Jan 26 19:52:35 kueche ntpd[773]: Soliciting pool server 144.76.0.164 Jan 26 19:54:31 kueche volumio[707]: verbose: New Socket.io Connection to kueche.local from 192.168.0.205 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/97.0.4692.71 Safari/537.36 Total Clients: 5 Jan 26 19:54:31 kueche volumio[707]: info: CoreCommandRouter::volumioGetState Jan 26 19:54:31 kueche volumio[707]: info: CorePlayQueue::getTrack 0 Jan 26 19:54:31 kueche volumio[707]: info: CoreCommandRouter::volumioGetQueue Jan 26 19:54:31 kueche volumio[707]: info: CoreStateMachine::getQueue Jan 26 19:54:31 kueche volumio[707]: info: CorePlayQueue::getQueue Jan 26 19:54:31 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jan 26 19:55:31 kueche volumio[707]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Jan 26 19:55:31 kueche volumio[707]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Jan 26 19:55:31 kueche sudo[2828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Jan 26 19:55:31 kueche sudo[2828]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 26 19:55:31 kueche sudo[2828]: pam_unix(sudo:session): session closed for user root Jan 26 19:56:17 kueche sshd[22204]: Accepted password for volumio from 192.168.0.205 port 55747 ssh2 Jan 26 19:56:17 kueche sshd[22204]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jan 26 19:56:17 kueche systemd[1]: Created slice User Slice of UID 1000. Jan 26 19:56:17 kueche systemd[1]: Starting User Runtime Directory /run/user/1000... Jan 26 19:56:17 kueche systemd-logind[491]: New session 1 of user volumio. Jan 26 19:56:17 kueche systemd[1]: Started User Runtime Directory /run/user/1000. Jan 26 19:56:17 kueche systemd[1]: Starting User Manager for UID 1000... Jan 26 19:56:17 kueche systemd[24073]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Jan 26 19:56:17 kueche systemd[24073]: Starting D-Bus User Message Bus Socket. Jan 26 19:56:17 kueche systemd[24073]: Listening on Sound System. Jan 26 19:56:17 kueche systemd[24073]: Listening on GnuPG network certificate management daemon. Jan 26 19:56:17 kueche systemd[24073]: Reached target Paths. Jan 26 19:56:17 kueche systemd[24073]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Jan 26 19:56:17 kueche systemd[24073]: Listening on GnuPG cryptographic agent and passphrase cache. Jan 26 19:56:17 kueche systemd[24073]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Jan 26 19:56:17 kueche systemd[24073]: Reached target Timers. Jan 26 19:56:17 kueche systemd[24073]: mpd.socket: Failed to create listening socket ([::]:6600): Address already in use Jan 26 19:56:17 kueche systemd[24073]: mpd.socket: Failed to listen on sockets: Address already in use Jan 26 19:56:17 kueche systemd[24073]: mpd.socket: Failed with result 'resources'. Jan 26 19:56:17 kueche systemd[24073]: Failed to listen on mpd.socket. Jan 26 19:56:17 kueche systemd[24073]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Jan 26 19:56:17 kueche systemd[24073]: Listening on D-Bus User Message Bus Socket. Jan 26 19:56:17 kueche systemd[24073]: Reached target Sockets. Jan 26 19:56:17 kueche systemd[24073]: Reached target Basic System. Jan 26 19:56:17 kueche systemd[1]: Started User Manager for UID 1000. Jan 26 19:56:17 kueche systemd[24073]: Starting Music Player Daemon... Jan 26 19:56:17 kueche systemd[1]: Started Session 1 of user volumio. Jan 26 19:56:18 kueche mpd[24235]: Jan 26 19:56 : exception: Failed to bind to '[::]:6600' Jan 26 19:56:18 kueche mpd[24235]: Jan 26 19:56 : exception: nested: Failed to bind socket: Address already in use Jan 26 19:56:18 kueche systemd[24073]: mpd.service: Main process exited, code=exited, status=1/FAILURE Jan 26 19:56:18 kueche systemd[24073]: mpd.service: Failed with result 'exit-code'. Jan 26 19:56:18 kueche systemd[24073]: Failed to start Music Player Daemon. Jan 26 19:56:18 kueche systemd[24073]: Reached target Default. Jan 26 19:56:18 kueche systemd[24073]: Startup finished in 1.542s. Jan 26 19:56:33 kueche sudo[31821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Jan 26 19:56:33 kueche sudo[31821]: pam_unix(sudo:session): session opened for user root by (uid=0)