Mon Nov 1 14:52:13 UTC 2021 Description="'connected to Synology NAS - Works on Pi , not on Pc'" # cat /proc/version --------------- Linux version 4.19.118-v7l+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1311 SMP Mon Apr 27 14:26:42 BST 2020 # cat /etc/os-release --------------- PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f" # ifconfig --------------- eth0 Link encap:Ethernet HWaddr e4:5f:01:34:63:2f UP BROADCAST MULTICAST MTU:1500 Metric:1 RX packets:0 errors:0 dropped:0 overruns:0 frame:0 TX packets:0 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:0 (0.0 B) TX bytes:0 (0.0 B) lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 UP LOOPBACK RUNNING MTU:65536 Metric:1 RX packets:13518 errors:0 dropped:0 overruns:0 frame:0 TX packets:13518 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:1271786 (1.2 MiB) TX bytes:1271786 (1.2 MiB) wlan0 Link encap:Ethernet HWaddr e4:5f:01:34:63:30 inet addr:192.168.5.60 Bcast:192.168.5.255 Mask:255.255.255.0 UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:15218 errors:0 dropped:4792 overruns:0 frame:0 TX packets:5592 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:3346373 (3.1 MiB) TX bytes:849609 (829.6 KiB) # sudo iwconfig --------------- eth0 no wireless extensions. lo no wireless extensions. wlan0 IEEE 802.11 ESSID:"MSA" Mode:Managed Frequency:2.462 GHz Access Point: DC:39:6F:80:2B:CE Bit Rate=65 Mb/s Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off Link Quality=57/70 Signal level=-53 dBm Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0 Tx excessive retries:6 Invalid misc:0 Missed beacon:0 # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 0: ALSA [bcm2835 ALSA], device 0: bcm2835 ALSA [bcm2835 ALSA] Subdevices: 4/4 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 card 0: ALSA [bcm2835 ALSA], device 1: bcm2835 IEC958/HDMI [bcm2835 IEC958/HDMI] Subdevices: 1/1 Subdevice #0: subdevice #0 card 0: ALSA [bcm2835 ALSA], device 2: bcm2835 IEC958/HDMI1 [bcm2835 IEC958/HDMI1] Subdevices: 1/1 Subdevice #0: subdevice #0 card 1: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones] Subdevices: 3/3 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 card 5: U0xccd0x77 [USB Device 0xccd:0x77], device 0: USB Audio [USB Audio] Subdevices: 1/1 Subdevice #0: subdevice #0 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 0 14:18 ? 00:00:04 /sbin/init root 2 0 0 14:18 ? 00:00:00 [kthreadd] root 3 2 0 14:18 ? 00:00:00 [rcu_gp] root 4 2 0 14:18 ? 00:00:00 [rcu_par_gp] root 8 2 0 14:18 ? 00:00:00 [mm_percpu_wq] root 9 2 0 14:18 ? 00:00:00 [ksoftirqd/0] root 10 2 0 14:18 ? 00:00:00 [rcu_sched] root 11 2 0 14:18 ? 00:00:00 [rcu_bh] root 12 2 0 14:18 ? 00:00:00 [migration/0] root 13 2 0 14:18 ? 00:00:00 [cpuhp/0] root 14 2 0 14:18 ? 00:00:00 [cpuhp/1] root 15 2 0 14:18 ? 00:00:00 [migration/1] root 16 2 0 14:18 ? 00:00:00 [ksoftirqd/1] root 19 2 0 14:18 ? 00:00:00 [cpuhp/2] root 20 2 0 14:18 ? 00:00:00 [migration/2] root 21 2 0 14:18 ? 00:00:00 [ksoftirqd/2] root 24 2 0 14:18 ? 00:00:00 [cpuhp/3] root 25 2 0 14:18 ? 00:00:00 [migration/3] root 26 2 0 14:18 ? 00:00:00 [ksoftirqd/3] root 29 2 0 14:18 ? 00:00:00 [kdevtmpfs] root 30 2 0 14:18 ? 00:00:00 [netns] root 31 2 0 14:18 ? 00:00:00 [kworker/0:1-eve] root 32 2 0 14:18 ? 00:00:00 [khungtaskd] root 33 2 0 14:18 ? 00:00:00 [oom_reaper] root 34 2 0 14:18 ? 00:00:00 [writeback] root 35 2 0 14:18 ? 00:00:00 [kcompactd0] root 36 2 0 14:18 ? 00:00:00 [crypto] root 37 2 0 14:18 ? 00:00:00 [kblockd] root 38 2 0 14:18 ? 00:00:00 [watchdogd] root 39 2 0 14:18 ? 00:00:00 [rpciod] root 40 2 0 14:18 ? 00:00:00 [kworker/u9:0-hc] root 41 2 0 14:18 ? 00:00:00 [xprtiod] root 43 2 0 14:18 ? 00:00:00 [kworker/3:1-cgr] root 46 2 0 14:18 ? 00:00:00 [kswapd0] root 47 2 0 14:18 ? 00:00:00 [nfsiod] root 69 2 0 14:18 ? 00:00:00 [kthrotld] root 70 2 0 14:18 ? 00:00:00 [iscsi_eh] root 72 2 0 14:18 ? 00:00:00 [DWC Notificatio] root 73 2 0 14:18 ? 00:00:00 [vchiq-slot/0] root 74 2 0 14:18 ? 00:00:00 [vchiq-recy/0] root 75 2 0 14:18 ? 00:00:00 [vchiq-sync/0] root 76 2 0 14:18 ? 00:00:00 [vchiq-keep/0] root 77 2 0 14:18 ? 00:00:00 [SMIO] root 78 2 0 14:18 ? 00:00:00 [kworker/0:2-eve] root 79 2 0 14:18 ? 00:00:00 [irq/37-brcmstb_] root 80 2 0 14:18 ? 00:00:00 [irq/38-mmc1] root 81 2 0 14:18 ? 00:00:00 [irq/38-mmc0] root 91 2 0 14:18 ? 00:00:00 [mmc_complete] root 92 2 0 14:18 ? 00:00:00 [kworker/0:1H-kb] root 113 2 0 14:18 ? 00:00:00 [kworker/1:1H-kb] root 114 2 0 14:18 ? 00:00:00 [kworker/1:2H-kb] root 118 2 0 14:18 ? 00:00:00 [kworker/3:1H-kb] root 119 2 0 14:18 ? 00:00:00 [kworker/3:2H-kb] root 120 2 0 14:18 ? 00:00:00 [jbd2/mmcblk0p2-] root 121 2 0 14:18 ? 00:00:00 [ext4-rsv-conver] root 128 2 0 14:18 ? 00:00:00 [loop0] root 148 2 0 14:18 ? 00:00:00 [jbd2/mmcblk0p3-] root 149 2 0 14:18 ? 00:00:00 [ext4-rsv-conver] root 156 2 0 14:18 ? 00:00:00 [ipv6_addrconf] root 183 1 0 14:18 ? 00:00:00 /lib/systemd/systemd-journald root 191 2 0 14:18 ? 00:00:00 [kworker/0:2H-mm] root 193 1 0 14:18 ? 00:00:00 /lib/systemd/systemd-udevd root 198 2 0 14:18 ? 00:00:00 [kworker/1:2-eve] root 244 2 0 14:18 ? 00:00:00 [SMIO] root 301 2 0 14:18 ? 00:00:00 [mmal-vchiq] root 305 2 0 14:18 ? 00:00:00 [mmal-vchiq] root 309 2 0 14:18 ? 00:00:00 [mmal-vchiq] root 328 2 0 14:18 ? 00:00:00 [cfg80211] root 333 2 0 14:18 ? 00:00:00 [brcmf_wq/mmc1:0] root 334 2 0 14:18 ? 00:00:00 [brcmf_wdog/mmc1] root 360 2 0 14:18 ? 00:00:00 [kworker/2:2H-kb] root 528 1 0 14:18 ? 00:00:00 /sbin/rpcbind -w statd 568 1 0 14:19 ? 00:00:00 /sbin/rpc.statd root 603 1 0 14:19 ? 00:00:00 /usr/sbin/rpc.idmapd root 605 1 0 14:19 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 --write=1024 volumio 608 1 0 14:19 ? 00:00:00 /usr/bin/udisks-glue --foreground volumio 612 1 0 14:19 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 614 1 0 14:19 ? 00:00:00 /bin/bash /bin/volumiologrotate root 620 1 0 14:19 ? 00:00:00 /lib/systemd/systemd-logind avahi 681 1 0 14:19 ? 00:00:00 avahi-daemon: running [volumiopi.local] mpd 687 1 0 14:19 ? 00:00:06 /usr/bin/mpd --no-daemon message+ 689 1 0 14:19 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation nobody 699 1 0 14:19 ? 00:00:00 /usr/sbin/thd --daemon --triggers /etc/triggerhappy/triggers.d/ --socket /var/run/thd.socket --pidfile /var/run/thd.pid --user nobody /dev/input/event0 root 724 1 0 14:19 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I avahi 733 681 0 14:19 ? 00:00:00 avahi-daemon: chroot helper ntp 744 1 0 14:19 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /var/lib/ntp/ntp.conf.dhcp -u 100:104 root 754 1 0 14:19 ? 00:00:00 /usr/lib/udisks/udisks-daemon --no-debug root 763 1 0 14:19 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 3000000 flow - b8:27:eb:d0:02:12 root 765 2 0 14:19 ? 00:00:00 [kworker/u9:2-hc] root 769 754 0 14:19 ? 00:00:00 udisks-daemon: not polling any devices root 783 1 0 14:19 tty1 00:00:00 /sbin/agetty --noclear tty1 linux root 790 1 0 14:19 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap root 792 1 0 14:19 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 843 1 0 14:19 ? 00:00:00 /usr/sbin/nmbd -D root 844 843 0 14:19 ? 00:00:00 /usr/sbin/nmbd -D root 861 1 0 14:19 ? 00:00:00 /usr/sbin/winbindd root 867 861 0 14:19 ? 00:00:00 /usr/sbin/winbindd root 871 1 0 14:19 ? 00:00:00 /usr/sbin/smbd -D root 884 861 0 14:19 ? 00:00:00 /usr/sbin/winbindd root 896 861 0 14:19 ? 00:00:00 /usr/sbin/winbindd root 917 871 0 14:19 ? 00:00:00 /usr/sbin/smbd -D root 921 1 0 14:19 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 1024 1 0 14:19 ? 00:00:00 /sbin/dhcpcd volumio 1047 1 0 14:19 ? 00:00:16 /usr/local/bin/node --use-openssl-ca /volumio/index.js root 1118 2 0 14:19 ? 00:00:00 [cifsiod] root 1119 2 0 14:19 ? 00:00:00 [cifsoplockd] root 1123 2 0 14:19 ? 00:00:00 [cifsd] volumio 1172 1047 0 14:19 ? 00:00:00 /bin/node --use-openssl-ca /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1184 1172 0 14:19 ? 00:00:00 /bin/node --use-openssl-ca /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1185 1172 0 14:19 ? 00:00:00 /bin/node --use-openssl-ca /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1190 1172 0 14:19 ? 00:00:00 /bin/node --use-openssl-ca /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1240 1 0 14:19 ? 00:00:00 /usr/bin/spopd -f -c /etc/spopd.conf shairpo+ 1320 1 0 14:19 ? 00:00:05 /usr/local/bin/shairport-sync --configfile=/tmp/shairport-sync.conf volumio 1351 1 0 14:19 ? 00:00:00 /bin/bash /data/plugins/music_service/volspotconnect2/startconnect.sh volumio 1353 1351 0 14:19 ? 00:00:00 ./vollibrespot -c volspotify.toml volumio 1365 1 0 14:19 ? 00:00:03 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf volumio 1395 1 0 14:19 ? 00:00:00 /bin/streaming-daemon root 3400 2 0 14:33 ? 00:00:00 [kworker/3:0-mm_] root 4226 2 0 14:39 ? 00:00:00 [kworker/2:0-mm_] root 4321 2 0 14:40 ? 00:00:00 [kworker/2:0H] root 4363 2 0 14:40 ? 00:00:00 [kworker/u8:1-br] root 4868 2 0 14:44 ? 00:00:00 [kworker/1:0-eve] root 4938 2 0 14:44 ? 00:00:00 [kworker/2:1-mm_] root 5182 2 0 14:46 ? 00:00:00 [kworker/u8:2-ev] root 5579 2 0 14:49 ? 00:00:00 [kworker/1:1-mm_] root 5643 2 0 14:49 ? 00:00:00 [kworker/0:0H] root 5648 2 0 14:49 ? 00:00:00 [kworker/2:2-mm_] root 5984 614 0 14:52 ? 00:00:00 sleep 60 volumio 6008 1047 0 14:52 ? 00:00:00 /bin/sh -c /usr/local/bin/node /volumio/logsubmit.js 'connected to Synology NAS - Works on Pi , not on Pc' volumio 6009 6008 19 14:52 ? 00:00:00 /usr/local/bin/node /volumio/logsubmit.js connected to Synology NAS - Works on Pi , not on Pc volumio 6034 6009 0 14:52 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 6035 6034 0 14:52 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.2G 826M 1.3G 40% /imgpart /dev/loop0 355M 355M 0 100% /static overlay 27G 191M 25G 1% / devtmpfs 950M 0 950M 0% /dev tmpfs 992M 12K 992M 1% /dev/shm tmpfs 992M 4.7M 988M 1% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 992M 0 992M 0% /sys/fs/cgroup tmpfs 992M 44K 992M 1% /tmp tmpfs 992M 0 992M 0% /var/spool/cups tmpfs 20M 16K 20M 1% /var/log tmpfs 992M 0 992M 0% /var/spool/cups/tmp /dev/mmcblk0p1 61M 57M 3.4M 95% /boot //192.168.5.4/music/sonos 7.9T 3.9T 4.1T 49% /mnt/NAS/CDs //192.168.5.4/music/Hires 7.9T 3.9T 4.1T 49% /mnt/NAS/HiRes # mount --------------- /dev/mmcblk0p2 on /imgpart type ext4 (rw,relatime,stripe=1024) /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=972028k,nr_inodes=182910,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) cgroup on /sys/fs/cgroup/systemd type cgroup (rw,nosuid,nodev,noexec,relatime,xattr,release_agent=/lib/systemd/systemd-cgroups-agent,name=systemd) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/memory type cgroup (rw,nosuid,nodev,noexec,relatime,memory) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/net_cls type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=22,pgrp=1,timeout=300,minproto=5,maxproto=5,direct) mqueue on /dev/mqueue type mqueue (rw,relatime) debugfs on /sys/kernel/debug type debugfs (rw,relatime) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) configfs on /sys/kernel/config type configfs (rw,relatime) fusectl on /sys/fs/fuse/connections type fusectl (rw,relatime) 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) rpc_pipefs on /run/rpc_pipefs type rpc_pipefs (rw,relatime) //192.168.5.4/music/sonos on /mnt/NAS/CDs type cifs (ro,relatime,vers=default,cache=strict,username=wolf,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.5.4,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=1048576,wsize=1048576,echo_interval=60,actimeo=1) //192.168.5.4/music/Hires on /mnt/NAS/HiRes type cifs (ro,relatime,vers=default,cache=strict,username=wolf,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.5.4,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=1048576,wsize=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:445 0.0.0.0:* LISTEN - tcp 0 0 192.168.5.60:49152 0.0.0.0:* LISTEN 1365/upmpdcli tcp 0 0 0.0.0.0:5000 0.0.0.0:* LISTEN - tcp 0 0 127.0.0.1:6602 0.0.0.0:* LISTEN 1240/spopd tcp 0 0 0.0.0.0:139 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:41293 0.0.0.0:* LISTEN 1353/vollibrespot tcp 0 0 0.0.0.0:111 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:48793 0.0.0.0:* LISTEN - tcp 0 0 127.0.0.1:55638 127.0.0.1:3000 ESTABLISHED 612/volumio-remote- tcp 0 0 127.0.0.1:38872 127.0.0.1:6599 ESTABLISHED 1365/upmpdcli tcp 0 0 127.0.0.1:55584 127.0.0.1:3000 ESTABLISHED 1047/node tcp 0 0 127.0.0.1:55602 127.0.0.1:3000 ESTABLISHED 1047/node tcp 0 0 127.0.0.1:40030 127.0.0.1:6602 ESTABLISHED 1047/node tcp 0 0 127.0.0.1:6602 127.0.0.1:40032 ESTABLISHED 1240/spopd tcp 0 0 192.168.5.60:48730 192.168.5.4:445 ESTABLISHED - tcp 0 0 127.0.0.1:45760 127.0.0.1:6600 ESTABLISHED 1047/node tcp 0 0 127.0.0.1:6602 127.0.0.1:40030 ESTABLISHED 1240/spopd tcp 0 0 192.168.5.60:54888 35.190.243.230:4070 ESTABLISHED 1240/spopd tcp 0 0 127.0.0.1:40032 127.0.0.1:6602 ESTABLISHED 1047/node tcp 0 1 192.168.5.60:57406 192.168.5.23:3000 SYN_SENT 1047/node tcp6 0 0 :::3005 :::* LISTEN 1047/node tcp6 0 0 :::40637 :::* LISTEN - tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::49152 :::* LISTEN 1365/upmpdcli tcp6 0 0 :::7777 :::* LISTEN 1395/streaming-daem tcp6 0 0 :::6599 :::* LISTEN 1047/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 1047/node tcp6 0 0 :::3001 :::* LISTEN 1172/node tcp6 0 0 192.168.5.60:3000 192.168.5.53:50200 FIN_WAIT2 - tcp6 0 0 127.0.0.1:3000 127.0.0.1:55602 ESTABLISHED 1047/node tcp6 0 0 192.168.5.60:3000 192.168.5.53:50197 FIN_WAIT2 - tcp6 0 0 127.0.0.1:3000 127.0.0.1:55638 ESTABLISHED 1047/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:55584 ESTABLISHED 1047/node tcp6 0 0 192.168.5.60:3000 192.168.5.53:50196 FIN_WAIT2 - tcp6 0 0 127.0.0.1:6599 127.0.0.1:38872 ESTABLISHED 1047/node tcp6 0 0 192.168.5.60:3000 192.168.5.53:50201 ESTABLISHED 1047/node tcp6 0 0 127.0.0.1:6600 127.0.0.1:45760 ESTABLISHED - # cat /var/log/mpd.log --------------- Oct 06 10:35 : zeroconf: No global port, disabling zeroconf Nov 01 14:19 : client: [0] opened from local Nov 01 14:19 : exception: No such directory Nov 01 14:19 : client: [1] opened from 127.0.0.1:45760 # 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": "STARTED" } }, "spop": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volspotconnect2": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "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" } } }, "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" gapless_mp3_playback "no" #save_absolute_paths_in_playlists "no" #metadata_to_use "artist,album,title,track,name,genre,date,composer,performer,disc" auto_update "yes" #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 ################################################################ ############################################################################### # Audio Output ################################################################ resampler { plugin "soxr" quality "high" threads "1" } audio_output { type "alsa" name "alsa" device "hw:5,0" dop "no" } audio_output { type "fifo" enabled "no" name "multiroom" path "/tmp/snapfifo" format "44100:16:2" } #replaygain "album" #replaygain_preamp "0" volume_normalization "no" ############################################################################### # MPD Internal Buffering ###################################################### audio_buffer_size "2048" buffer_before_play "10%" ############################################################################### # 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" id3v1_encoding "UTF-8" ############################################################################### # volumio endpointstest --------------- TESTING REMOTE ENDPOINTS https://google.com, 802 ms: OK https://www.googleapis.com, 555 ms: OK https://securetoken.googleapis.com, 676 ms: OK https://myvolumio.firebaseio.com, 521 ms: OK https://functions.volumio.cloud, 930 ms: OK https://oauth-performer.dfs.volumio.org, 464 ms: FAILED https://browsing-performer.dfs.volumio.org, 466 ms: FAILED http://cddb.volumio.org, 3819 ms: OK https://functions.volumio.cloud, 957 ms: OK http://pushupdates.volumio.org, 212 ms: OK http://plugins.volumio.org, 1276 ms: OK https://database.volumio.cloud, 594 ms: OK https://radio-directory.firebaseapp.com, 524 ms: OK ---------- WARNING!!! Some remote endpoints cannot be reached! Failing endpoints: https://oauth-performer.dfs.volumio.org, 524 ms https://browsing-performer.dfs.volumio.org, 524 ms ---------- # sudo journalctl -p 7 --------------- -- Logs begin at Wed 2021-10-06 10:34:49 UTC, end at Mon 2021-11-01 14:52:17 UTC. -- Oct 06 10:34:49 volumiopi systemd-journal[183]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 148.7M free of 987.6M available → current limit 30.0M). Oct 06 10:34:49 volumiopi kernel: Booting Linux on physical CPU 0x0 Oct 06 10:34:49 volumiopi kernel: Linux version 4.19.118-v7l+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1311 SMP Mon Apr 27 14:26:42 BST 2020 Oct 06 10:34:49 volumiopi kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Oct 06 10:34:49 volumiopi kernel: CPU: div instructions available: patching division code Oct 06 10:34:49 volumiopi kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Oct 06 10:34:49 volumiopi kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.4 Oct 06 10:34:49 volumiopi kernel: Memory policy: Data cache writealloc Oct 06 10:34:49 volumiopi kernel: cma: Reserved 64 MiB at 0x000000003a000000 Oct 06 10:34:49 volumiopi kernel: On node 0 totalpages: 516096 Oct 06 10:34:49 volumiopi kernel: DMA zone: 1728 pages used for memmap Oct 06 10:34:49 volumiopi kernel: DMA zone: 0 pages reserved Oct 06 10:34:49 volumiopi kernel: DMA zone: 196608 pages, LIFO batch:63 Oct 06 10:34:49 volumiopi kernel: HighMem zone: 319488 pages, LIFO batch:63 Oct 06 10:34:49 volumiopi kernel: random: get_random_bytes called from start_kernel+0xc0/0x4e8 with crng_init=0 Oct 06 10:34:49 volumiopi kernel: percpu: Embedded 17 pages/cpu s36928 r8192 d24512 u69632 Oct 06 10:34:49 volumiopi kernel: pcpu-alloc: s36928 r8192 d24512 u69632 alloc=17*4096 Oct 06 10:34:49 volumiopi kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 06 10:34:49 volumiopi kernel: Built 1 zonelists, mobility grouping on. Total pages: 514368 Oct 06 10:34:49 volumiopi kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 cma=64M bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=E4:5F:01:34:63:2F vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash quiet 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 console=ttyS0,115200 kgdboc=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh elevator=noop rootwait bootdelay=5 logo.nologo vt.global_cursor_default=0 loglevel=0 snd-bcm2835.enable_compat_alsa=1 snd_bcm2835.enable_headphones=1 Oct 06 10:34:49 volumiopi kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) Oct 06 10:34:49 volumiopi kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) Oct 06 10:34:49 volumiopi kernel: Memory: 1944056K/2064384K available (8192K kernel code, 690K rwdata, 2420K rodata, 2048K init, 860K bss, 54792K reserved, 65536K cma-reserved, 1212416K highmem) Oct 06 10:34:49 volumiopi kernel: Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0xf0800000 - 0xff800000 ( 240 MB) lowmem : 0xc0000000 - 0xf0000000 ( 768 MB) pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB) modules : 0xbf000000 - 0xbfe00000 ( 14 MB) .text : 0x(ptrval) - 0x(ptrval) (10208 kB) .init : 0x(ptrval) - 0x(ptrval) (2048 kB) .data : 0x(ptrval) - 0x(ptrval) ( 691 kB) .bss : 0x(ptrval) - 0x(ptrval) ( 861 kB) Oct 06 10:34:49 volumiopi kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 06 10:34:49 volumiopi kernel: ftrace: allocating 28928 entries in 85 pages Oct 06 10:34:49 volumiopi kernel: rcu: Hierarchical RCU implementation. Oct 06 10:34:49 volumiopi kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Oct 06 10:34:49 volumiopi kernel: GIC: Using split EOI/Deactivate mode Oct 06 10:34:49 volumiopi kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Oct 06 10:34:49 volumiopi kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Oct 06 10:34:49 volumiopi kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Oct 06 10:34:49 volumiopi kernel: Switching to timer-based delay loop, resolution 18ns Oct 06 10:34:49 volumiopi kernel: Console: colour dummy device 80x30 Oct 06 10:34:49 volumiopi kernel: console [tty1] enabled Oct 06 10:34:49 volumiopi kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Oct 06 10:34:49 volumiopi kernel: pid_max: default: 32768 minimum: 301 Oct 06 10:34:49 volumiopi kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) Oct 06 10:34:49 volumiopi kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) Oct 06 10:34:49 volumiopi kernel: CPU: Testing write buffer coherency: ok Oct 06 10:34:49 volumiopi kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Oct 06 10:34:49 volumiopi kernel: Setting up static identity map for 0x200000 - 0x20003c Oct 06 10:34:49 volumiopi kernel: rcu: Hierarchical SRCU implementation. Oct 06 10:34:49 volumiopi kernel: smp: Bringing up secondary CPUs ... Oct 06 10:34:49 volumiopi kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Oct 06 10:34:49 volumiopi kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Oct 06 10:34:49 volumiopi kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Oct 06 10:34:49 volumiopi kernel: smp: Brought up 1 node, 4 CPUs Oct 06 10:34:49 volumiopi kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Oct 06 10:34:49 volumiopi kernel: CPU: All CPU(s) started in HYP mode. Oct 06 10:34:49 volumiopi kernel: CPU: Virtualization extensions available. Oct 06 10:34:49 volumiopi kernel: devtmpfs: initialized Oct 06 10:34:49 volumiopi kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Oct 06 10:34:49 volumiopi kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Oct 06 10:34:49 volumiopi kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) Oct 06 10:34:49 volumiopi kernel: pinctrl core: initialized pinctrl subsystem Oct 06 10:34:49 volumiopi kernel: NET: Registered protocol family 16 Oct 06 10:34:49 volumiopi kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Oct 06 10:34:49 volumiopi kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Oct 06 10:34:49 volumiopi kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Oct 06 10:34:49 volumiopi kernel: Serial: AMBA PL011 UART driver Oct 06 10:34:49 volumiopi kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Oct 06 10:34:49 volumiopi kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2020-04-27 14:33, variant start Oct 06 10:34:49 volumiopi kernel: raspberrypi-firmware soc:firmware: Firmware hash is 3a8f1793b758d6fb7f375edaa260e069ecd34c88 Oct 06 10:34:49 volumiopi kernel: bcm2835-dma fe007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1 Oct 06 10:34:49 volumiopi kernel: vgaarb: loaded Oct 06 10:34:49 volumiopi kernel: SCSI subsystem initialized Oct 06 10:34:49 volumiopi kernel: usbcore: registered new interface driver usbfs Oct 06 10:34:49 volumiopi kernel: usbcore: registered new interface driver hub Oct 06 10:34:49 volumiopi kernel: usbcore: registered new device driver usb Oct 06 10:34:49 volumiopi kernel: clocksource: Switched to clocksource arch_sys_counter Oct 06 10:34:49 volumiopi kernel: VFS: Disk quotas dquot_6.6.0 Oct 06 10:34:49 volumiopi kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Oct 06 10:34:49 volumiopi kernel: FS-Cache: Loaded Oct 06 10:34:49 volumiopi kernel: CacheFiles: Loaded Oct 06 10:34:49 volumiopi kernel: NET: Registered protocol family 2 Oct 06 10:34:49 volumiopi kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes) Oct 06 10:34:49 volumiopi kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes) Oct 06 10:34:49 volumiopi kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes) Oct 06 10:34:49 volumiopi kernel: TCP: Hash tables configured (established 8192 bind 8192) Oct 06 10:34:49 volumiopi kernel: UDP hash table entries: 512 (order: 2, 16384 bytes) Oct 06 10:34:49 volumiopi kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) Oct 06 10:34:49 volumiopi kernel: NET: Registered protocol family 1 Oct 06 10:34:49 volumiopi kernel: RPC: Registered named UNIX socket transport module. Oct 06 10:34:49 volumiopi kernel: RPC: Registered udp transport module. Oct 06 10:34:49 volumiopi kernel: RPC: Registered tcp transport module. Oct 06 10:34:49 volumiopi kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Oct 06 10:34:49 volumiopi kernel: PCI: CLS 0 bytes, default 64 Oct 06 10:34:49 volumiopi kernel: Trying to unpack rootfs image as initramfs... Oct 06 10:34:49 volumiopi kernel: Freeing initrd memory: 19516K Oct 06 10:34:49 volumiopi kernel: hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available Oct 06 10:34:49 volumiopi kernel: Initialise system trusted keyrings Oct 06 10:34:49 volumiopi kernel: workingset: timestamp_bits=14 max_order=19 bucket_order=5 Oct 06 10:34:49 volumiopi kernel: FS-Cache: Netfs 'nfs' registered for caching Oct 06 10:34:49 volumiopi kernel: NFS: Registering the id_resolver key type Oct 06 10:34:49 volumiopi kernel: Key type id_resolver registered Oct 06 10:34:49 volumiopi kernel: Key type id_legacy registered Oct 06 10:34:49 volumiopi kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Oct 06 10:34:49 volumiopi kernel: Key type asymmetric registered Oct 06 10:34:49 volumiopi kernel: Asymmetric key parser 'x509' registered Oct 06 10:34:49 volumiopi kernel: bounce: pool size: 64 pages Oct 06 10:34:49 volumiopi kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Oct 06 10:34:49 volumiopi kernel: io scheduler noop registered (default) Oct 06 10:34:49 volumiopi kernel: io scheduler deadline registered Oct 06 10:34:49 volumiopi kernel: io scheduler cfq registered Oct 06 10:34:49 volumiopi kernel: io scheduler mq-deadline registered Oct 06 10:34:49 volumiopi kernel: io scheduler kyber registered Oct 06 10:34:49 volumiopi kernel: brcm-pcie fd500000.pcie: could not get clock Oct 06 10:34:49 volumiopi kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Oct 06 10:34:49 volumiopi kernel: brcm-pcie fd500000.pcie: MEM 0x600000000..0x603ffffff -> 0xf8000000 Oct 06 10:34:49 volumiopi kernel: brcm-pcie fd500000.pcie: link up, 5.0 Gbps x1 (!SSC) Oct 06 10:34:49 volumiopi kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Oct 06 10:34:49 volumiopi kernel: pci_bus 0000:00: root bus resource [bus 00-01] Oct 06 10:34:49 volumiopi kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x603ffffff] (bus address [0xf8000000-0xfbffffff]) Oct 06 10:34:49 volumiopi kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Oct 06 10:34:49 volumiopi kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Oct 06 10:34:49 volumiopi kernel: PCI: bus0: Fast back to back transfers disabled Oct 06 10:34:49 volumiopi kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Oct 06 10:34:49 volumiopi kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Oct 06 10:34:49 volumiopi kernel: pci 0000:01:00.0: PME# supported from D0 D3hot Oct 06 10:34:49 volumiopi kernel: PCI: bus1: Fast back to back transfers disabled Oct 06 10:34:49 volumiopi kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Oct 06 10:34:49 volumiopi kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Oct 06 10:34:49 volumiopi kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Oct 06 10:34:49 volumiopi kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Oct 06 10:34:49 volumiopi kernel: pcieport 0000:00:00.0: enabling device (0140 -> 0142) Oct 06 10:34:49 volumiopi kernel: pcieport 0000:00:00.0: Signaling PME with IRQ 52 Oct 06 10:34:49 volumiopi kernel: pcieport 0000:00:00.0: AER enabled with IRQ 52 Oct 06 10:34:49 volumiopi kernel: pci 0000:01:00.0: enabling device (0140 -> 0142) Oct 06 10:34:49 volumiopi kernel: pci 0000:01:00.0: quirk_usb_early_handoff+0x0/0x7c8 took 45695 usecs Oct 06 10:34:49 volumiopi kernel: bcm2708_fb soc:fb: FB found 1 display(s) Oct 06 10:34:49 volumiopi kernel: Console: switching to colour frame buffer device 80x30 Oct 06 10:34:49 volumiopi kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Oct 06 10:34:49 volumiopi kernel: iproc-rng200 fe104000.rng: hwrng registered Oct 06 10:34:49 volumiopi kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Oct 06 10:34:49 volumiopi kernel: vc-sm: Videocore shared memory driver Oct 06 10:34:49 volumiopi kernel: gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000 Oct 06 10:34:49 volumiopi kernel: brd: module loaded Oct 06 10:34:49 volumiopi kernel: loop: module loaded Oct 06 10:34:49 volumiopi kernel: Loading iSCSI transport class v2.0-870. Oct 06 10:34:49 volumiopi kernel: libphy: Fixed MDIO Bus: probed Oct 06 10:34:49 volumiopi kernel: bcmgenet fd580000.genet: failed to get enet clock Oct 06 10:34:49 volumiopi kernel: bcmgenet fd580000.genet: GENET 5.0 EPHY: 0x0000 Oct 06 10:34:49 volumiopi kernel: bcmgenet fd580000.genet: failed to get enet-wol clock Oct 06 10:34:49 volumiopi kernel: bcmgenet fd580000.genet: failed to get enet-eee clock Oct 06 10:34:49 volumiopi kernel: bcmgenet: Skipping UMAC reset Oct 06 10:34:49 volumiopi kernel: unimac-mdio unimac-mdio.-19: DMA mask not set Oct 06 10:34:49 volumiopi kernel: libphy: bcmgenet MII bus: probed Oct 06 10:34:49 volumiopi kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus at 0x(ptrval) Oct 06 10:34:49 volumiopi kernel: usbcore: registered new interface driver r8152 Oct 06 10:34:49 volumiopi kernel: usbcore: registered new interface driver lan78xx Oct 06 10:34:49 volumiopi kernel: usbcore: registered new interface driver smsc95xx Oct 06 10:34:49 volumiopi kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Oct 06 10:34:49 volumiopi kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Oct 06 10:34:49 volumiopi kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000001000000890 Oct 06 10:34:49 volumiopi kernel: genirq: irq_chip Brcm_MSI did not update eff. affinity mask of irq 53 Oct 06 10:34:49 volumiopi kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19 Oct 06 10:34:49 volumiopi kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 06 10:34:49 volumiopi kernel: usb usb1: Product: xHCI Host Controller Oct 06 10:34:49 volumiopi kernel: usb usb1: Manufacturer: Linux 4.19.118-v7l+ xhci-hcd Oct 06 10:34:49 volumiopi kernel: usb usb1: SerialNumber: 0000:01:00.0 Oct 06 10:34:49 volumiopi kernel: hub 1-0:1.0: USB hub found Oct 06 10:34:49 volumiopi kernel: hub 1-0:1.0: 1 port detected Oct 06 10:34:49 volumiopi kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Oct 06 10:34:49 volumiopi kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Oct 06 10:34:49 volumiopi kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Oct 06 10:34:49 volumiopi kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19 Oct 06 10:34:49 volumiopi kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Oct 06 10:34:49 volumiopi kernel: usb usb2: Product: xHCI Host Controller Oct 06 10:34:49 volumiopi kernel: usb usb2: Manufacturer: Linux 4.19.118-v7l+ xhci-hcd Oct 06 10:34:49 volumiopi kernel: usb usb2: SerialNumber: 0000:01:00.0 Oct 06 10:34:49 volumiopi kernel: hub 2-0:1.0: USB hub found Oct 06 10:34:49 volumiopi kernel: hub 2-0:1.0: 4 ports detected Oct 06 10:34:49 volumiopi kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Oct 06 10:34:49 volumiopi kernel: dwc_otg: FIQ enabled Oct 06 10:34:49 volumiopi kernel: dwc_otg: NAK holdoff enabled Oct 06 10:34:49 volumiopi kernel: dwc_otg: FIQ split-transaction FSM enabled Oct 06 10:34:49 volumiopi kernel: Module dwc_common_port init Oct 06 10:34:49 volumiopi kernel: usbcore: registered new interface driver uas Oct 06 10:34:49 volumiopi kernel: usbcore: registered new interface driver usb-storage Oct 06 10:34:49 volumiopi kernel: mousedev: PS/2 mouse device common for all mice Oct 06 10:34:49 volumiopi kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Oct 06 10:34:49 volumiopi kernel: sdhci: Secure Digital Host Controller Interface driver Oct 06 10:34:49 volumiopi kernel: sdhci: Copyright(c) Pierre Ossman Oct 06 10:34:49 volumiopi kernel: mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe Oct 06 10:34:49 volumiopi kernel: sdhci-pltfm: SDHCI platform and OF driver helper Oct 06 10:34:49 volumiopi kernel: ledtrig-cpu: registered to indicate activity on CPUs Oct 06 10:34:49 volumiopi kernel: hidraw: raw HID events driver (C) Jiri Kosina Oct 06 10:34:49 volumiopi kernel: usbcore: registered new interface driver usbhid Oct 06 10:34:49 volumiopi kernel: usbhid: USB HID core driver Oct 06 10:34:49 volumiopi kernel: vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0 Oct 06 10:34:49 volumiopi kernel: [vc_sm_connected_init]: start Oct 06 10:34:49 volumiopi kernel: [vc_sm_connected_init]: end - returning 0 Oct 06 10:34:49 volumiopi kernel: Initializing XFRM netlink socket Oct 06 10:34:49 volumiopi kernel: NET: Registered protocol family 17 Oct 06 10:34:49 volumiopi kernel: Key type dns_resolver registered Oct 06 10:34:49 volumiopi kernel: Registering SWP/SWPB emulation handler Oct 06 10:34:49 volumiopi kernel: registered taskstats version 1 Oct 06 10:34:49 volumiopi kernel: Loading compiled-in X.509 certificates Oct 06 10:34:49 volumiopi kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Oct 06 10:34:49 volumiopi kernel: fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 34, base_baud = 0) is a PL011 rev2 Oct 06 10:34:49 volumiopi kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Oct 06 10:34:49 volumiopi kernel: brcmstb_thermal fd5d2200.thermal: registered AVS TMON of-sensor driver Oct 06 10:34:49 volumiopi kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Oct 06 10:34:49 volumiopi kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Oct 06 10:34:49 volumiopi kernel: sdhci-iproc fe340000.emmc2: Linked as a consumer to regulator.3 Oct 06 10:34:49 volumiopi kernel: sdhci-iproc fe340000.emmc2: Linked as a consumer to regulator.4 Oct 06 10:34:49 volumiopi kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Oct 06 10:34:49 volumiopi kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Oct 06 10:34:49 volumiopi kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Oct 06 10:34:49 volumiopi kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Oct 06 10:34:49 volumiopi kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Oct 06 10:34:49 volumiopi kernel: mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA Oct 06 10:34:49 volumiopi kernel: of_cfs_init Oct 06 10:34:49 volumiopi kernel: of_cfs_init: OK Oct 06 10:34:49 volumiopi kernel: Freeing unused kernel memory: 2048K Oct 06 10:34:49 volumiopi kernel: Run /init as init process Oct 06 10:34:49 volumiopi kernel: random: fast init done Oct 06 10:34:49 volumiopi kernel: mmc1: new high speed SDIO card at address 0001 Oct 06 10:34:49 volumiopi kernel: mmc0: new ultra high speed DDR50 SDHC card at address 0007 Oct 06 10:34:49 volumiopi kernel: mmcblk0: mmc0:0007 SD32G 29.0 GiB Oct 06 10:34:49 volumiopi kernel: mmcblk0: p1 p2 p3 Oct 06 10:34:49 volumiopi initramfs: Booting Volumio for BCM2835 Oct 06 10:34:49 volumiopi initramfs: This script mounts rootfs RO with an overlay RW layer. Oct 06 10:34:49 volumiopi kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 06 10:34:49 volumiopi kernel: fuse init (API version 7.27) Oct 06 10:34:49 volumiopi initramfs: IMGPART=/dev/mmcblk0p2 Oct 06 10:34:49 volumiopi initramfs: IMGFILE=/volumio_current.sqsh Oct 06 10:34:49 volumiopi initramfs: Boot delay (except first time) will be 5 seconds Oct 06 10:34:49 volumiopi kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Oct 06 10:34:49 volumiopi initramfs: /dev/mmcblk0p2: Oct 06 10:34:49 volumiopi kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Oct 06 10:34:49 volumiopi kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Oct 06 10:34:49 volumiopi kernel: usb 1-1: Product: USB2.0 Hub Oct 06 10:34:49 volumiopi kernel: hub 1-1:1.0: USB hub found Oct 06 10:34:49 volumiopi kernel: hub 1-1:1.0: 4 ports detected Oct 06 10:34:49 volumiopi kernel: usb 1-1.4: new full-speed USB device number 3 using xhci_hcd Oct 06 10:34:49 volumiopi kernel: usb 1-1.4: New USB device found, idVendor=0ccd, idProduct=0077, bcdDevice= 1.00 Oct 06 10:34:49 volumiopi kernel: usb 1-1.4: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Oct 06 10:34:49 volumiopi kernel: input: HID 0ccd:0077 as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.3/0003:0CCD:0077.0001/input/input0 Oct 06 10:34:49 volumiopi kernel: hid-generic 0003:0CCD:0077.0001: input,hidraw0: USB HID v1.00 Device [HID 0ccd:0077] on usb-0000:01:00.0-1.4/input3 Oct 06 10:34:49 volumiopi kernel: EXT4-fs (mmcblk0p2): recovery complete Oct 06 10:34:49 volumiopi kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Oct 06 10:34:49 volumiopi initramfs: Doing a 5 second delay here to give kernel load a headstart Oct 06 10:34:49 volumiopi initramfs: Checking for USB updates Oct 06 10:34:49 volumiopi initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Oct 06 10:34:49 volumiopi kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 06 10:34:49 volumiopi kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 06 10:34:49 volumiopi kernel: EXT4-fs (mmcblk0p3): recovery complete Oct 06 10:34:49 volumiopi kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Oct 06 10:34:49 volumiopi initramfs: With Option: Oct 06 10:34:49 volumiopi initramfs: VOLUMIO_VERSION="2.917" Oct 06 10:34:49 volumiopi initramfs: Finish initramfs, continue booting Volumio Oct 06 10:34:49 volumiopi kernel: random: systemd: uninitialized urandom read (16 bytes read) Oct 06 10:34:49 volumiopi systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) Oct 06 10:34:49 volumiopi systemd[1]: Detected architecture 'arm'. Oct 06 10:34:49 volumiopi kernel: NET: Registered protocol family 10 Oct 06 10:34:49 volumiopi kernel: Segment Routing with IPv6 Oct 06 10:34:49 volumiopi systemd[1]: Inserted module 'ipv6' Oct 06 10:34:49 volumiopi systemd[1]: Set hostname to . Oct 06 10:34:49 volumiopi kernel: random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) Oct 06 10:34:49 volumiopi kernel: random: systemd: uninitialized urandom read (16 bytes read) Oct 06 10:34:49 volumiopi systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Oct 06 10:34:49 volumiopi systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Oct 06 10:34:49 volumiopi systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Oct 06 10:34:49 volumiopi systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Oct 06 10:34:49 volumiopi systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory. Oct 06 10:34:49 volumiopi systemd[1]: Starting Forward Password Requests to Wall Directory Watch. Oct 06 10:34:49 volumiopi kernel: i2c /dev entries driver Oct 06 10:34:49 volumiopi systemd-udevd[193]: starting version 215 Oct 06 10:34:49 volumiopi systemd-journal[183]: Journal started Oct 06 10:34:49 volumiopi systemd[1]: Starting Slices. Oct 06 10:34:49 volumiopi systemd[1]: Reached target Slices. Oct 06 10:34:49 volumiopi systemd[1]: Starting Remount Root and Kernel File Systems... Oct 06 10:34:49 volumiopi systemd[1]: Started Create list of required static device nodes for the current kernel. Oct 06 10:34:49 volumiopi systemd[1]: Starting Create Static Device Nodes in /dev... Oct 06 10:34:49 volumiopi systemd[1]: Started Restore / save the current clock. Oct 06 10:34:49 volumiopi systemd[1]: Time has been changed Oct 06 10:34:49 volumiopi systemd[1]: Started Create Static Device Nodes in /dev. Oct 06 10:34:49 volumiopi systemd[1]: Starting udev Kernel Device Manager... Oct 06 10:34:49 volumiopi systemd[1]: Mounted POSIX Message Queue File System. Oct 06 10:34:49 volumiopi systemd-modules-load[181]: Inserted module 'i2c_dev' Oct 06 10:34:49 volumiopi systemd[1]: Reached target Local File Systems (Pre). Oct 06 10:34:49 volumiopi systemd[1]: Mounting /tmp... Oct 06 10:34:49 volumiopi systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Oct 06 10:34:49 volumiopi systemd[1]: Mounting /var/spool/cups... Oct 06 10:34:49 volumiopi fake-hwclock[172]: Wed Oct 6 10:34:49 UTC 2021 Oct 06 10:34:49 volumiopi systemd[1]: Mounting /var/log... Oct 06 10:34:49 volumiopi systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Oct 06 10:34:49 volumiopi systemd[1]: Mounting FUSE Control File System... Oct 06 10:34:49 volumiopi systemd[1]: Mounting Configuration File System... Oct 06 10:34:49 volumiopi systemd[1]: Starting Apply Kernel Variables... Oct 06 10:34:49 volumiopi systemd[1]: Mounted Configuration File System. Oct 06 10:34:49 volumiopi systemd[1]: Mounted FUSE Control File System. Oct 06 10:34:49 volumiopi systemd[1]: Mounted /var/log. Oct 06 10:34:49 volumiopi systemd[1]: Mounted /var/spool/cups. Oct 06 10:34:49 volumiopi systemd[1]: Mounted /tmp. Oct 06 10:34:49 volumiopi systemd[1]: Started udev Coldplug all Devices. Oct 06 10:34:49 volumiopi systemd[1]: Started Copy rules generated while the root was ro. Oct 06 10:34:49 volumiopi systemd[1]: Started Load/Save Random Seed. Oct 06 10:34:49 volumiopi systemd[1]: Started Apply Kernel Variables. Oct 06 10:34:49 volumiopi systemd[1]: Starting Show Plymouth Boot Screen... Oct 06 10:34:49 volumiopi systemd[1]: Mounting /var/spool/cups/tmp... Oct 06 10:34:49 volumiopi systemd[1]: Mounted /var/spool/cups/tmp. Oct 06 10:34:49 volumiopi kernel: rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x0000ffff Oct 06 10:34:49 volumiopi kernel: rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00000fff Oct 06 10:34:49 volumiopi kernel: rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x0000ffff Oct 06 10:34:49 volumiopi kernel: rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x0000ffff Oct 06 10:34:49 volumiopi kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Oct 06 10:34:49 volumiopi kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Oct 06 10:34:49 volumiopi kernel: [vc_sm_connected_init]: start Oct 06 10:34:49 volumiopi kernel: [vc_sm_connected_init]: installed successfully Oct 06 10:34:49 volumiopi kernel: media: Linux media interface: v0.10 Oct 06 10:34:49 volumiopi systemd[1]: Started Show Plymouth Boot Screen. Oct 06 10:34:49 volumiopi systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch. Oct 06 10:34:49 volumiopi systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Oct 06 10:34:49 volumiopi systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 06 10:34:49 volumiopi systemd[1]: Starting Paths. Oct 06 10:34:49 volumiopi systemd[1]: Reached target Paths. Oct 06 10:34:49 volumiopi kernel: videodev: Linux video capture interface: v2.00 Oct 06 10:34:49 volumiopi kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Oct 06 10:34:49 volumiopi kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Oct 06 10:34:49 volumiopi kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Oct 06 10:34:49 volumiopi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Oct 06 10:34:49 volumiopi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Oct 06 10:34:49 volumiopi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Oct 06 10:34:49 volumiopi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Oct 06 10:34:49 volumiopi kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Oct 06 10:34:49 volumiopi kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Oct 06 10:34:50 volumiopi systemd[1]: Starting system-ifup.slice. Oct 06 10:34:50 volumiopi systemd[1]: Created slice system-ifup.slice. Oct 06 10:34:50 volumiopi kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Oct 06 10:34:50 volumiopi kernel: snd_bcm2835: unknown parameter 'index' ignored Oct 06 10:34:50 volumiopi kernel: bcm2835_audio soc:audio: card created with 5 channels Oct 06 10:34:50 volumiopi kernel: bcm2835_audio soc:audio: card created with 3 channels Oct 06 10:34:50 volumiopi kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Oct 06 10:34:50 volumiopi kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Oct 06 10:34:50 volumiopi kernel: platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 Oct 06 10:34:50 volumiopi kernel: cfg80211: failed to load regulatory.db Oct 06 10:34:50 volumiopi kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Oct 06 10:34:50 volumiopi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Oct 06 10:34:50 volumiopi kernel: usbcore: registered new interface driver brcmfmac Oct 06 10:34:50 volumiopi systemd[1]: Starting Sound Card. Oct 06 10:34:50 volumiopi systemd[1]: Reached target Sound Card. Oct 06 10:34:50 volumiopi systemd[1]: Found device /dev/mmcblk0p1. Oct 06 10:34:50 volumiopi systemd[1]: Starting File System Check on /dev/mmcblk0p1... Oct 06 10:34:50 volumiopi kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Oct 06 10:34:50 volumiopi kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Mar 2 2020 23:30:41 version 7.45.202 (r724630 CY) FWID 01-72f6ece2 Oct 06 10:34:50 volumiopi systemd[1]: Starting system-systemd\x2drfkill.slice. Oct 06 10:34:50 volumiopi systemd[1]: Created slice system-systemd\x2drfkill.slice. Oct 06 10:34:50 volumiopi systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill0... Oct 06 10:34:53 volumiopi systemd[1]: Started Load/Save RF Kill Switch Status of rfkill0. Oct 06 10:34:53 volumiopi kernel: snd_usb_audio: unknown parameter 'nrpacks' ignored Oct 06 10:34:53 volumiopi kernel: snd_usb_audio: unknown parameter 'nrpacks' ignored Oct 06 10:34:53 volumiopi kernel: usbcore: registered new interface driver snd-usb-audio Oct 06 10:34:53 volumiopi systemd-fsck[342]: fsck.fat 3.0.27 (2014-11-12) Oct 06 10:34:53 volumiopi systemd-fsck[342]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt. Oct 06 10:34:53 volumiopi systemd-fsck[342]: Automatically removing dirty bit. Oct 06 10:34:53 volumiopi systemd-fsck[342]: Performing changes. Oct 06 10:34:53 volumiopi systemd-fsck[342]: /dev/mmcblk0p1: 240 files, 116221/123044 clusters Oct 06 10:34:53 volumiopi systemd[1]: Started File System Check on /dev/mmcblk0p1. Oct 06 10:34:53 volumiopi systemd[1]: Mounting /boot... Oct 06 10:34:53 volumiopi systemd[1]: Mounted /boot. Oct 06 10:34:53 volumiopi systemd[1]: Starting Local File Systems. Oct 06 10:34:53 volumiopi systemd[1]: Reached target Local File Systems. Oct 06 10:34:53 volumiopi systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Oct 06 10:34:53 volumiopi systemd[1]: Starting Remote File Systems. Oct 06 10:34:53 volumiopi systemd[1]: Reached target Remote File Systems. Oct 06 10:34:53 volumiopi systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... Oct 06 10:34:53 volumiopi systemd[1]: Starting Create Volatile Files and Directories... Oct 06 10:34:53 volumiopi systemd[1]: Starting LSB: Raise network interfaces.... Oct 06 10:34:53 volumiopi systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Oct 06 10:34:53 volumiopi systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. Oct 06 10:34:53 volumiopi systemd[1]: Started Create Volatile Files and Directories. Oct 06 10:34:53 volumiopi systemd[1]: Starting Update UTMP about System Boot/Shutdown... Oct 06 10:34:53 volumiopi systemd[1]: Started Update UTMP about System Boot/Shutdown. Oct 06 10:34:55 volumiopi systemd[1]: Received SIGRTMIN+20 from PID 247 (plymouthd). Oct 06 10:34:55 volumiopi kernel: bcmgenet: Skipping UMAC reset Oct 06 10:34:55 volumiopi kernel: bcmgenet fd580000.genet: configuring instance for external RGMII (no delay) Oct 06 10:34:55 volumiopi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 06 10:34:55 volumiopi networking[378]: Configuring network interfaces...done. Oct 06 10:34:55 volumiopi systemd[1]: Started LSB: Raise network interfaces.. Oct 06 10:34:55 volumiopi systemd[1]: Starting ifup for wlan0... Oct 06 10:34:55 volumiopi systemd[1]: Started ifup for wlan0. Oct 06 10:34:55 volumiopi systemd[1]: Starting ifup for eth0... Oct 06 10:34:55 volumiopi systemd[1]: Started ifup for eth0. Oct 06 10:34:55 volumiopi systemd[1]: Starting Network. Oct 06 10:34:55 volumiopi systemd[1]: Reached target Network. Oct 06 10:34:55 volumiopi systemd[1]: Starting Network is Online. Oct 06 10:34:55 volumiopi systemd[1]: Reached target Network is Online. Oct 06 10:34:55 volumiopi systemd[1]: Starting LSB: RPC portmapper replacement... Oct 06 10:34:55 volumiopi ifup[466]: dhcpcd[500]: version 6.0.5 starting Oct 06 10:34:55 volumiopi dhcpcd[500]: version 6.0.5 starting Oct 06 10:34:56 volumiopi rpcbind[469]: Starting rpcbind daemon.... Oct 06 10:34:56 volumiopi systemd[1]: Started LSB: RPC portmapper replacement. Oct 06 10:34:56 volumiopi systemd[1]: Starting RPC Port Mapper. Oct 06 10:34:56 volumiopi systemd[1]: Reached target RPC Port Mapper. Oct 06 10:34:56 volumiopi systemd[1]: Starting LSB: NFS support files common to client and server... Oct 06 10:34:56 volumiopi kernel: bcmgenet fd580000.genet eth0: Link is Down Oct 06 10:34:57 volumiopi rpc.statd[568]: Version 1.2.8 starting Oct 06 10:34:57 volumiopi sm-notify[569]: Version 1.2.8 starting Oct 06 10:34:57 volumiopi dhcpcd[500]: eth0: waiting for carrier Oct 06 10:34:57 volumiopi ifup[466]: dhcpcd[500]: eth0: waiting for carrier Oct 06 10:34:57 volumiopi kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Oct 06 10:34:57 volumiopi nfs-common[548]: Starting NFS common utilities: statd idmapd. Oct 06 10:34:57 volumiopi systemd[1]: Started LSB: NFS support files common to client and server. Oct 06 10:34:57 volumiopi systemd[1]: Starting System Initialization. Oct 06 10:34:57 volumiopi systemd[1]: Reached target System Initialization. Oct 06 10:34:57 volumiopi systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. Oct 06 10:34:57 volumiopi systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Oct 06 10:34:57 volumiopi systemd[1]: Starting mpd.socket. Oct 06 10:34:57 volumiopi systemd[1]: Listening on mpd.socket. Oct 06 10:34:57 volumiopi systemd[1]: Starting D-Bus System Message Bus Socket. Oct 06 10:34:57 volumiopi systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 06 10:34:57 volumiopi systemd[1]: Starting Sockets. Oct 06 10:34:57 volumiopi systemd[1]: Reached target Sockets. Oct 06 10:34:57 volumiopi systemd[1]: Starting Daily Cleanup of Temporary Directories. Oct 06 10:34:57 volumiopi systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 06 10:34:57 volumiopi systemd[1]: Starting Timers. Oct 06 10:34:57 volumiopi systemd[1]: Reached target Timers. Oct 06 10:34:57 volumiopi systemd[1]: Started Manage Sound Card State (restore and store). Oct 06 10:34:57 volumiopi systemd[1]: Starting Restore Sound Card State... Oct 06 10:34:57 volumiopi systemd[1]: Starting Basic System. Oct 06 10:34:57 volumiopi systemd[1]: Reached target Basic System. Oct 06 10:34:57 volumiopi systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... Oct 06 10:34:57 volumiopi systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Oct 06 10:34:57 volumiopi systemd[1]: Starting Configure Bluetooth Modems connected by UART... Oct 06 10:34:57 volumiopi systemd[1]: Starting Volumio Iptables Module... Oct 06 10:34:57 volumiopi systemd[1]: Started Volumio Iptables Module. Oct 06 10:34:57 volumiopi systemd[1]: Starting mount disks automatically with standby... Oct 06 10:34:57 volumiopi systemd[1]: Started mount disks automatically with standby. Oct 06 10:34:57 volumiopi systemd[1]: Starting UPnP Renderer front-end to MPD... Oct 06 10:34:57 volumiopi systemd[1]: Started UPnP Renderer front-end to MPD. Oct 06 10:34:57 volumiopi systemd[1]: Starting volumio-remote-updater.service... Oct 06 10:34:57 volumiopi alsactl[604]: No state is present for card ALSA Oct 06 10:34:57 volumiopi alsactl[604]: Found hardware: "bcm2835_alsa" "Broadcom Mixer" "" "" "" Oct 06 10:34:57 volumiopi alsactl[604]: Hardware is initialized using a generic method Oct 06 10:34:57 volumiopi alsactl[604]: No state is present for card ALSA Oct 06 10:34:57 volumiopi alsactl[604]: No state is present for card Headphones Oct 06 10:34:57 volumiopi alsactl[604]: Found hardware: "bcm2835_headpho" "Broadcom Mixer" "" "" "" Oct 06 10:34:57 volumiopi alsactl[604]: Hardware is initialized using a generic method Oct 06 10:34:57 volumiopi alsactl[604]: No state is present for card Headphones Oct 06 10:34:57 volumiopi systemd[1]: Started volumio-remote-updater.service. Oct 06 10:34:57 volumiopi systemd[1]: Starting Volumio Log Rotation Service... Oct 06 10:34:57 volumiopi alsactl[604]: No state is present for card U0xccd0x77 Oct 06 10:34:57 volumiopi alsactl[604]: Found hardware: "USB-Audio" "USB Mixer" "USB0ccd:0077" "" "" Oct 06 10:34:57 volumiopi alsactl[604]: Hardware is initialized using a generic method Oct 06 10:34:57 volumiopi alsactl[604]: No state is present for card U0xccd0x77 Oct 06 10:34:57 volumiopi systemd[1]: Started Volumio Log Rotation Service. Oct 06 10:34:57 volumiopi systemd[1]: Starting Volumio SSH enabler... Oct 06 10:34:57 volumiopi systemd[1]: Started Volumio SSH enabler. Oct 06 10:34:57 volumiopi systemd[1]: Starting Wireless Services... Oct 06 10:34:57 volumiopi systemd[1]: Starting /etc/rc.local Compatibility... Oct 06 10:34:57 volumiopi systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. Oct 06 10:34:57 volumiopi systemd[1]: Starting Login Service... Oct 06 10:34:57 volumiopi systemd[1]: Starting LSB: start Winbind daemon... Oct 06 10:34:57 volumiopi systemd[1]: Starting LSB: triggerhappy hotkey daemon... Oct 06 10:34:57 volumiopi systemd[1]: Starting LSB: start Samba daemons for the AD DC... Oct 06 10:34:57 volumiopi systemd[1]: Starting LSB: Start NTP daemon... Oct 06 10:34:57 volumiopi systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... Oct 06 10:34:57 volumiopi systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Oct 06 10:34:57 volumiopi systemd[1]: Starting LSB: Brings up/down network automatically... Oct 06 10:34:58 volumiopi systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... Oct 06 10:34:58 volumiopi systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... Oct 06 10:34:58 volumiopi systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Oct 06 10:34:58 volumiopi systemd[1]: Starting Music Player Daemon... Oct 06 10:34:58 volumiopi systemd[1]: Starting D-Bus System Message Bus... Oct 06 10:34:58 volumiopi systemd[1]: Started D-Bus System Message Bus. Oct 06 10:34:58 volumiopi kernel: uart-pl011 fe201000.serial: no DMA platform data Oct 06 10:34:58 volumiopi triggerhappy[635]: Unable to parse trigger line: Oct 06 10:34:58 volumiopi triggerhappy[635]: Unable to parse trigger line: Oct 06 10:34:58 volumiopi triggerhappy[635]: Unable to parse trigger line: Oct 06 10:34:58 volumiopi triggerhappy[635]: Unable to parse trigger line: Oct 06 10:34:58 volumiopi triggerhappy[635]: Unable to parse trigger line: Oct 06 10:34:58 volumiopi triggerhappy[635]: Unable to parse trigger line: Oct 06 10:34:58 volumiopi triggerhappy[635]: Unable to parse trigger line: Oct 06 10:34:58 volumiopi dhcpcd[656]: Not running dhcpcd because there is aleady an interface specific instance ... failed! Oct 06 10:34:58 volumiopi dhcpcd[656]: /var/run/dhcpcd-eth0.pid ... failed! Oct 06 10:34:58 volumiopi btuart[606]: Patch not found for BCM4345C0, continue anyway Oct 06 10:34:58 volumiopi ifplugd(eth0)[724]: ifplugd 0.28 initializing. Oct 06 10:34:58 volumiopi ifplugd(eth0)[724]: Using interface eth0/E4:5F:01:34:63:2F with driver (version: v2.0) Oct 06 10:34:58 volumiopi ifplugd(eth0)[724]: Using detection mode: SIOCETHTOOL Oct 06 10:34:58 volumiopi ifplugd(eth0)[724]: Initialization complete, link beat not detected. Oct 06 10:34:58 volumiopi ifplugd[654]: Network Interface Plugging Daemon...start eth0...done. Oct 06 10:34:58 volumiopi avahi-daemon[681]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). Oct 06 10:34:58 volumiopi avahi-daemon[681]: Successfully dropped root privileges. Oct 06 10:34:58 volumiopi avahi-daemon[681]: avahi-daemon 0.6.31 starting up. Oct 06 10:34:58 volumiopi ntpd[707]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) Oct 06 10:34:58 volumiopi ntp[640]: Starting NTP server: ntpd. Oct 06 10:34:58 volumiopi loadcpufreq[650]: Loading cpufreq kernel modules...done (none). Oct 06 10:34:58 volumiopi avahi-daemon[681]: Successfully called chroot(). Oct 06 10:34:58 volumiopi avahi-daemon[681]: Successfully dropped remaining capabilities. Oct 06 10:34:58 volumiopi avahi-daemon[681]: Loading service file /services/volumio.service. Oct 06 10:34:58 volumiopi dbus[689]: [system] Successfully activated service 'org.freedesktop.systemd1' Oct 06 10:34:58 volumiopi avahi-daemon[681]: Network interface enumeration completed. Oct 06 10:34:58 volumiopi systemd[1]: Started Avahi mDNS/DNS-SD Stack. Oct 06 10:34:58 volumiopi systemd[1]: Starting Permit User Sessions... Oct 06 10:34:58 volumiopi avahi-daemon[681]: Registering HINFO record with values 'ARMV7L'/'LINUX'. Oct 06 10:34:58 volumiopi avahi-daemon[681]: Server startup complete. Host name is volumiopi.local. Local service cookie is 4203040944. Oct 06 10:34:58 volumiopi avahi-daemon[681]: Service "VolumioPI" (/services/volumio.service) successfully established. Oct 06 10:34:58 volumiopi systemd[1]: Started Restore Sound Card State. Oct 06 10:34:58 volumiopi systemd[1]: Started /etc/rc.local Compatibility. Oct 06 10:34:58 volumiopi systemd[1]: Started LSB: triggerhappy hotkey daemon. Oct 06 10:34:58 volumiopi systemd[1]: Started LSB: Start NTP daemon. Oct 06 10:34:58 volumiopi systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Oct 06 10:34:58 volumiopi systemd[1]: Started LSB: Brings up/down network automatically. Oct 06 10:34:58 volumiopi systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. Oct 06 10:34:58 volumiopi systemd[1]: dhcpcd.service: control process exited, code=exited status=6 Oct 06 10:34:58 volumiopi systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. Oct 06 10:34:58 volumiopi systemd[1]: Unit dhcpcd.service entered failed state. Oct 06 10:34:58 volumiopi systemd[1]: Started Permit User Sessions. Oct 06 10:34:58 volumiopi dbus[689]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' Oct 06 10:34:58 volumiopi sudo[632]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Oct 06 10:34:58 volumiopi ntpd[744]: proto: precision = 1.204 usec Oct 06 10:34:58 volumiopi ntpd[744]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Oct 06 10:34:58 volumiopi volumio-remote-updater[612]: Error: No active session Oct 06 10:34:58 volumiopi ntpd[744]: Listen and drop on 1 v6wildcard :: UDP 123 Oct 06 10:34:58 volumiopi ntpd[744]: Listen normally on 2 lo 127.0.0.1 UDP 123 Oct 06 10:34:58 volumiopi ntpd[744]: peers refreshed Oct 06 10:34:58 volumiopi ntpd[744]: Listening on routing socket on fd #19 for interface updates Oct 06 10:34:58 volumiopi kernel: Bluetooth: Core ver 2.22 Oct 06 10:34:58 volumiopi kernel: NET: Registered protocol family 31 Oct 06 10:34:58 volumiopi kernel: Bluetooth: HCI device and connection manager initialized Oct 06 10:34:58 volumiopi kernel: Bluetooth: HCI socket layer initialized Oct 06 10:34:58 volumiopi kernel: Bluetooth: L2CAP socket layer initialized Oct 06 10:34:58 volumiopi kernel: Bluetooth: SCO socket layer initialized Oct 06 10:34:58 volumiopi volumio-remote-updater[612]: [2021-10-06 10:34:58] [info] asio async_connect error: system:111 (Connection refused) Oct 06 10:34:58 volumiopi volumio-remote-updater[612]: [2021-10-06 10:34:58] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 06 10:34:58 volumiopi volumio-remote-updater[612]: [2021-10-06 10:34:58] [error] handle_connect error: Underlying Transport Error Oct 06 10:34:58 volumiopi systemd[1]: Starting Disk Manager (legacy version)... Oct 06 10:34:58 volumiopi systemd-logind[620]: New seat seat0. Oct 06 10:34:58 volumiopi systemd[1]: Starting LSB: set CPUFreq kernel parameters... Oct 06 10:34:58 volumiopi systemd[1]: Starting Hold until boot process finishes up... Oct 06 10:34:58 volumiopi sudo[632]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:34:58 volumiopi systemd[1]: Starting Terminate Plymouth Boot Screen... Oct 06 10:34:58 volumiopi kernel: Bluetooth: HCI UART driver ver 2.3 Oct 06 10:34:58 volumiopi kernel: Bluetooth: HCI UART protocol H4 registered Oct 06 10:34:58 volumiopi kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Oct 06 10:34:58 volumiopi kernel: Bluetooth: HCI UART protocol Broadcom registered Oct 06 10:34:58 volumiopi systemd[1]: Started Login Service. Oct 06 10:34:58 volumiopi btuart[606]: bcm43xx_init Oct 06 10:34:58 volumiopi btuart[606]: Set BDADDR UART: b8:27:eb:d0:02:12 Oct 06 10:34:58 volumiopi btuart[606]: Set Controller UART speed to 3000000 bit/s Oct 06 10:34:58 volumiopi btuart[606]: Device setup complete Oct 06 10:34:58 volumiopi systemd[1]: Started Configure Bluetooth Modems connected by UART. Oct 06 10:34:58 volumiopi systemd-udevd[764]: failed to execute '/usr/bin/hciconfig' '/usr/bin/hciconfig hci0 up': No such file or directory Oct 06 10:34:58 volumiopi sudo[632]: pam_unix(sudo:session): session closed for user root Oct 06 10:34:58 volumiopi dbus[689]: [system] Successfully activated service 'org.freedesktop.UDisks' Oct 06 10:34:58 volumiopi systemd[1]: Started Disk Manager (legacy version). Oct 06 10:35:00 volumiopi systemd[1]: Received SIGRTMIN+21 from PID 247 (plymouthd). Oct 06 10:35:00 volumiopi systemd[1]: Started Hold until boot process finishes up. Oct 06 10:35:00 volumiopi systemd[1]: Started Terminate Plymouth Boot Screen. Oct 06 10:35:00 volumiopi cpufrequtils[755]: CPUFreq Utilities: Setting performance CPUFreq governor...error: unexpectedly disconnected from boot status daemon Oct 06 10:35:00 volumiopi systemd[1]: Starting Getty on tty1... Oct 06 10:35:00 volumiopi systemd[1]: Started Getty on tty1. Oct 06 10:35:00 volumiopi systemd[1]: Starting Login Prompts. Oct 06 10:35:00 volumiopi systemd[1]: Reached target Login Prompts. Oct 06 10:35:00 volumiopi cpufrequtils[755]: CPU0...CPU1...CPU2...CPU3...done. Oct 06 10:35:00 volumiopi systemd[1]: Started LSB: set CPUFreq kernel parameters. Oct 06 10:35:00 volumiopi systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill1... Oct 06 10:35:00 volumiopi systemd[1]: Starting Bluetooth service... Oct 06 10:35:00 volumiopi dbus[689]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' Oct 06 10:35:00 volumiopi systemd[1]: Started Load/Save RF Kill Switch Status of rfkill1. Oct 06 10:35:00 volumiopi systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... Oct 06 10:35:00 volumiopi haveged[605]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K Oct 06 10:35:00 volumiopi haveged[605]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244 Oct 06 10:35:00 volumiopi haveged[605]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99996 Oct 06 10:35:00 volumiopi haveged[605]: haveged: fills: 0, generated: 0 Oct 06 10:35:00 volumiopi kernel: random: crng init done Oct 06 10:35:00 volumiopi kernel: random: 7 urandom warning(s) missed due to ratelimiting Oct 06 10:35:00 volumiopi volumio[609]: Could not open config: /tmp/upmpdcli.conf Oct 06 10:35:00 volumiopi polkitd[792]: started daemon version 0.105 using authority implementation `local' version `0.105' Oct 06 10:35:00 volumiopi dbus[689]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 06 10:35:00 volumiopi systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. Oct 06 10:35:00 volumiopi systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE Oct 06 10:35:00 volumiopi systemd[1]: Unit upmpdcli.service entered failed state. Oct 06 10:35:00 volumiopi udisks-glue[608]: Device file /dev/mmcblk0p3 inserted Oct 06 10:35:00 volumiopi udisks-glue[608]: Device file /dev/mmcblk0 inserted Oct 06 10:35:00 volumiopi bluetoothd[790]: Bluetooth daemon 5.23 Oct 06 10:35:00 volumiopi systemd[1]: Started Bluetooth service. Oct 06 10:35:00 volumiopi systemd[1]: Starting Bluetooth. Oct 06 10:35:00 volumiopi systemd[1]: Reached target Bluetooth. Oct 06 10:35:00 volumiopi bluetoothd[790]: Starting SDP server Oct 06 10:35:00 volumiopi bluetoothd[790]: Excluding (cli) sap Oct 06 10:35:00 volumiopi dbus[689]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' Oct 06 10:35:00 volumiopi bluetoothd[790]: Bluetooth management interface 1.14 initialized Oct 06 10:35:00 volumiopi kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Oct 06 10:35:00 volumiopi kernel: Bluetooth: BNEP filters: protocol multicast Oct 06 10:35:00 volumiopi kernel: Bluetooth: BNEP socket layer initialized Oct 06 10:35:00 volumiopi systemd[1]: Starting Hostname Service... Oct 06 10:35:00 volumiopi systemd-hostnamed[798]: Warning: nss-myhostname is not installed. Changing the local hostname might make it unresolveable. Please install nss-myhostname! Oct 06 10:35:00 volumiopi dbus[689]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 06 10:35:00 volumiopi systemd[1]: Started Hostname Service. Oct 06 10:35:01 volumiopi ntpd[744]: Deferring DNS for 0.debian.pool.ntp.org 1 Oct 06 10:35:01 volumiopi ntpd[744]: Deferring DNS for 1.debian.pool.ntp.org 1 Oct 06 10:35:01 volumiopi ntpd[744]: Deferring DNS for 2.debian.pool.ntp.org 1 Oct 06 10:35:01 volumiopi ntpd[744]: Deferring DNS for 3.debian.pool.ntp.org 1 Oct 06 10:35:01 volumiopi ntpd[799]: signal_no_reset: signal 17 had flags 4000000 Oct 06 10:35:02 volumiopi wireless.js[772]: Cleaning previous... Oct 06 10:35:02 volumiopi systemd[1]: Stopped hotspot.service. Oct 06 10:35:02 volumiopi sudo[818]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 06 10:35:02 volumiopi sudo[818]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:02 volumiopi sudo[818]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:02 volumiopi sudo[826]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 06 10:35:02 volumiopi sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:02 volumiopi systemd[1]: Started LSB: start Samba daemons for the AD DC. Oct 06 10:35:02 volumiopi winbind[634]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Oct 06 10:35:02 volumiopi winbind[634]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 06 10:35:02 volumiopi winbind[634]: Unable to setup corepath for winbindd: No such file or directory Oct 06 10:35:02 volumiopi winbind[634]: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 06 10:35:02 volumiopi winbind[634]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 06 10:35:02 volumiopi winbind[634]: Unable to setup corepath for winbindd: No such file or directory Oct 06 10:35:02 volumiopi winbind[634]: [2021/10/06 10:35:02, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:02 volumiopi winbind[634]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 06 10:35:02 volumiopi winbind[634]: [2021/10/06 10:35:02, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:02 volumiopi winbind[634]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 06 10:35:02 volumiopi winbind[634]: [2021/10/06 10:35:02, 0] ../source3/winbindd/winbindd.c:1549(main) Oct 06 10:35:02 volumiopi winbind[634]: winbindd version 4.2.14-Debian started. Oct 06 10:35:02 volumiopi winbind[634]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Oct 06 10:35:02 volumiopi winbind[634]: [2021/10/06 10:35:02, 0] ../lib/util/util.c:220(directory_create_or_exist) Oct 06 10:35:02 volumiopi winbind[634]: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 06 10:35:02 volumiopi winbind[634]: [2021/10/06 10:35:02, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Oct 06 10:35:02 volumiopi winbind[634]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 06 10:35:02 volumiopi winbind[634]: [2021/10/06 10:35:02, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Oct 06 10:35:02 volumiopi winbind[634]: Unable to setup corepath for winbindd: No such file or directory Oct 06 10:35:02 volumiopi winbindd[839]: [2021/10/06 10:35:02.697639, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:02 volumiopi winbindd[839]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 06 10:35:02 volumiopi winbindd[839]: [2021/10/06 10:35:02.698147, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:02 volumiopi winbindd[839]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 06 10:35:02 volumiopi winbind[634]: [2021/10/06 10:35:02.697639, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:02 volumiopi winbind[634]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 06 10:35:02 volumiopi winbind[634]: [2021/10/06 10:35:02.698147, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:02 volumiopi winbind[634]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 06 10:35:02 volumiopi nmbd[642]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Oct 06 10:35:02 volumiopi nmbd[642]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 06 10:35:02 volumiopi nmbd[642]: Unable to setup corepath for nmbd: No such file or directory Oct 06 10:35:02 volumiopi nmbd[842]: [2021/10/06 10:35:02.709983, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:02 volumiopi nmbd[842]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 06 10:35:02 volumiopi nmbd[842]: [2021/10/06 10:35:02.710733, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:02 volumiopi nmbd[842]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 06 10:35:02 volumiopi nmbd[642]: [2021/10/06 10:35:02, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:02 volumiopi nmbd[642]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 06 10:35:02 volumiopi nmbd[642]: [2021/10/06 10:35:02, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:02 volumiopi nmbd[642]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 06 10:35:02 volumiopi nmbd[642]: [2021/10/06 10:35:02, 0] ../source3/nmbd/nmbd.c:908(main) Oct 06 10:35:02 volumiopi nmbd[642]: nmbd version 4.2.14-Debian started. Oct 06 10:35:02 volumiopi nmbd[642]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Oct 06 10:35:02 volumiopi nmbd[642]: [2021/10/06 10:35:02.709983, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:02 volumiopi nmbd[642]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 06 10:35:02 volumiopi nmbd[642]: [2021/10/06 10:35:02.710733, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:02 volumiopi nmbd[642]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 06 10:35:02 volumiopi nmbd[843]: [2021/10/06 10:35:02.714282, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) Oct 06 10:35:02 volumiopi nmbd[843]: started asyncdns process 844 Oct 06 10:35:02 volumiopi systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Oct 06 10:35:02 volumiopi systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Oct 06 10:35:02 volumiopi nmbd[642]: . Oct 06 10:35:03 volumiopi sudo[826]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:03 volumiopi nmbd[843]: [2021/10/06 10:35:03.090169, 0] ../lib/util/become_daemon.c:135(daemon_status) Oct 06 10:35:03 volumiopi nmbd[843]: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Oct 06 10:35:03 volumiopi winbind[634]: . Oct 06 10:35:03 volumiopi systemd[1]: Started LSB: start Winbind daemon. Oct 06 10:35:03 volumiopi winbindd[861]: [2021/10/06 10:35:03.107055, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Oct 06 10:35:03 volumiopi winbindd[861]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 06 10:35:03 volumiopi ntpd_intres[799]: host name not found: 0.debian.pool.ntp.org Oct 06 10:35:03 volumiopi ntpd_intres[799]: host name not found: 1.debian.pool.ntp.org Oct 06 10:35:03 volumiopi ntpd_intres[799]: host name not found: 2.debian.pool.ntp.org Oct 06 10:35:03 volumiopi ntpd_intres[799]: host name not found: 3.debian.pool.ntp.org Oct 06 10:35:03 volumiopi volumio-remote-updater[612]: [2021-10-06 10:35:03] [info] asio async_connect error: system:111 (Connection refused) Oct 06 10:35:03 volumiopi volumio-remote-updater[612]: [2021-10-06 10:35:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 06 10:35:03 volumiopi volumio-remote-updater[612]: [2021-10-06 10:35:03] [error] handle_connect error: Underlying Transport Error Oct 06 10:35:04 volumiopi systemd[1]: Started Music Player Daemon. Oct 06 10:35:04 volumiopi smbd[845]: Starting SMB/CIFS daemon: smbd[2021/10/06 10:35:04, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:04 volumiopi smbd[845]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 06 10:35:04 volumiopi smbd[845]: [2021/10/06 10:35:04, 0] ../lib/util/util.c:220(directory_create_or_exist) Oct 06 10:35:04 volumiopi smbd[845]: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 06 10:35:04 volumiopi smbd[845]: [2021/10/06 10:35:04, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Oct 06 10:35:04 volumiopi smbd[845]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 06 10:35:04 volumiopi smbd[845]: [2021/10/06 10:35:04, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Oct 06 10:35:04 volumiopi smbd[845]: Unable to setup corepath for smbd: No such file or directory Oct 06 10:35:04 volumiopi smbd[845]: [2021/10/06 10:35:04, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:04 volumiopi smbd[845]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 06 10:35:04 volumiopi smbd[845]: [2021/10/06 10:35:04, 0] ../source3/smbd/server.c:1241(main) Oct 06 10:35:04 volumiopi smbd[845]: smbd version 4.2.14-Debian started. Oct 06 10:35:04 volumiopi smbd[845]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Oct 06 10:35:04 volumiopi winbindd[861]: [2021/10/06 10:35:04.645833, 0] ../lib/util/become_daemon.c:124(daemon_ready) Oct 06 10:35:04 volumiopi winbindd[861]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 06 10:35:04 volumiopi wireless.js[772]: Stopped aP Oct 06 10:35:04 volumiopi winbindd[867]: [2021/10/06 10:35:04.650158, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:04 volumiopi winbindd[867]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIOPI': No such file or directory Oct 06 10:35:04 volumiopi wireless.js[772]: Start wireless flow Oct 06 10:35:04 volumiopi wireless.js[772]: Stopped hotspot (if there).. Oct 06 10:35:04 volumiopi smbd[854]: [2021/10/06 10:35:04.661523, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:04 volumiopi smbd[854]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 06 10:35:04 volumiopi smbd[854]: [2021/10/06 10:35:04.662253, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:04 volumiopi smbd[854]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 06 10:35:04 volumiopi smbd[854]: [2021/10/06 10:35:04.662399, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:04 volumiopi smbd[854]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 06 10:35:04 volumiopi smbd[845]: [2021/10/06 10:35:04.661523, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:04 volumiopi smbd[845]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 06 10:35:04 volumiopi smbd[845]: [2021/10/06 10:35:04.662253, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:04 volumiopi smbd[845]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 06 10:35:04 volumiopi smbd[845]: [2021/10/06 10:35:04.662399, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:04 volumiopi smbd[845]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 06 10:35:04 volumiopi sudo[869]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 06 10:35:04 volumiopi systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Oct 06 10:35:04 volumiopi smbd[845]: . Oct 06 10:35:04 volumiopi sudo[869]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:04 volumiopi sudo[869]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:04 volumiopi sudo[878]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 06 10:35:04 volumiopi sudo[878]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:04 volumiopi winbindd[884]: [2021/10/06 10:35:04.728165, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:04 volumiopi winbindd[884]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Oct 06 10:35:04 volumiopi sudo[878]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:10 volumiopi wireless.js[772]: DHCP IP Oct 06 10:35:10 volumiopi wireless.js[772]: Start ap Oct 06 10:35:10 volumiopi sudo[891]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Oct 06 10:35:11 volumiopi sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:11 volumiopi dhcpcd[895]: version 6.0.5 starting Oct 06 10:35:11 volumiopi winbindd[896]: [2021/10/06 10:35:11.019474, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:11 volumiopi winbindd[896]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory Oct 06 10:35:11 volumiopi kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Oct 06 10:35:11 volumiopi wpa_supplicant[889]: Successfully initialized wpa_supplicant Oct 06 10:35:11 volumiopi smbd[871]: [2021/10/06 10:35:11.075220, 0] ../lib/util/become_daemon.c:124(daemon_ready) Oct 06 10:35:11 volumiopi smbd[871]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 06 10:35:11 volumiopi smbd[917]: [2021/10/06 10:35:11.079613, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 06 10:35:11 volumiopi smbd[917]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 06 10:35:11 volumiopi dhcpcd[895]: eth0: waiting for carrier Oct 06 10:35:11 volumiopi dhcpcd[895]: wlan0: waiting for carrier Oct 06 10:35:11 volumiopi volumio-remote-updater[612]: [2021-10-06 10:35:11] [info] asio async_connect error: system:111 (Connection refused) Oct 06 10:35:11 volumiopi volumio-remote-updater[612]: [2021-10-06 10:35:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 06 10:35:11 volumiopi volumio-remote-updater[612]: [2021-10-06 10:35:11] [error] handle_connect error: Underlying Transport Error Oct 06 10:35:11 volumiopi wireless.js[772]: trying... Oct 06 10:35:12 volumiopi sudo[923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 10:35:12 volumiopi sudo[923]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:12 volumiopi sudo[923]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:13 volumiopi wireless.js[772]: trying... Oct 06 10:35:13 volumiopi sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 10:35:13 volumiopi sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:13 volumiopi sudo[932]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:13 volumiopi wpa_supplicant[921]: wlan0: Trying to associate with SSID 'MSA' Oct 06 10:35:14 volumiopi wireless.js[772]: trying... Oct 06 10:35:14 volumiopi sudo[941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 10:35:14 volumiopi sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:14 volumiopi sudo[941]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:15 volumiopi wireless.js[772]: trying... Oct 06 10:35:15 volumiopi sudo[950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 10:35:15 volumiopi sudo[950]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:15 volumiopi sudo[950]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:16 volumiopi wireless.js[772]: trying... Oct 06 10:35:16 volumiopi sudo[959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 10:35:16 volumiopi sudo[959]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:16 volumiopi sudo[959]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:16 volumiopi wpa_supplicant[921]: wlan0: Associated with dc:39:6f:80:2b:ce Oct 06 10:35:16 volumiopi wpa_supplicant[921]: wlan0: WPA: Key negotiation completed with dc:39:6f:80:2b:ce [PTK=CCMP GTK=CCMP] Oct 06 10:35:16 volumiopi wpa_supplicant[921]: wlan0: CTRL-EVENT-CONNECTED - Connection to dc:39:6f:80:2b:ce completed [id=0 id_str=] Oct 06 10:35:16 volumiopi wpa_supplicant[921]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Oct 06 10:35:16 volumiopi dhcpcd[895]: wlan0: carrier acquired Oct 06 10:35:16 volumiopi dhcpcd[895]: wlan0: rebinding lease of 192.168.5.60 Oct 06 10:35:16 volumiopi dhcpcd[895]: wlan0: leased 192.168.5.60 for 864000 seconds Oct 06 10:35:16 volumiopi avahi-daemon[681]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.5.60. Oct 06 10:35:16 volumiopi avahi-daemon[681]: New relevant interface wlan0.IPv4 for mDNS. Oct 06 10:35:16 volumiopi avahi-daemon[681]: Registering new address record for 192.168.5.60 on wlan0.IPv4. Oct 06 10:35:16 volumiopi dhcpcd[895]: wlan0: adding host route to 192.168.5.60 via 127.0.0.1 Oct 06 10:35:16 volumiopi dhcpcd[895]: wlan0: adding route to 192.168.5.0/24 Oct 06 10:35:16 volumiopi dhcpcd[895]: wlan0: adding default route via 192.168.5.1 Oct 06 10:35:16 volumiopi dhcpcd[895]: forked to background, child pid 1024 Oct 06 10:35:16 volumiopi sudo[891]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:17 volumiopi wireless.js[772]: trying... Oct 06 10:35:17 volumiopi sudo[1029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 06 10:35:17 volumiopi sudo[1029]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:17 volumiopi sudo[1029]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:17 volumiopi wireless.js[772]: Connected to: ----MSA Oct 06 10:35:17 volumiopi wireless.js[772]: ---- Oct 06 10:35:17 volumiopi sudo[1038]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 10:35:17 volumiopi sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:17 volumiopi sudo[1038]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:17 volumiopi wireless.js[772]: ... joined AP, wlan0 IPv4 is 192.168.5.60, ipV6 is undefined Oct 06 10:35:17 volumiopi wireless.js[772]: It's done! AP Oct 06 10:35:17 volumiopi systemd[1]: Started Wireless Services. Oct 06 10:35:17 volumiopi systemd[1]: Starting Volumio Backend Module... Oct 06 10:35:17 volumiopi systemd[1]: Started Volumio Backend Module. Oct 06 10:35:17 volumiopi systemd[1]: Starting Volumio Streaming Daemon... Oct 06 10:35:17 volumiopi systemd[1]: Started Volumio Streaming Daemon. Oct 06 10:35:17 volumiopi systemd[1]: Starting Multi-User System. Oct 06 10:35:17 volumiopi systemd[1]: Reached target Multi-User System. Oct 06 10:35:17 volumiopi systemd[1]: Starting Graphical Interface. Oct 06 10:35:17 volumiopi systemd[1]: Reached target Graphical Interface. Oct 06 10:35:17 volumiopi systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 06 10:35:17 volumiopi systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 06 10:35:17 volumiopi systemd[1]: Startup finished in 16.928s (kernel) + 28.930s (userspace) = 45.859s. Oct 06 10:35:17 volumiopi volumio-streaming-daemon[1048]: ############################ Oct 06 10:35:17 volumiopi volumio-streaming-daemon[1048]: # Volumio Streaming Daemon # Oct 06 10:35:17 volumiopi volumio-streaming-daemon[1048]: # Running on port 7777 # Oct 06 10:35:17 volumiopi volumio-streaming-daemon[1048]: ############################ Oct 06 10:35:17 volumiopi volumio-streaming-daemon[1048]: Environment: production Oct 06 10:35:18 volumiopi nmbd[843]: [2021/10/06 10:35:18.092798, 0] ../lib/util/become_daemon.c:124(daemon_ready) Oct 06 10:35:18 volumiopi nmbd[843]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 06 10:35:18 volumiopi ntpd[744]: Listen normally on 3 wlan0 192.168.5.60 UDP 123 Oct 06 10:35:18 volumiopi ntpd[744]: peers refreshed Oct 06 10:35:18 volumiopi volumio[1047]: info: ------------------------------------------- Oct 06 10:35:18 volumiopi volumio[1047]: info: ----- Volumio2 ---- Oct 06 10:35:18 volumiopi volumio[1047]: info: ------------------------------------------- Oct 06 10:35:18 volumiopi volumio[1047]: info: ----- System startup ---- Oct 06 10:35:18 volumiopi volumio[1047]: info: ------------------------------------------- Oct 06 10:35:19 volumiopi volumio[1047]: info: MYVOLUMIO Environment detected Oct 06 10:35:19 volumiopi volumio[1047]: info: Plugin folders cleanup Oct 06 10:35:19 volumiopi volumio[1047]: info: Scanning into folder /volumio/app/plugins/ Oct 06 10:35:19 volumiopi volumio[1047]: info: Scanning category audio_interface Oct 06 10:35:19 volumiopi volumio[1047]: info: Scanning category miscellanea Oct 06 10:35:19 volumiopi volumio[1047]: info: Scanning category music_service Oct 06 10:35:19 volumiopi volumio[1047]: info: Scanning category plugins.json Oct 06 10:35:19 volumiopi volumio[1047]: info: Scanning category system_controller Oct 06 10:35:19 volumiopi volumio[1047]: info: Scanning category user_interface Oct 06 10:35:19 volumiopi volumio[1047]: info: Scanning into folder /data/plugins/ Oct 06 10:35:19 volumiopi volumio[1047]: info: Scanning category music_service Oct 06 10:35:19 volumiopi volumio[1047]: info: Plugin folders cleanup completed Oct 06 10:35:19 volumiopi volumio[1047]: info: ------------------------------------------- Oct 06 10:35:19 volumiopi volumio[1047]: info: ----- Core plugins startup ---- Oct 06 10:35:19 volumiopi volumio[1047]: info: ------------------------------------------- Oct 06 10:35:19 volumiopi volumio[1047]: info: Loading plugins from folder /volumio/app/plugins/ Oct 06 10:35:19 volumiopi volumio[1047]: info: Adding plugin upnp to MyMusic Plugins Oct 06 10:35:19 volumiopi volumio[1047]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 06 10:35:19 volumiopi volumio[1047]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 06 10:35:19 volumiopi volumio[1047]: info: Loading plugins from folder /data/plugins/ Oct 06 10:35:19 volumiopi volumio[1047]: info: Loading plugin "system"... Oct 06 10:35:19 volumiopi volumio[1047]: info: Loading plugin "appearance"... Oct 06 10:35:20 volumiopi ntpd_intres[799]: DNS 0.debian.pool.ntp.org -> 193.141.27.1 Oct 06 10:35:20 volumiopi ntpd_intres[799]: DNS 1.debian.pool.ntp.org -> 213.209.109.44 Oct 06 10:35:20 volumiopi ntpd_intres[799]: DNS 2.debian.pool.ntp.org -> 217.243.166.213 Oct 06 10:35:20 volumiopi ntpd_intres[799]: DNS 3.debian.pool.ntp.org -> 195.201.19.162 Oct 06 10:35:21 volumiopi volumio-remote-updater[612]: [2021-10-06 10:35:21] [connect] Successful connection Oct 06 10:35:21 volumiopi volumio[1047]: info: Loading plugin "network"... Oct 06 10:35:21 volumiopi volumio[1047]: info: Refreshing Cached IP Addresses Oct 06 10:35:21 volumiopi sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 10:35:21 volumiopi volumio[1047]: info: Loading plugin "services"... Oct 06 10:35:21 volumiopi volumio[1047]: info: Loading plugin "alsa_controller"... Oct 06 10:35:21 volumiopi sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:21 volumiopi sudo[1076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 10:35:21 volumiopi sudo[1074]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:21 volumiopi sudo[1076]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:21 volumiopi sudo[1076]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:21 volumiopi volumio[1047]: info: Loading plugin "wizard"... Oct 06 10:35:21 volumiopi volumio[1047]: info: Loading plugin "volumio_command_line_client"... Oct 06 10:35:21 volumiopi volumio[1047]: info: Loading plugin "upnp"... Oct 06 10:35:21 volumiopi volumio[1047]: info: [1633516521517] Starting Upmpd Daemon Oct 06 10:35:21 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 06 10:35:21 volumiopi volumio[1047]: info: Loading plugin "my_music"... Oct 06 10:35:21 volumiopi volumio[1047]: info: Loading plugin "mpd"... Oct 06 10:35:21 volumiopi volumio[1047]: info: Loading plugin "upnp_browser"... Oct 06 10:35:22 volumiopi volumio[1047]: info: Loading plugin "networkfs"... Oct 06 10:35:22 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 06 10:35:22 volumiopi volumio[1047]: info: Loading plugin "alarm-clock"... Oct 06 10:35:22 volumiopi sudo[1100]: 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.5.4/music/Hires /mnt/NAS/HiRes Oct 06 10:35:22 volumiopi sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:22 volumiopi sudo[1102]: 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.5.4/music/sonos /mnt/NAS/CDs Oct 06 10:35:22 volumiopi sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:22 volumiopi kernel: FS-Cache: Netfs 'cifs' registered for caching Oct 06 10:35:22 volumiopi kernel: Key type cifs.spnego registered Oct 06 10:35:22 volumiopi kernel: Key type cifs.idmap registered Oct 06 10:35:22 volumiopi 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. Oct 06 10:35:22 volumiopi 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. Oct 06 10:35:22 volumiopi kernel: FS-Cache: Duplicate cookie detected Oct 06 10:35:22 volumiopi kernel: FS-Cache: O-cookie c=439bd95c [p=789576a2 fl=222 nc=0 na=1] Oct 06 10:35:22 volumiopi kernel: FS-Cache: O-cookie d=1b72e43a n=25b89b25 Oct 06 10:35:22 volumiopi kernel: FS-Cache: O-key=[8] '020001bdc0a80504' Oct 06 10:35:22 volumiopi kernel: FS-Cache: N-cookie c=5a09bfa8 [p=789576a2 fl=2 nc=0 na=1] Oct 06 10:35:22 volumiopi kernel: FS-Cache: N-cookie d=1b72e43a n=51eee8e8 Oct 06 10:35:22 volumiopi kernel: FS-Cache: N-key=[8] '020001bdc0a80504' Oct 06 10:35:22 volumiopi volumio[1047]: info: Loading plugin "airplay_emulation"... Oct 06 10:35:22 volumiopi volumio[1047]: info: Starting Shairport Sync Oct 06 10:35:22 volumiopi volumio[1047]: info: Loading plugin "last_100"... Oct 06 10:35:22 volumiopi volumio[1047]: info: Loading plugin "webradio"... Oct 06 10:35:22 volumiopi volumio[1047]: info: Loading plugin "i2s_dacs"... Oct 06 10:35:22 volumiopi volumio[1047]: info: I2S DAC not set, start Auto-detection Oct 06 10:35:22 volumiopi volumio[1047]: info: Loading plugin "volumiodiscovery"... Oct 06 10:35:22 volumiopi volumio[1047]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 06 10:35:22 volumiopi node[1047]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 06 10:35:22 volumiopi node[1047]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 06 10:35:22 volumiopi node[1047]: *** WARNING *** For more information see Oct 06 10:35:22 volumiopi node[1047]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 06 10:35:22 volumiopi node[1047]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 06 10:35:22 volumiopi node[1047]: *** WARNING *** For more information see Oct 06 10:35:22 volumiopi volumio[1047]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 06 10:35:22 volumiopi volumio[1047]: *** WARNING *** For more information see Oct 06 10:35:22 volumiopi volumio[1047]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 06 10:35:22 volumiopi volumio[1047]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 06 10:35:22 volumiopi volumio[1047]: *** WARNING *** For more information see Oct 06 10:35:22 volumiopi volumio[1047]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 06 10:35:22 volumiopi volumio[1047]: Discovery: StartAdv! undefined Oct 06 10:35:22 volumiopi volumio[1047]: Discovery: Started advertising... VolumioPI - undefined Oct 06 10:35:22 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 06 10:35:22 volumiopi volumio[1047]: info: Loading plugin "outputs"... Oct 06 10:35:22 volumiopi volumio[1047]: info: Loading plugin "albumart"... Oct 06 10:35:22 volumiopi volumio[1047]: info: Plugin example_plugin is not enabled Oct 06 10:35:22 volumiopi volumio[1047]: info: Loading plugin "inputs"... Oct 06 10:35:22 volumiopi volumio[1047]: info: Loading plugin "updater_comm"... Oct 06 10:35:22 volumiopi sudo[1102]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:22 volumiopi sudo[1100]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:22 volumiopi volumio[1047]: info: Plugin mpdemulation is not enabled Oct 06 10:35:22 volumiopi volumio[1047]: info: Loading plugin "rest_api"... Oct 06 10:35:22 volumiopi volumio[1047]: info: Loading plugin "websocket"... Oct 06 10:35:22 volumiopi volumio[1047]: info: Loading plugin "spop"... Oct 06 10:35:23 volumiopi volumio[1047]: Forking 3 albumart workers Oct 06 10:35:23 volumiopi volumio[1047]: Starting albumart workers Oct 06 10:35:23 volumiopi volumio[1047]: Starting albumart workers Oct 06 10:35:23 volumiopi volumio[1047]: Starting albumart workers Oct 06 10:35:24 volumiopi volumio[1047]: info: Loading plugin "volspotconnect2"... Oct 06 10:35:24 volumiopi volumio[1047]: info: ___________ START PLUGINS ___________ Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 06 10:35:24 volumiopi volumio[1047]: info: [1633516524755] CoreMusicLibrary::Adding element Media Servers Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 06 10:35:24 volumiopi volumio[1047]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 06 10:35:24 volumiopi volumio[1047]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 06 10:35:24 volumiopi volumio[1047]: info: [1633516524836] CoreMusicLibrary::Adding element Last_100 Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 06 10:35:24 volumiopi volumio[1047]: info: [1633516524840] CoreMusicLibrary::Adding element Webradio Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 06 10:35:24 volumiopi volumio[1047]: [SpotifyConnect] Creating VLS config file Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 10:35:24 volumiopi volumio[1047]: [SpotifyConnect] Starting metadata listener Oct 06 10:35:24 volumiopi sudo[1233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Oct 06 10:35:24 volumiopi sudo[1233]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:24 volumiopi volumio[1047]: info: ------------------------------------------- Oct 06 10:35:24 volumiopi volumio[1047]: info: ----- MyVolumio plugins startup ---- Oct 06 10:35:24 volumiopi volumio[1047]: info: ------------------------------------------- Oct 06 10:35:24 volumiopi systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Oct 06 10:35:24 volumiopi systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Oct 06 10:35:24 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 06 10:35:24 volumiopi systemd[1]: Starting Spotify Daemon... Oct 06 10:35:24 volumiopi systemd[1]: Started Spotify Daemon. Oct 06 10:35:24 volumiopi sudo[1233]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:24 volumiopi sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Oct 06 10:35:24 volumiopi volumio[1047]: info: Loading i18n strings for locale de Oct 06 10:35:24 volumiopi sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 10:35:24 volumiopi volumio[1047]: Updating browse sources language Oct 06 10:35:24 volumiopi volumio[1047]: Cannot find translation for sourceMedia Servers Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 06 10:35:24 volumiopi systemd[1]: Starting Volspotconnect2 Daemon... Oct 06 10:35:24 volumiopi systemd[1]: Started Volspotconnect2 Daemon. Oct 06 10:35:24 volumiopi sudo[1239]: pam_unix(sudo:session): session closed for user root Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::initPlayerControls Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 10:35:24 volumiopi volumio[1047]: info: BOOT COMPLETED Oct 06 10:35:24 volumiopi volumio[1047]: [Metrics] CommandRouter: 6s 94.46ms Oct 06 10:35:24 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 10:35:25 volumiopi volumio[1240]: 2021-10-06 10:35:25 [SPTF] 10:35:25.071 I [offline_authorizer.cpp:297] Unable to login offline: no such user Oct 06 10:35:25 volumiopi volumio[1240]: 2021-10-06 10:35:25 [SPTF] 10:35:25.072 I [ap:1752] Connecting to AP ap.spotify.com:4070 Oct 06 10:35:25 volumiopi volumio[1248]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Oct 06 10:35:25 volumiopi volumio[1248]: Reading Config from "volspotify.toml" Oct 06 10:35:25 volumiopi volumio[1248]: Malformed config key: expected an equals, found a comma at line 33 column 4 Oct 06 10:35:25 volumiopi systemd[1]: volspotconnect2.service: main process exited, code=exited, status=1/FAILURE Oct 06 10:35:25 volumiopi systemd[1]: Unit volspotconnect2.service entered failed state. Oct 06 10:35:25 volumiopi volumio[1240]: 2021-10-06 10:35:25 [SPTF] 10:35:25.166 I [ap:1226] Connected to AP: 35.190.243.230:4070 Oct 06 10:35:26 volumiopi volumio[1240]: 2021-10-06 10:35:26 [SPTF] 10:35:26.804 I [offline-mgr:2030] 0 files are locked. 0 images are locked Oct 06 10:35:26 volumiopi volumio[1240]: 2021-10-06 10:35:26 [SPTF] 10:35:26.804 I [offline-mgr:2056] 0 files unlocked. 0 images unlocked Oct 06 10:35:27 volumiopi systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Oct 06 10:35:27 volumiopi systemd[1]: Stopping Volspotconnect2 Daemon... Oct 06 10:35:27 volumiopi systemd[1]: Starting Volspotconnect2 Daemon... Oct 06 10:35:27 volumiopi systemd[1]: Started Volspotconnect2 Daemon. Oct 06 10:35:27 volumiopi volumio[1263]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Oct 06 10:35:27 volumiopi volumio[1263]: Reading Config from "volspotify.toml" Oct 06 10:35:27 volumiopi volumio[1263]: Malformed config key: expected an equals, found a comma at line 33 column 4 Oct 06 10:35:27 volumiopi systemd[1]: volspotconnect2.service: main process exited, code=exited, status=1/FAILURE Oct 06 10:35:27 volumiopi systemd[1]: Unit volspotconnect2.service entered failed state. Oct 06 10:35:27 volumiopi dhcpcd[500]: timed out Oct 06 10:35:27 volumiopi dhcpcd[500]: exited Oct 06 10:35:27 volumiopi ifup[466]: dhcpcd[500]: timed out Oct 06 10:35:27 volumiopi ifup[466]: dhcpcd[500]: exited Oct 06 10:35:27 volumiopi ifup[466]: Failed to bring up eth0. Nov 01 14:19:30 volumiopi systemd[1]: Time has been changed Nov 01 14:19:32 volumiopi systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Nov 01 14:19:32 volumiopi systemd[1]: Stopping Volspotconnect2 Daemon... Nov 01 14:19:32 volumiopi systemd[1]: Starting Volspotconnect2 Daemon... Nov 01 14:19:32 volumiopi systemd[1]: Started Volspotconnect2 Daemon. Nov 01 14:19:32 volumiopi volumio[1268]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Nov 01 14:19:32 volumiopi volumio[1268]: Reading Config from "volspotify.toml" Nov 01 14:19:32 volumiopi volumio[1268]: Malformed config key: expected an equals, found a comma at line 33 column 4 Nov 01 14:19:32 volumiopi systemd[1]: volspotconnect2.service: main process exited, code=exited, status=1/FAILURE Nov 01 14:19:32 volumiopi systemd[1]: Unit volspotconnect2.service entered failed state. Nov 01 14:19:33 volumiopi volumio[1047]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreCommandRouter::Close All Modals sent Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreCommandRouter::Close All Modals sent Nov 01 14:19:33 volumiopi volumio[1047]: Express server listening on port 3000 Nov 01 14:19:33 volumiopi volumio[1047]: [Metrics] WebUI: 11s 882.03ms Nov 01 14:19:33 volumiopi volumio[1047]: Volumio Calling Home Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreStateMachine::resetVolumioState Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreStateMachine::getcurrentVolume Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 14:19:33 volumiopi volumio[1047]: info: Setting Device type: Raspberry PI Nov 01 14:19:33 volumiopi volumio[1047]: [SpotifyConnect] Vollibrespot Daemon service started! Nov 01 14:19:33 volumiopi volumio[1047]: [Metrics] SpotifyConnect: 5s 768.77ms Nov 01 14:19:33 volumiopi volumio[1047]: info: SpopD Daemon Started Nov 01 14:19:33 volumiopi volumio[1047]: info: MPD running with PID687 Nov 01 14:19:33 volumiopi volumio[1047]: ,establishing connection Nov 01 14:19:33 volumiopi sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Nov 01 14:19:33 volumiopi sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 14:19:33 volumiopi sudo[1280]: pam_unix(sudo:session): session closed for user root Nov 01 14:19:33 volumiopi volumio[1047]: info: VolumeController:: Volume=19 Mute =false Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreStateMachine::pushState Nov 01 14:19:33 volumiopi volumio[1047]: info: CorePlayQueue::getTrack 0 Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreCommandRouter::volumioPushState Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreStateMachine::updateTrackBlock Nov 01 14:19:33 volumiopi volumio[1047]: info: CorePlayQueue::getTrackBlock Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreCommandRouter::volumioRetrievevolume Nov 01 14:19:33 volumiopi volumio[1047]: info: Volumio called home Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 14:19:33 volumiopi volumio[1047]: info: Reloading queue from file Nov 01 14:19:33 volumiopi volumio[1047]: error: updateQueue error: null Nov 01 14:19:33 volumiopi volumio[1047]: error: Failed LSINFO: Error: [50@0] {} No such directory Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreStateMachine::setRepeat null single undefined Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreStateMachine::pushState Nov 01 14:19:33 volumiopi volumio[1047]: info: CorePlayQueue::getTrack 0 Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreCommandRouter::volumioPushState Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreStateMachine::setRandom null Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreStateMachine::pushState Nov 01 14:19:33 volumiopi volumio[1047]: info: CorePlayQueue::getTrack 0 Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreCommandRouter::volumioPushState Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 14:19:33 volumiopi volumio[1047]: Discovery: Changing my name to volumiopi CINGHIALE is undefined Nov 01 14:19:33 volumiopi volumio[1047]: info: VolumeController:: Volume=19 Mute =false Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreStateMachine::pushState Nov 01 14:19:33 volumiopi volumio[1047]: info: CorePlayQueue::getTrack 0 Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreCommandRouter::volumioPushState Nov 01 14:19:33 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 14:19:33 volumiopi volumio[1047]: info: Starting Shairport Sync Nov 01 14:19:33 volumiopi volumio[1047]: info: Starting Shairport Sync Nov 01 14:19:33 volumiopi volumio[1047]: info: mDNS: A device disapperared from network Nov 01 14:19:33 volumiopi sudo[1296]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 01 14:19:33 volumiopi volumio[1047]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Nov 01 14:19:33 volumiopi sudo[1296]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 14:19:33 volumiopi sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 01 14:19:33 volumiopi sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 14:19:33 volumiopi systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 01 14:19:33 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 01 14:19:33 volumiopi volumio[1047]: info: mDNS: A device disapperared from network Nov 01 14:19:33 volumiopi volumio[1047]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Nov 01 14:19:33 volumiopi sudo[1296]: pam_unix(sudo:session): session closed for user root Nov 01 14:19:33 volumiopi systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 01 14:19:33 volumiopi systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Nov 01 14:19:33 volumiopi systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 01 14:19:33 volumiopi sudo[1298]: pam_unix(sudo:session): session closed for user root Nov 01 14:19:34 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 01 14:19:34 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 01 14:19:34 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 01 14:19:34 volumiopi sudo[1338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 14:19:34 volumiopi sudo[1338]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 14:19:34 volumiopi volumio[1047]: Discovery: adding 3c6bc3c3-93d6-49b9-8c33-0de71a011507 Nov 01 14:19:34 volumiopi volumio[1047]: info: mDNS: Found device VolumioPI Nov 01 14:19:34 volumiopi volumio[1047]: info: CoreCommandRouter::volumioGetState Nov 01 14:19:34 volumiopi volumio[1047]: info: CorePlayQueue::getTrack 0 Nov 01 14:19:34 volumiopi sudo[1338]: pam_unix(sudo:session): session closed for user root Nov 01 14:19:34 volumiopi volumio[1047]: Discovery: adding e3e6557f-6f25-47cb-ba27-5963450bf198 Nov 01 14:19:34 volumiopi volumio[1047]: info: mDNS: Found device Volumio Nov 01 14:19:34 volumiopi sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 14:19:34 volumiopi systemd[1]: volspotconnect2.service holdoff time over, scheduling restart. Nov 01 14:19:34 volumiopi systemd[1]: Stopping Volspotconnect2 Daemon... Nov 01 14:19:34 volumiopi systemd[1]: Starting Volspotconnect2 Daemon... Nov 01 14:19:34 volumiopi systemd[1]: Started Volspotconnect2 Daemon. Nov 01 14:19:34 volumiopi sudo[1340]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 14:19:34 volumiopi sudo[1340]: pam_unix(sudo:session): session closed for user root Nov 01 14:19:34 volumiopi volumio[1351]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Nov 01 14:19:34 volumiopi volumio[1351]: Reading Config from "volspotify.toml" Nov 01 14:19:34 volumiopi volumio[1351]: [Vollibrespot] : Using Alsa backend with device: plughw:5 Nov 01 14:19:34 volumiopi volumio[1351]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Nov 01 14:19:35 volumiopi volumio[1047]: info: Shairport-Sync Started Nov 01 14:19:35 volumiopi volumio[1047]: Error adding Membership: Error: addMembership EINVAL Nov 01 14:19:35 volumiopi volumio[1047]: info: Shairport-Sync Started Nov 01 14:19:35 volumiopi volumio[1047]: info: CoreCommandRouter::volumioGetState Nov 01 14:19:35 volumiopi volumio[1047]: info: CorePlayQueue::getTrack 0 Nov 01 14:19:35 volumiopi volumio[1047]: Discovery: this is already registered, 3c6bc3c3-93d6-49b9-8c33-0de71a011507 Nov 01 14:19:35 volumiopi volumio[1047]: info: mDNS: Found device VolumioPI Nov 01 14:19:35 volumiopi volumio[1047]: info: CoreCommandRouter::volumioGetState Nov 01 14:19:35 volumiopi volumio[1047]: info: CorePlayQueue::getTrack 0 Nov 01 14:19:35 volumiopi volumio[1047]: Discovery: this is already registered, e3e6557f-6f25-47cb-ba27-5963450bf198 Nov 01 14:19:35 volumiopi volumio[1047]: info: mDNS: Found device Volumio Nov 01 14:19:35 volumiopi volumio[1047]: info: CoreCommandRouter::volumioGetState Nov 01 14:19:35 volumiopi volumio[1047]: info: CorePlayQueue::getTrack 0 Nov 01 14:19:35 volumiopi volumio[1047]: info: mDNS: A device disapperared from network Nov 01 14:19:35 volumiopi volumio[1047]: info: mDNS: Device volumiopi disapperared from network Nov 01 14:19:35 volumiopi sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 01 14:19:35 volumiopi sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 14:19:35 volumiopi systemd[1]: Starting UPnP Renderer front-end to MPD... Nov 01 14:19:35 volumiopi systemd[1]: Started UPnP Renderer front-end to MPD. Nov 01 14:19:35 volumiopi sudo[1360]: pam_unix(sudo:session): session closed for user root Nov 01 14:19:35 volumiopi volumio[1047]: info: Upmpdcli Daemon Started Nov 01 14:19:35 volumiopi nmbd[843]: [2021/11/01 14:19:35.404553, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 01 14:19:35 volumiopi nmbd[843]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.5.60 for name WORKGROUP<1d>. Nov 01 14:19:35 volumiopi nmbd[843]: This response was from IP 192.168.5.221, reporting an IP address of 192.168.5.221. Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 01 14:19:36 volumiopi volumio[1047]: info: Adding plugin bluetooth to MyMusic Plugins Nov 01 14:19:36 volumiopi volumio[1047]: info: Adding plugin cd_controller to MyMusic Plugins Nov 01 14:19:36 volumiopi volumio[1047]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 01 14:19:36 volumiopi volumio[1047]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 01 14:19:36 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 14:19:36 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 14:19:36 volumiopi volumio[1047]: info: Starting MyVolumio Remote Streaming Endpoints Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 01 14:19:36 volumiopi volumio[1047]: info: Streaming services startup Nov 01 14:19:36 volumiopi volumio[1047]: info: Starting Streaming Daemon Nov 01 14:19:36 volumiopi sudo[1385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 01 14:19:36 volumiopi sudo[1385]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 14:19:36 volumiopi volumio[1047]: info: MyVolumio not started Nov 01 14:19:36 volumiopi volumio[1047]: info: Initializing device activation check Nov 01 14:19:36 volumiopi systemd[1]: Stopping Volumio Streaming Daemon... Nov 01 14:19:36 volumiopi volumio[1047]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 01 14:19:36 volumiopi systemd[1]: Starting Volumio Streaming Daemon... Nov 01 14:19:36 volumiopi systemd[1]: Started Volumio Streaming Daemon. Nov 01 14:19:36 volumiopi sudo[1385]: pam_unix(sudo:session): session closed for user root Nov 01 14:19:36 volumiopi volumio-streaming-daemon[1395]: ############################ Nov 01 14:19:36 volumiopi volumio-streaming-daemon[1395]: # Volumio Streaming Daemon # Nov 01 14:19:36 volumiopi volumio-streaming-daemon[1395]: # Running on port 7777 # Nov 01 14:19:36 volumiopi volumio-streaming-daemon[1395]: ############################ Nov 01 14:19:36 volumiopi volumio-streaming-daemon[1395]: Environment: production Nov 01 14:19:36 volumiopi volumio[1047]: info: CoreCommandRouter::volumioGetVisibleSources Nov 01 14:19:36 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 14:19:36 volumiopi volumio[1047]: info: CoreCommandRouter::volumioGetState Nov 01 14:19:36 volumiopi volumio[1047]: info: CorePlayQueue::getTrack 0 Nov 01 14:19:36 volumiopi volumio[1047]: info: CoreCommandRouter::volumioGetQueue Nov 01 14:19:36 volumiopi volumio[1047]: info: CoreStateMachine::getQueue Nov 01 14:19:36 volumiopi volumio[1047]: info: CorePlayQueue::getQueue Nov 01 14:19:36 volumiopi volumio[1047]: info: Listing playlists Nov 01 14:19:36 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 01 14:19:38 volumiopi volumio[1047]: info: Connecting to daemon Nov 01 14:19:38 volumiopi volumio[1047]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 01 14:19:38 volumiopi volumio[1047]: info: [1635776378402] CoreMusicLibrary::Adding element Spotify Nov 01 14:19:38 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 14:19:38 volumiopi volumio[1047]: info: FIRST BRANCH Nov 01 14:19:38 volumiopi volumio[1047]: info: An error occurred while listing Spotify categories WebapiError: Unauthorized Nov 01 14:19:38 volumiopi volumio[1047]: info: An error occurred while listing Spotify new albums WebapiError: Unauthorized Nov 01 14:19:38 volumiopi volumio[1047]: info: An error occurred while listing Spotify featured playlists WebapiError: Unauthorized Nov 01 14:19:38 volumiopi volumio[1047]: info: New Spotify access token = BQB01qi3CSdnBR-dSRPpyo92yKlxlfIQ-Gf4BObqdQQ-cr4OS9VFhxkvd94DC0LYEkBDcZXftbx3Oa_yyVkvrPO-kg2rjkRmIvFBiCT5IennGPTEgCO8EA8k23P6ISpAPqBTonoRPNleJAtVXDNQpRO9X7fqNs02g81rrs0oew_ATJtm_ThmKkcLIeV7mA Nov 01 14:19:38 volumiopi volumio[1047]: info: Spotify credentials grant success - running version from March 24, 2019 Nov 01 14:19:39 volumiopi volumio-remote-updater[612]: [2021-11-01 14:19:39] [connect] Successful connection Nov 01 14:19:39 volumiopi volumio-remote-updater[612]: [2021-11-01 14:19:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1635776379 101 Nov 01 14:19:39 volumiopi volumio-streaming-daemon[1395]: Environment: production Nov 01 14:19:39 volumiopi volumio[1047]: Discovery: adding 3c6bc3c3-93d6-49b9-8c33-0de71a011507 Nov 01 14:19:39 volumiopi volumio[1047]: info: mDNS: Found device Volumiopi Nov 01 14:19:39 volumiopi volumio[1047]: info: CoreCommandRouter::volumioGetState Nov 01 14:19:39 volumiopi volumio[1047]: info: CorePlayQueue::getTrack 0 Nov 01 14:19:40 volumiopi volumio[1047]: info: Fetching Streaming Services browse cache Nov 01 14:19:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 14:19:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 14:24:07 volumiopi volumio[1047]: Discovery: adding e3e6557f-6f25-47cb-ba27-5963450bf198 Nov 01 14:24:07 volumiopi volumio[1047]: info: mDNS: Found device VolumioPC Nov 01 14:24:07 volumiopi volumio[1047]: info: CoreCommandRouter::volumioGetState Nov 01 14:24:07 volumiopi volumio[1047]: info: CorePlayQueue::getTrack 0 Nov 01 14:24:12 volumiopi volumio[1047]: info: CoreCommandRouter::volumioGetState Nov 01 14:24:12 volumiopi volumio[1047]: info: CorePlayQueue::getTrack 0 Nov 01 14:24:13 volumiopi volumio[1047]: info: mDNS: A device disapperared from network Nov 01 14:24:13 volumiopi volumio[1047]: info: mDNS: Device volumiopc disapperared from network Nov 01 14:24:17 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 01 14:24:17 volumiopi volumio[1047]: info: CURURI: music-library/NAS/CDs/Carpenters/A Song For You Nov 01 14:24:18 volumiopi volumio[1047]: Discovery: adding e3e6557f-6f25-47cb-ba27-5963450bf198 Nov 01 14:24:18 volumiopi volumio[1047]: info: mDNS: Found device Volumiopc Nov 01 14:24:37 volumiopi nmbd[843]: [2021/11/01 14:24:37.387705, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 01 14:24:37 volumiopi nmbd[843]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.5.60 for name WORKGROUP<1d>. Nov 01 14:24:37 volumiopi nmbd[843]: This response was from IP 192.168.5.221, reporting an IP address of 192.168.5.221. Nov 01 14:25:31 volumiopi volumio[1047]: info: mDNS: A device disapperared from network Nov 01 14:28:16 volumiopi wpa_supplicant[921]: wlan0: WPA: Group rekeying completed with dc:39:6f:80:2b:ce [GTK=CCMP] Nov 01 14:28:33 volumiopi volumio[1047]: info: CoreCommandRouter::volumioGetState Nov 01 14:28:33 volumiopi volumio[1047]: info: CorePlayQueue::getTrack 0 Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 14:28:57 volumiopi volumio[1047]: info: Retrieving Cloud Streaming UI Nov 01 14:28:57 volumiopi volumio[1047]: info: Getting Tidal Cloud Configuration Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 14:28:57 volumiopi volumio[1047]: info: Getting Qobuz Cloud Configuration Nov 01 14:28:57 volumiopi volumio[1047]: info: Asking plugin for UI Config Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 14:28:57 volumiopi volumio[1047]: info: Getting Spotify Cloud Configuration Nov 01 14:28:57 volumiopi volumio[1047]: info: Asking plugin for UI Config Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 14:28:57 volumiopi volumio[1047]: info: Saving Spotify Acccount Nov 01 14:28:57 volumiopi volumio[1047]: info: Got Tidal Cloud Configuration Nov 01 14:28:57 volumiopi volumio[1047]: info: Got it Nov 01 14:28:57 volumiopi volumio[1047]: info: Got it Nov 01 14:28:57 volumiopi volumio[1047]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 14:28:57 volumiopi volumio[1047]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:28:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 01 14:29:01 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:29:05 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:29:09 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:29:13 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:29:17 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:29:21 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:29:25 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:29:29 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:29:33 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:29:37 volumiopi nmbd[843]: [2021/11/01 14:29:37.276237, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 01 14:29:37 volumiopi nmbd[843]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.5.60 for name WORKGROUP<1d>. Nov 01 14:29:37 volumiopi nmbd[843]: This response was from IP 192.168.5.221, reporting an IP address of 192.168.5.221. Nov 01 14:29:37 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:29:41 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:29:45 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:29:49 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:29:53 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:29:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:30:01 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:30:05 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:30:09 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:30:13 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:30:17 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:30:21 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:30:25 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:30:29 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:30:33 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:30:37 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:30:41 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:30:45 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:30:49 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:30:53 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:30:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:31:01 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:31:05 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:31:09 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:31:13 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:31:17 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:31:21 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:31:25 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:31:29 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:31:33 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:31:37 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:31:41 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:31:45 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:31:49 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:31:53 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:31:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:32:01 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:32:05 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:32:09 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:32:13 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:32:17 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:32:21 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:32:25 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:32:29 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:32:33 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:32:37 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:32:41 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:32:45 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:32:49 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:32:53 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:32:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:33:01 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:33:05 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:33:09 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:33:13 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:33:17 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:33:21 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:33:25 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:33:29 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:33:33 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:33:37 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:33:41 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:33:45 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:33:45 volumiopi systemd[1]: Starting Cleanup of Temporary Directories... Nov 01 14:33:45 volumiopi systemd[1]: Started Cleanup of Temporary Directories. Nov 01 14:33:49 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:33:53 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:33:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:34:01 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:34:05 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:34:09 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:34:13 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:34:17 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:34:21 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:34:25 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:34:29 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:34:33 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:34:37 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:34:41 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:34:45 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:34:49 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:34:53 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:34:55 volumiopi nmbd[843]: [2021/11/01 14:34:55.438650, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 01 14:34:55 volumiopi nmbd[843]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.5.60 for name WORKGROUP<1d>. Nov 01 14:34:55 volumiopi nmbd[843]: This response was from IP 192.168.5.221, reporting an IP address of 192.168.5.221. Nov 01 14:34:57 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:35:01 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:35:05 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:35:09 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:35:27 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:37:27 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:38:16 volumiopi wpa_supplicant[921]: wlan0: WPA: Group rekeying completed with dc:39:6f:80:2b:ce [GTK=CCMP] Nov 01 14:38:27 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:39:27 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:39:46 volumiopi nmbd[843]: [2021/11/01 14:39:46.606209, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 01 14:39:46 volumiopi nmbd[843]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.5.60 for name WORKGROUP<1d>. Nov 01 14:39:46 volumiopi nmbd[843]: This response was from IP 192.168.5.221, reporting an IP address of 192.168.5.221. Nov 01 14:40:27 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:41:27 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:42:27 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:43:27 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:44:27 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:44:45 volumiopi nmbd[843]: [2021/11/01 14:44:45.355001, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 01 14:44:45 volumiopi nmbd[843]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.5.60 for name WORKGROUP<1d>. Nov 01 14:44:45 volumiopi nmbd[843]: This response was from IP 192.168.5.221, reporting an IP address of 192.168.5.221. Nov 01 14:45:27 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:46:27 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:47:27 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:48:16 volumiopi wpa_supplicant[921]: wlan0: WPA: Group rekeying completed with dc:39:6f:80:2b:ce [GTK=CCMP] Nov 01 14:48:27 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:49:27 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:49:55 volumiopi nmbd[843]: [2021/11/01 14:49:55.443886, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 01 14:49:55 volumiopi nmbd[843]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.5.60 for name WORKGROUP<1d>. Nov 01 14:49:55 volumiopi nmbd[843]: This response was from IP 192.168.5.221, reporting an IP address of 192.168.5.221. Nov 01 14:50:27 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:51:25 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:51:25 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:51:29 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:51:33 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 14:51:36 volumiopi volumio[1047]: info: CoreCommandRouter::volumioGetState Nov 01 14:51:36 volumiopi volumio[1047]: info: CorePlayQueue::getTrack 0 Nov 01 14:51:36 volumiopi volumio[1047]: info: CoreCommandRouter::volumioGetQueue Nov 01 14:51:36 volumiopi volumio[1047]: info: CoreStateMachine::getQueue Nov 01 14:51:36 volumiopi volumio[1047]: info: CorePlayQueue::getQueue Nov 01 14:51:36 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Nov 01 14:52:12 volumiopi volumio[1047]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Nov 01 14:52:12 volumiopi volumio[1047]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Nov 01 14:52:13 volumiopi sudo[6024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Nov 01 14:52:13 volumiopi sudo[6024]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 14:52:13 volumiopi sudo[6024]: pam_unix(sudo:session): session closed for user root Nov 01 14:52:17 volumiopi sudo[6072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Nov 01 14:52:17 volumiopi sudo[6072]: pam_unix(sudo:session): session opened for user root by (uid=0)