Fri Jan 21 17:54:07 UTC 2022 Description="'Trying to access plugins section (DHCP enabled)'" # 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 42200 bytes 22720326 (21.6 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 13466 bytes 7371307 (7.0 MiB) 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 3267 bytes 293527 (286.6 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 3267 bytes 293527 (286.6 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 0 16:06 ? 00:00:09 /sbin/init root 2 0 0 16:06 ? 00:00:00 [kthreadd] root 3 2 0 16:06 ? 00:00:00 [rcu_gp] root 4 2 0 16:06 ? 00:00:00 [rcu_par_gp] root 8 2 0 16:06 ? 00:00:00 [mm_percpu_wq] root 9 2 0 16:06 ? 00:00:06 [ksoftirqd/0] root 10 2 0 16:06 ? 00:00:14 [rcu_sched] root 11 2 0 16:06 ? 00:00:00 [migration/0] root 12 2 0 16:06 ? 00:00:00 [cpuhp/0] root 13 2 0 16:06 ? 00:00:00 [cpuhp/1] root 14 2 0 16:06 ? 00:00:00 [migration/1] root 15 2 0 16:06 ? 00:00:01 [ksoftirqd/1] root 18 2 0 16:06 ? 00:00:00 [cpuhp/2] root 19 2 0 16:06 ? 00:00:00 [migration/2] root 20 2 0 16:06 ? 00:00:03 [ksoftirqd/2] root 21 2 0 16:06 ? 00:00:00 [kworker/2:0-events] root 23 2 0 16:06 ? 00:00:00 [cpuhp/3] root 24 2 0 16:06 ? 00:00:00 [migration/3] root 25 2 0 16:06 ? 00:00:01 [ksoftirqd/3] root 26 2 0 16:06 ? 00:00:00 [kworker/3:0-events_power_efficient] root 28 2 0 16:06 ? 00:00:00 [kdevtmpfs] root 29 2 0 16:06 ? 00:00:00 [netns] root 32 2 0 16:06 ? 00:00:00 [kauditd] root 33 2 0 16:06 ? 00:00:00 [khungtaskd] root 34 2 0 16:06 ? 00:00:00 [oom_reaper] root 35 2 0 16:06 ? 00:00:00 [writeback] root 36 2 0 16:06 ? 00:00:00 [kcompactd0] root 54 2 0 16:06 ? 00:00:00 [kblockd] root 55 2 0 16:06 ? 00:00:00 [blkcg_punt_bio] root 56 2 0 16:06 ? 00:00:00 [watchdogd] root 59 2 0 16:06 ? 00:00:00 [rpciod] root 60 2 0 16:06 ? 00:00:00 [kworker/u9:0-hci0] root 61 2 0 16:06 ? 00:00:00 [xprtiod] root 62 2 0 16:06 ? 00:00:00 [kswapd0] root 63 2 0 16:06 ? 00:00:00 [nfsiod] root 64 2 0 16:06 ? 00:00:00 [iscsi_eh] root 65 2 0 16:06 ? 00:00:00 [dwc_otg] root 66 2 0 16:06 ? 00:00:00 [DWC Notificatio] root 68 2 0 16:06 ? 00:00:00 [vchiq-slot/0] root 69 2 0 16:06 ? 00:00:00 [vchiq-recy/0] root 70 2 0 16:06 ? 00:00:00 [vchiq-sync/0] root 71 2 0 16:06 ? 00:00:00 [vchiq-keep/0] root 72 2 0 16:06 ? 00:00:00 [SMIO] root 80 2 0 16:06 ? 00:00:00 [mmc_complete] root 82 2 0 16:06 ? 00:00:00 [kworker/1:1H-kblockd] root 104 2 0 16:06 ? 00:00:00 [kworker/0:2H-mmc_complete] root 108 2 0 16:06 ? 00:00:00 [kworker/3:1H-kblockd] root 110 2 0 16:06 ? 00:00:00 [jbd2/mmcblk0p2-] root 111 2 0 16:06 ? 00:00:00 [ext4-rsv-conver] root 113 2 0 16:06 ? 00:00:00 [kworker/u8:1-flush-179:0] root 119 2 0 16:06 ? 00:00:01 [loop0] root 122 2 0 16:06 ? 00:00:00 [kworker/2:1H-kblockd] root 148 2 0 16:06 ? 00:00:00 [jbd2/mmcblk0p3-] root 149 2 0 16:06 ? 00:00:00 [ext4-rsv-conver] root 156 2 0 16:06 ? 00:00:00 [ipv6_addrconf] root 187 1 0 16:07 ? 00:00:01 /lib/systemd/systemd-journald root 190 2 0 16:07 ? 00:00:00 [kworker/3:3-cgroup_destroy] root 208 1 0 16:07 ? 00:00:01 /lib/systemd/systemd-udevd root 248 2 0 16:07 ? 00:00:00 [SMIO] root 278 2 0 16:07 ? 00:00:00 [mmal-vchiq] root 280 2 0 16:07 ? 00:00:00 [mmal-vchiq] root 283 2 0 16:07 ? 00:00:00 [mmal-vchiq] root 284 2 0 16:07 ? 00:00:00 [mmal-vchiq] root 353 2 0 16:07 ? 00:00:00 [cfg80211] root 364 2 0 16:07 ? 00:00:00 [brcmf_wq/mmc1:0] root 366 2 0 16:07 ? 00:00:00 [brcmf_wdog/mmc1] root 437 1 0 16:07 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 _rpc 439 1 0 16:07 ? 00:00:00 /sbin/rpcbind -f -w nobody 453 1 0 16:07 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* root 454 1 0 16:07 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon volumio 455 1 0 16:07 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 459 2 0 16:07 ? 00:00:08 [kworker/0:3-events_freezable] avahi 461 1 0 16:07 ? 00:00:02 avahi-daemon: running [kueche.local] root 462 1 0 16:07 ? 00:00:00 /lib/systemd/systemd-logind root 470 2 0 16:07 ? 00:00:02 [kworker/2:3-events_power_efficient] message+ 476 1 0 16:07 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 500 1 0 16:07 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant avahi 538 461 0 16:07 ? 00:00:00 avahi-daemon: chroot helper root 563 1 0 16:07 ? 00:00:00 /sbin/dhcpcd -q -b root 667 1 0 16:07 ? 00:00:01 /usr/sbin/nmbd --foreground --no-process-group root 684 1 0 16:07 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux root 686 1 0 16:07 ? 00:00:02 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I root 706 667 0 16:07 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 719 2 0 16:07 ? 00:00:00 [kworker/u9:1-hci0] root 720 1 0 16:07 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 460800 noflow - b8:27:eb:bf:84:1b root 736 1 0 16:07 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap volumio 746 1 0 16:07 ? 00:00:39 /usr/bin/node /volumio/index.js volumio 747 1 0 16:07 ? 00:00:00 /bin/streaming-daemon root 779 1 0 16:07 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 790 779 0 16:07 ? 00:00:00 winbindd: domain child [KUECHE] root 792 1 0 16:07 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 794 792 0 16:07 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 795 792 0 16:07 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 796 779 0 16:07 ? 00:00:00 winbindd: idmap child root 797 779 0 16:07 ? 00:00:00 winbindd: domain child [BUILTIN] root 798 792 0 16:07 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 835 2 0 16:07 ? 00:00:00 [cifsiod] root 836 2 0 16:07 ? 00:00:00 [smb3decryptd] root 837 2 0 16:07 ? 00:00:00 [cifsfileinfoput] root 838 2 0 16:07 ? 00:00:00 [cifsoplockd] root 842 2 0 16:07 ? 00:00:00 [cifsd] volumio 861 746 0 16:07 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 868 861 0 16:07 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 869 861 0 16:07 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 870 861 0 16:07 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart mpd 961 1 0 16:07 ? 00:00:01 /usr/bin/mpd --no-daemon shairpo+ 996 1 0 16:07 ? 00:00:10 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf volumio 997 1 0 16:07 ? 00:00:00 /bin/sh /usr/bin/startx /etc/X11/Xsession /opt/volumiokiosk.sh -- -nocursor volumio 1025 997 0 16:07 ? 00:00:00 xinit /etc/X11/Xsession /opt/volumiokiosk.sh -- /usr/bin/X :0 -nocursor -auth /tmp/serverauth.tm5XXIj2Th root 1026 1025 0 16:07 tty2 00:00:00 /usr/lib/xorg/Xorg :0 -nocursor -auth /tmp/serverauth.tm5XXIj2Th volumio 1078 1025 33 16:07 ? 00:36:13 /bin/bash /opt/volumiokiosk.sh volumio 1104 1078 0 16:07 ? 00:00:00 /usr/bin/ssh-agent /opt/volumiokiosk.sh volumio 1110 1078 0 16:07 ? 00:00:00 /usr/bin/openbox --startup /usr/lib/arm-linux-gnueabihf/openbox-autostart OPENBOX root 2067 2 0 17:39 ? 00:00:00 [kworker/0:1H] root 3420 2 0 17:45 ? 00:00:00 [kworker/1:2-events] root 5172 2 0 17:06 ? 00:00:00 [kworker/u8:2-events_unbound] root 5954 2 0 17:52 ? 00:00:00 [kworker/0:1-events_freezable] root 8972 2 0 17:47 ? 00:00:00 [kworker/2:2H] ntp 11038 1 0 17:52 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 root 11948 2 0 17:49 ? 00:00:00 [kworker/1:3-events] volumio 14661 1 0 17:52 ? 00:00:00 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf root 14730 2 0 17:38 ? 00:00:00 [kworker/1:2H] root 16005 2 0 16:22 ? 00:00:00 [kworker/0:0-events] root 16851 2 0 17:44 ? 00:00:00 [kworker/3:2H-kblockd] root 20515 2 0 17:52 ? 00:00:00 [kworker/2:0H] root 23064 2 0 17:51 ? 00:00:00 [kworker/1:0H] root 24437 2 0 17:39 ? 00:00:00 [kworker/1:0-events] root 26057 2 0 17:30 ? 00:00:00 [kworker/1:1-events_freezable] volumio 28261 746 0 17:54 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'Trying to access plugins section (DHCP enabled)' volumio 28267 28261 0 17:54 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js Trying to access plugins section (DHCP enabled) shairpo+ 28512 996 0 17:54 ? 00:00:00 /bin/bash /usr/local/bin/volumio stopairplayplayback shairpo+ 28522 28512 0 17:54 ? 00:00:00 /usr/bin/curl http://127.0.0.1:3000/api/v1/commands/?cmd=stopAirplayPlayback volumio 28536 28267 0 17:54 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 28539 28536 0 17:54 ? 00:00:00 ps -ef root 32137 2 0 17:53 ? 00:00:00 [kworker/3:0H] # 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 7.8G 43G 16% / 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 485M 52K 485M 1% /tmp tmpfs 20M 40K 20M 1% /var/log 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.3T 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/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) 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) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) 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/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=31,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) mqueue on /dev/mqueue type mqueue (rw,relatime) debugfs on /sys/kernel/debug type debugfs (rw,relatime) configfs on /sys/kernel/config type configfs (rw,relatime) sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) 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 14661/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 14661/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 127.0.0.1:51668 127.0.0.1:3000 TIME_WAIT - tcp 0 0 192.168.0.243:57412 192.168.0.163:445 ESTABLISHED - tcp 0 0 127.0.0.1:45334 127.0.0.1:6599 ESTABLISHED 14661/upmpdcli tcp 0 0 127.0.0.1:45330 127.0.0.1:6599 ESTABLISHED 14661/upmpdcli tcp 0 0 127.0.0.1:44124 127.0.0.1:6600 ESTABLISHED 746/node tcp 0 0 127.0.0.1:50402 127.0.0.1:3000 ESTABLISHED 455/volumio-remote- tcp 0 0 127.0.0.1:50430 127.0.0.1:3000 ESTABLISHED 746/node tcp 0 1 192.168.0.31:5000 192.168.0.205:55796 FIN_WAIT1 - tcp 0 0 127.0.0.1:50408 127.0.0.1:3000 ESTABLISHED 746/node tcp 0 333 192.168.0.31:51260 192.168.0.163:445 FIN_WAIT1 - tcp6 0 0 :::3005 :::* LISTEN 746/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::7777 :::* LISTEN 747/streaming-daemo tcp6 0 0 :::4004 :::* LISTEN 746/node tcp6 0 0 :::6599 :::* LISTEN 746/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 746/node tcp6 0 0 :::3001 :::* LISTEN 861/node tcp6 0 0 192.168.0.243:3000 192.168.0.205:55847 TIME_WAIT - tcp6 0 0 127.0.0.1:6599 127.0.0.1:45330 ESTABLISHED 746/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:50408 ESTABLISHED 746/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:50430 ESTABLISHED 746/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:50402 ESTABLISHED 746/node tcp6 0 0 192.168.0.243:3000 192.168.0.205:55844 TIME_WAIT - tcp6 0 0 192.168.0.243:3000 192.168.0.205:55832 ESTABLISHED 746/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:45334 ESTABLISHED 746/node tcp6 0 1102 192.168.0.31:3000 192.168.0.205:55805 FIN_WAIT1 - tcp6 0 0 127.0.0.1:6600 127.0.0.1:44124 ESTABLISHED - tcp6 0 0 192.168.0.243:3000 192.168.0.205:55841 ESTABLISHED 746/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 20 10:27 : zeroconf: No global port, disabling zeroconf Jan 20 10:27 : zeroconf: No global port, disabling zeroconf Jan 20 10:27 : client: [0] opened from local Jan 20 10:27 : exception: No such directory Jan 20 10:27 : client: [1] opened from local Jan 20 10:27 : zeroconf: No global port, disabling zeroconf Jan 20 10:27 : client: [0] opened from local Jan 20 10:27 : client: [1] opened from 127.0.0.1:42898 Jan 20 10:27 : client: [2] opened from 127.0.0.1:42902 Jan 20 10:27 : exception: Failed to read mixer for 'alsa': no such mixer control: PCM Jan 21 16:08 : client: [1] closed Jan 21 17:52 : client: [3] opened from 127.0.0.1:44120 Jan 21 17:52 : client: [4] opened from 127.0.0.1:44124 Jan 21 17:53 : client: [2] closed Jan 21 17:53 : client: [3] 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: true, active: true }, { 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": true }, "status": { "type": "string", "value": "STARTED" } }, "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, 5189 ms: FAILED https://www.googleapis.com, 5171 ms: FAILED https://securetoken.googleapis.com, 5169 ms: FAILED https://myvolumio.firebaseio.com, 5171 ms: FAILED https://functions.volumio.cloud, 5171 ms: FAILED https://oauth-performer.dfs.volumio.org, 5176 ms: FAILED https://browsing-performer.dfs.volumio.org, 5179 ms: FAILED http://cddb.volumio.org, 5182 ms: FAILED https://functions.volumio.cloud, 5186 ms: FAILED http://pushupdates.volumio.org, 5187 ms: FAILED http://plugins.volumio.org, 5188 ms: FAILED https://database.volumio.cloud, 5192 ms: FAILED https://radio-directory.firebaseapp.com, 5196 ms: FAILED ---------- WARNING!!! Some remote endpoints cannot be reached! Failing endpoints: https://google.com, 5196 ms https://www.googleapis.com, 5196 ms https://securetoken.googleapis.com, 5196 ms https://myvolumio.firebaseio.com, 5196 ms https://functions.volumio.cloud, 5196 ms https://oauth-performer.dfs.volumio.org, 5196 ms https://browsing-performer.dfs.volumio.org, 5196 ms http://cddb.volumio.org, 5196 ms https://functions.volumio.cloud, 5196 ms http://pushupdates.volumio.org, 5196 ms http://plugins.volumio.org, 5196 ms https://database.volumio.cloud, 5196 ms https://radio-directory.firebaseapp.com, 5196 ms ---------- # sudo journalctl -p 7 --------------- -- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Fri 2022-01-21 17:55:56 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 Jan 20 10:27:13 kueche kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Jan 20 10:27:13 kueche kernel: sdhost: log_buf @ (ptrval) (fa107000) Jan 20 10:27:13 kueche kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Jan 20 10:27:13 kueche kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Jan 20 10:27:13 kueche kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Jan 20 10:27:13 kueche kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Jan 20 10:27:13 kueche kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Jan 20 10:27:13 kueche kernel: of_cfs_init Jan 20 10:27:13 kueche kernel: of_cfs_init: OK Jan 20 10:27:13 kueche kernel: Freeing unused kernel memory: 1024K Jan 20 10:27:13 kueche kernel: Run /init as init process Jan 20 10:27:13 kueche kernel: mmc0: host does not support reading read-only switch, assuming write-enable Jan 20 10:27:13 kueche kernel: random: fast init done Jan 20 10:27:13 kueche kernel: mmc0: new high speed SDXC card at address 1234 Jan 20 10:27:13 kueche kernel: mmcblk0: mmc0:1234 SA64G 57.7 GiB Jan 20 10:27:13 kueche kernel: mmcblk0: p1 p2 p3 Jan 20 10:27:13 kueche kernel: Indeed it is in host mode hprt0 = 00021501 Jan 20 10:27:13 kueche kernel: mmc1: new high speed SDIO card at address 0001 Jan 20 10:27:13 kueche initramfs: Booting Volumio for BCM2835 Jan 20 10:27:13 kueche initramfs: This script mounts rootfs RO with an overlay RW layer. Jan 20 10:27:13 kueche kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jan 20 10:27:13 kueche initramfs: IMGPART=/dev/mmcblk0p2 Jan 20 10:27:13 kueche initramfs: IMGFILE=/volumio_current.sqsh Jan 20 10:27:13 kueche initramfs: Boot delay (except first time) will be 5 seconds Jan 20 10:27:13 kueche kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Jan 20 10:27:13 kueche kernel: Indeed it is in host mode hprt0 = 00001101 Jan 20 10:27:13 kueche initramfs: /dev/mmcblk0p2: Jan 20 10:27:13 kueche kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Jan 20 10:27:13 kueche kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jan 20 10:27:13 kueche kernel: hub 1-1:1.0: USB hub found Jan 20 10:27:13 kueche kernel: hub 1-1:1.0: 5 ports detected Jan 20 10:27:13 kueche kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Jan 20 10:27:13 kueche kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Jan 20 10:27:13 kueche kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Jan 20 10:27:13 kueche kernel: smsc95xx v1.0.6 Jan 20 10:27:13 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 Jan 20 10:27:13 kueche kernel: EXT4-fs (mmcblk0p2): recovery complete Jan 20 10:27:13 kueche kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Jan 20 10:27:13 kueche initramfs: Doing a 5 second delay here to give kernel load a headstart Jan 20 10:27:13 kueche initramfs: Checking for USB updates Jan 20 10:27:13 kueche initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Jan 20 10:27:13 kueche kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 20 10:27:13 kueche kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jan 20 10:27:13 kueche kernel: EXT4-fs (mmcblk0p3): recovery complete Jan 20 10:27:13 kueche kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Jan 20 10:27:13 kueche initramfs: With Option: Jan 20 10:27:13 kueche initramfs: VOLUMIO_VERSION="3.179" Jan 20 10:27:13 kueche initramfs: Finish initramfs, continue booting Volumio Jan 20 10:27:13 kueche systemd[1]: System time before build time, advancing clock. Jan 20 10:27:13 kueche kernel: NET: Registered protocol family 10 Jan 20 10:27:13 kueche kernel: Segment Routing with IPv6 Jan 20 10:27:13 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) Jan 20 10:27:13 kueche systemd[1]: Detected architecture arm. Jan 20 10:27:13 kueche systemd[1]: Set hostname to . Jan 20 10:27:13 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 20 10:27:13 kueche kernel: random: systemd: uninitialized urandom read (16 bytes read) Jan 20 10:27:13 kueche kernel: random: systemd: uninitialized urandom read (16 bytes read) Jan 20 10:27:13 kueche kernel: random: systemd: uninitialized urandom read (16 bytes read) Jan 20 10:27:13 kueche systemd[1]: Listening on RPCbind Server Activation Socket. Jan 20 10:27:13 kueche systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Jan 20 10:27:13 kueche systemd[1]: Listening on Journal Audit Socket. Jan 20 10:27:13 kueche systemd[1]: Started Forward Password Requests to Wall Directory Watch. Jan 20 10:27:13 kueche systemd[1]: Created slice system-getty.slice. Jan 20 10:27:13 kueche systemd[1]: Listening on udev Kernel Socket. Jan 20 10:27:13 kueche kernel: i2c /dev entries driver Jan 20 10:27:13 kueche systemd-journald[187]: Journal started Jan 20 10:27:13 kueche systemd-journald[187]: Runtime journal (/run/log/journal/03837a839dbf02e377dd5ba761c0a77e) is 7.5M, max 30.0M, 22.5M free. Jan 20 10:27:13 kueche systemd-modules-load[182]: Inserted module 'i2c_dev' Jan 20 10:27:13 kueche fake-hwclock[176]: Thu Jan 20 10:27:13 UTC 2022 Jan 20 10:27:13 kueche systemd[1]: Started Load/Save Random Seed. Jan 20 10:27:13 kueche systemd[1]: dynamicswap.service: Succeeded. Jan 20 10:27:13 kueche systemd[1]: Started Create System Users. Jan 20 10:27:13 kueche systemd[1]: Starting Create Static Device Nodes in /dev... Jan 20 10:27:13 kueche systemd[1]: Started Create Static Device Nodes in /dev. Jan 20 10:27:13 kueche systemd[1]: Reached target Local File Systems (Pre). Jan 20 10:27:13 kueche systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jan 20 10:27:13 kueche systemd[1]: Mounting /tmp... Jan 20 10:27:13 kueche systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jan 20 10:27:13 kueche systemd[1]: Mounting /var/log... Jan 20 10:27:13 kueche systemd[1]: Mounting /var/spool/cups... Jan 20 10:27:13 kueche systemd[1]: Starting udev Kernel Device Manager... Jan 20 10:27:13 kueche systemd[1]: Started udev Coldplug all Devices. Jan 20 10:27:13 kueche systemd[1]: Mounted /tmp. Jan 20 10:27:13 kueche systemd[1]: Mounted /var/log. Jan 20 10:27:13 kueche systemd[1]: Mounted /var/spool/cups. Jan 20 10:27:13 kueche systemd[1]: Mounting /var/spool/cups/tmp... Jan 20 10:27:13 kueche systemd[1]: Starting Flush Journal to Persistent Storage... Jan 20 10:27:13 kueche systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jan 20 10:27:13 kueche systemd[1]: Mounted /var/spool/cups/tmp. Jan 20 10:27:13 kueche systemd-udevd[208]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jan 20 10:27:13 kueche systemd-journald[187]: Runtime journal (/run/log/journal/03837a839dbf02e377dd5ba761c0a77e) is 7.5M, max 30.0M, 22.5M free. Jan 20 10:27:13 kueche systemd[1]: Started Flush Journal to Persistent Storage. Jan 20 10:27:13 kueche systemd[1]: Started udev Kernel Device Manager. Jan 20 10:27:13 kueche systemd[1]: Starting Show Plymouth Boot Screen... Jan 20 10:27:13 kueche systemd[1]: Received SIGRTMIN+20 from PID 226 (plymouthd). Jan 20 10:27:13 kueche systemd[1]: Started Show Plymouth Boot Screen. Jan 20 10:27:13 kueche systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 20 10:27:13 kueche systemd[1]: Reached target Local Encrypted Volumes. Jan 20 10:27:13 kueche systemd[1]: Reached target Paths. Jan 20 10:27:13 kueche systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jan 20 10:27:13 kueche kernel: mc: Linux media interface: v0.10 Jan 20 10:27:14 kueche kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 10:27:14 kueche kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jan 20 10:27:14 kueche kernel: [vc_sm_connected_init]: start Jan 20 10:27:14 kueche kernel: [vc_sm_connected_init]: installed successfully Jan 20 10:27:14 kueche kernel: videodev: Linux video capture interface: v2.00 Jan 20 10:27:14 kueche kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 10:27:14 kueche kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 10:27:14 kueche kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 10:27:14 kueche kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 10:27:14 kueche kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 10:27:14 kueche kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 10:27:14 kueche kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jan 20 10:27:14 kueche kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jan 20 10:27:14 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jan 20 10:27:14 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jan 20 10:27:14 kueche kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jan 20 10:27:14 kueche kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jan 20 10:27:14 kueche kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jan 20 10:27:14 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jan 20 10:27:14 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jan 20 10:27:14 kueche kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jan 20 10:27:14 kueche kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jan 20 10:27:14 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jan 20 10:27:14 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jan 20 10:27:14 kueche kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jan 20 10:27:14 kueche kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jan 20 10:27:14 kueche kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jan 20 10:27:14 kueche kernel: bcm2835_audio bcm2835_audio: card created with 8 channels Jan 20 10:27:14 kueche kernel: input: raspberrypi-ts as /devices/platform/soc/soc:firmware/soc:firmware:touchscreen/input/input0 Jan 20 10:27:14 kueche kernel: snd-rpi-hifiberry-dacplus soc:sound: ASoC: failed to init link HiFiBerry DAC+: -517 Jan 20 10:27:14 kueche kernel: snd-rpi-hifiberry-dacplus soc:sound: ASoC: failed to init link HiFiBerry DAC+: -517 Jan 20 10:27:15 kueche systemd[1]: Found device /dev/mmcblk0p1. Jan 20 10:27:15 kueche systemd-udevd[222]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 20 10:27:15 kueche systemd-udevd[223]: Using default interface naming scheme 'v240'. Jan 20 10:27:15 kueche systemd-udevd[223]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 20 10:27:15 kueche kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jan 20 10:27:15 kueche kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jan 20 10:27:16 kueche systemd-udevd[216]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 20 10:27:16 kueche kernel: snd-rpi-hifiberry-dacplus soc:sound: pcm512x-hifi <-> 3f203000.i2s mapping ok Jan 20 10:27:16 kueche kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Jan 20 10:27:16 kueche kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Jan 20 10:27:16 kueche kernel: usbcore: registered new interface driver brcmfmac Jan 20 10:27:16 kueche systemd-udevd[221]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 20 10:27:16 kueche kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43430-sdio.raspberrypi,3-model-b.txt failed with error -2 Jan 20 10:27:16 kueche systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jan 20 10:27:16 kueche systemd[1]: Condition check resulted in FUSE Control File System being skipped. Jan 20 10:27:16 kueche systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jan 20 10:27:16 kueche systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jan 20 10:27:16 kueche systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jan 20 10:27:16 kueche systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jan 20 10:27:16 kueche systemd[1]: Created slice system-systemd\x2dbacklight.slice. Jan 20 10:27:16 kueche systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:rpi_backlight... Jan 20 10:27:16 kueche systemd[1]: Starting File System Check on /dev/mmcblk0p1... Jan 20 10:27:16 kueche systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:rpi_backlight. Jan 20 10:27:16 kueche systemd[1]: Started File System Check Daemon to report status. Jan 20 10:27:16 kueche kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Jan 20 10:27:16 kueche kernel: brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available Jan 20 10:27:16 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 20 10:27:16 kueche systemd[1]: Found device SMSC9512/9514 Fast Ethernet Adapter. Jan 20 10:27:16 kueche systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 20 10:27:16 kueche systemd-udevd[223]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jan 20 10:27:16 kueche systemd[1]: Started Load/Save RF Kill Switch Status. Jan 20 10:27:16 kueche systemd-fsck[368]: fsck.fat 4.1 (2017-01-24) Jan 20 10:27:16 kueche systemd-fsck[368]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Jan 20 10:27:16 kueche systemd-fsck[368]: Automatically removing dirty bit. Jan 20 10:27:16 kueche systemd-fsck[368]: Performing changes. Jan 20 10:27:16 kueche systemd-fsck[368]: /dev/mmcblk0p1: 332 files, 26663/46774 clusters Jan 20 10:27:16 kueche systemd[1]: Started File System Check on /dev/mmcblk0p1. Jan 20 10:27:16 kueche systemd[1]: Mounting /boot... Jan 20 10:27:17 kueche systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jan 20 10:27:17 kueche systemd[1]: Started Helper to synchronize boot up for ifupdown. Jan 20 10:27:17 kueche systemd[1]: Mounted /boot. Jan 20 10:27:17 kueche systemd[1]: Reached target Local File Systems. Jan 20 10:27:17 kueche systemd[1]: Started ifup for eth0. Jan 20 10:27:17 kueche systemd[1]: Starting Preprocess NFS configuration... Jan 20 10:27:17 kueche systemd[1]: Starting Create Volatile Files and Directories... Jan 20 10:27:17 kueche systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jan 20 10:27:17 kueche systemd[1]: Starting Raise network interfaces... Jan 20 10:27:17 kueche systemd[1]: Started ifup for wlan0. Jan 20 10:27:17 kueche systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jan 20 10:27:17 kueche systemd[1]: nfs-config.service: Succeeded. Jan 20 10:27:17 kueche systemd[1]: Started Preprocess NFS configuration. Jan 20 10:27:17 kueche systemd[1]: plymouth-read-write.service: Succeeded. Jan 20 10:27:17 kueche systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jan 20 10:27:17 kueche systemd[1]: Received SIGRTMIN+20 from PID 226 (plymouthd). Jan 20 10:27:17 kueche systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jan 20 10:27:17 kueche systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jan 20 10:27:17 kueche systemd[1]: Reached target NFS client services. Jan 20 10:27:17 kueche systemd[1]: Started Create Volatile Files and Directories. Jan 20 10:27:17 kueche systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jan 20 10:27:17 kueche systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jan 20 10:27:17 kueche systemd[1]: Starting RPC bind portmap service... Jan 20 10:27:17 kueche systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jan 20 10:27:17 kueche systemd[1]: Started Update UTMP about System Boot/Shutdown. Jan 20 10:27:17 kueche systemd[1]: Reached target System Initialization. Jan 20 10:27:17 kueche systemd[1]: Listening on triggerhappy.socket. Jan 20 10:27:17 kueche systemd[1]: man-db.timer: Not using persistent file timestamp Fri 2022-01-21 05:31:54 UTC as it is in the future. Jan 20 10:27:17 kueche systemd[1]: Started Daily man-db regeneration. Jan 20 10:27:17 kueche systemd[1]: Listening on D-Bus System Message Bus Socket. Jan 20 10:27:17 kueche systemd[1]: Started Daily Cleanup of Temporary Directories. Jan 20 10:27:17 kueche systemd[1]: Listening on mpd.socket. Jan 20 10:27:17 kueche systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jan 20 10:27:17 kueche systemd[1]: Reached target Sockets. Jan 20 10:27:17 kueche systemd[1]: Reached target Basic System. Jan 20 10:27:17 kueche systemd[1]: Starting triggerhappy global hotkey daemon... Jan 20 10:27:17 kueche systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jan 20 10:27:17 kueche systemd[1]: Started Manage Sound Card State (restore and store). Jan 20 10:27:17 kueche systemd[1]: Started volumio-remote-updater.service. Jan 20 10:27:17 kueche systemd[1]: Started UPnP Renderer front-end to MPD. Jan 20 10:27:17 kueche thd[453]: Unable to parse trigger line: Jan 20 10:27:17 kueche thd[453]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jan 20 10:27:17 kueche thd[453]: Unable to parse trigger line: Jan 20 10:27:17 kueche thd[453]: Unable to parse trigger line: Jan 20 10:27:17 kueche alsactl[454]: alsactl 1.1.8 daemon started Jan 20 10:27:17 kueche systemd[1]: Starting Wireless Services... Jan 20 10:27:17 kueche kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jan 20 10:27:17 kueche systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jan 20 10:27:17 kueche systemd[1]: Starting Login Service... Jan 20 10:27:17 kueche systemd[1]: Starting Save/Restore Sound Card State... Jan 20 10:27:17 kueche systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jan 20 10:27:17 kueche kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jan 20 10:27:17 kueche systemd[1]: Starting dhcpcd on all interfaces... Jan 20 10:27:17 kueche systemd[1]: Started Volumio Iptables Module. Jan 20 10:27:17 kueche systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jan 20 10:27:17 kueche systemd[1]: Started D-Bus System Message Bus. Jan 20 10:27:17 kueche thd[453]: Found socket passed from systemd Jan 20 10:27:17 kueche systemd[1]: Starting WPA supplicant... Jan 20 10:27:17 kueche systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jan 20 10:27:17 kueche systemd[1]: apt-daily.timer: Not using persistent file timestamp Fri 2022-01-21 05:31:54 UTC as it is in the future. Jan 20 10:27:17 kueche systemd[1]: Started Daily apt download activities. Jan 20 10:27:17 kueche systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Fri 2022-01-21 06:11:48 UTC as it is in the future. Jan 20 10:27:17 kueche systemd[1]: Started Daily apt upgrade and clean activities. Jan 20 10:27:17 kueche systemd[1]: Reached target Timers. Jan 20 10:27:17 kueche systemd[1]: Started triggerhappy global hotkey daemon. Jan 20 10:27:17 kueche systemd[1]: Started RPC bind portmap service. Jan 20 10:27:17 kueche systemd[1]: Started Save/Restore Sound Card State. Jan 20 10:27:18 kueche systemd-logind[462]: New seat seat0. Jan 20 10:27:18 kueche dhcpcd[465]: dev: loaded udev Jan 20 10:27:18 kueche ifup[388]: ifup: waiting for lock on /run/network/ifstate.wlan0 Jan 20 10:27:18 kueche systemd[1]: Reached target Sound Card. Jan 20 10:27:18 kueche systemd[1]: Reached target RPC Port Mapper. Jan 20 10:27:18 kueche systemd[1]: Reached target Remote File Systems (Pre). Jan 20 10:27:18 kueche systemd[1]: Reached target Remote File Systems. Jan 20 10:27:18 kueche sh[383]: eth0=eth0 Jan 20 10:27:18 kueche sh[393]: wlan0=wlan0 Jan 20 10:27:18 kueche avahi-daemon[461]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jan 20 10:27:18 kueche systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jan 20 10:27:18 kueche avahi-daemon[461]: Successfully dropped root privileges. Jan 20 10:27:18 kueche avahi-daemon[461]: avahi-daemon 0.7 starting up. Jan 20 10:27:18 kueche kernel: 8021q: 802.1Q VLAN Support v1.8 Jan 20 10:27:18 kueche systemd[1]: Started Login Service. Jan 20 10:27:18 kueche avahi-daemon[461]: Successfully called chroot(). Jan 20 10:27:18 kueche avahi-daemon[461]: Successfully dropped remaining capabilities. Jan 20 10:27:18 kueche systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jan 20 10:27:18 kueche avahi-daemon[461]: Loading service file /services/volumio.service. Jan 20 10:27:18 kueche dhcpcd[465]: forked to background, child pid 563 Jan 20 10:27:18 kueche avahi-daemon[461]: Network interface enumeration completed. Jan 20 10:27:18 kueche avahi-daemon[461]: Server startup complete. Host name is kueche.local. Local service cookie is 3889908987. Jan 20 10:27:18 kueche avahi-daemon[461]: Service "Kueche" (/services/volumio.service) successfully established. Jan 20 10:27:18 kueche systemd[1]: Started dhcpcd on all interfaces. Jan 20 10:27:18 kueche kernel: uart-pl011 3f201000.serial: no DMA platform data Jan 20 10:27:18 kueche systemd[1]: Started Raise network interfaces. Jan 20 10:27:18 kueche dhcpcd-run-hooks[589]: wlan0: ctrl_interface not defined in /etc/wpa_supplicant/wpa_supplicant.conf Jan 20 10:27:18 kueche dhcpcd-run-hooks[594]: wlan0: not interacting with wpa_supplicant(8) Jan 20 10:27:18 kueche dhcpcd[563]: wlan0: connected to Access Point `' Jan 20 10:27:18 kueche loadcpufreq[537]: Loading cpufreq kernel modules...done (none). Jan 20 10:27:18 kueche systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jan 20 10:27:18 kueche systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jan 20 10:27:18 kueche dhcpcd[563]: DUID 00:01:00:01:29:53:65:b7:b8:27:eb:15:2e:b1 Jan 20 10:27:18 kueche dhcpcd[563]: eth0: IAID eb:15:2e:b1 Jan 20 10:27:18 kueche dhcpcd[563]: eth0: adding address fe80::ba27:ebff:fe15:2eb1 Jan 20 10:27:18 kueche dhcpcd[563]: ipv6_addaddr1: Permission denied Jan 20 10:27:18 kueche dhcpcd[563]: eth0: probing address 192.168.0.31/24 Jan 20 10:27:18 kueche dhcpcd[563]: wlan0: waiting for carrier Jan 20 10:27:18 kueche dhcpcd[563]: eth0: carrier lost Jan 20 10:27:19 kueche kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xCDE1 Jan 20 10:27:19 kueche systemd[1]: Started WPA supplicant. Jan 20 10:27:19 kueche cpufrequtils[625]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jan 20 10:27:19 kueche wpa_supplicant[500]: Successfully initialized wpa_supplicant Jan 20 10:27:19 kueche systemd[1]: Reached target Network. Jan 20 10:27:19 kueche systemd[1]: Reached target Network is Online. Jan 20 10:27:19 kueche systemd[1]: Starting LSB: Brings up/down network automatically... Jan 20 10:27:19 kueche systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 20 10:27:19 kueche systemd[1]: Starting Samba NMB Daemon... Jan 20 10:27:19 kueche dhcpcd[563]: eth0: carrier acquired Jan 20 10:27:19 kueche systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jan 20 10:27:19 kueche dhcpcd[563]: eth0: IAID eb:15:2e:b1 Jan 20 10:27:19 kueche dhcpcd[563]: eth0: probing address 192.168.0.31/24 Jan 20 10:27:19 kueche systemd[1]: Starting Music Player Daemon... Jan 20 10:27:19 kueche systemd[1]: Starting /etc/rc.local Compatibility... Jan 20 10:27:19 kueche systemd[1]: Starting Permit User Sessions... Jan 20 10:27:19 kueche systemd[1]: Starting Network Time Service... Jan 20 10:27:19 kueche systemd[1]: Started LSB: set CPUFreq kernel parameters. Jan 20 10:27:19 kueche systemd[1]: Started /etc/rc.local Compatibility. Jan 20 10:27:19 kueche systemd[1]: Started Permit User Sessions. Jan 20 10:27:19 kueche systemd[1]: Starting Hold until boot process finishes up... Jan 20 10:27:19 kueche systemd[1]: Starting Terminate Plymouth Boot Screen... Jan 20 10:27:19 kueche systemd[1]: Received SIGRTMIN+21 from PID 226 (plymouthd). Jan 20 10:27:19 kueche systemd[1]: Received SIGRTMIN+21 from PID 226 (plymouthd). Jan 20 10:27:19 kueche ifplugd[656]: Network Interface Plugging Daemon...error: unexpectedly disconnected from boot status daemon Jan 20 10:27:19 kueche systemd[1]: plymouth-quit-wait.service: Succeeded. Jan 20 10:27:19 kueche systemd[1]: Started Hold until boot process finishes up. Jan 20 10:27:19 kueche systemd[1]: plymouth-quit.service: Succeeded. Jan 20 10:27:19 kueche systemd[1]: Started Terminate Plymouth Boot Screen. Jan 20 10:27:19 kueche systemd[1]: plymouth-start.service: Succeeded. Jan 20 10:27:19 kueche systemd[1]: Started Getty on tty1. Jan 20 10:27:19 kueche ifplugd(eth0)[686]: ifplugd 0.28 initializing. Jan 20 10:27:19 kueche ifplugd(eth0)[686]: Using interface eth0/B8:27:EB:15:2E:B1 with driver (version: 22-Aug-2005) Jan 20 10:27:19 kueche ifplugd(eth0)[686]: Using detection mode: SIOCETHTOOL Jan 20 10:27:19 kueche ifplugd(eth0)[686]: Initialization complete, link beat detected. Jan 20 10:27:19 kueche systemd[1]: Reached target Login Prompts. Jan 20 10:27:19 kueche ntpd[666]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 20 10:27:19 kueche ntpd[666]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jan 20 10:27:19 kueche systemd[1]: Started Network Time Service. Jan 20 10:27:19 kueche ifplugd(eth0)[686]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Jan 20 10:27:19 kueche dhcpcd[563]: eth0: soliciting an IPv6 router Jan 20 10:27:19 kueche ntpd[689]: proto: precision = 0.729 usec (-20) Jan 20 10:27:19 kueche ifplugd(eth0)[686]: client: ifup: interface eth0 already configured Jan 20 10:27:19 kueche ntpd[689]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 20 10:27:19 kueche ntpd[689]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 20 10:27:19 kueche systemd[1]: iptables.service: Succeeded. Jan 20 10:27:19 kueche ntpd[689]: Listen and drop on 0 v6wildcard [::]:123 Jan 20 10:27:19 kueche ntpd[689]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 20 10:27:19 kueche ntpd[689]: Listen normally on 2 lo 127.0.0.1:123 Jan 20 10:27:19 kueche ntpd[689]: Listening on routing socket on fd #19 for interface updates Jan 20 10:27:19 kueche ntpd[689]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 20 10:27:19 kueche ntpd[689]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jan 20 10:27:19 kueche ifplugd(eth0)[686]: Program executed successfully. Jan 20 10:27:19 kueche ifplugd[656]: start eth0...done. Jan 20 10:27:19 kueche systemd[1]: Started LSB: Brings up/down network automatically. Jan 20 10:27:19 kueche kernel: random: crng init done Jan 20 10:27:19 kueche kernel: random: 7 urandom warning(s) missed due to ratelimiting Jan 20 10:27:19 kueche haveged[437]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jan 20 10:27:19 kueche haveged[437]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jan 20 10:27:19 kueche haveged[437]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99736 Jan 20 10:27:19 kueche haveged[437]: haveged: fills: 0, generated: 0 Jan 20 10:27:19 kueche volumio-remote-updater[455]: Error: No active session Jan 20 10:27:19 kueche volumio-remote-updater[455]: [2022-01-20 10:27:19] [info] asio async_connect error: system:111 (Connection refused) Jan 20 10:27:19 kueche volumio-remote-updater[455]: [2022-01-20 10:27:19] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 20 10:27:19 kueche volumio-remote-updater[455]: [2022-01-20 10:27:19] [error] handle_connect error: Underlying Transport Error Jan 20 10:27:20 kueche volumio[456]: Could not open config: /tmp/upmpdcli.conf Jan 20 10:27:20 kueche systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 20 10:27:20 kueche systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 20 10:27:20 kueche ntpd[689]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jan 20 10:27:20 kueche nmbd[667]: [2022/01/20 10:27:20.814191, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jan 20 10:27:20 kueche nmbd[667]: started asyncdns process 706 Jan 20 10:27:20 kueche nmbd[667]: [2022/01/20 10:27:20.866801, 0] ../lib/util/become_daemon.c:149(daemon_status) Jan 20 10:27:20 kueche nmbd[667]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jan 20 10:27:20 kueche nmbd[667]: [2022/01/20 10:27:20.867010, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jan 20 10:27:20 kueche nmbd[667]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jan 20 10:27:21 kueche ntpd[689]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jan 20 10:27:21 kueche systemd[1]: systemd-rfkill.service: Succeeded. Jan 20 10:27:22 kueche wireless.js[457]: WIRELESS DAEMON: start Jan 20 10:27:22 kueche wireless.js[457]: WIRELESS: Loaded configuration Jan 20 10:27:22 kueche wireless.js[457]: Cleaning previous... Jan 20 10:27:22 kueche ntpd[689]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jan 20 10:27:22 kueche kernel: Bluetooth: Core ver 2.22 Jan 20 10:27:22 kueche kernel: NET: Registered protocol family 31 Jan 20 10:27:22 kueche kernel: Bluetooth: HCI device and connection manager initialized Jan 20 10:27:22 kueche kernel: Bluetooth: HCI socket layer initialized Jan 20 10:27:22 kueche kernel: Bluetooth: L2CAP socket layer initialized Jan 20 10:27:22 kueche kernel: Bluetooth: SCO socket layer initialized Jan 20 10:27:22 kueche btuart[507]: bcm43xx_init Jan 20 10:27:22 kueche btuart[507]: Flash firmware /lib/firmware/brcm/BCM43430A1.hcd Jan 20 10:27:22 kueche btuart[507]: Set BDADDR UART: b8:27:eb:bf:84:1b Jan 20 10:27:22 kueche btuart[507]: Set Controller UART speed to 460800 bit/s Jan 20 10:27:22 kueche btuart[507]: Device setup complete Jan 20 10:27:22 kueche kernel: Bluetooth: HCI UART driver ver 2.3 Jan 20 10:27:22 kueche kernel: Bluetooth: HCI UART protocol H4 registered Jan 20 10:27:22 kueche kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jan 20 10:27:22 kueche kernel: Bluetooth: HCI UART protocol Broadcom registered Jan 20 10:27:22 kueche systemd[1]: Starting Load/Save RF Kill Switch Status... Jan 20 10:27:22 kueche systemd[1]: Started Configure Bluetooth Modems connected by UART. Jan 20 10:27:22 kueche sudo[717]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jan 20 10:27:22 kueche sudo[717]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:22 kueche sudo[717]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:22 kueche sudo[724]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jan 20 10:27:22 kueche sudo[724]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:22 kueche systemd[1]: Started Load/Save RF Kill Switch Status. Jan 20 10:27:22 kueche systemd[1]: Created slice system-bthelper.slice. Jan 20 10:27:22 kueche systemd[1]: Starting Raspberry Pi bluetooth helper... Jan 20 10:27:22 kueche bthelper[726]: Raspberry Pi BDADDR already set Jan 20 10:27:22 kueche systemd[1]: Started Raspberry Pi bluetooth helper. Jan 20 10:27:22 kueche systemd[1]: Starting Bluetooth service... Jan 20 10:27:22 kueche bluetoothd[736]: Bluetooth daemon 5.50 Jan 20 10:27:22 kueche bluetoothd[736]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jan 20 10:27:22 kueche systemd[1]: Started Bluetooth service. Jan 20 10:27:22 kueche systemd[1]: Reached target Bluetooth. Jan 20 10:27:22 kueche bluetoothd[736]: Starting SDP server Jan 20 10:27:22 kueche bluetoothd[736]: Excluding (cli) sap Jan 20 10:27:23 kueche bluetoothd[736]: Bluetooth management interface 1.14 initialized Jan 20 10:27:23 kueche dbus-daemon[476]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=736 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jan 20 10:27:23 kueche kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jan 20 10:27:23 kueche kernel: Bluetooth: BNEP filters: protocol multicast Jan 20 10:27:23 kueche kernel: Bluetooth: BNEP socket layer initialized Jan 20 10:27:23 kueche systemd[1]: Starting Hostname Service... Jan 20 10:27:23 kueche bluetoothd[736]: Failed to set privacy: Rejected (0x0b) Jan 20 10:27:23 kueche mpd[697]: Jan 20 10:27 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 20 10:27:23 kueche mpd[697]: Jan 20 10:27 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 20 10:27:23 kueche mpd[697]: Jan 20 10:27 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 20 10:27:23 kueche sudo[724]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:23 kueche systemd[1]: Started Music Player Daemon. Jan 20 10:27:23 kueche wireless.js[457]: Stopped aP Jan 20 10:27:23 kueche wireless.js[457]: Wireless Networking DISABLED, not starting wireless flow Jan 20 10:27:23 kueche systemd[1]: wireless.service: Succeeded. Jan 20 10:27:23 kueche systemd[1]: Started Wireless Services. Jan 20 10:27:23 kueche systemd[1]: Started Volumio Backend Module. Jan 20 10:27:23 kueche systemd[1]: Started Volumio Streaming Daemon. Jan 20 10:27:23 kueche dbus-daemon[476]: [system] Successfully activated service 'org.freedesktop.hostname1' Jan 20 10:27:23 kueche systemd[1]: Started Hostname Service. Jan 20 10:27:23 kueche ntpd[689]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jan 20 10:27:23 kueche volumio-streaming-daemon[747]: ############################ Jan 20 10:27:23 kueche volumio-streaming-daemon[747]: # Volumio Streaming Daemon # Jan 20 10:27:23 kueche volumio-streaming-daemon[747]: # Running on port 7777 # Jan 20 10:27:23 kueche volumio-streaming-daemon[747]: ############################ Jan 20 10:27:23 kueche volumio-streaming-daemon[747]: Environment: production Jan 20 10:27:24 kueche dhcpcd[563]: eth0: using static address 192.168.0.31/24 Jan 20 10:27:24 kueche dhcpcd[563]: eth0: adding route to 192.168.0.0/24 Jan 20 10:27:24 kueche dhcpcd[563]: eth0: adding default route via 192.168.0.10 Jan 20 10:27:24 kueche avahi-daemon[461]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.31. Jan 20 10:27:24 kueche avahi-daemon[461]: New relevant interface eth0.IPv4 for mDNS. Jan 20 10:27:24 kueche avahi-daemon[461]: Registering new address record for 192.168.0.31 on eth0.IPv4. Jan 20 10:27:24 kueche volumio-remote-updater[455]: [2022-01-20 10:27:24] [info] asio async_connect error: system:111 (Connection refused) Jan 20 10:27:24 kueche volumio-remote-updater[455]: [2022-01-20 10:27:24] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 20 10:27:24 kueche volumio-remote-updater[455]: [2022-01-20 10:27:24] [error] handle_connect error: Underlying Transport Error Jan 20 10:27:25 kueche ntpd[689]: Listen normally on 3 eth0 192.168.0.31:123 Jan 20 10:27:25 kueche ntpd[689]: new interface(s) found: waking up resolver Jan 20 10:27:25 kueche nmbd[667]: [2022/01/20 10:27:25.878723, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 20 10:27:25 kueche systemd[1]: Started Samba NMB Daemon. Jan 20 10:27:25 kueche nmbd[667]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 20 10:27:25 kueche systemd[1]: Starting Samba Winbind Daemon... Jan 20 10:27:26 kueche winbindd[779]: [2022/01/20 10:27:26.263911, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 20 10:27:26 kueche winbindd[779]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 20 10:27:26 kueche winbindd[779]: [2022/01/20 10:27:26.375276, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 20 10:27:26 kueche winbindd[779]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 20 10:27:26 kueche systemd[1]: Started Samba Winbind Daemon. Jan 20 10:27:26 kueche systemd[1]: Starting Samba SMB Daemon... Jan 20 10:27:26 kueche volumio[746]: info: ------------------------------------------- Jan 20 10:27:26 kueche volumio[746]: info: ----- Volumio3 ---- Jan 20 10:27:26 kueche volumio[746]: info: ------------------------------------------- Jan 20 10:27:26 kueche volumio[746]: info: ----- System startup ---- Jan 20 10:27:26 kueche volumio[746]: info: ------------------------------------------- Jan 20 10:27:26 kueche winbindd[779]: [2022/01/20 10:27:26.984495, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jan 20 10:27:26 kueche winbindd[779]: res_names->count = 1, expected 2 Jan 20 10:27:27 kueche smbd[792]: [2022/01/20 10:27:27.027553, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 20 10:27:27 kueche systemd[1]: Started Samba SMB Daemon. Jan 20 10:27:27 kueche smbd[792]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 20 10:27:27 kueche systemd[1]: Reached target Multi-User System. Jan 20 10:27:27 kueche systemd[1]: Reached target Graphical Interface. Jan 20 10:27:27 kueche systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 20 10:27:27 kueche systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 20 10:27:27 kueche systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 20 10:27:27 kueche systemd[1]: Startup finished in 14.579s (kernel) + 16.002s (userspace) = 30.582s. Jan 20 10:27:27 kueche systemd[1]: systemd-rfkill.service: Succeeded. Jan 20 10:27:27 kueche bthelper[726]: Changing power off succeeded Jan 20 10:27:27 kueche volumio[746]: info: MYVOLUMIO Environment detected Jan 20 10:27:28 kueche volumio[746]: info: Plugin folders cleanup Jan 20 10:27:28 kueche volumio[746]: info: Scanning into folder /volumio/app/plugins/ Jan 20 10:27:28 kueche volumio[746]: info: Scanning category audio_interface Jan 20 10:27:28 kueche bthelper[726]: [CHG] Controller B8:27:EB:BF:84:1B Class: 0x0000041c Jan 20 10:27:28 kueche bthelper[726]: Changing power on succeeded Jan 20 10:27:28 kueche volumio[746]: info: Scanning category miscellanea Jan 20 10:27:28 kueche volumio[746]: info: Scanning category music_service Jan 20 10:27:28 kueche volumio[746]: info: Scanning category plugins.json Jan 20 10:27:28 kueche volumio[746]: info: Scanning category system_controller Jan 20 10:27:28 kueche volumio[746]: info: Scanning category user_interface Jan 20 10:27:28 kueche volumio[746]: info: Scanning into folder /data/plugins/ Jan 20 10:27:28 kueche volumio[746]: info: Scanning category user_interface Jan 20 10:27:28 kueche volumio[746]: info: Plugin folders cleanup completed Jan 20 10:27:28 kueche volumio[746]: info: ------------------------------------------- Jan 20 10:27:28 kueche volumio[746]: info: ----- Core plugins startup ---- Jan 20 10:27:28 kueche volumio[746]: info: ------------------------------------------- Jan 20 10:27:28 kueche volumio[746]: info: Loading plugins from folder /volumio/app/plugins/ Jan 20 10:27:28 kueche volumio[746]: info: Adding plugin upnp to MyMusic Plugins Jan 20 10:27:28 kueche volumio[746]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 20 10:27:28 kueche volumio[746]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 20 10:27:28 kueche volumio[746]: info: Loading plugins from folder /data/plugins/ Jan 20 10:27:28 kueche volumio[746]: info: Loading plugin "system"... Jan 20 10:27:28 kueche volumio[746]: info: Loading plugin "appearance"... Jan 20 10:27:31 kueche volumio[746]: info: Loading plugin "network"... Jan 20 10:27:31 kueche volumio[746]: info: Refreshing Cached IP Addresses Jan 20 10:27:31 kueche sudo[811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 20 10:27:31 kueche volumio[746]: info: Loading plugin "services"... Jan 20 10:27:31 kueche sudo[811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:31 kueche volumio[746]: info: Loading plugin "alsa_controller"... Jan 20 10:27:31 kueche sudo[811]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:31 kueche sudo[813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 20 10:27:31 kueche sudo[813]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:31 kueche sudo[813]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:31 kueche volumio[746]: info: Loading plugin "wizard"... Jan 20 10:27:31 kueche volumio[746]: info: Loading plugin "volumio_command_line_client"... Jan 20 10:27:31 kueche volumio[746]: info: Loading plugin "upnp"... Jan 20 10:27:31 kueche volumio[746]: info: [1642674451821] Starting Upmpd Daemon Jan 20 10:27:31 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 10:27:31 kueche volumio[746]: info: Loading plugin "my_music"... Jan 20 10:27:31 kueche volumio[746]: info: Loading plugin "mpd"... Jan 20 10:27:32 kueche volumio[746]: info: Creating MPD Configuration file Jan 20 10:27:32 kueche volumio[746]: info: Loading plugin "upnp_browser"... Jan 20 10:27:32 kueche sudo[819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 20 10:27:32 kueche sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:32 kueche sudo[819]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:32 kueche sudo[821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 20 10:27:32 kueche sudo[821]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:32 kueche systemd[1]: Stopping Music Player Daemon... Jan 20 10:27:32 kueche volumio-remote-updater[455]: [2022-01-20 10:27:32] [connect] Successful connection Jan 20 10:27:32 kueche systemd[1]: mpd.service: Succeeded. Jan 20 10:27:32 kueche systemd[1]: Stopped Music Player Daemon. Jan 20 10:27:32 kueche systemd[1]: Starting Music Player Daemon... Jan 20 10:27:32 kueche volumio[746]: info: Loading plugin "networkfs"... Jan 20 10:27:33 kueche volumio[746]: info: Starting Udev Watcher for removable devices Jan 20 10:27:33 kueche sudo[829]: 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 20 10:27:33 kueche sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:33 kueche kernel: FS-Cache: Netfs 'cifs' registered for caching Jan 20 10:27:33 kueche kernel: Key type cifs.spnego registered Jan 20 10:27:33 kueche kernel: Key type cifs.idmap registered Jan 20 10:27:33 kueche kernel: CIFS: Attempting to mount //192.168.0.163/music Jan 20 10:27:33 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 20 10:27:33 kueche volumio[746]: info: Ignoring mount for partition: boot Jan 20 10:27:33 kueche volumio[746]: info: Ignoring mount for partition: volumio Jan 20 10:27:33 kueche volumio[746]: info: Ignoring mount for partition: volumio_data Jan 20 10:27:33 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 10:27:33 kueche volumio[746]: info: Loading plugin "alarm-clock"... Jan 20 10:27:33 kueche sudo[829]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:34 kueche volumio[746]: info: Loading plugin "airplay_emulation"... Jan 20 10:27:34 kueche volumio[746]: info: Starting Shairport Sync Jan 20 10:27:34 kueche volumio[746]: info: Loading plugin "last_100"... Jan 20 10:27:34 kueche volumio[746]: info: Loading plugin "webradio"... Jan 20 10:27:34 kueche mpd[826]: Jan 20 10:27 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 20 10:27:34 kueche volumio[746]: info: Loading plugin "i2s_dacs"... Jan 20 10:27:34 kueche volumio[746]: info: Loading plugin "volumiodiscovery"... Jan 20 10:27:34 kueche volumio[746]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 20 10:27:34 kueche volumio[746]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 10:27:34 kueche volumio[746]: *** WARNING *** For more information see Jan 20 10:27:34 kueche volumio[746]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 20 10:27:34 kueche node[746]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 20 10:27:34 kueche volumio[746]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 10:27:34 kueche volumio[746]: *** WARNING *** For more information see Jan 20 10:27:34 kueche node[746]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 10:27:34 kueche node[746]: *** WARNING *** For more information see Jan 20 10:27:34 kueche node[746]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 20 10:27:34 kueche node[746]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 20 10:27:34 kueche node[746]: *** WARNING *** For more information see Jan 20 10:27:34 kueche volumio[746]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 20 10:27:34 kueche volumio[746]: info: Discovery: Started advertising with name: Kueche Jan 20 10:27:34 kueche mpd[826]: Jan 20 10:27 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 20 10:27:34 kueche mpd[826]: Jan 20 10:27 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 20 10:27:34 kueche systemd[1]: Started Music Player Daemon. Jan 20 10:27:34 kueche sudo[821]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:34 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 10:27:34 kueche volumio[746]: info: Loading plugin "now_playing"... Jan 20 10:27:35 kueche volumio[746]: info: Loading plugin "outputs"... Jan 20 10:27:35 kueche volumio[746]: info: Loading plugin "albumart"... Jan 20 10:27:35 kueche volumio[746]: info: Plugin example_plugin is not enabled Jan 20 10:27:35 kueche volumio[746]: info: Loading plugin "inputs"... Jan 20 10:27:35 kueche volumio[746]: info: Loading plugin "updater_comm"... Jan 20 10:27:35 kueche volumio[746]: info: Plugin mpdemulation is not enabled Jan 20 10:27:35 kueche volumio[746]: info: Loading plugin "rest_api"... Jan 20 10:27:35 kueche volumio[746]: info: Loading plugin "websocket"... Jan 20 10:27:35 kueche volumio[746]: info: Loading plugin "touch_display"... Jan 20 10:27:35 kueche volumio[746]: Forking 3 albumart workers Jan 20 10:27:36 kueche volumio[746]: info: Applying required configuration parameters for plugin touch_display Jan 20 10:27:36 kueche volumio[746]: info: Loading i18n strings for locale de Jan 20 10:27:36 kueche volumio[746]: Updating browse sources language Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::initPlayerControls Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 10:27:36 kueche volumio[746]: Express server listening on port 3000 Jan 20 10:27:36 kueche volumio[746]: [Metrics] WebUI: 10s 989.54ms Jan 20 10:27:36 kueche volumio[746]: info: CoreStateMachine::resetVolumioState Jan 20 10:27:36 kueche volumio[746]: info: CoreStateMachine::getcurrentVolume Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::volumioRetrievevolume Jan 20 10:27:36 kueche volumio[746]: Starting albumart workers Jan 20 10:27:36 kueche volumio[746]: Starting albumart workers Jan 20 10:27:36 kueche volumio[746]: info: MPD Permissions set Jan 20 10:27:36 kueche volumio[746]: Starting albumart workers Jan 20 10:27:36 kueche volumio-remote-updater[455]: [2022-01-20 10:27:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1642674452 101 Jan 20 10:27:36 kueche volumio[746]: 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 20 10:27:36 kueche volumio[746]: info: CoreStateMachine::pushState Jan 20 10:27:36 kueche volumio[746]: info: CorePlayQueue::getTrack 0 Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 20 10:27:36 kueche volumio[746]: info: CoreStateMachine::updateTrackBlock Jan 20 10:27:36 kueche volumio[746]: info: CorePlayQueue::getTrackBlock Jan 20 10:27:36 kueche volumio[746]: info: CoreCommandRouter::volumioRetrievevolume Jan 20 10:27:36 kueche volumio[746]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jan 20 10:27:36 kueche volumio[746]: info: MPD running with PID826 Jan 20 10:27:36 kueche volumio[746]: ,establishing connection Jan 20 10:27:37 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:37 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:37 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:37 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:37 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:37 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:37 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:37 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:37 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 10:27:37 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:37 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 10:27:37 kueche volumio[746]: info: Reloading queue from file Jan 20 10:27:37 kueche volumio[746]: error: updateQueue error: null Jan 20 10:27:37 kueche volumio[746]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 20 10:27:37 kueche volumio[746]: info: CoreStateMachine::pushState Jan 20 10:27:37 kueche volumio[746]: info: CorePlayQueue::getTrack 0 Jan 20 10:27:37 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 20 10:27:37 kueche volumio[746]: info: Setting Device type: Raspberry PI Jan 20 10:27:37 kueche volumio[746]: info: CoreStateMachine::setRepeat null single undefined Jan 20 10:27:37 kueche volumio[746]: info: CoreStateMachine::pushState Jan 20 10:27:37 kueche volumio[746]: info: CorePlayQueue::getTrack 0 Jan 20 10:27:37 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 20 10:27:37 kueche volumio[746]: info: CoreStateMachine::setRandom null Jan 20 10:27:37 kueche volumio[746]: info: CoreStateMachine::pushState Jan 20 10:27:37 kueche volumio[746]: info: CorePlayQueue::getTrack 0 Jan 20 10:27:37 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 20 10:27:37 kueche volumio[746]: info: Completed loading Core Plugins Jan 20 10:27:37 kueche volumio[746]: info: Preparing to generate the ALSA configuration file Jan 20 10:27:37 kueche volumio[746]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jan 20 10:27:37 kueche volumio[746]: info: Reading ALSA contributions from plugins. Jan 20 10:27:37 kueche volumio[746]: error: updateQueue error: null Jan 20 10:27:38 kueche volumio[746]: info: Asound.conf file unchanged, so no further update is needed Jan 20 10:27:38 kueche volumio[746]: info: Output device has changed, restarting MPD Jan 20 10:27:38 kueche volumio[746]: info: Output device has changed, restarting Shairport Sync Jan 20 10:27:38 kueche sudo[940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 20 10:27:38 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:38 kueche sudo[940]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:38 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 10:27:38 kueche sudo[940]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:38 kueche sudo[942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 20 10:27:38 kueche sudo[942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:38 kueche systemd[1]: Stopping Music Player Daemon... Jan 20 10:27:38 kueche volumio[746]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 10:27:38 kueche volumio[746]: info: ___________ START PLUGINS ___________ Jan 20 10:27:38 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 10:27:38 kueche volumio[746]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 10:27:38 kueche volumio[746]: info: [1642674458549] CoreMusicLibrary::Adding element Media Servers Jan 20 10:27:38 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 10:27:38 kueche volumio[746]: Cannot find translation for sourceMedia Servers Jan 20 10:27:38 kueche systemd[1]: mpd.service: Succeeded. Jan 20 10:27:38 kueche systemd[1]: Stopped Music Player Daemon. Jan 20 10:27:38 kueche systemd[1]: Starting Music Player Daemon... Jan 20 10:27:38 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:38 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 10:27:38 kueche volumio[746]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 10:27:38 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:38 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 10:27:38 kueche volumio[746]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 10:27:38 kueche volumio[746]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 10:27:38 kueche volumio[746]: info: [1642674458691] CoreMusicLibrary::Adding element Last_100 Jan 20 10:27:38 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 10:27:38 kueche volumio[746]: Cannot find translation for sourceMedia Servers Jan 20 10:27:38 kueche volumio[746]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 20 10:27:38 kueche volumio[746]: info: [1642674458696] CoreMusicLibrary::Adding element Webradio Jan 20 10:27:38 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 10:27:38 kueche volumio[746]: Cannot find translation for sourceMedia Servers Jan 20 10:27:38 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 10:27:38 kueche volumio[746]: info: Loading i18n strings for locale de Jan 20 10:27:38 kueche volumio[746]: info: Volumio Calling Home Jan 20 10:27:38 kueche sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 20 10:27:38 kueche sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:38 kueche systemd[1]: Reloading. Jan 20 10:27:38 kueche volumio[746]: info: [now-playing-app] App is listening on port 4004. Jan 20 10:27:39 kueche volumio[746]: info: MPD Permissions set Jan 20 10:27:39 kueche volumio[746]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Jan 20 10:27:39 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:39 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:39 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:39 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:39 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:39 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:39 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:39 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:39 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 10:27:39 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:27:39 kueche volumio[746]: info: Starting Shairport Sync Jan 20 10:27:39 kueche volumio[746]: info: Starting Shairport Sync Jan 20 10:27:39 kueche sudo[985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 10:27:39 kueche volumio[746]: info: Starting Shairport Sync Jan 20 10:27:39 kueche sudo[985]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:39 kueche sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 10:27:39 kueche sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:39 kueche sudo[990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 10:27:39 kueche sudo[990]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:39 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 20 10:27:39 kueche systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jan 20 10:27:39 kueche sudo[957]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:39 kueche volumio[746]: info: touch_display: systemctl daemon-reload succeeded. Jan 20 10:27:39 kueche sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 20 10:27:39 kueche sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:39 kueche systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 20 10:27:39 kueche systemd[1]: shairport-sync.service: Succeeded. Jan 20 10:27:39 kueche systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 20 10:27:39 kueche systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 20 10:27:39 kueche sudo[985]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:39 kueche sudo[990]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:39 kueche sudo[987]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:39 kueche volumio[746]: info: Shairport-Sync Started Jan 20 10:27:39 kueche volumio[746]: Error adding Membership: Error: addMembership EINVAL Jan 20 10:27:39 kueche volumio[746]: info: Shairport-Sync Started Jan 20 10:27:39 kueche volumio[746]: info: Shairport-Sync Started Jan 20 10:27:39 kueche systemd[1]: Started Volumio Kiosk. Jan 20 10:27:39 kueche sudo[994]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:39 kueche volumio[746]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jan 20 10:27:39 kueche volumio[746]: info: touch_display: Volumio Kiosk started Jan 20 10:27:39 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 10:27:39 kueche volumio[746]: info: CoreCommandRouter::volumioGetState Jan 20 10:27:39 kueche volumio[746]: info: CorePlayQueue::getTrack 0 Jan 20 10:27:39 kueche volumio[746]: info: Completed starting Core Plugins Jan 20 10:27:39 kueche volumio[746]: info: ------------------------------------------- Jan 20 10:27:39 kueche volumio[746]: info: ----- MyVolumio plugins startup ---- Jan 20 10:27:39 kueche volumio[746]: info: ------------------------------------------- Jan 20 10:27:39 kueche volumio[746]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 20 10:27:40 kueche volumio[746]: info: CoreCommandRouter::volumioGetState Jan 20 10:27:40 kueche volumio[746]: info: CorePlayQueue::getTrack 0 Jan 20 10:27:40 kueche volumio[746]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jan 20 10:27:40 kueche volumio[746]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. Jan 20 10:27:40 kueche volumio[746]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Jan 20 10:27:40 kueche mpd[961]: Jan 20 10:27 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 20 10:27:40 kueche sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness Jan 20 10:27:40 kueche sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:40 kueche sudo[1028]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:40 kueche volumio[746]: info: touch_display: File permissions for backlight brightness control set. Jan 20 10:27:40 kueche volumio[746]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 20 10:27:40 kueche sudo[1021]: 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 20 10:27:40 kueche sudo[1021]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:40 kueche sudo[1021]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:40 kueche startx[997]: X.Org X Server 1.20.4 Jan 20 10:27:40 kueche startx[997]: X Protocol Version 11, Revision 0 Jan 20 10:27:40 kueche startx[997]: Build Operating System: Linux 5.10.17-v8+ aarch64 Raspbian Jan 20 10:27:40 kueche startx[997]: Current Operating System: Linux kueche 5.4.83-v7+ #1379 SMP Mon Dec 14 13:08:57 GMT 2020 armv7l Jan 20 10:27:40 kueche volumio[746]: info: touch_display: Touchscreen transformation matrix removed. Jan 20 10:27:40 kueche startx[997]: 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 20 10:27:40 kueche startx[997]: Build Date: 19 April 2021 03:23:36PM Jan 20 10:27:40 kueche startx[997]: xorg-server 2:1.20.4-1+rpt3+deb10u3 (https://www.debian.org/support) Jan 20 10:27:40 kueche startx[997]: Current version of pixman: 0.36.0 Jan 20 10:27:40 kueche startx[997]: Before reporting problems, check http://wiki.x.org Jan 20 10:27:40 kueche startx[997]: to make sure that you have the latest version. Jan 20 10:27:40 kueche startx[997]: Markers: (--) probed, (**) from config file, (==) default setting, Jan 20 10:27:40 kueche startx[997]: (++) from command line, (!!) notice, (II) informational, Jan 20 10:27:40 kueche startx[997]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jan 20 10:27:40 kueche startx[997]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Jan 20 10:27:40 2022 Jan 20 10:27:40 kueche startx[997]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jan 20 10:27:40 kueche startx[997]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jan 20 10:27:40 kueche mpd[961]: Jan 20 10:27 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 20 10:27:40 kueche mpd[961]: Jan 20 10:27 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 20 10:27:40 kueche systemd[1]: Started Music Player Daemon. Jan 20 10:27:40 kueche sudo[942]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:40 kueche volumio[746]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 20 10:27:40 kueche volumio[746]: error: updateQueue error: null Jan 20 10:27:41 kueche sudo[1051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 20 10:27:41 kueche sudo[1051]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:41 kueche sudo[1051]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:41 kueche sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 20 10:27:41 kueche sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:41 kueche sudo[1053]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:41 kueche sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 20 10:27:41 kueche sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 10:27:41 kueche systemd[1]: Started UPnP Renderer front-end to MPD. Jan 20 10:27:41 kueche sudo[1057]: pam_unix(sudo:session): session closed for user root Jan 20 10:27:41 kueche volumio[746]: info: Upmpdcli Daemon Started Jan 20 10:27:42 kueche volumio[1059]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 20 10:27:42 kueche volumio[746]: info: touch_display: Setting screensaver timeout to 0 seconds. Jan 20 10:27:46 kueche systemd[1]: systemd-fsckd.service: Succeeded. Jan 20 10:27:46 kueche volumio[746]: info: Volumio called home Jan 20 10:27:53 kueche systemd[1]: systemd-hostnamed.service: Succeeded. Jan 20 10:27:53 kueche volumio[746]: info: Discovery: adding 39f22b47-6286-4923-88c5-e5cbe79911b2 Jan 20 10:27:53 kueche volumio[746]: info: mDNS: Found device Kueche Jan 20 10:27:53 kueche volumio[746]: info: CoreCommandRouter::volumioGetState Jan 20 10:27:53 kueche volumio[746]: info: CorePlayQueue::getTrack 0 Jan 20 10:27:55 kueche volumio[746]: info: CoreCommandRouter::volumioGetState Jan 20 10:27:55 kueche volumio[746]: info: CorePlayQueue::getTrack 0 Jan 20 10:28:00 kueche volumio[746]: error: MyVolumio Plugin failed to start in a timely fashion Jan 20 10:28:00 kueche volumio[746]: info: BOOT COMPLETED Jan 20 10:28:00 kueche volumio[746]: [Metrics] CommandRouter: 33s 307.83ms Jan 20 10:28:00 kueche volumio[746]: info: CoreCommandRouter::volumiosetStartupVolume Jan 20 10:28:00 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 10:28:00 kueche volumio[746]: info: VolumeController:: Setting startup Volume 40 Jan 20 10:28:00 kueche volumio[746]: info: VolumeController::SetAlsaVolume40 Jan 20 10:28:00 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 10:28:00 kueche volumio[746]: info: CoreCommandRouter::Close All Modals sent Jan 20 10:28:00 kueche volumio[746]: info: CoreCommandRouter::Close All Modals sent Jan 20 10:28:00 kueche volumio[746]: info: CoreStateMachine::pushState Jan 20 10:28:00 kueche volumio[746]: info: CorePlayQueue::getTrack 0 Jan 20 10:28:00 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 10:28:00 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 20 10:28:00 kueche kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 20 10:28:01 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 20 10:28:01 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 20 10:28:01 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 20 10:28:08 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 10:28:08 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 10:28:08 kueche volumio[1059]: ..............................................................................................................................................................................++++ Jan 20 10:28:33 kueche ntpd[689]: Soliciting pool server 200.25.3.17 Jan 20 10:28:34 kueche ntpd[689]: Soliciting pool server 176.235.41.255 Jan 20 10:28:35 kueche ntpd[689]: Soliciting pool server 149.56.121.16 Jan 20 10:28:36 kueche ntpd[689]: Soliciting pool server 37.187.205.149 Jan 20 10:28:36 kueche volumio[1059]: ......................................................................................................................................................................................++++ Jan 20 10:28:36 kueche volumio[1059]: e is 65537 (0x010001) Jan 20 10:28:36 kueche volumio[1059]: writing RSA key Jan 21 16:08:29 kueche systemd[1]: Starting Daily apt download activities... Jan 21 16:08:29 kueche systemd[1]: Starting Daily man-db regeneration... Jan 21 16:08:30 kueche systemd[1]: man-db.service: Succeeded. Jan 21 16:08:30 kueche systemd[1]: Started Daily man-db regeneration. Jan 21 16:08:30 kueche ntpd[689]: Soliciting pool server 216.218.254.202 Jan 21 16:08:30 kueche volumio[746]: info: mDNS: A device disapperared from network Jan 21 16:08:30 kueche volumio[746]: info: mDNS: Device kueche disapperared from network Jan 21 16:08:31 kueche ntpd[689]: Soliciting pool server 185.53.93.157 Jan 21 16:08:32 kueche ntpd[689]: Soliciting pool server 85.199.214.98 Jan 21 16:08:33 kueche ntpd[689]: Soliciting pool server 104.171.113.34 Jan 21 16:08:33 kueche systemd[1]: apt-daily.service: Succeeded. Jan 21 16:08:33 kueche systemd[1]: Started Daily apt download activities. Jan 21 16:08:33 kueche systemd[1]: Starting Daily apt upgrade and clean activities... Jan 21 16:08:34 kueche ntpd[689]: Soliciting pool server 2001:418:3ff::53 Jan 21 16:08:36 kueche systemd[1]: apt-daily-upgrade.service: Succeeded. Jan 21 16:08:36 kueche systemd[1]: Started Daily apt upgrade and clean activities. Jan 21 16:08:38 kueche ntpd[689]: Soliciting pool server 213.209.109.44 Jan 21 16:08:39 kueche ntpd[689]: Soliciting pool server 159.69.4.181 Jan 21 16:08:46 kueche ntpd[689]: Soliciting pool server 212.227.8.137 Jan 21 16:08:47 kueche volumio[746]: info: Discovery: adding 39f22b47-6286-4923-88c5-e5cbe79911b2 Jan 21 16:08:47 kueche volumio[746]: info: mDNS: Found device Kueche Jan 21 16:08:47 kueche volumio[746]: info: CoreCommandRouter::volumioGetState Jan 21 16:08:47 kueche volumio[746]: info: CorePlayQueue::getTrack 0 Jan 21 16:21:26 kueche ntpd[689]: 200.25.3.17 local addr 192.168.0.31 -> Jan 21 16:21:33 kueche ntpd[689]: 176.235.41.255 local addr 192.168.0.31 -> Jan 21 16:21:57 kueche ntpd[689]: 149.56.121.16 local addr 192.168.0.31 -> Jan 21 16:22:02 kueche ntpd[689]: 37.187.205.149 local addr 192.168.0.31 -> Jan 21 16:22:02 kueche systemd[1]: Starting Cleanup of Temporary Directories... Jan 21 16:22:02 kueche systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Jan 21 16:22:02 kueche systemd[1]: Started Cleanup of Temporary Directories. Jan 21 16:23:06 kueche ntpd[689]: 104.171.113.34 local addr 192.168.0.31 -> Jan 21 17:06:49 kueche volumio[746]: info: Airplay started streaming, receiving metadatas Jan 21 17:06:49 kueche volumio[746]: info: CorePlayQueue::getTrack 0 Jan 21 17:06:49 kueche volumio[746]: info: CoreCommandRouter::volumioStop Jan 21 17:06:49 kueche volumio[746]: info: CoreStateMachine::stop Jan 21 17:06:49 kueche volumio[746]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 17:06:49 kueche volumio[746]: verbose: UNSET VOLATILE Jan 21 17:06:49 kueche volumio[746]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 17:06:49 kueche volumio[746]: info: Airplay started streaming, receiving metadatas Jan 21 17:06:49 kueche volumio[746]: info: CorePlayQueue::getTrack 0 Jan 21 17:06:49 kueche volumio[746]: info: CoreCommandRouter::volumioStop Jan 21 17:06:49 kueche volumio[746]: info: CoreStateMachine::stop Jan 21 17:06:49 kueche volumio[746]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 17:06:49 kueche volumio[746]: verbose: UNSET VOLATILE Jan 21 17:06:49 kueche volumio[746]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 17:06:49 kueche volumio[746]: info: Airplay started streaming, receiving metadatas Jan 21 17:06:49 kueche volumio[746]: info: CorePlayQueue::getTrack 0 Jan 21 17:06:49 kueche volumio[746]: info: CoreCommandRouter::volumioStop Jan 21 17:06:49 kueche volumio[746]: info: CoreStateMachine::stop Jan 21 17:06:49 kueche volumio[746]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 17:06:49 kueche volumio[746]: verbose: UNSET VOLATILE Jan 21 17:06:49 kueche volumio[746]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 17:06:50 kueche shairport-sync[996]: % Total % Received % Xferd Average Speed Time Time Time Current Jan 21 17:06:50 kueche shairport-sync[996]: Dload Upload Total Spent Left Speed Jan 21 17:06:50 kueche volumio[746]: info: Jan 21 17:06:50 kueche volumio[746]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jan 21 17:06:50 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jan 21 17:06:50 kueche volumio[746]: info: Airplay playback start Jan 21 17:06:50 kueche volumio[746]: info: CorePlayQueue::getTrack 0 Jan 21 17:06:50 kueche volumio[746]: info: CoreCommandRouter::volumioStop Jan 21 17:06:50 kueche volumio[746]: info: CoreStateMachine::stop Jan 21 17:06:50 kueche volumio[746]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 17:06:50 kueche volumio[746]: verbose: UNSET VOLATILE Jan 21 17:06:50 kueche volumio[746]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 21 17:06:50 kueche shairport-sync[996]: [158B blob data] Jan 21 17:06:50 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:06:50 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:06:50 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:06:50 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:06:50 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:06:50 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:06:51 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:06:51 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:06:51 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:06:51 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:06:51 kueche kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:06:51 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:06:51 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:06:51 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:06:51 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:06:51 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:06:57 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:06:57 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:06:57 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:06:57 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:06:57 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:06:57 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:06:57 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:06:57 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:06:57 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:06:57 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:06:57 kueche shairport-sync[996]: {"time":1642784810080,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jan 21 17:06:57 kueche shairport-sync[996]: Dload Upload Total Spent Left Speed Jan 21 17:06:57 kueche volumio[746]: info: Jan 21 17:06:57 kueche volumio[746]: ---------------------------- Client requests Stop Airplay Playback Jan 21 17:06:57 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Jan 21 17:06:57 kueche volumio[746]: info: Airplay playback stop Jan 21 17:06:57 kueche volumio[746]: info: Airplay Playback Stopped, clearing state Jan 21 17:06:57 kueche volumio[746]: info: CoreStateMachine::resetVolumioState Jan 21 17:06:57 kueche volumio[746]: info: CoreStateMachine::getcurrentVolume Jan 21 17:06:57 kueche volumio[746]: info: CoreCommandRouter::volumioRetrievevolume Jan 21 17:06:57 kueche shairport-sync[996]: [158B blob data] Jan 21 17:06:57 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:06:57 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:06:57 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:06:57 kueche volumio[746]: info: CoreStateMachine::updateTrackBlock Jan 21 17:06:57 kueche volumio[746]: info: CorePlayQueue::getTrackBlock Jan 21 17:06:57 kueche volumio[746]: info: CoreCommandRouter::volumioRetrievevolume Jan 21 17:06:57 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:06:57 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:50:54 kueche volumio[746]: info: Airplay started streaming, receiving metadatas Jan 21 17:50:54 kueche shairport-sync[996]: {"time":1642784817726,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jan 21 17:50:54 kueche shairport-sync[996]: Dload Upload Total Spent Left Speed Jan 21 17:50:54 kueche volumio[746]: info: Jan 21 17:50:54 kueche volumio[746]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jan 21 17:50:54 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jan 21 17:50:54 kueche volumio[746]: info: Airplay playback start Jan 21 17:50:54 kueche shairport-sync[996]: [158B blob data] Jan 21 17:50:55 kueche kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:50:55 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:50:55 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:50:55 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:50:55 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:50:55 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:50:55 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:50:55 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:50:55 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:50:55 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:50:55 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:50:55 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:51:19 kueche sudo[15944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 17:51:19 kueche sudo[15944]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:51:19 kueche sudo[15953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 17:51:19 kueche sudo[15944]: pam_unix(sudo:session): session closed for user root Jan 21 17:51:19 kueche sudo[15953]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:51:19 kueche sudo[15953]: pam_unix(sudo:session): session closed for user root Jan 21 17:51:19 kueche volumio[746]: verbose: New Socket.io Connection to 192.168.0.31 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: 4 Jan 21 17:51:19 kueche volumio[746]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 17:51:19 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 21 17:51:19 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 21 17:51:19 kueche volumio[746]: info: CoreCommandRouter::volumioGetState Jan 21 17:51:19 kueche volumio[746]: info: CoreCommandRouter::volumioGetVisibleSources Jan 21 17:51:19 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 17:51:19 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 21 17:51:19 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 21 17:51:19 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 21 17:51:19 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 21 17:51:20 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 21 17:51:20 kueche sudo[16284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 21 17:51:20 kueche sudo[16284]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:51:20 kueche sudo[16297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 21 17:51:20 kueche sudo[16284]: pam_unix(sudo:session): session closed for user root Jan 21 17:51:20 kueche sudo[16297]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:51:20 kueche sudo[16297]: pam_unix(sudo:session): session closed for user root Jan 21 17:51:20 kueche sudo[16331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 21 17:51:20 kueche sudo[16317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 21 17:51:20 kueche sudo[16317]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:51:20 kueche sudo[16331]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:51:20 kueche sudo[16317]: pam_unix(sudo:session): session closed for user root Jan 21 17:51:20 kueche sudo[16331]: pam_unix(sudo:session): session closed for user root Jan 21 17:51:20 kueche sudo[16353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 17:51:20 kueche sudo[16353]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:51:20 kueche sudo[16353]: pam_unix(sudo:session): session closed for user root Jan 21 17:51:20 kueche sudo[16372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 17:51:20 kueche sudo[16372]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:51:20 kueche sudo[16372]: pam_unix(sudo:session): session closed for user root Jan 21 17:51:20 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jan 21 17:51:20 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jan 21 17:51:22 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 21 17:51:23 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 21 17:51:23 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 21 17:51:23 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 21 17:51:25 kueche volumio[746]: 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 21 17:51:25 kueche volumio[746]: info: CoreCommandRouter::volumioGetState Jan 21 17:51:25 kueche volumio[746]: info: CoreCommandRouter::volumioGetQueue Jan 21 17:51:25 kueche volumio[746]: info: CoreStateMachine::getQueue Jan 21 17:51:25 kueche volumio[746]: info: CorePlayQueue::getQueue Jan 21 17:51:25 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jan 21 17:52:07 kueche volumio[746]: info: CALLMETHOD: system_controller network saveWiredNet [object Object] Jan 21 17:52:07 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: network , saveWiredNet Jan 21 17:52:07 kueche sudo[5147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/network/interfaces Jan 21 17:52:07 kueche sudo[5147]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:07 kueche sudo[5147]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:08 kueche sudo[5163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/dhcpcd.conf Jan 21 17:52:08 kueche sudo[5163]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:08 kueche sudo[5163]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:08 kueche volumio[746]: info: Permissions for /etc/network/interfaces set Jan 21 17:52:08 kueche volumio[746]: info: Restarting Wired Network Jan 21 17:52:08 kueche sudo[5192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 Jan 21 17:52:08 kueche sudo[5192]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:08 kueche avahi-daemon[461]: Withdrawing address record for 192.168.0.31 on eth0. Jan 21 17:52:08 kueche avahi-daemon[461]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.31. Jan 21 17:52:08 kueche avahi-daemon[461]: Interface eth0.IPv4 no longer relevant for mDNS. Jan 21 17:52:08 kueche sudo[5192]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:08 kueche dhcpcd[563]: eth0: pid 5201 deleted IP address 192.168.0.31/24 Jan 21 17:52:08 kueche dhcpcd[563]: eth0: deleting route to 192.168.0.0/24 Jan 21 17:52:08 kueche dhcpcd[563]: eth0: deleting default route via 192.168.0.10 Jan 21 17:52:08 kueche volumio[746]: info: mDNS: A device disapperared from network Jan 21 17:52:08 kueche volumio[746]: info: mDNS: Device kueche disapperared from network Jan 21 17:52:08 kueche sudo[5212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down Jan 21 17:52:08 kueche sudo[5212]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:08 kueche kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jan 21 17:52:08 kueche sudo[5212]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:08 kueche dhcpcd[563]: eth0: probing address 192.168.0.31/24 Jan 21 17:52:08 kueche dhcpcd[563]: arp_probe1: Network is down Jan 21 17:52:08 kueche dhcpcd[563]: arp_read: eth0: Network is down Jan 21 17:52:08 kueche sudo[5235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up Jan 21 17:52:08 kueche sudo[5235]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:08 kueche dhcpcd[563]: eth0: carrier lost Jan 21 17:52:08 kueche sudo[5235]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:08 kueche kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Jan 21 17:52:08 kueche dhcpcd[563]: eth0: carrier acquired Jan 21 17:52:08 kueche dhcpcd[563]: eth0: confile file changed, expiring leases Jan 21 17:52:08 kueche dhcpcd[563]: eth0: IAID eb:15:2e:b1 Jan 21 17:52:08 kueche ifplugd(eth0)[686]: Link beat lost. Jan 21 17:52:08 kueche dhcpcd[563]: eth0: soliciting a DHCP lease Jan 21 17:52:08 kueche dhcpcd[563]: eth0: carrier lost Jan 21 17:52:09 kueche dhcpcd[563]: eth0: carrier acquired Jan 21 17:52:09 kueche kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xCDE1 Jan 21 17:52:09 kueche dhcpcd[563]: eth0: IAID eb:15:2e:b1 Jan 21 17:52:09 kueche ifplugd(eth0)[686]: Link beat detected. Jan 21 17:52:09 kueche dhcpcd[563]: eth0: soliciting an IPv6 router Jan 21 17:52:10 kueche dhcpcd[563]: eth0: soliciting a DHCP lease Jan 21 17:52:11 kueche ntpd[689]: Deleting interface #3 eth0, 192.168.0.31#123, interface stats: received=413, sent=427, dropped=0, active_time=6297 secs Jan 21 17:52:11 kueche ntpd[689]: 212.227.8.137 local addr 192.168.0.31 -> Jan 21 17:52:11 kueche ntpd[689]: 159.69.4.181 local addr 192.168.0.31 -> Jan 21 17:52:11 kueche ntpd[689]: 213.209.109.44 local addr 192.168.0.31 -> Jan 21 17:52:11 kueche ntpd[689]: 85.199.214.98 local addr 192.168.0.31 -> Jan 21 17:52:11 kueche ntpd[689]: 185.53.93.157 local addr 192.168.0.31 -> Jan 21 17:52:11 kueche ntpd[689]: 216.218.254.202 local addr 192.168.0.31 -> Jan 21 17:52:15 kueche dhcpcd[563]: eth0: probing for an IPv4LL address Jan 21 17:52:15 kueche dhcpcd[563]: eth0: offered 192.168.0.243 from 192.168.0.10 Jan 21 17:52:15 kueche dhcpcd[563]: eth0: probing address 192.168.0.243/16 Jan 21 17:52:18 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jan 21 17:52:18 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jan 21 17:52:18 kueche volumio[746]: info: Refreshing Cached IP Addresses Jan 21 17:52:18 kueche sudo[9931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jan 21 17:52:18 kueche sudo[9931]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:18 kueche sudo[9945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 17:52:18 kueche sudo[9945]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:18 kueche sudo[9931]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:18 kueche sudo[9945]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:18 kueche sudo[9955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 17:52:18 kueche sudo[9955]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:18 kueche systemd[1]: upmpdcli.service: Succeeded. Jan 21 17:52:18 kueche sudo[9955]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:20 kueche dhcpcd[563]: eth0: leased 192.168.0.243 for 86400 seconds Jan 21 17:52:20 kueche dhcpcd[563]: eth0: adding route to 192.168.0.0/16 Jan 21 17:52:20 kueche dhcpcd[563]: eth0: adding default route via 192.168.0.10 Jan 21 17:52:20 kueche avahi-daemon[461]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.243. Jan 21 17:52:20 kueche avahi-daemon[461]: New relevant interface eth0.IPv4 for mDNS. Jan 21 17:52:20 kueche avahi-daemon[461]: Registering new address record for 192.168.0.243 on eth0.IPv4. Jan 21 17:52:20 kueche ntpd[689]: ntpd exiting on signal 15 (Terminated) Jan 21 17:52:20 kueche systemd[1]: Stopping Network Time Service... Jan 21 17:52:20 kueche systemd[1]: ntp.service: Succeeded. Jan 21 17:52:20 kueche systemd[1]: Stopped Network Time Service. Jan 21 17:52:20 kueche systemd[1]: Starting Network Time Service... Jan 21 17:52:20 kueche ntpd[11014]: ntpd 4.2.8p12@1.3728-o (1): Starting Jan 21 17:52:20 kueche ntpd[11014]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 103:103 Jan 21 17:52:20 kueche systemd[1]: Started Network Time Service. Jan 21 17:52:20 kueche ntpd[11038]: proto: precision = 0.729 usec (-20) Jan 21 17:52:20 kueche ntpd[11038]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jan 21 17:52:20 kueche ntpd[11038]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jan 21 17:52:20 kueche ntpd[11038]: Listen and drop on 0 v6wildcard [::]:123 Jan 21 17:52:20 kueche ntpd[11038]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jan 21 17:52:20 kueche ntpd[11038]: Listen normally on 2 lo 127.0.0.1:123 Jan 21 17:52:20 kueche ntpd[11038]: Listen normally on 3 eth0 192.168.0.243:123 Jan 21 17:52:20 kueche ntpd[11038]: Listening on routing socket on fd #20 for interface updates Jan 21 17:52:20 kueche ntpd[11038]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jan 21 17:52:20 kueche ntpd[11038]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jan 21 17:52:28 kueche sudo[14602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 17:52:28 kueche sudo[14602]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:28 kueche sudo[14602]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:28 kueche sudo[14611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 17:52:28 kueche sudo[14611]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:28 kueche sudo[14611]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:28 kueche sudo[14632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 21 17:52:28 kueche sudo[14632]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:28 kueche systemd[1]: Started UPnP Renderer front-end to MPD. Jan 21 17:52:28 kueche sudo[14632]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:28 kueche volumio[746]: info: Upmpdcli Daemon Started Jan 21 17:52:28 kueche volumio[14661]: writing RSA key Jan 21 17:52:29 kueche ntpd[11038]: Soliciting pool server 130.208.87.151 Jan 21 17:52:30 kueche ntpd[11038]: Soliciting pool server 142.114.187.107 Jan 21 17:52:31 kueche ntpd[11038]: Soliciting pool server 134.0.16.1 Jan 21 17:52:32 kueche ntpd[11038]: Soliciting pool server 5.34.248.224 Jan 21 17:52:37 kueche volumio[746]: info: Discovery: adding 39f22b47-6286-4923-88c5-e5cbe79911b2 Jan 21 17:52:37 kueche volumio[746]: info: mDNS: Found device Kueche Jan 21 17:52:37 kueche volumio[746]: info: CoreCommandRouter::volumioGetState Jan 21 17:52:37 kueche ntpd[11038]: Soliciting pool server 131.188.3.223 Jan 21 17:52:38 kueche ntpd[11038]: Soliciting pool server 136.243.7.20 Jan 21 17:52:39 kueche ntpd[11038]: Soliciting pool server 162.159.200.1 Jan 21 17:52:40 kueche ntpd[11038]: Soliciting pool server 78.47.249.55 Jan 21 17:52:45 kueche ntpd[11038]: Soliciting pool server 193.141.27.6 Jan 21 17:52:48 kueche sudo[23760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 17:52:48 kueche sudo[23760]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:48 kueche sudo[23760]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:48 kueche sudo[23769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 17:52:48 kueche sudo[23769]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:48 kueche sudo[23769]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:48 kueche volumio[746]: 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 21 17:52:48 kueche volumio[746]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 21 17:52:48 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 21 17:52:48 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 21 17:52:48 kueche volumio[746]: info: CoreCommandRouter::volumioGetState Jan 21 17:52:48 kueche volumio[746]: info: CoreCommandRouter::volumioGetVisibleSources Jan 21 17:52:48 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 21 17:52:48 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 21 17:52:48 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jan 21 17:52:48 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jan 21 17:52:48 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 21 17:52:48 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jan 21 17:52:48 kueche sudo[24102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jan 21 17:52:48 kueche sudo[24102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:49 kueche sudo[24102]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:49 kueche sudo[24120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 21 17:52:49 kueche sudo[24120]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:49 kueche sudo[24120]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:49 kueche sudo[24139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 21 17:52:49 kueche sudo[24139]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:49 kueche sudo[24154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jan 21 17:52:49 kueche sudo[24139]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:49 kueche sudo[24154]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:49 kueche sudo[24154]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:49 kueche sudo[24173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 21 17:52:49 kueche sudo[24173]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:49 kueche sudo[24173]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:49 kueche sudo[24190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 21 17:52:49 kueche sudo[24190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:52:49 kueche sudo[24190]: pam_unix(sudo:session): session closed for user root Jan 21 17:52:49 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jan 21 17:52:49 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jan 21 17:52:51 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 21 17:52:51 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jan 21 17:52:53 kueche ntpd[11038]: Soliciting pool server 144.91.116.85 Jan 21 17:53:18 kueche volumio[746]: 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: 6 Jan 21 17:53:18 kueche volumio[746]: info: CoreCommandRouter::volumioGetState Jan 21 17:53:18 kueche volumio[746]: info: CoreCommandRouter::volumioGetQueue Jan 21 17:53:18 kueche volumio[746]: info: CoreStateMachine::getQueue Jan 21 17:53:18 kueche volumio[746]: info: CorePlayQueue::getQueue Jan 21 17:53:18 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jan 21 17:53:46 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 21 17:53:48 kueche volumio[746]: info: CoreCommandRouter::Close All Modals sent Jan 21 17:54:07 kueche volumio[746]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Jan 21 17:54:07 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Jan 21 17:54:08 kueche sudo[28497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Jan 21 17:54:08 kueche sudo[28497]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 21 17:54:08 kueche sudo[28497]: pam_unix(sudo:session): session closed for user root Jan 21 17:54:08 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:54:08 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:54:08 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:54:08 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:54:08 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:54:08 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:54:08 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:54:08 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:54:08 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:54:08 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:54:08 kueche shairport-sync[996]: {"time":1642787454871,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jan 21 17:54:08 kueche shairport-sync[996]: Dload Upload Total Spent Left Speed Jan 21 17:54:08 kueche volumio[746]: info: Jan 21 17:54:08 kueche volumio[746]: ---------------------------- Client requests Stop Airplay Playback Jan 21 17:54:08 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Jan 21 17:54:08 kueche volumio[746]: info: Airplay playback stop Jan 21 17:54:08 kueche volumio[746]: info: Airplay Playback Stopped, clearing state Jan 21 17:54:08 kueche volumio[746]: info: CoreStateMachine::resetVolumioState Jan 21 17:54:08 kueche volumio[746]: info: CoreStateMachine::getcurrentVolume Jan 21 17:54:08 kueche volumio[746]: info: CoreCommandRouter::volumioRetrievevolume Jan 21 17:54:08 kueche shairport-sync[996]: [237B blob data] Jan 21 17:54:08 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:54:08 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:54:08 kueche volumio[746]: info: CoreStateMachine::updateTrackBlock Jan 21 17:54:08 kueche volumio[746]: info: CorePlayQueue::getTrackBlock Jan 21 17:54:08 kueche volumio[746]: info: CoreCommandRouter::volumioRetrievevolume Jan 21 17:54:08 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:54:08 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:54:54 kueche kernel: CIFS VFS: \\192.168.0.163 has not responded in 180 seconds. Reconnecting... Jan 21 17:55:30 kueche volumio[746]: info: Airplay started streaming, receiving metadatas Jan 21 17:55:30 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:55:30 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:55:30 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:55:30 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:55:30 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:55:30 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:55:30 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:55:30 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:55:30 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:55:30 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:55:30 kueche shairport-sync[996]: {"time":1642787648223,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jan 21 17:55:30 kueche shairport-sync[996]: Dload Upload Total Spent Left Speed Jan 21 17:55:30 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:55:30 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:55:30 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:55:30 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:55:30 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:55:30 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:55:30 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:55:30 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:55:30 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:55:30 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:55:30 kueche volumio[746]: info: Jan 21 17:55:30 kueche volumio[746]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jan 21 17:55:30 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jan 21 17:55:30 kueche volumio[746]: info: Airplay playback start Jan 21 17:55:30 kueche shairport-sync[996]: [158B blob data] Jan 21 17:55:31 kueche kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 21 17:55:31 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:55:31 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:55:31 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:55:31 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:55:31 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:55:31 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:55:31 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:55:31 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:55:31 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:55:31 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:55:31 kueche volumio[746]: info: CoreCommandRouter::servicePushState Jan 21 17:55:31 kueche volumio[746]: info: CoreStateMachine::pushState Jan 21 17:55:31 kueche volumio[746]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 21 17:55:31 kueche volumio[746]: info: CoreCommandRouter::volumioPushState Jan 21 17:55:56 kueche sudo[13353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Jan 21 17:55:56 kueche sudo[13353]: pam_unix(sudo:session): session opened for user root by (uid=0)