Fri Feb 28 19:52:57 UTC 2020 Description="'NAS Music Library after reboot when DRC plugin is enabled'" # cat /proc/version --------------- Linux version 4.19.86-v7l+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1283 SMP Fri Nov 29 18:37:40 GMT 2019 # 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="7441f93fb55fc13e1243278874bfebef55eaac89" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="60cbd8a1e03b2e464c11c01f54954168a34e9b45" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Feb 15 03:28:59 CET 2020" VOLUMIO_VERSION="2.703" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="c6482eede154d29f2cc010e5a630bcb4" # ifconfig --------------- eth0 Link encap:Ethernet HWaddr dc:a6:32:61:fe:4b inet addr:192.168.178.63 Bcast:192.168.178.255 Mask:255.255.255.0 UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:493 errors:0 dropped:0 overruns:0 frame:0 TX packets:468 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:118217 (115.4 KiB) TX bytes:94671 (92.4 KiB) 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:669 errors:0 dropped:0 overruns:0 frame:0 TX packets:669 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:102781 (100.3 KiB) TX bytes:102781 (100.3 KiB) # sudo iwconfig --------------- eth0 no wireless extensions. lo no wireless extensions. wlan0 IEEE 802.11 ESSID:off/any Mode:Managed Access Point: Not-Associated Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 0: ALSA [bcm2835 ALSA], device 0: bcm2835 ALSA [bcm2835 ALSA] Subdevices: 7/7 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 Subdevice #4: subdevice #4 Subdevice #5: subdevice #5 Subdevice #6: subdevice #6 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: sndallodigione [snd_allo_digione], device 0: Allo DigiOne HiFi wm8804-spdif-0 [Allo DigiOne HiFi wm8804-spdif-0] Subdevices: 0/1 Subdevice #0: subdevice #0 card 7: Loopback [Loopback], device 0: Loopback PCM [Loopback PCM] Subdevices: 2/2 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 card 7: Loopback [Loopback], device 1: Loopback PCM [Loopback PCM] Subdevices: 2/2 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 2 19:50 ? 00:00:04 /sbin/init root 2 0 0 19:50 ? 00:00:00 [kthreadd] root 3 2 0 19:50 ? 00:00:00 [rcu_gp] root 4 2 0 19:50 ? 00:00:00 [rcu_par_gp] root 5 2 0 19:50 ? 00:00:00 [kworker/0:0-cgr] root 6 2 0 19:50 ? 00:00:00 [kworker/0:0H-kb] root 7 2 0 19:50 ? 00:00:00 [kworker/u8:0-ev] root 8 2 0 19:50 ? 00:00:00 [mm_percpu_wq] root 9 2 0 19:50 ? 00:00:00 [ksoftirqd/0] root 10 2 0 19:50 ? 00:00:00 [rcu_sched] root 11 2 0 19:50 ? 00:00:00 [rcu_bh] root 12 2 0 19:50 ? 00:00:00 [migration/0] root 13 2 0 19:50 ? 00:00:00 [cpuhp/0] root 14 2 0 19:50 ? 00:00:00 [cpuhp/1] root 15 2 0 19:50 ? 00:00:00 [migration/1] root 16 2 0 19:50 ? 00:00:00 [ksoftirqd/1] root 17 2 0 19:50 ? 00:00:00 [kworker/1:0-mm_] root 18 2 0 19:50 ? 00:00:00 [kworker/1:0H-kb] root 19 2 0 19:50 ? 00:00:00 [cpuhp/2] root 20 2 0 19:50 ? 00:00:00 [migration/2] root 21 2 0 19:50 ? 00:00:00 [ksoftirqd/2] root 22 2 0 19:50 ? 00:00:00 [kworker/2:0-cgr] root 23 2 0 19:50 ? 00:00:00 [kworker/2:0H-kb] root 24 2 0 19:50 ? 00:00:00 [cpuhp/3] root 25 2 0 19:50 ? 00:00:00 [migration/3] root 26 2 0 19:50 ? 00:00:00 [ksoftirqd/3] root 27 2 0 19:50 ? 00:00:00 [kworker/3:0-mm_] root 28 2 0 19:50 ? 00:00:00 [kworker/3:0H-kb] root 29 2 0 19:50 ? 00:00:00 [kdevtmpfs] root 30 2 0 19:50 ? 00:00:00 [netns] root 31 2 0 19:50 ? 00:00:00 [kworker/0:1-eve] root 32 2 0 19:50 ? 00:00:00 [khungtaskd] root 33 2 0 19:50 ? 00:00:00 [oom_reaper] root 34 2 0 19:50 ? 00:00:00 [writeback] root 35 2 0 19:50 ? 00:00:00 [kcompactd0] root 36 2 0 19:50 ? 00:00:00 [crypto] root 37 2 0 19:50 ? 00:00:00 [kblockd] root 38 2 0 19:50 ? 00:00:00 [watchdogd] root 39 2 0 19:50 ? 00:00:00 [kworker/1:1-cgr] root 40 2 0 19:50 ? 00:00:00 [rpciod] root 41 2 0 19:50 ? 00:00:00 [kworker/u9:0-hc] root 42 2 0 19:50 ? 00:00:00 [xprtiod] root 43 2 0 19:50 ? 00:00:00 [kworker/u8:1-ev] root 45 2 0 19:50 ? 00:00:00 [kswapd0] root 46 2 0 19:50 ? 00:00:00 [nfsiod] root 57 2 0 19:50 ? 00:00:00 [kthrotld] root 58 2 0 19:50 ? 00:00:00 [kworker/3:1-eve] root 59 2 0 19:50 ? 00:00:00 [iscsi_eh] root 61 2 0 19:50 ? 00:00:00 [DWC Notificatio] root 62 2 0 19:50 ? 00:00:00 [vchiq-slot/0] root 63 2 0 19:50 ? 00:00:00 [vchiq-recy/0] root 64 2 0 19:50 ? 00:00:00 [vchiq-sync/0] root 65 2 0 19:50 ? 00:00:00 [vchiq-keep/0] root 66 2 0 19:50 ? 00:00:00 [SMIO] root 67 2 0 19:50 ? 00:00:00 [kworker/3:2-eve] root 68 2 0 19:50 ? 00:00:00 [irq/37-brcmstb_] root 69 2 0 19:50 ? 00:00:00 [irq/38-mmc1] root 70 2 0 19:50 ? 00:00:00 [kworker/3:3-pm] root 71 2 0 19:50 ? 00:00:00 [irq/38-mmc0] root 74 2 0 19:50 ? 00:00:00 [kworker/3:4-rcu] root 80 2 0 19:50 ? 00:00:00 [kworker/2:1-cgr] root 81 2 0 19:50 ? 00:00:00 [mmc_complete] root 82 2 0 19:50 ? 00:00:00 [kworker/3:1H-kb] root 83 2 0 19:50 ? 00:00:00 [kworker/0:1H-mm] root 104 2 0 19:50 ? 00:00:00 [kworker/2:1H-kb] root 108 2 0 19:50 ? 00:00:00 [kworker/1:1H-kb] root 109 2 0 19:50 ? 00:00:00 [kworker/1:2H-kb] root 110 2 0 19:50 ? 00:00:00 [jbd2/mmcblk0p2-] root 111 2 0 19:50 ? 00:00:00 [ext4-rsv-conver] root 118 2 0 19:50 ? 00:00:00 [loop0] root 137 2 0 19:50 ? 00:00:00 [jbd2/mmcblk0p3-] root 138 2 0 19:50 ? 00:00:00 [ext4-rsv-conver] root 145 2 0 19:50 ? 00:00:00 [ipv6_addrconf] root 157 2 0 19:50 ? 00:00:00 [kworker/3:2H-kb] root 174 1 0 19:50 ? 00:00:00 /lib/systemd/systemd-journald root 183 2 0 19:50 ? 00:00:00 [kworker/1:2] root 187 2 0 19:50 ? 00:00:00 [kworker/0:2-mm_] root 196 2 0 19:50 ? 00:00:00 [kworker/2:2-mm_] root 197 1 0 19:50 ? 00:00:00 /lib/systemd/systemd-udevd root 245 2 0 19:50 ? 00:00:00 [SMIO] root 261 2 0 19:50 ? 00:00:00 [kworker/0:2H-mm] root 274 2 0 19:50 ? 00:00:00 [kworker/2:2H-kb] root 304 2 0 19:50 ? 00:00:00 [mmal-vchiq] root 307 2 0 19:50 ? 00:00:00 [mmal-vchiq] root 308 2 0 19:50 ? 00:00:00 [mmal-vchiq] root 319 2 0 19:50 ? 00:00:00 [kworker/u8:2-ev] root 324 2 0 19:50 ? 00:00:00 [cfg80211] root 332 2 0 19:50 ? 00:00:00 [brcmf_wq/mmc1:0] root 333 2 0 19:50 ? 00:00:00 [brcmf_wdog/mmc1] root 556 1 0 19:50 ? 00:00:00 /sbin/rpcbind -w statd 568 1 0 19:50 ? 00:00:00 /sbin/rpc.statd root 580 1 0 19:50 ? 00:00:00 /usr/sbin/rpc.idmapd root 582 1 1 19:50 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 --write=1024 volumio 586 1 0 19:50 ? 00:00:00 /usr/bin/udisks-glue --foreground volumio 591 1 0 19:50 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 611 1 0 19:50 ? 00:00:00 /lib/systemd/systemd-logind avahi 645 1 0 19:50 ? 00:00:00 avahi-daemon: running [volumio-living.local] message+ 649 1 0 19:50 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation root 690 1 0 19:50 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I root 698 2 0 19:50 ? 00:00:00 [kworker/u8:3-ev] avahi 704 645 0 19:50 ? 00:00:00 avahi-daemon: chroot helper root 709 1 0 19:50 ? 00:00:00 /usr/sbin/sshd -D root 711 1 0 19:50 ? 00:00:00 /usr/lib/udisks/udisks-daemon --no-debug ntp 723 1 0 19:50 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 100:104 root 740 1 0 19:50 tty1 00:00:00 /sbin/agetty --noclear tty1 linux root 760 711 0 19:50 ? 00:00:00 udisks-daemon: not polling any devices root 761 1 0 19:50 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 3000000 flow - b8:27:eb:1a:2c:3d root 762 2 0 19:50 ? 00:00:00 [kworker/u9:1-hc] root 764 2 0 19:50 ? 00:00:00 [kworker/u9:2-hc] root 773 1 0 19:50 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap root 780 1 0 19:50 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 825 1 0 19:50 ? 00:00:00 /sbin/dhcpcd root 890 1 0 19:50 ? 00:00:00 /usr/sbin/nmbd -D root 891 890 0 19:50 ? 00:00:00 /usr/sbin/nmbd -D root 905 1 0 19:50 ? 00:00:00 /usr/sbin/winbindd root 911 905 0 19:50 ? 00:00:00 /usr/sbin/winbindd volumio 913 1 7 19:50 ? 00:00:09 /usr/local/bin/node /volumio/index.js root 915 1 0 19:50 ? 00:00:00 /usr/sbin/smbd -D root 926 905 0 19:50 ? 00:00:00 /usr/sbin/winbindd root 933 905 0 19:50 ? 00:00:00 /usr/sbin/winbindd root 934 915 0 19:50 ? 00:00:00 /usr/sbin/smbd -D root 962 2 0 19:51 ? 00:00:00 [cifsiod] root 963 2 0 19:51 ? 00:00:00 [cifsoplockd] root 968 2 0 19:51 ? 00:00:00 [cifsd] volumio 1007 913 0 19:51 ? 00:00:00 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1015 1007 0 19:51 ? 00:00:00 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1016 1007 0 19:51 ? 00:00:00 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1021 1007 0 19:51 ? 00:00:00 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1033 913 0 19:51 ? 00:00:00 /bin/sh -c /usr/bin/sudo /bin/journalctl -o json -f -u brutefir root 1035 1033 0 19:51 ? 00:00:00 /usr/bin/sudo /bin/journalctl -o json -f -u brutefir root 1047 1035 0 19:51 ? 00:00:00 /bin/journalctl -o json -f -u brutefir volumio 1060 913 0 19:51 ? 00:00:00 /bin/sh -c /usr/bin/sudo /bin/journalctl -o json -f -u brutefir root 1062 1060 0 19:51 ? 00:00:00 /usr/bin/sudo /bin/journalctl -o json -f -u brutefir root 1070 1062 0 19:51 ? 00:00:00 /bin/journalctl -o json -f -u brutefir root 1108 1 0 19:51 ? 00:00:00 /usr/bin/mpd --no-daemon root 1140 1 0 19:51 ? 00:00:00 /bin/sh /usr/bin/brutefir /data/configuration/audio_interface/brutefir/volumio-brutefir-config root 1142 1140 0 19:51 ? 00:00:00 /usr/lib/brutefir/brutefir.real /data/configuration/audio_interface/brutefir/volumio-brutefir-config root 1183 1142 0 19:51 ? 00:00:00 /usr/lib/brutefir/brutefir.real /data/configuration/audio_interface/brutefir/volumio-brutefir-config root 1184 1142 0 19:51 ? 00:00:00 /usr/lib/brutefir/brutefir.real /data/configuration/audio_interface/brutefir/volumio-brutefir-config root 1185 1142 0 19:51 ? 00:00:00 /usr/lib/brutefir/brutefir.real /data/configuration/audio_interface/brutefir/volumio-brutefir-config root 1186 1142 0 19:51 ? 00:00:00 /usr/lib/brutefir/brutefir.real /data/configuration/audio_interface/brutefir/volumio-brutefir-config volumio 1194 1 0 19:51 ? 00:00:00 /bin/streaming-daemon volumio 1306 913 0 19:52 ? 00:00:00 /bin/sh -c /usr/local/bin/node /volumio/logsubmit.js 'NAS Music Library after reboot when DRC plugin is enabled' volumio 1307 1306 19 19:52 ? 00:00:00 /usr/local/bin/node /volumio/logsubmit.js NAS Music Library after reboot when DRC plugin is enabled volumio 1332 1307 0 19:52 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1333 1332 0 19:52 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.2G 796M 1.3G 39% /imgpart /dev/loop0 338M 338M 0 100% /static overlay 27G 191M 25G 1% / devtmpfs 950M 0 950M 0% /dev tmpfs 992M 0 992M 0% /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 40K 992M 1% /tmp tmpfs 992M 0 992M 0% /var/spool/cups tmpfs 20M 40K 20M 1% /var/log tmpfs 992M 0 992M 0% /var/spool/cups/tmp /dev/mmcblk0p1 61M 59M 1.9M 97% /boot //FILESERVER/archive/003-AUDIO/10-MUSIC 921G 525G 396G 58% /mnt/NAS/archive # 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=972064k,nr_inodes=182928,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) fusectl on /sys/fs/fuse/connections type fusectl (rw,relatime) configfs on /sys/kernel/config type configfs (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) //FILESERVER/archive/003-AUDIO/10-MUSIC on /mnt/NAS/archive type cifs (ro,relatime,vers=default,cache=strict,username=guest,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.178.81,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=1048576,wsize=1048576,echo_interval=60,actimeo=1) # sudo journalctl -p 7 --------------- -- Logs begin at Fri 2020-02-28 19:50:11 UTC, end at Fri 2020-02-28 19:52:57 UTC. -- Feb 28 19:50:11 volumio-living systemd-journal[174]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 148.7M free of 987.6M available → current limit 30.0M). Feb 28 19:50:11 volumio-living kernel: Booting Linux on physical CPU 0x0 Feb 28 19:50:11 volumio-living kernel: Linux version 4.19.86-v7l+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1283 SMP Fri Nov 29 18:37:40 GMT 2019 Feb 28 19:50:11 volumio-living kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Feb 28 19:50:11 volumio-living kernel: CPU: div instructions available: patching division code Feb 28 19:50:11 volumio-living kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Feb 28 19:50:11 volumio-living kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.1 Feb 28 19:50:11 volumio-living kernel: Memory policy: Data cache writealloc Feb 28 19:50:11 volumio-living kernel: cma: Reserved 64 MiB at 0x000000003a000000 Feb 28 19:50:11 volumio-living kernel: On node 0 totalpages: 516096 Feb 28 19:50:11 volumio-living kernel: DMA zone: 1728 pages used for memmap Feb 28 19:50:11 volumio-living kernel: DMA zone: 0 pages reserved Feb 28 19:50:11 volumio-living kernel: DMA zone: 196608 pages, LIFO batch:63 Feb 28 19:50:11 volumio-living kernel: HighMem zone: 319488 pages, LIFO batch:63 Feb 28 19:50:11 volumio-living kernel: random: get_random_bytes called from start_kernel+0xc0/0x4e8 with crng_init=0 Feb 28 19:50:11 volumio-living kernel: percpu: Embedded 17 pages/cpu s36928 r8192 d24512 u69632 Feb 28 19:50:11 volumio-living kernel: pcpu-alloc: s36928 r8192 d24512 u69632 alloc=17*4096 Feb 28 19:50:11 volumio-living kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 28 19:50:11 volumio-living kernel: Built 1 zonelists, mobility grouping on. Total pages: 514368 Feb 28 19:50:11 volumio-living 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=DC:A6:32:61:FE:4B 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 Feb 28 19:50:11 volumio-living kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) Feb 28 19:50:11 volumio-living kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) Feb 28 19:50:11 volumio-living kernel: Memory: 1944128K/2064384K available (8192K kernel code, 687K rwdata, 2408K rodata, 2048K init, 850K bss, 54720K reserved, 65536K cma-reserved, 1212416K highmem) Feb 28 19:50:11 volumio-living 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) ( 688 kB) .bss : 0x(ptrval) - 0x(ptrval) ( 851 kB) Feb 28 19:50:11 volumio-living kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 28 19:50:11 volumio-living kernel: ftrace: allocating 28674 entries in 85 pages Feb 28 19:50:11 volumio-living kernel: rcu: Hierarchical RCU implementation. Feb 28 19:50:11 volumio-living kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Feb 28 19:50:11 volumio-living kernel: GIC: Using split EOI/Deactivate mode Feb 28 19:50:11 volumio-living kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Feb 28 19:50:11 volumio-living kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Feb 28 19:50:11 volumio-living kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Feb 28 19:50:11 volumio-living kernel: Switching to timer-based delay loop, resolution 18ns Feb 28 19:50:11 volumio-living kernel: Console: colour dummy device 80x30 Feb 28 19:50:11 volumio-living kernel: console [tty1] enabled Feb 28 19:50:11 volumio-living kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Feb 28 19:50:11 volumio-living kernel: pid_max: default: 32768 minimum: 301 Feb 28 19:50:11 volumio-living kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) Feb 28 19:50:11 volumio-living kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) Feb 28 19:50:11 volumio-living kernel: CPU: Testing write buffer coherency: ok Feb 28 19:50:11 volumio-living kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Feb 28 19:50:11 volumio-living kernel: Setting up static identity map for 0x200000 - 0x20003c Feb 28 19:50:11 volumio-living kernel: rcu: Hierarchical SRCU implementation. Feb 28 19:50:11 volumio-living kernel: smp: Bringing up secondary CPUs ... Feb 28 19:50:11 volumio-living kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Feb 28 19:50:11 volumio-living kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Feb 28 19:50:11 volumio-living kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Feb 28 19:50:11 volumio-living kernel: smp: Brought up 1 node, 4 CPUs Feb 28 19:50:11 volumio-living kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Feb 28 19:50:11 volumio-living kernel: CPU: All CPU(s) started in HYP mode. Feb 28 19:50:11 volumio-living kernel: CPU: Virtualization extensions available. Feb 28 19:50:11 volumio-living kernel: devtmpfs: initialized Feb 28 19:50:11 volumio-living kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Feb 28 19:50:11 volumio-living kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 28 19:50:11 volumio-living kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) Feb 28 19:50:11 volumio-living kernel: pinctrl core: initialized pinctrl subsystem Feb 28 19:50:11 volumio-living kernel: NET: Registered protocol family 16 Feb 28 19:50:11 volumio-living kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 28 19:50:11 volumio-living kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 28 19:50:11 volumio-living kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 28 19:50:11 volumio-living kernel: Serial: AMBA PL011 UART driver Feb 28 19:50:11 volumio-living kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Feb 28 19:50:11 volumio-living kernel: bcm2835-dma fe007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1 Feb 28 19:50:11 volumio-living kernel: vgaarb: loaded Feb 28 19:50:11 volumio-living kernel: SCSI subsystem initialized Feb 28 19:50:11 volumio-living kernel: usbcore: registered new interface driver usbfs Feb 28 19:50:11 volumio-living kernel: usbcore: registered new interface driver hub Feb 28 19:50:11 volumio-living kernel: usbcore: registered new device driver usb Feb 28 19:50:11 volumio-living kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2019-11-29 18:44, variant start Feb 28 19:50:11 volumio-living kernel: raspberrypi-firmware soc:firmware: Firmware hash is aabb1fb5c19d80db268aeccd67b9f4e0f3d48a2a Feb 28 19:50:11 volumio-living kernel: clocksource: Switched to clocksource arch_sys_counter Feb 28 19:50:11 volumio-living kernel: VFS: Disk quotas dquot_6.6.0 Feb 28 19:50:11 volumio-living kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 28 19:50:11 volumio-living kernel: FS-Cache: Loaded Feb 28 19:50:11 volumio-living kernel: CacheFiles: Loaded Feb 28 19:50:11 volumio-living kernel: NET: Registered protocol family 2 Feb 28 19:50:11 volumio-living kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes) Feb 28 19:50:11 volumio-living kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes) Feb 28 19:50:11 volumio-living kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes) Feb 28 19:50:11 volumio-living kernel: TCP: Hash tables configured (established 8192 bind 8192) Feb 28 19:50:11 volumio-living kernel: UDP hash table entries: 512 (order: 2, 16384 bytes) Feb 28 19:50:11 volumio-living kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) Feb 28 19:50:11 volumio-living kernel: NET: Registered protocol family 1 Feb 28 19:50:11 volumio-living kernel: RPC: Registered named UNIX socket transport module. Feb 28 19:50:11 volumio-living kernel: RPC: Registered udp transport module. Feb 28 19:50:11 volumio-living kernel: RPC: Registered tcp transport module. Feb 28 19:50:11 volumio-living kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 28 19:50:11 volumio-living kernel: PCI: CLS 0 bytes, default 64 Feb 28 19:50:11 volumio-living kernel: Trying to unpack rootfs image as initramfs... Feb 28 19:50:11 volumio-living kernel: Freeing initrd memory: 19456K Feb 28 19:50:11 volumio-living kernel: Initialise system trusted keyrings Feb 28 19:50:11 volumio-living kernel: workingset: timestamp_bits=14 max_order=19 bucket_order=5 Feb 28 19:50:11 volumio-living kernel: FS-Cache: Netfs 'nfs' registered for caching Feb 28 19:50:11 volumio-living kernel: NFS: Registering the id_resolver key type Feb 28 19:50:11 volumio-living kernel: Key type id_resolver registered Feb 28 19:50:11 volumio-living kernel: Key type id_legacy registered Feb 28 19:50:11 volumio-living kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 28 19:50:11 volumio-living kernel: Key type asymmetric registered Feb 28 19:50:11 volumio-living kernel: Asymmetric key parser 'x509' registered Feb 28 19:50:11 volumio-living kernel: bounce: pool size: 64 pages Feb 28 19:50:11 volumio-living kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Feb 28 19:50:11 volumio-living kernel: io scheduler noop registered (default) Feb 28 19:50:11 volumio-living kernel: io scheduler deadline registered Feb 28 19:50:11 volumio-living kernel: io scheduler cfq registered Feb 28 19:50:11 volumio-living kernel: io scheduler mq-deadline registered Feb 28 19:50:11 volumio-living kernel: io scheduler kyber registered Feb 28 19:50:11 volumio-living kernel: brcm-pcie fd500000.pcie: could not get clock Feb 28 19:50:11 volumio-living kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Feb 28 19:50:11 volumio-living kernel: brcm-pcie fd500000.pcie: MEM 0x600000000..0x603ffffff -> 0xf8000000 Feb 28 19:50:11 volumio-living kernel: brcm-pcie fd500000.pcie: link up, 5.0 Gbps x1 (!SSC) Feb 28 19:50:11 volumio-living kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Feb 28 19:50:11 volumio-living kernel: pci_bus 0000:00: root bus resource [bus 00-01] Feb 28 19:50:11 volumio-living kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x603ffffff] (bus address [0xf8000000-0xfbffffff]) Feb 28 19:50:11 volumio-living kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Feb 28 19:50:11 volumio-living kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Feb 28 19:50:11 volumio-living kernel: PCI: bus0: Fast back to back transfers disabled Feb 28 19:50:11 volumio-living kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Feb 28 19:50:11 volumio-living kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Feb 28 19:50:11 volumio-living kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Feb 28 19:50:11 volumio-living kernel: pci 0000:01:00.0: PME# supported from D0 D3cold Feb 28 19:50:11 volumio-living kernel: PCI: bus1: Fast back to back transfers disabled Feb 28 19:50:11 volumio-living kernel: pci_bus 0000:01: busn_res: [bus 01] end is updated to 01 Feb 28 19:50:11 volumio-living kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Feb 28 19:50:11 volumio-living kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Feb 28 19:50:11 volumio-living kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Feb 28 19:50:11 volumio-living kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Feb 28 19:50:11 volumio-living kernel: pcieport 0000:00:00.0: enabling device (0140 -> 0142) Feb 28 19:50:11 volumio-living kernel: pcieport 0000:00:00.0: Signaling PME with IRQ 52 Feb 28 19:50:11 volumio-living kernel: pcieport 0000:00:00.0: AER enabled with IRQ 52 Feb 28 19:50:11 volumio-living kernel: pci 0000:01:00.0: enabling device (0140 -> 0142) Feb 28 19:50:11 volumio-living kernel: bcm2708_fb soc:fb: FB found 1 display(s) Feb 28 19:50:11 volumio-living kernel: Console: switching to colour frame buffer device 80x30 Feb 28 19:50:11 volumio-living kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Feb 28 19:50:11 volumio-living kernel: iproc-rng200 fe104000.rng: hwrng registered Feb 28 19:50:11 volumio-living kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Feb 28 19:50:11 volumio-living kernel: vc-sm: Videocore shared memory driver Feb 28 19:50:11 volumio-living kernel: gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000 Feb 28 19:50:11 volumio-living kernel: brd: module loaded Feb 28 19:50:11 volumio-living kernel: loop: module loaded Feb 28 19:50:11 volumio-living kernel: Loading iSCSI transport class v2.0-870. Feb 28 19:50:11 volumio-living kernel: libphy: Fixed MDIO Bus: probed Feb 28 19:50:11 volumio-living kernel: bcmgenet fd580000.genet: failed to get enet clock Feb 28 19:50:11 volumio-living kernel: bcmgenet fd580000.genet: GENET 5.0 EPHY: 0x0000 Feb 28 19:50:11 volumio-living kernel: bcmgenet fd580000.genet: failed to get enet-wol clock Feb 28 19:50:11 volumio-living kernel: bcmgenet fd580000.genet: failed to get enet-eee clock Feb 28 19:50:11 volumio-living kernel: bcmgenet: Skipping UMAC reset Feb 28 19:50:11 volumio-living kernel: unimac-mdio unimac-mdio.-19: DMA mask not set Feb 28 19:50:11 volumio-living kernel: libphy: bcmgenet MII bus: probed Feb 28 19:50:11 volumio-living kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus at 0x(ptrval) Feb 28 19:50:11 volumio-living kernel: usbcore: registered new interface driver r8152 Feb 28 19:50:11 volumio-living kernel: usbcore: registered new interface driver lan78xx Feb 28 19:50:11 volumio-living kernel: usbcore: registered new interface driver smsc95xx Feb 28 19:50:11 volumio-living kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Feb 28 19:50:11 volumio-living kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Feb 28 19:50:11 volumio-living kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000001000000890 Feb 28 19:50:11 volumio-living kernel: genirq: irq_chip Brcm_MSI did not update eff. affinity mask of irq 53 Feb 28 19:50:11 volumio-living kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19 Feb 28 19:50:11 volumio-living kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 28 19:50:11 volumio-living kernel: usb usb1: Product: xHCI Host Controller Feb 28 19:50:11 volumio-living kernel: usb usb1: Manufacturer: Linux 4.19.86-v7l+ xhci-hcd Feb 28 19:50:11 volumio-living kernel: usb usb1: SerialNumber: 0000:01:00.0 Feb 28 19:50:11 volumio-living kernel: hub 1-0:1.0: USB hub found Feb 28 19:50:11 volumio-living kernel: hub 1-0:1.0: 1 port detected Feb 28 19:50:11 volumio-living kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Feb 28 19:50:11 volumio-living kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Feb 28 19:50:11 volumio-living kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Feb 28 19:50:11 volumio-living kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 4.19 Feb 28 19:50:11 volumio-living kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 28 19:50:11 volumio-living kernel: usb usb2: Product: xHCI Host Controller Feb 28 19:50:11 volumio-living kernel: usb usb2: Manufacturer: Linux 4.19.86-v7l+ xhci-hcd Feb 28 19:50:11 volumio-living kernel: usb usb2: SerialNumber: 0000:01:00.0 Feb 28 19:50:11 volumio-living kernel: hub 2-0:1.0: USB hub found Feb 28 19:50:11 volumio-living kernel: hub 2-0:1.0: 4 ports detected Feb 28 19:50:11 volumio-living kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 28 19:50:11 volumio-living kernel: dwc_otg: FIQ enabled Feb 28 19:50:11 volumio-living kernel: dwc_otg: NAK holdoff enabled Feb 28 19:50:11 volumio-living kernel: dwc_otg: FIQ split-transaction FSM enabled Feb 28 19:50:11 volumio-living kernel: Module dwc_common_port init Feb 28 19:50:11 volumio-living kernel: usbcore: registered new interface driver uas Feb 28 19:50:11 volumio-living kernel: usbcore: registered new interface driver usb-storage Feb 28 19:50:11 volumio-living kernel: mousedev: PS/2 mouse device common for all mice Feb 28 19:50:11 volumio-living kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Feb 28 19:50:11 volumio-living kernel: sdhci: Secure Digital Host Controller Interface driver Feb 28 19:50:11 volumio-living kernel: sdhci: Copyright(c) Pierre Ossman Feb 28 19:50:11 volumio-living kernel: mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe Feb 28 19:50:11 volumio-living kernel: sdhci-pltfm: SDHCI platform and OF driver helper Feb 28 19:50:11 volumio-living kernel: ledtrig-cpu: registered to indicate activity on CPUs Feb 28 19:50:11 volumio-living kernel: hidraw: raw HID events driver (C) Jiri Kosina Feb 28 19:50:11 volumio-living kernel: usbcore: registered new interface driver usbhid Feb 28 19:50:11 volumio-living kernel: usbhid: USB HID core driver Feb 28 19:50:11 volumio-living kernel: vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0 Feb 28 19:50:11 volumio-living kernel: [vc_sm_connected_init]: start Feb 28 19:50:11 volumio-living kernel: [vc_sm_connected_init]: end - returning 0 Feb 28 19:50:11 volumio-living kernel: Initializing XFRM netlink socket Feb 28 19:50:11 volumio-living kernel: NET: Registered protocol family 17 Feb 28 19:50:11 volumio-living kernel: Key type dns_resolver registered Feb 28 19:50:11 volumio-living kernel: Registering SWP/SWPB emulation handler Feb 28 19:50:11 volumio-living kernel: registered taskstats version 1 Feb 28 19:50:11 volumio-living kernel: Loading compiled-in X.509 certificates Feb 28 19:50:11 volumio-living kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Feb 28 19:50:11 volumio-living kernel: fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 34, base_baud = 0) is a PL011 rev2 Feb 28 19:50:11 volumio-living kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Feb 28 19:50:11 volumio-living kernel: brcmstb_thermal fd5d2200.thermal: registered AVS TMON of-sensor driver Feb 28 19:50:11 volumio-living kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Feb 28 19:50:11 volumio-living kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Feb 28 19:50:11 volumio-living kernel: sdhci-iproc fe340000.emmc2: Linked as a consumer to regulator.1 Feb 28 19:50:11 volumio-living kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Feb 28 19:50:11 volumio-living kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Feb 28 19:50:11 volumio-living kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Feb 28 19:50:11 volumio-living kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Feb 28 19:50:11 volumio-living kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Feb 28 19:50:11 volumio-living kernel: mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA Feb 28 19:50:11 volumio-living kernel: of_cfs_init Feb 28 19:50:11 volumio-living kernel: of_cfs_init: OK Feb 28 19:50:11 volumio-living kernel: Freeing unused kernel memory: 2048K Feb 28 19:50:11 volumio-living kernel: Run /init as init process Feb 28 19:50:11 volumio-living kernel: random: fast init done Feb 28 19:50:11 volumio-living kernel: mmc0: new ultra high speed DDR50 SDHC card at address 0007 Feb 28 19:50:11 volumio-living kernel: mmc1: new high speed SDIO card at address 0001 Feb 28 19:50:11 volumio-living kernel: mmcblk0: mmc0:0007 SD32G 29.0 GiB Feb 28 19:50:11 volumio-living kernel: mmcblk0: p1 p2 p3 Feb 28 19:50:11 volumio-living initramfs: Booting Volumio for BCM2835 Feb 28 19:50:11 volumio-living initramfs: This script mounts rootfs RO with an overlay RW layer. Feb 28 19:50:11 volumio-living kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 28 19:50:11 volumio-living kernel: fuse init (API version 7.27) Feb 28 19:50:11 volumio-living initramfs: IMGPART=/dev/mmcblk0p2 Feb 28 19:50:11 volumio-living initramfs: IMGFILE=/volumio_current.sqsh Feb 28 19:50:11 volumio-living initramfs: Boot delay (except first time) will be 5 seconds Feb 28 19:50:11 volumio-living kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Feb 28 19:50:11 volumio-living initramfs: /dev/mmcblk0p2: Feb 28 19:50:11 volumio-living kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Feb 28 19:50:11 volumio-living kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Feb 28 19:50:11 volumio-living kernel: usb 1-1: Product: USB2.0 Hub Feb 28 19:50:11 volumio-living kernel: hub 1-1:1.0: USB hub found Feb 28 19:50:11 volumio-living kernel: hub 1-1:1.0: 4 ports detected Feb 28 19:50:11 volumio-living kernel: EXT4-fs (mmcblk0p2): recovery complete Feb 28 19:50:11 volumio-living kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Feb 28 19:50:11 volumio-living initramfs: Doing a 5 second delay here to give kernel load a headstart Feb 28 19:50:11 volumio-living initramfs: Checking for USB updates Feb 28 19:50:11 volumio-living initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Feb 28 19:50:11 volumio-living kernel: EXT4-fs (mmcblk0p3): recovery complete Feb 28 19:50:11 volumio-living kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Feb 28 19:50:11 volumio-living initramfs: With Option: Feb 28 19:50:11 volumio-living initramfs: VOLUMIO_VERSION="2.703" Feb 28 19:50:11 volumio-living initramfs: Finish initramfs, continue booting Volumio Feb 28 19:50:11 volumio-living kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 28 19:50:11 volumio-living systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) Feb 28 19:50:11 volumio-living systemd[1]: Detected architecture 'arm'. Feb 28 19:50:11 volumio-living kernel: NET: Registered protocol family 10 Feb 28 19:50:11 volumio-living kernel: Segment Routing with IPv6 Feb 28 19:50:11 volumio-living systemd[1]: Inserted module 'ipv6' Feb 28 19:50:11 volumio-living systemd[1]: Set hostname to . Feb 28 19:50:11 volumio-living kernel: random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) Feb 28 19:50:11 volumio-living kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 28 19:50:11 volumio-living systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Feb 28 19:50:11 volumio-living systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Feb 28 19:50:11 volumio-living systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Feb 28 19:50:11 volumio-living systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Feb 28 19:50:11 volumio-living systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory. Feb 28 19:50:11 volumio-living systemd[1]: Starting Forward Password Requests to Wall Directory Watch. Feb 28 19:50:11 volumio-living kernel: i2c /dev entries driver Feb 28 19:50:11 volumio-living systemd-journal[174]: Journal started Feb 28 19:50:11 volumio-living systemd[1]: Starting Slices. Feb 28 19:50:11 volumio-living systemd[1]: Reached target Slices. Feb 28 19:50:11 volumio-living systemd[1]: Starting Remount Root and Kernel File Systems... Feb 28 19:50:11 volumio-living systemd[1]: Started Restore / save the current clock. Feb 28 19:50:11 volumio-living systemd[1]: Started Create list of required static device nodes for the current kernel. Feb 28 19:50:11 volumio-living systemd[1]: Time has been changed Feb 28 19:50:11 volumio-living systemd[1]: Starting Create Static Device Nodes in /dev... Feb 28 19:50:11 volumio-living systemd[1]: Mounted POSIX Message Queue File System. Feb 28 19:50:11 volumio-living systemd[1]: Started Remount Root and Kernel File Systems. Feb 28 19:50:11 volumio-living systemd[1]: Started Various fixups to make systemd work better on Debian. Feb 28 19:50:11 volumio-living systemd[1]: Starting Load/Save Random Seed... Feb 28 19:50:11 volumio-living systemd-modules-load[171]: Inserted module 'i2c_dev' Feb 28 19:50:11 volumio-living systemd[1]: Reached target Local File Systems (Pre). Feb 28 19:50:11 volumio-living systemd[1]: Mounting /tmp... Feb 28 19:50:11 volumio-living systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Feb 28 19:50:11 volumio-living systemd[1]: Mounting /var/spool/cups... Feb 28 19:50:11 volumio-living systemd[1]: Mounting /var/log... Feb 28 19:50:11 volumio-living systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Feb 28 19:50:11 volumio-living systemd[1]: Mounted /var/spool/cups. Feb 28 19:50:11 volumio-living systemd[1]: Mounted /tmp. Feb 28 19:50:11 volumio-living systemd[1]: Started udev Kernel Device Manager. Feb 28 19:50:11 volumio-living systemd[1]: Started Load Kernel Modules. Feb 28 19:50:11 volumio-living systemd-udevd[197]: starting version 215 Feb 28 19:50:11 volumio-living fake-hwclock[161]: Fri Feb 28 19:50:11 UTC 2020 Feb 28 19:50:11 volumio-living systemd[1]: Mounted /var/log. Feb 28 19:50:11 volumio-living systemd[1]: Mounting FUSE Control File System... Feb 28 19:50:11 volumio-living systemd[1]: Mounting Configuration File System... Feb 28 19:50:11 volumio-living systemd[1]: Starting Apply Kernel Variables... Feb 28 19:50:11 volumio-living systemd[1]: Starting Show Plymouth Boot Screen... Feb 28 19:50:11 volumio-living systemd[1]: Starting Copy rules generated while the root was ro... Feb 28 19:50:11 volumio-living systemd[1]: Mounting /var/spool/cups/tmp... Feb 28 19:50:11 volumio-living systemd[1]: Mounted Configuration File System. Feb 28 19:50:11 volumio-living systemd[1]: Mounted FUSE Control File System. Feb 28 19:50:11 volumio-living systemd[1]: Started Apply Kernel Variables. Feb 28 19:50:11 volumio-living systemd[1]: Started Copy rules generated while the root was ro. Feb 28 19:50:11 volumio-living systemd[1]: Mounted /var/spool/cups/tmp. Feb 28 19:50:11 volumio-living systemd[1]: Started Show Plymouth Boot Screen. Feb 28 19:50:11 volumio-living systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch. Feb 28 19:50:11 volumio-living systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Feb 28 19:50:11 volumio-living systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Feb 28 19:50:11 volumio-living systemd[1]: Starting Paths. Feb 28 19:50:11 volumio-living systemd[1]: Reached target Paths. Feb 28 19:50:11 volumio-living kernel: rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x0000ffff Feb 28 19:50:11 volumio-living kernel: rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00000fff Feb 28 19:50:11 volumio-living kernel: rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x0000ffff Feb 28 19:50:11 volumio-living kernel: rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x0000ffff Feb 28 19:50:11 volumio-living kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Feb 28 19:50:11 volumio-living kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Feb 28 19:50:11 volumio-living kernel: [vc_sm_connected_init]: start Feb 28 19:50:11 volumio-living kernel: [vc_sm_connected_init]: installed successfully Feb 28 19:50:11 volumio-living kernel: media: Linux media interface: v0.10 Feb 28 19:50:11 volumio-living kernel: videodev: Linux video capture interface: v2.00 Feb 28 19:50:12 volumio-living kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Feb 28 19:50:12 volumio-living kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Feb 28 19:50:12 volumio-living kernel: snd_bcm2835: unknown parameter 'index' ignored Feb 28 19:50:12 volumio-living kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Feb 28 19:50:12 volumio-living kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Feb 28 19:50:12 volumio-living kernel: bcm2835_audio soc:audio: card created with 8 channels Feb 28 19:50:12 volumio-living kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Feb 28 19:50:12 volumio-living kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Feb 28 19:50:12 volumio-living kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Feb 28 19:50:12 volumio-living kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Feb 28 19:50:12 volumio-living kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Feb 28 19:50:12 volumio-living kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Feb 28 19:50:12 volumio-living kernel: snd-rpi-wm8804 soc:sound: ASoC: CPU DAI (null) not registered - will retry Feb 28 19:50:12 volumio-living kernel: snd-rpi-wm8804 soc:sound: ASoC: CODEC DAI wm8804-spdif not registered - will retry Feb 28 19:50:12 volumio-living kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Feb 28 19:50:12 volumio-living systemd[1]: Starting system-ifup.slice. Feb 28 19:50:12 volumio-living systemd[1]: Created slice system-ifup.slice. Feb 28 19:50:12 volumio-living kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Feb 28 19:50:12 volumio-living kernel: platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 Feb 28 19:50:12 volumio-living kernel: cfg80211: failed to load regulatory.db Feb 28 19:50:12 volumio-living systemd[1]: Starting Sound Card. Feb 28 19:50:12 volumio-living systemd[1]: Reached target Sound Card. Feb 28 19:50:12 volumio-living kernel: snd-rpi-wm8804 soc:sound: ASoC: CODEC DAI wm8804-spdif not registered - will retry Feb 28 19:50:12 volumio-living kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Feb 28 19:50:12 volumio-living kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Feb 28 19:50:12 volumio-living kernel: usbcore: registered new interface driver brcmfmac Feb 28 19:50:12 volumio-living kernel: snd-rpi-wm8804 soc:sound: ASoC: CODEC DAI wm8804-spdif not registered - will retry Feb 28 19:50:12 volumio-living kernel: wm8804 1-003b: Linked as a consumer to regulator.2 Feb 28 19:50:12 volumio-living kernel: wm8804 1-003b: revision E Feb 28 19:50:12 volumio-living systemd[1]: Found device /dev/mmcblk0p1. Feb 28 19:50:12 volumio-living systemd[1]: Starting File System Check on /dev/mmcblk0p1... Feb 28 19:50:12 volumio-living kernel: snd-rpi-wm8804 soc:sound: wm8804-spdif <-> fe203000.i2s mapping ok Feb 28 19:50:12 volumio-living systemd-fsck[337]: fsck.fat 3.0.27 (2014-11-12) Feb 28 19:50:12 volumio-living systemd-fsck[337]: /dev/mmcblk0p1: 227 files, 119241/123044 clusters Feb 28 19:50:12 volumio-living systemd[1]: Started File System Check on /dev/mmcblk0p1. Feb 28 19:50:12 volumio-living systemd[1]: Mounting /boot... Feb 28 19:50:13 volumio-living systemd[1]: Mounted /boot. Feb 28 19:50:13 volumio-living systemd[1]: Starting Local File Systems. Feb 28 19:50:13 volumio-living systemd[1]: Reached target Local File Systems. Feb 28 19:50:13 volumio-living systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Feb 28 19:50:13 volumio-living systemd[1]: Starting Remote File Systems. Feb 28 19:50:13 volumio-living systemd[1]: Reached target Remote File Systems. Feb 28 19:50:13 volumio-living systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... Feb 28 19:50:13 volumio-living systemd[1]: Starting Create Volatile Files and Directories... Feb 28 19:50:13 volumio-living systemd[1]: Starting LSB: Raise network interfaces.... Feb 28 19:50:13 volumio-living systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Feb 28 19:50:13 volumio-living kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Feb 28 19:50:13 volumio-living systemd[1]: Started Create Volatile Files and Directories. Feb 28 19:50:13 volumio-living kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Feb 27 2018 03:15:32 version 7.45.154 (r684107 CY) FWID 01-4fbe0b04 Feb 28 19:50:13 volumio-living systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. Feb 28 19:50:13 volumio-living systemd[1]: Starting system-systemd\x2drfkill.slice. Feb 28 19:50:13 volumio-living systemd[1]: Created slice system-systemd\x2drfkill.slice. Feb 28 19:50:13 volumio-living systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill0... Feb 28 19:50:13 volumio-living systemd[1]: Starting Update UTMP about System Boot/Shutdown... Feb 28 19:50:13 volumio-living systemd[1]: Started Load/Save RF Kill Switch Status of rfkill0. Feb 28 19:50:13 volumio-living systemd[1]: Started Update UTMP about System Boot/Shutdown. Feb 28 19:50:13 volumio-living kernel: brcmfmac: power management disabled Feb 28 19:50:14 volumio-living systemd[1]: Received SIGRTMIN+20 from PID 226 (plymouthd). Feb 28 19:50:14 volumio-living kernel: bcmgenet: Skipping UMAC reset Feb 28 19:50:15 volumio-living kernel: bcmgenet fd580000.genet: configuring instance for external RGMII (no delay) Feb 28 19:50:15 volumio-living networking[351]: Configuring network interfaces...done. Feb 28 19:50:15 volumio-living kernel: brcmfmac: power management disabled Feb 28 19:50:15 volumio-living systemd[1]: Started LSB: Raise network interfaces.. Feb 28 19:50:15 volumio-living systemd[1]: Starting ifup for wlan0... Feb 28 19:50:15 volumio-living systemd[1]: Started ifup for wlan0. Feb 28 19:50:15 volumio-living systemd[1]: Starting ifup for eth0... Feb 28 19:50:15 volumio-living ifup[491]: /sbin/ifup: interface wlan0 already configured Feb 28 19:50:15 volumio-living systemd[1]: Started ifup for eth0. Feb 28 19:50:15 volumio-living systemd[1]: Starting Network. Feb 28 19:50:15 volumio-living systemd[1]: Reached target Network. Feb 28 19:50:15 volumio-living systemd[1]: Starting Network is Online. Feb 28 19:50:15 volumio-living systemd[1]: Reached target Network is Online. Feb 28 19:50:15 volumio-living systemd[1]: Starting LSB: RPC portmapper replacement... Feb 28 19:50:15 volumio-living rpcbind[494]: Starting rpcbind daemon.... Feb 28 19:50:15 volumio-living systemd[1]: Started LSB: RPC portmapper replacement. Feb 28 19:50:15 volumio-living systemd[1]: Starting RPC Port Mapper. Feb 28 19:50:15 volumio-living systemd[1]: Reached target RPC Port Mapper. Feb 28 19:50:15 volumio-living systemd[1]: Starting LSB: NFS support files common to client and server... Feb 28 19:50:15 volumio-living rpc.statd[568]: Version 1.2.8 starting Feb 28 19:50:15 volumio-living sm-notify[569]: Version 1.2.8 starting Feb 28 19:50:15 volumio-living kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Feb 28 19:50:15 volumio-living nfs-common[562]: Starting NFS common utilities: statd idmapd. Feb 28 19:50:15 volumio-living systemd[1]: Started LSB: NFS support files common to client and server. Feb 28 19:50:15 volumio-living systemd[1]: Starting System Initialization. Feb 28 19:50:15 volumio-living systemd[1]: Reached target System Initialization. Feb 28 19:50:15 volumio-living systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. Feb 28 19:50:15 volumio-living systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Feb 28 19:50:15 volumio-living systemd[1]: Starting mpd.socket. Feb 28 19:50:15 volumio-living systemd[1]: Listening on mpd.socket. Feb 28 19:50:15 volumio-living systemd[1]: Starting D-Bus System Message Bus Socket. Feb 28 19:50:15 volumio-living systemd[1]: Listening on D-Bus System Message Bus Socket. Feb 28 19:50:15 volumio-living systemd[1]: Starting Sockets. Feb 28 19:50:15 volumio-living systemd[1]: Reached target Sockets. Feb 28 19:50:15 volumio-living systemd[1]: Starting Daily Cleanup of Temporary Directories. Feb 28 19:50:15 volumio-living systemd[1]: Started Daily Cleanup of Temporary Directories. Feb 28 19:50:15 volumio-living systemd[1]: Starting Timers. Feb 28 19:50:15 volumio-living systemd[1]: Reached target Timers. Feb 28 19:50:15 volumio-living systemd[1]: Started Manage Sound Card State (restore and store). Feb 28 19:50:15 volumio-living systemd[1]: Starting Restore Sound Card State... Feb 28 19:50:15 volumio-living systemd[1]: Starting Basic System. Feb 28 19:50:15 volumio-living systemd[1]: Reached target Basic System. Feb 28 19:50:15 volumio-living systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... Feb 28 19:50:15 volumio-living systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Feb 28 19:50:15 volumio-living systemd[1]: Starting cd.service... Feb 28 19:50:15 volumio-living systemd[1]: Started cd.service. Feb 28 19:50:15 volumio-living systemd[1]: Starting Configure Bluetooth Modems connected by UART... Feb 28 19:50:15 volumio-living systemd[1]: Starting Volumio Iptables Module... Feb 28 19:50:15 volumio-living systemd[1]: Started Volumio Iptables Module. Feb 28 19:50:15 volumio-living systemd[1]: Starting mount disks automatically with standby... Feb 28 19:50:15 volumio-living systemd[1]: Started mount disks automatically with standby. Feb 28 19:50:15 volumio-living systemd[1]: Starting UPnP Renderer front-end to MPD... Feb 28 19:50:15 volumio-living systemd[1]: Started UPnP Renderer front-end to MPD. Feb 28 19:50:15 volumio-living systemd[1]: Starting volumio-remote-updater.service... Feb 28 19:50:15 volumio-living systemd[1]: Started volumio-remote-updater.service. Feb 28 19:50:15 volumio-living systemd[1]: Starting Volumio SSH enabler... Feb 28 19:50:16 volumio-living volumio[583]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Feb 28 19:50:16 volumio-living systemd[1]: Started Volumio SSH enabler. Feb 28 19:50:16 volumio-living systemd[1]: Starting Wireless Services... Feb 28 19:50:16 volumio-living systemd[1]: Starting Xbindrc... Feb 28 19:50:16 volumio-living systemd[1]: Started Xbindrc. Feb 28 19:50:16 volumio-living systemd[1]: Starting OpenBSD Secure Shell server... Feb 28 19:50:16 volumio-living systemd[1]: Starting /etc/rc.local Compatibility... Feb 28 19:50:16 volumio-living systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. Feb 28 19:50:16 volumio-living systemd[1]: Starting Login Service... Feb 28 19:50:16 volumio-living kernel: bcmgenet fd580000.genet eth0: Link is Down Feb 28 19:50:16 volumio-living systemd[1]: Starting LSB: start Winbind daemon... Feb 28 19:50:16 volumio-living systemd[1]: Starting LSB: start Samba daemons for the AD DC... Feb 28 19:50:16 volumio-living systemd[1]: Starting LSB: Start NTP daemon... Feb 28 19:50:16 volumio-living systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... Feb 28 19:50:16 volumio-living systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Feb 28 19:50:16 volumio-living systemd[1]: Starting LSB: Brings up/down network automatically... Feb 28 19:50:16 volumio-living systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... Feb 28 19:50:16 volumio-living kernel: uart-pl011 fe201000.serial: no DMA platform data Feb 28 19:50:16 volumio-living systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... Feb 28 19:50:16 volumio-living systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Feb 28 19:50:16 volumio-living systemd[1]: Starting Music Player Daemon... Feb 28 19:50:16 volumio-living systemd[1]: Starting D-Bus System Message Bus... Feb 28 19:50:16 volumio-living systemd[1]: Started D-Bus System Message Bus. Feb 28 19:50:16 volumio-living btuart[584]: Patch not found for BCM4345C0, continue anyway Feb 28 19:50:16 volumio-living ifplugd(eth0)[690]: ifplugd 0.28 initializing. Feb 28 19:50:16 volumio-living ifplugd(eth0)[690]: Using interface eth0/DC:A6:32:61:FE:4B with driver (version: v2.0) Feb 28 19:50:16 volumio-living ifplugd(eth0)[690]: Using detection mode: SIOCETHTOOL Feb 28 19:50:16 volumio-living ifplugd(eth0)[690]: Initialization complete, link beat not detected. Feb 28 19:50:16 volumio-living loadcpufreq[620]: Loading cpufreq kernel modules...done (none). Feb 28 19:50:16 volumio-living ifplugd[634]: Network Interface Plugging Daemon...start eth0...done. Feb 28 19:50:16 volumio-living avahi-daemon[645]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). Feb 28 19:50:16 volumio-living avahi-daemon[645]: Successfully dropped root privileges. Feb 28 19:50:16 volumio-living avahi-daemon[645]: avahi-daemon 0.6.31 starting up. Feb 28 19:50:16 volumio-living systemd[1]: Starting Permit User Sessions... Feb 28 19:50:16 volumio-living systemd[1]: Started Restore Sound Card State. Feb 28 19:50:16 volumio-living systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Feb 28 19:50:16 volumio-living systemd[1]: Unit cd.service entered failed state. Feb 28 19:50:16 volumio-living avahi-daemon[645]: Successfully called chroot(). Feb 28 19:50:16 volumio-living avahi-daemon[645]: Successfully dropped remaining capabilities. Feb 28 19:50:16 volumio-living systemd[1]: Started OpenBSD Secure Shell server. Feb 28 19:50:16 volumio-living avahi-daemon[645]: Loading service file /services/volumio.service. Feb 28 19:50:16 volumio-living avahi-daemon[645]: Network interface enumeration completed. Feb 28 19:50:16 volumio-living avahi-daemon[645]: Registering HINFO record with values 'ARMV7L'/'LINUX'. Feb 28 19:50:16 volumio-living avahi-daemon[645]: Server startup complete. Host name is volumio-living.local. Local service cookie is 2741354064. Feb 28 19:50:16 volumio-living avahi-daemon[645]: Service "Volumio-Living" (/services/volumio.service) successfully established. Feb 28 19:50:16 volumio-living systemd[1]: Started /etc/rc.local Compatibility. Feb 28 19:50:16 volumio-living systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Feb 28 19:50:16 volumio-living systemd[1]: Started LSB: Brings up/down network automatically. Feb 28 19:50:16 volumio-living systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. Feb 28 19:50:16 volumio-living systemd[1]: Started Permit User Sessions. Feb 28 19:50:16 volumio-living sshd[709]: Server listening on 0.0.0.0 port 22. Feb 28 19:50:16 volumio-living sshd[709]: Server listening on :: port 22. Feb 28 19:50:16 volumio-living systemd[1]: cd.service holdoff time over, scheduling restart. Feb 28 19:50:16 volumio-living dbus[649]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' Feb 28 19:50:16 volumio-living systemd[1]: Started Avahi mDNS/DNS-SD Stack. Feb 28 19:50:16 volumio-living systemd-logind[611]: New seat seat0. Feb 28 19:50:16 volumio-living systemd[1]: Started Login Service. Feb 28 19:50:16 volumio-living systemd[1]: Starting Disk Manager (legacy version)... Feb 28 19:50:16 volumio-living systemd[1]: Stopping cd.service... Feb 28 19:50:16 volumio-living systemd[1]: Starting cd.service... Feb 28 19:50:16 volumio-living systemd[1]: Started cd.service. Feb 28 19:50:16 volumio-living systemd[1]: Starting LSB: set CPUFreq kernel parameters... Feb 28 19:50:16 volumio-living systemd[1]: Starting Hold until boot process finishes up... Feb 28 19:50:16 volumio-living dhcpcd[712]: version 6.0.5 starting Feb 28 19:50:16 volumio-living dhcpcd[643]: dhcpcd[712]: version 6.0.5 starting Feb 28 19:50:16 volumio-living systemd[1]: Starting Terminate Plymouth Boot Screen... Feb 28 19:50:17 volumio-living volumio[713]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Feb 28 19:50:17 volumio-living ntpd[674]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) Feb 28 19:50:17 volumio-living systemd[1]: Received SIGRTMIN+21 from PID 226 (plymouthd). Feb 28 19:50:17 volumio-living systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Feb 28 19:50:17 volumio-living systemd[1]: Unit cd.service entered failed state. Feb 28 19:50:17 volumio-living systemd[1]: Started Hold until boot process finishes up. Feb 28 19:50:17 volumio-living ntp[616]: Starting NTP server: ntpd. Feb 28 19:50:17 volumio-living systemd[1]: Started Terminate Plymouth Boot Screen. Feb 28 19:50:17 volumio-living systemd[1]: Started LSB: Start NTP daemon. Feb 28 19:50:17 volumio-living systemd[1]: Starting Getty on tty1... Feb 28 19:50:17 volumio-living systemd[1]: Started Getty on tty1. Feb 28 19:50:17 volumio-living systemd[1]: Starting Login Prompts. Feb 28 19:50:17 volumio-living systemd[1]: Reached target Login Prompts. Feb 28 19:50:17 volumio-living systemd[1]: cd.service holdoff time over, scheduling restart. Feb 28 19:50:17 volumio-living systemd[1]: Stopping cd.service... Feb 28 19:50:17 volumio-living systemd[1]: Starting cd.service... Feb 28 19:50:17 volumio-living systemd[1]: Started cd.service. Feb 28 19:50:17 volumio-living volumio[741]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Feb 28 19:50:17 volumio-living systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Feb 28 19:50:17 volumio-living systemd[1]: Unit cd.service entered failed state. Feb 28 19:50:17 volumio-living systemd[1]: cd.service holdoff time over, scheduling restart. Feb 28 19:50:17 volumio-living systemd[1]: Stopping cd.service... Feb 28 19:50:17 volumio-living systemd[1]: Starting cd.service... Feb 28 19:50:17 volumio-living systemd[1]: Started cd.service. Feb 28 19:50:17 volumio-living volumio[743]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Feb 28 19:50:17 volumio-living systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Feb 28 19:50:17 volumio-living systemd[1]: Unit cd.service entered failed state. Feb 28 19:50:18 volumio-living systemd[1]: cd.service holdoff time over, scheduling restart. Feb 28 19:50:18 volumio-living systemd[1]: Stopping cd.service... Feb 28 19:50:18 volumio-living systemd[1]: Starting cd.service... Feb 28 19:50:18 volumio-living systemd[1]: Started cd.service. Feb 28 19:50:18 volumio-living volumio[745]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Feb 28 19:50:18 volumio-living systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Feb 28 19:50:18 volumio-living systemd[1]: Unit cd.service entered failed state. Feb 28 19:50:18 volumio-living dhcpcd[712]: `undefined' is not a valid IP address Feb 28 19:50:18 volumio-living dhcpcd[643]: dhcpcd[712]: `undefined' is not a valid IP address Feb 28 19:50:18 volumio-living cpufrequtils[714]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Feb 28 19:50:18 volumio-living systemd[1]: Started LSB: set CPUFreq kernel parameters. Feb 28 19:50:18 volumio-living systemd[1]: cd.service holdoff time over, scheduling restart. Feb 28 19:50:18 volumio-living systemd[1]: Stopping cd.service... Feb 28 19:50:18 volumio-living systemd[1]: Starting cd.service... Feb 28 19:50:18 volumio-living systemd[1]: cd.service start request repeated too quickly, refusing to start. Feb 28 19:50:18 volumio-living systemd[1]: Failed to start cd.service. Feb 28 19:50:18 volumio-living systemd[1]: Unit cd.service entered failed state. Feb 28 19:50:18 volumio-living dhcpcd[712]: eth0: waiting for carrier Feb 28 19:50:18 volumio-living dhcpcd[712]: wlan0: waiting for carrier Feb 28 19:50:18 volumio-living dhcpcd[643]: dhcpcd[712]: eth0: waiting for carrier Feb 28 19:50:18 volumio-living dhcpcd[643]: dhcpcd[712]: wlan0: waiting for carrier Feb 28 19:50:18 volumio-living dbus[649]: [system] Successfully activated service 'org.freedesktop.UDisks' Feb 28 19:50:18 volumio-living systemd[1]: Started Disk Manager (legacy version). Feb 28 19:50:18 volumio-living haveged[582]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K Feb 28 19:50:18 volumio-living haveged[582]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244 Feb 28 19:50:18 volumio-living haveged[582]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99795 Feb 28 19:50:18 volumio-living haveged[582]: haveged: fills: 0, generated: 0 Feb 28 19:50:18 volumio-living kernel: random: crng init done Feb 28 19:50:18 volumio-living kernel: random: 7 urandom warning(s) missed due to ratelimiting Feb 28 19:50:18 volumio-living kernel: Bluetooth: Core ver 2.22 Feb 28 19:50:18 volumio-living kernel: NET: Registered protocol family 31 Feb 28 19:50:18 volumio-living kernel: Bluetooth: HCI device and connection manager initialized Feb 28 19:50:18 volumio-living kernel: Bluetooth: HCI socket layer initialized Feb 28 19:50:18 volumio-living kernel: Bluetooth: L2CAP socket layer initialized Feb 28 19:50:18 volumio-living kernel: Bluetooth: SCO socket layer initialized Feb 28 19:50:18 volumio-living btuart[584]: bcm43xx_init Feb 28 19:50:18 volumio-living btuart[584]: Set BDADDR UART: b8:27:eb:1a:2c:3d Feb 28 19:50:18 volumio-living btuart[584]: Set Controller UART speed to 3000000 bit/s Feb 28 19:50:18 volumio-living btuart[584]: Device setup complete Feb 28 19:50:18 volumio-living kernel: Bluetooth: HCI UART driver ver 2.3 Feb 28 19:50:18 volumio-living kernel: Bluetooth: HCI UART protocol H4 registered Feb 28 19:50:18 volumio-living kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Feb 28 19:50:18 volumio-living kernel: Bluetooth: HCI UART protocol Broadcom registered Feb 28 19:50:18 volumio-living systemd[1]: Started Configure Bluetooth Modems connected by UART. Feb 28 19:50:18 volumio-living systemd-udevd[763]: failed to execute '/usr/bin/hciconfig' '/usr/bin/hciconfig hci0 up': No such file or directory Feb 28 19:50:18 volumio-living sudo[608]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Feb 28 19:50:18 volumio-living sudo[608]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:50:18 volumio-living sudo[608]: pam_unix(sudo:session): session closed for user root Feb 28 19:50:18 volumio-living ntpd[723]: proto: precision = 0.463 usec Feb 28 19:50:18 volumio-living ntpd[723]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Feb 28 19:50:18 volumio-living ntpd[723]: Listen and drop on 1 v6wildcard :: UDP 123 Feb 28 19:50:18 volumio-living ntpd[723]: Listen normally on 2 lo 127.0.0.1 UDP 123 Feb 28 19:50:18 volumio-living ntpd[723]: peers refreshed Feb 28 19:50:18 volumio-living ntpd[723]: Listening on routing socket on fd #19 for interface updates Feb 28 19:50:18 volumio-living systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill1... Feb 28 19:50:18 volumio-living systemd[1]: Starting Bluetooth service... Feb 28 19:50:18 volumio-living systemd[1]: Started Load/Save RF Kill Switch Status of rfkill1. Feb 28 19:50:18 volumio-living dbus[649]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' Feb 28 19:50:18 volumio-living systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... Feb 28 19:50:18 volumio-living polkitd[780]: started daemon version 0.105 using authority implementation `local' version `0.105' Feb 28 19:50:18 volumio-living dbus[649]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Feb 28 19:50:18 volumio-living systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. Feb 28 19:50:18 volumio-living volumio-remote-updater[591]: Error: No active session Feb 28 19:50:18 volumio-living volumio-remote-updater[591]: [2020-02-28 19:50:18] [info] asio async_connect error: system:111 (Connection refused) Feb 28 19:50:18 volumio-living volumio-remote-updater[591]: [2020-02-28 19:50:18] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 28 19:50:18 volumio-living volumio-remote-updater[591]: [2020-02-28 19:50:18] [error] handle_connect error: Underlying Transport Error Feb 28 19:50:18 volumio-living udisks-glue[586]: Device file /dev/mmcblk0p3 inserted Feb 28 19:50:18 volumio-living udisks-glue[586]: Device file /dev/mmcblk0 inserted Feb 28 19:50:18 volumio-living volumio[587]: Could not open config: /tmp/upmpdcli.conf Feb 28 19:50:18 volumio-living systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE Feb 28 19:50:18 volumio-living systemd[1]: Unit upmpdcli.service entered failed state. Feb 28 19:50:18 volumio-living bluetoothd[773]: Bluetooth daemon 5.23 Feb 28 19:50:19 volumio-living systemd[1]: Started Bluetooth service. Feb 28 19:50:19 volumio-living systemd[1]: Starting Bluetooth. Feb 28 19:50:19 volumio-living systemd[1]: Reached target Bluetooth. Feb 28 19:50:19 volumio-living bluetoothd[773]: Starting SDP server Feb 28 19:50:19 volumio-living bluetoothd[773]: Excluding (cli) sap Feb 28 19:50:19 volumio-living kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Feb 28 19:50:19 volumio-living kernel: Bluetooth: BNEP filters: protocol multicast Feb 28 19:50:19 volumio-living kernel: Bluetooth: BNEP socket layer initialized Feb 28 19:50:19 volumio-living bluetoothd[773]: Bluetooth management interface 1.14 initialized Feb 28 19:50:19 volumio-living dbus[649]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' Feb 28 19:50:19 volumio-living systemd[1]: Starting Hostname Service... Feb 28 19:50:19 volumio-living systemd-hostnamed[788]: Warning: nss-myhostname is not installed. Changing the local hostname might make it unresolveable. Please install nss-myhostname! Feb 28 19:50:19 volumio-living dbus[649]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 28 19:50:19 volumio-living systemd[1]: Started Hostname Service. Feb 28 19:50:19 volumio-living dhcpcd[712]: eth0: carrier acquired Feb 28 19:50:19 volumio-living dhcpcd[643]: dhcpcd[712]: eth0: carrier acquired Feb 28 19:50:19 volumio-living kernel: bcmgenet fd580000.genet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Feb 28 19:50:19 volumio-living dhcpcd[712]: eth0: soliciting an IPv6 router Feb 28 19:50:19 volumio-living dhcpcd[643]: dhcpcd[712]: eth0: soliciting an IPv6 router Feb 28 19:50:19 volumio-living dhcpcd[712]: eth0: using static address 192.168.178.63 Feb 28 19:50:19 volumio-living avahi-daemon[645]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.63. Feb 28 19:50:19 volumio-living dhcpcd[712]: eth0: adding host route to 192.168.178.63 via 127.0.0.1 Feb 28 19:50:19 volumio-living avahi-daemon[645]: New relevant interface eth0.IPv4 for mDNS. Feb 28 19:50:19 volumio-living dhcpcd[712]: eth0: adding route to 192.168.178.0/24 Feb 28 19:50:19 volumio-living avahi-daemon[645]: Registering new address record for 192.168.178.63 on eth0.IPv4. Feb 28 19:50:19 volumio-living dhcpcd[712]: eth0: adding default route via 192.168.178.1 Feb 28 19:50:19 volumio-living dhcpcd[643]: dhcpcd[712]: eth0: using static address 192.168.178.63 Feb 28 19:50:19 volumio-living dhcpcd[643]: dhcpcd[712]: eth0: adding host route to 192.168.178.63 via 127.0.0.1 Feb 28 19:50:19 volumio-living dhcpcd[643]: dhcpcd[712]: eth0: adding route to 192.168.178.0/24 Feb 28 19:50:19 volumio-living dhcpcd[643]: dhcpcd[712]: eth0: adding default route via 192.168.178.1 Feb 28 19:50:19 volumio-living ifplugd(eth0)[690]: Link beat detected. Feb 28 19:50:19 volumio-living ifplugd(eth0)[690]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 28 19:50:20 volumio-living ifplugd(eth0)[690]: client: /sbin/ifup: interface eth0 already configured Feb 28 19:50:20 volumio-living dhcpcd[643]: dhcpcd[712]: forked to background, child pid 825 Feb 28 19:50:20 volumio-living dhcpcd[712]: forked to background, child pid 825 Feb 28 19:50:20 volumio-living systemd[1]: Started LSB: IPv4 DHCP client with IPv4LL support. Feb 28 19:50:20 volumio-living ifplugd(eth0)[690]: Program executed successfully. Feb 28 19:50:21 volumio-living systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Feb 28 19:50:21 volumio-living systemd[1]: Stopping Xbindrc... Feb 28 19:50:21 volumio-living systemd[1]: Starting Xbindrc... Feb 28 19:50:21 volumio-living systemd[1]: Started Xbindrc. Feb 28 19:50:21 volumio-living systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Feb 28 19:50:21 volumio-living systemd[1]: Stopping Xbindrc... Feb 28 19:50:21 volumio-living systemd[1]: Starting Xbindrc... Feb 28 19:50:21 volumio-living systemd[1]: Started Xbindrc. Feb 28 19:50:21 volumio-living ntpd[723]: Deferring DNS for 0.debian.pool.ntp.org 1 Feb 28 19:50:21 volumio-living ntpd[841]: signal_no_reset: signal 17 had flags 4000000 Feb 28 19:50:21 volumio-living systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Feb 28 19:50:21 volumio-living systemd[1]: Stopping Xbindrc... Feb 28 19:50:21 volumio-living systemd[1]: Starting Xbindrc... Feb 28 19:50:21 volumio-living systemd[1]: Started Xbindrc. Feb 28 19:50:22 volumio-living systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Feb 28 19:50:22 volumio-living systemd[1]: Stopping Xbindrc... Feb 28 19:50:22 volumio-living systemd[1]: Starting Xbindrc... Feb 28 19:50:22 volumio-living systemd[1]: Started Xbindrc. Feb 28 19:50:22 volumio-living systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Feb 28 19:50:22 volumio-living systemd[1]: Stopping Xbindrc... Feb 28 19:50:22 volumio-living systemd[1]: Starting Xbindrc... Feb 28 19:50:22 volumio-living systemd[1]: xbindkeysrc.service start request repeated too quickly, refusing to start. Feb 28 19:50:22 volumio-living systemd[1]: Failed to start Xbindrc. Feb 28 19:50:22 volumio-living systemd[1]: Unit xbindkeysrc.service entered failed state. Feb 28 19:50:22 volumio-living wireless.js[777]: WIRELESS DAEMON: start Feb 28 19:50:22 volumio-living wireless.js[777]: WIRELESS: Loaded configuration Feb 28 19:50:22 volumio-living wireless.js[777]: Cleaning previous... Feb 28 19:50:22 volumio-living systemd[1]: Stopped hotspot.service. Feb 28 19:50:22 volumio-living sudo[861]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 28 19:50:22 volumio-living sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:50:22 volumio-living sudo[861]: pam_unix(sudo:session): session closed for user root Feb 28 19:50:22 volumio-living sudo[872]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 28 19:50:22 volumio-living ntpd[723]: Listen normally on 3 eth0 192.168.178.63 UDP 123 Feb 28 19:50:22 volumio-living ntpd[723]: peers refreshed Feb 28 19:50:22 volumio-living sudo[872]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:50:22 volumio-living systemd[1]: Started LSB: start Samba daemons for the AD DC. Feb 28 19:50:22 volumio-living systemd[1]: Started Music Player Daemon. Feb 28 19:50:22 volumio-living winbind[614]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Feb 28 19:50:22 volumio-living winbind[614]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Feb 28 19:50:22 volumio-living winbind[614]: Unable to setup corepath for winbindd: No such file or directory Feb 28 19:50:22 volumio-living winbind[614]: mkdir failed on directory /var/log/samba/cores: No such file or directory Feb 28 19:50:22 volumio-living winbind[614]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Feb 28 19:50:22 volumio-living winbind[614]: Unable to setup corepath for winbindd: No such file or directory Feb 28 19:50:22 volumio-living winbind[614]: [2020/02/28 19:50:22, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:22 volumio-living winbind[614]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Feb 28 19:50:22 volumio-living winbind[614]: [2020/02/28 19:50:22, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:22 volumio-living winbind[614]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Feb 28 19:50:22 volumio-living winbind[614]: [2020/02/28 19:50:22, 0] ../source3/winbindd/winbindd.c:1549(main) Feb 28 19:50:22 volumio-living winbind[614]: winbindd version 4.2.14-Debian started. Feb 28 19:50:22 volumio-living winbind[614]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Feb 28 19:50:22 volumio-living winbind[614]: [2020/02/28 19:50:22, 0] ../lib/util/util.c:220(directory_create_or_exist) Feb 28 19:50:22 volumio-living winbind[614]: mkdir failed on directory /var/log/samba/cores: No such file or directory Feb 28 19:50:22 volumio-living winbind[614]: [2020/02/28 19:50:22, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Feb 28 19:50:22 volumio-living winbind[614]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Feb 28 19:50:22 volumio-living winbind[614]: [2020/02/28 19:50:22, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Feb 28 19:50:22 volumio-living winbind[614]: Unable to setup corepath for winbindd: No such file or directory Feb 28 19:50:22 volumio-living winbindd[886]: [2020/02/28 19:50:22.791733, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:22 volumio-living winbindd[886]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Feb 28 19:50:22 volumio-living winbindd[886]: [2020/02/28 19:50:22.792333, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:22 volumio-living winbindd[886]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Feb 28 19:50:22 volumio-living winbind[614]: [2020/02/28 19:50:22.791733, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:22 volumio-living winbind[614]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Feb 28 19:50:22 volumio-living winbind[614]: [2020/02/28 19:50:22.792333, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:22 volumio-living winbind[614]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Feb 28 19:50:22 volumio-living nmbd[618]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Feb 28 19:50:22 volumio-living nmbd[618]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Feb 28 19:50:22 volumio-living nmbd[618]: Unable to setup corepath for nmbd: No such file or directory Feb 28 19:50:22 volumio-living nmbd[889]: [2020/02/28 19:50:22.809222, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:22 volumio-living nmbd[889]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Feb 28 19:50:22 volumio-living nmbd[889]: [2020/02/28 19:50:22.810132, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:22 volumio-living nmbd[889]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Feb 28 19:50:22 volumio-living nmbd[618]: [2020/02/28 19:50:22, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:22 volumio-living nmbd[618]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Feb 28 19:50:22 volumio-living nmbd[618]: [2020/02/28 19:50:22, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:22 volumio-living nmbd[618]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Feb 28 19:50:22 volumio-living nmbd[618]: [2020/02/28 19:50:22, 0] ../source3/nmbd/nmbd.c:908(main) Feb 28 19:50:22 volumio-living nmbd[618]: nmbd version 4.2.14-Debian started. Feb 28 19:50:22 volumio-living nmbd[618]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Feb 28 19:50:22 volumio-living nmbd[618]: [2020/02/28 19:50:22.809222, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:22 volumio-living nmbd[618]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Feb 28 19:50:22 volumio-living nmbd[618]: [2020/02/28 19:50:22.810132, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:22 volumio-living nmbd[618]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Feb 28 19:50:22 volumio-living nmbd[890]: [2020/02/28 19:50:22.813819, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) Feb 28 19:50:22 volumio-living nmbd[890]: started asyncdns process 891 Feb 28 19:50:22 volumio-living systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Feb 28 19:50:22 volumio-living systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Feb 28 19:50:22 volumio-living nmbd[618]: . Feb 28 19:50:23 volumio-living sudo[872]: pam_unix(sudo:session): session closed for user root Feb 28 19:50:23 volumio-living nmbd[890]: [2020/02/28 19:50:23.203183, 0] ../lib/util/become_daemon.c:124(daemon_ready) Feb 28 19:50:23 volumio-living nmbd[890]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 28 19:50:23 volumio-living winbindd[905]: [2020/02/28 19:50:23.212999, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Feb 28 19:50:23 volumio-living winbindd[905]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 28 19:50:23 volumio-living systemd[1]: Started LSB: start Winbind daemon. Feb 28 19:50:23 volumio-living winbind[614]: . Feb 28 19:50:23 volumio-living ntpd_intres[841]: DNS 0.debian.pool.ntp.org -> 213.109.127.82 Feb 28 19:50:23 volumio-living volumio-remote-updater[591]: [2020-02-28 19:50:23] [info] asio async_connect error: system:111 (Connection refused) Feb 28 19:50:23 volumio-living volumio-remote-updater[591]: [2020-02-28 19:50:23] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 28 19:50:23 volumio-living volumio-remote-updater[591]: [2020-02-28 19:50:23] [error] handle_connect error: Underlying Transport Error Feb 28 19:50:24 volumio-living smbd[892]: Starting SMB/CIFS daemon: smbd[2020/02/28 19:50:24, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:24 volumio-living smbd[892]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 28 19:50:24 volumio-living smbd[892]: [2020/02/28 19:50:24, 0] ../lib/util/util.c:220(directory_create_or_exist) Feb 28 19:50:24 volumio-living smbd[892]: mkdir failed on directory /var/log/samba/cores: No such file or directory Feb 28 19:50:24 volumio-living smbd[892]: [2020/02/28 19:50:24, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Feb 28 19:50:24 volumio-living smbd[892]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Feb 28 19:50:24 volumio-living smbd[892]: [2020/02/28 19:50:24, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Feb 28 19:50:24 volumio-living smbd[892]: Unable to setup corepath for smbd: No such file or directory Feb 28 19:50:24 volumio-living smbd[892]: [2020/02/28 19:50:24, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:24 volumio-living smbd[892]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 28 19:50:24 volumio-living smbd[892]: [2020/02/28 19:50:24, 0] ../source3/smbd/server.c:1241(main) Feb 28 19:50:24 volumio-living smbd[892]: smbd version 4.2.14-Debian started. Feb 28 19:50:24 volumio-living smbd[892]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Feb 28 19:50:24 volumio-living winbindd[905]: [2020/02/28 19:50:24.705352, 0] ../lib/util/become_daemon.c:124(daemon_ready) Feb 28 19:50:24 volumio-living winbindd[905]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 28 19:50:24 volumio-living winbindd[911]: [2020/02/28 19:50:24.709896, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:24 volumio-living winbindd[911]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO-LIVING': No such file or directory Feb 28 19:50:24 volumio-living smbd[901]: [2020/02/28 19:50:24.712350, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:24 volumio-living smbd[901]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 28 19:50:24 volumio-living smbd[892]: [2020/02/28 19:50:24.712350, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:24 volumio-living smbd[892]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 28 19:50:24 volumio-living smbd[901]: [2020/02/28 19:50:24.717085, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:24 volumio-living smbd[901]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 28 19:50:24 volumio-living smbd[892]: [2020/02/28 19:50:24.717085, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:24 volumio-living smbd[892]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 28 19:50:24 volumio-living smbd[901]: [2020/02/28 19:50:24.717269, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:24 volumio-living smbd[901]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 28 19:50:24 volumio-living wireless.js[777]: Stopped aP Feb 28 19:50:24 volumio-living wireless.js[777]: Wireless Networking DISABLED, not starting wireless flow Feb 28 19:50:24 volumio-living smbd[892]: [2020/02/28 19:50:24.717269, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:24 volumio-living smbd[892]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 28 19:50:24 volumio-living systemd[1]: Started Wireless Services. Feb 28 19:50:24 volumio-living systemd[1]: Starting Volumio Backend Module... Feb 28 19:50:24 volumio-living systemd[1]: Started Volumio Backend Module. Feb 28 19:50:24 volumio-living systemd[1]: Starting Volumio Streaming Daemon... Feb 28 19:50:24 volumio-living systemd[1]: Started Volumio Streaming Daemon. Feb 28 19:50:24 volumio-living smbd[892]: . Feb 28 19:50:24 volumio-living systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Feb 28 19:50:24 volumio-living systemd[1]: Starting Multi-User System. Feb 28 19:50:24 volumio-living systemd[1]: Reached target Multi-User System. Feb 28 19:50:24 volumio-living systemd[1]: Starting Graphical Interface. Feb 28 19:50:24 volumio-living systemd[1]: Reached target Graphical Interface. Feb 28 19:50:24 volumio-living systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 28 19:50:24 volumio-living systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 28 19:50:24 volumio-living systemd[1]: Startup finished in 13.772s (kernel) + 14.412s (userspace) = 28.185s. Feb 28 19:50:24 volumio-living winbindd[926]: [2020/02/28 19:50:24.830547, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:24 volumio-living winbindd[926]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Feb 28 19:50:26 volumio-living winbindd[933]: [2020/02/28 19:50:26.499640, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:26 volumio-living winbindd[933]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory Feb 28 19:50:26 volumio-living smbd[915]: [2020/02/28 19:50:26.541146, 0] ../lib/util/become_daemon.c:124(daemon_ready) Feb 28 19:50:26 volumio-living smbd[915]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 28 19:50:26 volumio-living smbd[934]: [2020/02/28 19:50:26.546530, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 28 19:50:26 volumio-living smbd[934]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 28 19:50:26 volumio-living volumio-streaming-daemon[914]: ############################ Feb 28 19:50:26 volumio-living volumio-streaming-daemon[914]: # Volumio Streaming Daemon # Feb 28 19:50:26 volumio-living volumio-streaming-daemon[914]: # Running on port 7777 # Feb 28 19:50:26 volumio-living volumio-streaming-daemon[914]: ############################ Feb 28 19:50:27 volumio-living volumio[913]: info: ------------------------------------------- Feb 28 19:50:27 volumio-living volumio[913]: info: ----- Volumio2 ---- Feb 28 19:50:27 volumio-living volumio[913]: info: ------------------------------------------- Feb 28 19:50:27 volumio-living volumio[913]: info: ----- System startup ---- Feb 28 19:50:27 volumio-living volumio[913]: info: ------------------------------------------- Feb 28 19:50:27 volumio-living volumio[913]: info: MYVOLUMIO Environment detected Feb 28 19:50:28 volumio-living volumio[913]: info: Plugin folders cleanup Feb 28 19:50:28 volumio-living volumio[913]: info: Scanning into folder /volumio/app/plugins/ Feb 28 19:50:28 volumio-living volumio[913]: info: Scanning category audio_interface Feb 28 19:50:28 volumio-living volumio[913]: info: Scanning category miscellanea Feb 28 19:50:28 volumio-living volumio[913]: info: Scanning category music_service Feb 28 19:50:28 volumio-living volumio[913]: info: Scanning category plugins.json Feb 28 19:50:28 volumio-living volumio[913]: info: Scanning category system_controller Feb 28 19:50:28 volumio-living volumio[913]: info: Scanning category user_interface Feb 28 19:50:28 volumio-living volumio[913]: info: Scanning into folder /data/plugins/ Feb 28 19:50:28 volumio-living volumio[913]: info: Scanning category audio_interface Feb 28 19:50:28 volumio-living volumio[913]: info: Plugin folders cleanup completed Feb 28 19:50:28 volumio-living volumio[913]: info: ------------------------------------------- Feb 28 19:50:28 volumio-living volumio[913]: info: ----- Core plugins startup ---- Feb 28 19:50:28 volumio-living volumio[913]: info: ------------------------------------------- Feb 28 19:50:28 volumio-living volumio[913]: info: Loading plugins from folder /volumio/app/plugins/ Feb 28 19:50:28 volumio-living volumio[913]: info: Adding plugin upnp to MyMusic Plugins Feb 28 19:50:28 volumio-living volumio[913]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 28 19:50:28 volumio-living volumio[913]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 28 19:50:28 volumio-living volumio[913]: info: Loading plugins from folder /data/plugins/ Feb 28 19:50:28 volumio-living volumio[913]: info: Loading plugin "system"... Feb 28 19:50:28 volumio-living volumio[913]: info: Loading plugin "appearance"... Feb 28 19:50:29 volumio-living volumio[913]: info: Loading plugin "network"... Feb 28 19:51:01 volumio-living systemd[1]: Time has been changed Feb 28 19:51:02 volumio-living volumio[913]: info: Loading plugin "services"... Feb 28 19:51:02 volumio-living volumio[913]: info: Loading plugin "alsa_controller"... Feb 28 19:51:02 volumio-living volumio[913]: info: Loading plugin "wizard"... Feb 28 19:51:02 volumio-living volumio[913]: info: Loading plugin "volumio_command_line_client"... Feb 28 19:51:02 volumio-living volumio[913]: info: Plugin upnp is not enabled Feb 28 19:51:02 volumio-living volumio[913]: info: Loading plugin "my_music"... Feb 28 19:51:02 volumio-living volumio[913]: info: Loading plugin "mpd"... Feb 28 19:51:02 volumio-living volumio[913]: info: Plugin upnp_browser is not enabled Feb 28 19:51:02 volumio-living volumio[913]: info: Loading plugin "networkfs"... Feb 28 19:51:02 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 28 19:51:02 volumio-living volumio[913]: info: Loading plugin "alarm-clock"... Feb 28 19:51:02 volumio-living sudo[953]: 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 //FILESERVER/archive/003-AUDIO/10-MUSIC /mnt/NAS/archive Feb 28 19:51:02 volumio-living sudo[953]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:02 volumio-living kernel: FS-Cache: Netfs 'cifs' registered for caching Feb 28 19:51:02 volumio-living kernel: Key type cifs.spnego registered Feb 28 19:51:02 volumio-living kernel: Key type cifs.idmap registered Feb 28 19:51:02 volumio-living 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. Feb 28 19:51:02 volumio-living volumio[913]: info: Plugin airplay_emulation is not enabled Feb 28 19:51:02 volumio-living volumio[913]: info: Loading plugin "last_100"... Feb 28 19:51:02 volumio-living volumio[913]: info: Loading plugin "webradio"... Feb 28 19:51:02 volumio-living sudo[953]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:02 volumio-living volumio[913]: info: Loading plugin "i2s_dacs"... Feb 28 19:51:02 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 19:51:02 volumio-living volumio[913]: info: Loading plugin "volumiodiscovery"... Feb 28 19:51:03 volumio-living volumio[913]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 28 19:51:03 volumio-living node[913]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 28 19:51:03 volumio-living node[913]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 19:51:03 volumio-living node[913]: *** WARNING *** For more information see Feb 28 19:51:03 volumio-living node[913]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 28 19:51:03 volumio-living node[913]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 19:51:03 volumio-living node[913]: *** WARNING *** For more information see Feb 28 19:51:03 volumio-living volumio[913]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 19:51:03 volumio-living volumio[913]: *** WARNING *** For more information see Feb 28 19:51:03 volumio-living volumio[913]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 28 19:51:03 volumio-living volumio[913]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 28 19:51:03 volumio-living volumio[913]: *** WARNING *** For more information see Feb 28 19:51:03 volumio-living volumio[913]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 28 19:51:03 volumio-living volumio[913]: Discovery: StartAdv! undefined Feb 28 19:51:03 volumio-living volumio[913]: Discovery: Started advertising... Volumio-living - undefined Feb 28 19:51:03 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 28 19:51:03 volumio-living volumio[913]: info: Loading plugin "outputs"... Feb 28 19:51:03 volumio-living volumio[913]: info: Loading plugin "albumart"... Feb 28 19:51:03 volumio-living volumio-remote-updater[591]: [2020-02-28 19:51:03] [connect] Successful connection Feb 28 19:51:04 volumio-living volumio[913]: info: Plugin example_plugin is not enabled Feb 28 19:51:04 volumio-living volumio[913]: info: Loading plugin "inputs"... Feb 28 19:51:04 volumio-living volumio[913]: info: Loading plugin "updater_comm"... Feb 28 19:51:04 volumio-living volumio[913]: info: Plugin mpdemulation is not enabled Feb 28 19:51:04 volumio-living volumio[913]: info: Loading plugin "rest_api"... Feb 28 19:51:04 volumio-living volumio[913]: info: Loading plugin "websocket"... Feb 28 19:51:04 volumio-living volumio[913]: info: Loading plugin "brutefir"... Feb 28 19:51:04 volumio-living volumio[913]: Forking 3 albumart workers Feb 28 19:51:05 volumio-living volumio[913]: Starting albumart workers Feb 28 19:51:05 volumio-living volumio[913]: Starting albumart workers Feb 28 19:51:05 volumio-living volumio[913]: Starting albumart workers Feb 28 19:51:05 volumio-living volumio[913]: info: Applying required configuration parameters for plugin brutefir Feb 28 19:51:05 volumio-living volumio[913]: info: ___________ START PLUGINS ___________ Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 28 19:51:05 volumio-living volumio[913]: info: [1582919465415] CoreMusicLibrary::Adding element Last_100 Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 28 19:51:05 volumio-living volumio[913]: info: [1582919465424] CoreMusicLibrary::Adding element Webradio Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 19:51:05 volumio-living sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -o json -f -u brutefir Feb 28 19:51:05 volumio-living sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:05 volumio-living volumio[913]: info: ------------------------------------------- Feb 28 19:51:05 volumio-living volumio[913]: info: ----- MyVolumio plugins startup ---- Feb 28 19:51:05 volumio-living volumio[913]: info: ------------------------------------------- Feb 28 19:51:05 volumio-living volumio[913]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 28 19:51:05 volumio-living sudo[1061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Feb 28 19:51:05 volumio-living volumio[913]: info: Loading i18n strings for locale en Feb 28 19:51:05 volumio-living volumio[913]: Updating browse sources language Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 19:51:05 volumio-living sudo[1062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -o json -f -u brutefir Feb 28 19:51:05 volumio-living sudo[1061]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:05 volumio-living sudo[1062]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:51:05 volumio-living sudo[1061]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::initPlayerControls Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:05 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Feb 28 19:51:08 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:08 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:08 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:08 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:08 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:08 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:51:08 volumio-living volumio[913]: info: BOOT COMPLETED Feb 28 19:51:08 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 19:51:13 volumio-living volumio[913]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::Close All Modals sent Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::Close All Modals sent Feb 28 19:51:13 volumio-living volumio[913]: Express server listening on port 3000 Feb 28 19:51:13 volumio-living volumio[913]: Volumio Calling Home Feb 28 19:51:13 volumio-living volumio[913]: info: CoreStateMachine::resetVolumioState Feb 28 19:51:13 volumio-living volumio[913]: info: CoreStateMachine::getcurrentVolume Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::volumioRetrievevolume Feb 28 19:51:13 volumio-living volumio[913]: info: CoreStateMachine::pushState Feb 28 19:51:13 volumio-living volumio[913]: info: CoreStateMachine::getState Feb 28 19:51:13 volumio-living volumio[913]: info: CorePlayQueue::getTrack 0 Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::volumioPushState Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , disableI2SDAC Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 28 19:51:13 volumio-living volumio[913]: info: Output device has changed, restarting MPD Feb 28 19:51:13 volumio-living sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 28 19:51:13 volumio-living sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:13 volumio-living sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 28 19:51:13 volumio-living sudo[1087]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:13 volumio-living sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 28 19:51:13 volumio-living systemd[1]: Stopping Music Player Daemon... Feb 28 19:51:13 volumio-living volumio[913]: info: Device Loopback does not have any Mixer Control Available, setting a softvol device Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 28 19:51:13 volumio-living volumio[913]: info: Updating Volume Controller Parameters: Device: Loopback Name: Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 28 19:51:13 volumio-living volumio[913]: info: CoreStateMachine::pushState Feb 28 19:51:13 volumio-living volumio[913]: info: CoreStateMachine::getState Feb 28 19:51:13 volumio-living volumio[913]: info: CorePlayQueue::getTrack 0 Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::volumioPushState Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 19:51:13 volumio-living volumio[913]: info: MPD Permissions set Feb 28 19:51:13 volumio-living volumio[913]: info: Volumio called home Feb 28 19:51:13 volumio-living volumio[913]: info: Setting Device type: Raspberry PI Feb 28 19:51:13 volumio-living volumio[913]: info: snd_aloop loaded Feb 28 19:51:13 volumio-living volumio[913]: info: MPD running with PID646 ,establishing connection Feb 28 19:51:13 volumio-living systemd[1]: Starting Music Player Daemon... Feb 28 19:51:13 volumio-living sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Feb 28 19:51:13 volumio-living sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:13 volumio-living sudo[1110]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:13 volumio-living volumio[913]: info: No Overlays Loaded Feb 28 19:51:13 volumio-living volumio[913]: info: no delay needed Feb 28 19:51:13 volumio-living volumio[913]: Factory_S24_4LE Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 19:51:13 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 28 19:51:14 volumio-living mpd[1108]: output: No 'AudioOutput' defined in config file Feb 28 19:51:14 volumio-living mpd[1108]: output: Attempt to detect audio output device Feb 28 19:51:14 volumio-living mpd[1108]: output: Attempting to detect a alsa audio device Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 28 19:51:14 volumio-living mpd[1108]: output: Successfully detected a alsa audio device Feb 28 19:51:14 volumio-living systemd[1]: Started Music Player Daemon. Feb 28 19:51:14 volumio-living sudo[1089]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:14 volumio-living volumio[913]: AAAAAAAAAAAAAAAAAAAAAAAAAA-> 2 <-AAAAAAAAAAAAA Feb 28 19:51:14 volumio-living volumio[913]: AAAAAAAAAAAAAAAAAAAAAAAAAA-> S16_LE S24_LE <-AAAAAAAAAAAAA Feb 28 19:51:14 volumio-living volumio[913]: AAAAAAAAAAAAAAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Feb 28 19:51:14 volumio-living volumio[913]: info: Auto set output format : -------> S24_LE Feb 28 19:51:14 volumio-living volumio[913]: info: CoreStateMachine::setRepeat null single undefined Feb 28 19:51:14 volumio-living volumio[913]: info: CoreStateMachine::pushState Feb 28 19:51:14 volumio-living volumio[913]: info: CoreStateMachine::getState Feb 28 19:51:14 volumio-living volumio[913]: info: CorePlayQueue::getTrack 0 Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::volumioPushState Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 19:51:14 volumio-living volumio[913]: info: CoreStateMachine::setRandom null Feb 28 19:51:14 volumio-living volumio[913]: info: CoreStateMachine::pushState Feb 28 19:51:14 volumio-living volumio[913]: info: CoreStateMachine::getState Feb 28 19:51:14 volumio-living volumio[913]: info: CorePlayQueue::getTrack 0 Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::volumioPushState Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 19:51:14 volumio-living volumio[913]: info: Feb 28 19:51:14 volumio-living volumio[913]: info: message= [50@0] {} No database, stack=Error: [50@0] {} No database Feb 28 19:51:14 volumio-living volumio[913]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Feb 28 19:51:14 volumio-living volumio[913]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Feb 28 19:51:14 volumio-living volumio[913]: at emitOne (events.js:116:13) Feb 28 19:51:14 volumio-living volumio[913]: at Socket.emit (events.js:211:7) Feb 28 19:51:14 volumio-living volumio[913]: at addChunk (_stream_readable.js:263:12) Feb 28 19:51:14 volumio-living volumio[913]: at readableAddChunk (_stream_readable.js:246:13) Feb 28 19:51:14 volumio-living volumio[913]: at Socket.Readable.push (_stream_readable.js:208:10) Feb 28 19:51:14 volumio-living volumio[913]: at Pipe.onread (net.js:607:20) Feb 28 19:51:14 volumio-living sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart brutefir.service Feb 28 19:51:14 volumio-living sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:14 volumio-living systemd[1]: Starting brutefir Daemon... Feb 28 19:51:14 volumio-living systemd[1]: Started brutefir Daemon. Feb 28 19:51:14 volumio-living sudo[1135]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:14 volumio-living volumio[913]: Discovery: adding 2656ed68-aae8-4ced-81a4-3d25d858a660 Feb 28 19:51:14 volumio-living volumio[913]: info: mDNS: Found device Volumio-living Feb 28 19:51:14 volumio-living volumio[913]: info: Feb 28 19:51:14 volumio-living sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 19:51:14 volumio-living volumio[1140]: BruteFIR v1.0m (November 2013) (c) Anders Torger Feb 28 19:51:14 volumio-living sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:14 volumio-living sudo[1146]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:14 volumio-living volumio[1140]: Internal resolution is 64 bit floating point. Feb 28 19:51:14 volumio-living volumio[1140]: Creating 4 FFTW plans of size 16384...finished. Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::volumioGetState Feb 28 19:51:14 volumio-living volumio[913]: info: CoreStateMachine::getState Feb 28 19:51:14 volumio-living volumio[913]: info: CorePlayQueue::getTrack 0 Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::volumioGetState Feb 28 19:51:14 volumio-living volumio[913]: info: CoreStateMachine::getState Feb 28 19:51:14 volumio-living volumio[913]: info: CorePlayQueue::getTrack 0 Feb 28 19:51:14 volumio-living sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 19:51:14 volumio-living sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:14 volumio-living sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 19:51:14 volumio-living sudo[1155]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:14 volumio-living sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 28 19:51:14 volumio-living sudo[1157]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Feb 28 19:51:14 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 28 19:51:14 volumio-living volumio[913]: info: Starting brutefir Feb 28 19:51:14 volumio-living sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start brutefir.service Feb 28 19:51:14 volumio-living volumio[1140]: Loading 16 coefficient sets...finished. Feb 28 19:51:14 volumio-living sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:14 volumio-living systemd[1]: Started brutefir Daemon. Feb 28 19:51:14 volumio-living sudo[1175]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:14 volumio-living volumio[913]: info: Brutefir Daemon Started Feb 28 19:51:14 volumio-living volumio[913]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 28 19:51:14 volumio-living volumio[913]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 28 19:51:14 volumio-living volumio[913]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 28 19:51:14 volumio-living volumio[913]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 28 19:51:14 volumio-living volumio[913]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 28 19:51:14 volumio-living volumio[913]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 28 19:51:14 volumio-living volumio[913]: info: Adding plugin bluetooth to MyMusic Plugins Feb 28 19:51:15 volumio-living volumio[913]: info: Adding plugin cd_controller to MyMusic Plugins Feb 28 19:51:15 volumio-living volumio[913]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 28 19:51:15 volumio-living volumio[913]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 28 19:51:15 volumio-living volumio[1140]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4. Feb 28 19:51:15 volumio-living volumio[1140]: Warning: no support for clock cycle counter on this platform. Feb 28 19:51:15 volumio-living volumio[1140]: Timers for benchmarking may be unreliable. Feb 28 19:51:15 volumio-living volumio[1140]: Filters in process 0: 0 2 Feb 28 19:51:15 volumio-living volumio[1140]: Filters in process 1: 1 3 Feb 28 19:51:16 volumio-living volumio[1140]: Realtime priority 1 set for cli process (pid 1185) Feb 28 19:51:16 volumio-living volumio[1140]: Realtime priority 3 set for input process (pid 1142) Feb 28 19:51:16 volumio-living volumio[1140]: Realtime priority 4 set for filter process (pid 1184) Feb 28 19:51:16 volumio-living volumio[1140]: Realtime priority 4 set for filter process (pid 1183) Feb 28 19:51:16 volumio-living volumio[1140]: Realtime priority 3 set for output process (pid 1186) Feb 28 19:51:16 volumio-living volumio[1140]: Fixed I/O-delay is 16384 samples Feb 28 19:51:16 volumio-living volumio[1140]: Audio processing starts now Feb 28 19:51:17 volumio-living volumio[913]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 28 19:51:18 volumio-living volumio-remote-updater[591]: [2020-02-28 19:51:18] [connect] Successful connection Feb 28 19:51:18 volumio-living volumio[913]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 28 19:51:18 volumio-living volumio[913]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 28 19:51:18 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 19:51:18 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 19:51:18 volumio-living volumio[913]: info: Starting MyVolumio Remote Streaming Endpoints Feb 28 19:51:18 volumio-living volumio[913]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 28 19:51:18 volumio-living volumio[913]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 28 19:51:18 volumio-living volumio[913]: info: Streaming services startup Feb 28 19:51:18 volumio-living volumio[913]: info: Starting Streaming Daemon Feb 28 19:51:18 volumio-living volumio[913]: info: MyVolumio not started Feb 28 19:51:18 volumio-living volumio[913]: info: Initializing device activation check Feb 28 19:51:18 volumio-living sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 28 19:51:18 volumio-living sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:18 volumio-living volumio[913]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 28 19:51:18 volumio-living systemd[1]: Stopping Volumio Streaming Daemon... Feb 28 19:51:18 volumio-living systemd[1]: Starting Volumio Streaming Daemon... Feb 28 19:51:18 volumio-living systemd[1]: Started Volumio Streaming Daemon. Feb 28 19:51:18 volumio-living sudo[1188]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:18 volumio-living volumio-streaming-daemon[1194]: ############################ Feb 28 19:51:18 volumio-living volumio-streaming-daemon[1194]: # Volumio Streaming Daemon # Feb 28 19:51:18 volumio-living volumio-streaming-daemon[1194]: # Running on port 7777 # Feb 28 19:51:18 volumio-living volumio-streaming-daemon[1194]: ############################ Feb 28 19:51:18 volumio-living volumio[913]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 28 19:51:18 volumio-living volumio[913]: info: CoreStateMachine::getcurrentVolume Feb 28 19:51:18 volumio-living volumio[913]: info: CoreCommandRouter::volumioRetrievevolume Feb 28 19:51:18 volumio-living volumio[913]: info: CoreStateMachine::pushState Feb 28 19:51:18 volumio-living volumio[913]: info: CoreStateMachine::getState Feb 28 19:51:18 volumio-living volumio[913]: info: CorePlayQueue::getTrack 0 Feb 28 19:51:18 volumio-living volumio[913]: info: CoreCommandRouter::volumioPushState Feb 28 19:51:18 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 19:51:18 volumio-living sudo[1204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 19:51:18 volumio-living sudo[1204]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:18 volumio-living sudo[1204]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:18 volumio-living volumio[913]: info: VolumeController::SetAlsaVolumemute Feb 28 19:51:18 volumio-living volumio[913]: info: CoreStateMachine::pushState Feb 28 19:51:18 volumio-living volumio[913]: info: CoreStateMachine::getState Feb 28 19:51:18 volumio-living volumio[913]: info: CorePlayQueue::getTrack 0 Feb 28 19:51:18 volumio-living volumio[913]: info: CoreCommandRouter::volumioPushState Feb 28 19:51:18 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 19:51:18 volumio-living sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 19:51:18 volumio-living volumio[913]: info: VolumeController::SetAlsaVolumeunmute Feb 28 19:51:18 volumio-living volumio[913]: info: CoreStateMachine::pushState Feb 28 19:51:18 volumio-living volumio[913]: info: CoreStateMachine::getState Feb 28 19:51:18 volumio-living volumio[913]: info: CorePlayQueue::getTrack 0 Feb 28 19:51:18 volumio-living volumio[913]: info: CoreCommandRouter::volumioPushState Feb 28 19:51:18 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 19:51:18 volumio-living sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:19 volumio-living sudo[1213]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:19 volumio-living volumio-remote-updater[591]: [2020-02-28 19:51:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1582919478 101 Feb 28 19:51:19 volumio-living sudo[1219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 19:51:19 volumio-living sudo[1219]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:19 volumio-living sudo[1219]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:21 volumio-living volumio[913]: info: Fetching Streaming Services browse cache Feb 28 19:51:21 volumio-living volumio[913]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Feb 28 19:51:21 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 19:51:21 volumio-living volumio[913]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Feb 28 19:51:21 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 19:51:21 volumio-living volumio[913]: info: CoreCommandRouter::volumioGetVisibleSources Feb 28 19:51:21 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 19:51:21 volumio-living volumio[913]: info: CoreCommandRouter::volumioGetState Feb 28 19:51:21 volumio-living volumio[913]: info: CoreStateMachine::getState Feb 28 19:51:21 volumio-living volumio[913]: info: CorePlayQueue::getTrack 0 Feb 28 19:51:21 volumio-living sudo[1241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 19:51:21 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 28 19:51:21 volumio-living sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:21 volumio-living volumio[913]: info: Listing playlists Feb 28 19:51:21 volumio-living volumio[913]: info: CoreCommandRouter::volumioGetQueue Feb 28 19:51:21 volumio-living volumio[913]: info: CoreStateMachine::getQueue Feb 28 19:51:21 volumio-living volumio[913]: info: CorePlayQueue::getQueue Feb 28 19:51:21 volumio-living sudo[1244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 19:51:21 volumio-living sudo[1241]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:22 volumio-living sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:22 volumio-living sudo[1244]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:26 volumio-living volumio[913]: { device: '1', Feb 28 19:51:26 volumio-living volumio[913]: name: 'Allo DigiOne', Feb 28 19:51:26 volumio-living volumio[913]: mixer: '', Feb 28 19:51:26 volumio-living volumio[913]: mixertype: 'None', Feb 28 19:51:26 volumio-living volumio[913]: maxvolume: '100', Feb 28 19:51:26 volumio-living volumio[913]: volumecurve: 'logarithmic', Feb 28 19:51:26 volumio-living volumio[913]: volumestart: 'disabled', Feb 28 19:51:26 volumio-living volumio[913]: volumesteps: '10', Feb 28 19:51:26 volumio-living volumio[913]: softvolumenumber: 'undefined' } Feb 28 19:51:26 volumio-living volumio[913]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 28 19:51:26 volumio-living volumio[913]: info: Updating Volume Controller Parameters: Device: 1 Name: Allo DigiOne Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Feb 28 19:51:26 volumio-living volumio[913]: info: CoreStateMachine::pushState Feb 28 19:51:26 volumio-living volumio[913]: info: CoreStateMachine::getState Feb 28 19:51:26 volumio-living volumio[913]: info: CorePlayQueue::getTrack 0 Feb 28 19:51:26 volumio-living volumio[913]: info: CoreCommandRouter::volumioPushState Feb 28 19:51:26 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 28 19:51:26 volumio-living sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 19:51:26 volumio-living sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:26 volumio-living sudo[1259]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:35 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 28 19:51:35 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 28 19:51:45 volumio-living sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 28 19:51:45 volumio-living sudo[1270]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:45 volumio-living sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 19:51:45 volumio-living sudo[1270]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:45 volumio-living sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:45 volumio-living sudo[1272]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:45 volumio-living volumio[913]: info: CoreCommandRouter::volumioGetVisibleSources Feb 28 19:51:45 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 28 19:51:45 volumio-living volumio[913]: info: CoreCommandRouter::volumioGetState Feb 28 19:51:45 volumio-living volumio[913]: info: CoreStateMachine::getState Feb 28 19:51:45 volumio-living volumio[913]: info: CorePlayQueue::getTrack 0 Feb 28 19:51:45 volumio-living volumio[913]: info: CoreCommandRouter::volumioGetQueue Feb 28 19:51:45 volumio-living volumio[913]: info: CoreStateMachine::getQueue Feb 28 19:51:45 volumio-living volumio[913]: info: CorePlayQueue::getQueue Feb 28 19:51:45 volumio-living volumio[913]: info: Listing playlists Feb 28 19:51:45 volumio-living sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 19:51:45 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 28 19:51:45 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 28 19:51:45 volumio-living sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:51:45 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 28 19:51:45 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 28 19:51:45 volumio-living sudo[1288]: pam_unix(sudo:session): session closed for user root Feb 28 19:51:47 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 28 19:51:47 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Feb 28 19:51:47 volumio-living volumio[913]: info: CURURI: music-library Feb 28 19:51:47 volumio-living volumio[913]: info: message= [50@0] {} No database, stack=Error: [50@0] {} No database Feb 28 19:51:47 volumio-living volumio[913]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Feb 28 19:51:47 volumio-living volumio[913]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Feb 28 19:51:47 volumio-living volumio[913]: at emitOne (events.js:116:13) Feb 28 19:51:47 volumio-living volumio[913]: at Socket.emit (events.js:211:7) Feb 28 19:51:47 volumio-living volumio[913]: at addChunk (_stream_readable.js:263:12) Feb 28 19:51:47 volumio-living volumio[913]: at readableAddChunk (_stream_readable.js:246:13) Feb 28 19:51:47 volumio-living volumio[913]: at Socket.Readable.push (_stream_readable.js:208:10) Feb 28 19:51:47 volumio-living volumio[913]: at Pipe.onread (net.js:607:20) Feb 28 19:52:22 volumio-living volumio[913]: info: CoreCommandRouter::volumioGetState Feb 28 19:52:22 volumio-living volumio[913]: info: CoreStateMachine::getState Feb 28 19:52:22 volumio-living volumio[913]: info: CorePlayQueue::getTrack 0 Feb 28 19:52:22 volumio-living volumio[913]: info: CoreCommandRouter::volumioGetQueue Feb 28 19:52:22 volumio-living volumio[913]: info: CoreStateMachine::getQueue Feb 28 19:52:22 volumio-living volumio[913]: info: CorePlayQueue::getQueue Feb 28 19:52:22 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Feb 28 19:52:22 volumio-living sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 28 19:52:22 volumio-living sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:52:22 volumio-living sudo[1298]: pam_unix(sudo:session): session closed for user root Feb 28 19:52:56 volumio-living volumio[913]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Feb 28 19:52:56 volumio-living volumio[913]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Feb 28 19:52:57 volumio-living sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Feb 28 19:52:57 volumio-living sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 28 19:52:57 volumio-living sudo[1322]: pam_unix(sudo:session): session closed for user root Feb 28 19:52:57 volumio-living sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Feb 28 19:52:57 volumio-living sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) # cat /var/log/mpd.log --------------- Feb 28 19:50 : zeroconf: No global port, disabling zeroconf