Sat Nov 30 18:28:57 UTC 2019 Description="'trying to play a track from hra stream'" # cat /proc/version --------------- Linux version 4.19.79-v7+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1273 SMP Fri Oct 11 18:13:16 BST 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="76976c3150e60fa37f55c84c5ca4b7a47b667b83" VOLUMIO_FE_VERSION="" VOLUMIO_BE_VERSION="c2994f71c07acc7ac3f15b2e53c5c34d813f10e9" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Nov 29 20:40:42 CET 2019" VOLUMIO_VERSION="2.672" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="35db3da51c5ccb9145e8eaa30efccca1" # ifconfig --------------- eth0 Link encap:Ethernet HWaddr b8:27:eb:9c:20:49 UP BROADCAST MULTICAST MTU:1500 Metric:1 RX packets:0 errors:0 dropped:0 overruns:0 frame:0 TX packets:0 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:0 (0.0 B) TX bytes:0 (0.0 B) lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 UP LOOPBACK RUNNING MTU:65536 Metric:1 RX packets:1096 errors:0 dropped:0 overruns:0 frame:0 TX packets:1096 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:114697 (112.0 KiB) TX bytes:114697 (112.0 KiB) wlan0 Link encap:Ethernet HWaddr b8:27:eb:c9:75:1c inet addr:192.168.1.149 Bcast:192.168.1.255 Mask:255.255.255.0 UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:1206 errors:0 dropped:0 overruns:0 frame:0 TX packets:948 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:619471 (604.9 KiB) TX bytes:202440 (197.6 KiB) # sudo iwconfig --------------- lo no wireless extensions. eth0 no wireless extensions. wlan0 IEEE 802.11 ESSID:"padme" Mode:Managed Frequency:2.412 GHz Access Point: 44:D9:E7:F3:8C:48 Bit Rate=72.2 Mb/s Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off Link Quality=65/70 Signal level=-45 dBm Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0 Tx excessive retries:0 Invalid misc:0 Missed beacon:0 # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 0: ALSA [bcm2835 ALSA], device 0: bcm2835 ALSA [bcm2835 ALSA] Subdevices: 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 5: Qutest [Qutest], device 0: USB Audio [USB Audio] Subdevices: 1/1 Subdevice #0: subdevice #0 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 4 18:26 ? 00:00:05 /sbin/init root 2 0 0 18:26 ? 00:00:00 [kthreadd] root 3 2 0 18:26 ? 00:00:00 [rcu_gp] root 4 2 0 18:26 ? 00:00:00 [rcu_par_gp] root 5 2 0 18:26 ? 00:00:00 [kworker/0:0-eve] root 6 2 0 18:26 ? 00:00:00 [kworker/0:0H-mm] root 7 2 0 18:26 ? 00:00:00 [kworker/u8:0-ev] root 8 2 0 18:26 ? 00:00:00 [mm_percpu_wq] root 9 2 0 18:26 ? 00:00:00 [ksoftirqd/0] root 10 2 0 18:26 ? 00:00:00 [rcu_sched] root 11 2 0 18:26 ? 00:00:00 [rcu_bh] root 12 2 0 18:26 ? 00:00:00 [migration/0] root 13 2 0 18:26 ? 00:00:00 [cpuhp/0] root 14 2 0 18:26 ? 00:00:00 [cpuhp/1] root 15 2 0 18:26 ? 00:00:00 [migration/1] root 16 2 0 18:26 ? 00:00:00 [ksoftirqd/1] root 17 2 0 18:26 ? 00:00:00 [kworker/1:0-mm_] root 18 2 0 18:26 ? 00:00:00 [kworker/1:0H-kb] root 19 2 0 18:26 ? 00:00:00 [cpuhp/2] root 20 2 0 18:26 ? 00:00:00 [migration/2] root 21 2 0 18:26 ? 00:00:00 [ksoftirqd/2] root 22 2 0 18:26 ? 00:00:00 [kworker/2:0-cgr] root 23 2 0 18:26 ? 00:00:00 [kworker/2:0H-kb] root 24 2 0 18:26 ? 00:00:00 [cpuhp/3] root 25 2 0 18:26 ? 00:00:00 [migration/3] root 26 2 0 18:26 ? 00:00:00 [ksoftirqd/3] root 27 2 0 18:26 ? 00:00:00 [kworker/3:0-mm_] root 28 2 0 18:26 ? 00:00:00 [kworker/3:0H-kb] root 29 2 0 18:26 ? 00:00:00 [kdevtmpfs] root 30 2 0 18:26 ? 00:00:00 [netns] root 31 2 0 18:26 ? 00:00:00 [kworker/0:1-eve] root 32 2 0 18:26 ? 00:00:00 [kworker/1:1-eve] root 33 2 0 18:26 ? 00:00:00 [kworker/2:1-mm_] root 34 2 0 18:26 ? 00:00:00 [khungtaskd] root 35 2 0 18:26 ? 00:00:00 [oom_reaper] root 36 2 0 18:26 ? 00:00:00 [writeback] root 37 2 0 18:26 ? 00:00:00 [kcompactd0] root 38 2 0 18:26 ? 00:00:00 [crypto] root 39 2 0 18:26 ? 00:00:00 [kblockd] root 40 2 0 18:26 ? 00:00:00 [watchdogd] root 41 2 0 18:26 ? 00:00:00 [rpciod] root 42 2 0 18:26 ? 00:00:00 [kworker/u9:0-hc] root 43 2 0 18:26 ? 00:00:00 [xprtiod] root 44 2 0 18:26 ? 00:00:00 [kworker/u8:1-ev] root 46 2 0 18:26 ? 00:00:00 [kswapd0] root 47 2 0 18:26 ? 00:00:00 [nfsiod] root 58 2 0 18:26 ? 00:00:00 [kthrotld] root 59 2 0 18:26 ? 00:00:00 [kworker/3:1-eve] root 60 2 0 18:26 ? 00:00:00 [iscsi_eh] root 61 2 0 18:26 ? 00:00:00 [dwc_otg] root 62 2 0 18:26 ? 00:00:00 [DWC Notificatio] root 63 2 0 18:26 ? 00:00:00 [vchiq-slot/0] root 64 2 0 18:26 ? 00:00:00 [vchiq-recy/0] root 65 2 0 18:26 ? 00:00:00 [vchiq-sync/0] root 66 2 0 18:26 ? 00:00:00 [vchiq-keep/0] root 67 2 0 18:26 ? 00:00:00 [SMIO] root 68 2 0 18:26 ? 00:00:00 [irq/86-mmc1] root 69 2 0 18:26 ? 00:00:00 [kworker/1:2-eve] root 70 2 0 18:26 ? 00:00:00 [kworker/1:3-eve] root 78 2 0 18:26 ? 00:00:00 [mmc_complete] root 79 2 0 18:26 ? 00:00:00 [kworker/1:1H] root 80 2 0 18:26 ? 00:00:00 [kworker/0:1H-kb] root 101 2 0 18:26 ? 00:00:00 [kworker/2:1H-kb] root 105 2 0 18:26 ? 00:00:00 [kworker/3:1H-kb] root 106 2 0 18:26 ? 00:00:00 [kworker/3:2H-kb] root 107 2 0 18:26 ? 00:00:00 [jbd2/mmcblk0p2-] root 108 2 0 18:26 ? 00:00:00 [ext4-rsv-conver] root 110 2 0 18:26 ? 00:00:00 [kworker/0:2-cgr] root 112 2 0 18:26 ? 00:00:00 [irq/166-usb-001] root 118 2 0 18:26 ? 00:00:01 [loop0] root 137 2 0 18:26 ? 00:00:00 [kworker/1:2H-kb] root 138 2 0 18:26 ? 00:00:00 [jbd2/mmcblk0p3-] root 139 2 0 18:26 ? 00:00:00 [ext4-rsv-conver] root 146 2 0 18:26 ? 00:00:00 [ipv6_addrconf] root 158 2 0 18:26 ? 00:00:00 [kworker/0:2H-kb] root 174 1 1 18:26 ? 00:00:01 /lib/systemd/systemd-journald root 182 2 0 18:26 ? 00:00:00 [kworker/2:2-cgr] root 189 2 0 18:26 ? 00:00:00 [kworker/3:2-mm_] root 200 1 0 18:26 ? 00:00:00 /lib/systemd/systemd-udevd root 246 2 0 18:26 ? 00:00:00 [SMIO] root 278 2 0 18:26 ? 00:00:00 [mmal-vchiq] root 282 2 0 18:26 ? 00:00:00 [mmal-vchiq] root 284 2 0 18:26 ? 00:00:00 [mmal-vchiq] root 290 2 0 18:26 ? 00:00:00 [cfg80211] root 299 2 0 18:26 ? 00:00:00 [brcmf_wq/mmc1:0] root 302 2 0 18:26 ? 00:00:00 [brcmf_wdog/mmc1] root 316 2 0 18:26 ? 00:00:00 [kworker/2:2H-kb] root 322 2 0 18:26 ? 00:00:00 [kworker/u8:2-ev] root 423 2 0 18:26 ? 00:00:00 [kworker/1:4] root 426 2 0 18:26 ? 00:00:00 [kworker/3:3-mm_] root 503 1 0 18:26 ? 00:00:00 /sbin/rpcbind -w statd 551 1 0 18:26 ? 00:00:00 /sbin/rpc.statd root 563 1 0 18:26 ? 00:00:00 /usr/sbin/rpc.idmapd root 565 1 2 18:26 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 --write=1024 root 567 2 0 18:26 ? 00:00:00 [kworker/u8:3-br] volumio 570 1 0 18:26 ? 00:00:00 /usr/bin/udisks-glue --foreground volumio 575 1 0 18:26 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 591 1 0 18:26 ? 00:00:00 /lib/systemd/systemd-logind avahi 630 1 0 18:26 ? 00:00:00 avahi-daemon: running [vio.local] mpd 634 1 2 18:26 ? 00:00:03 /usr/bin/mpd --no-daemon message+ 635 1 0 18:26 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation root 667 1 0 18:26 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I avahi 683 630 0 18:26 ? 00:00:00 avahi-daemon: chroot helper ntp 690 1 0 18:26 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /var/lib/ntp/ntp.conf.dhcp -u 100:104 root 692 1 0 18:26 ? 00:00:00 /usr/lib/udisks/udisks-daemon --no-debug root 694 2 0 18:26 ? 00:00:00 [kworker/u8:4-br] root 701 2 0 18:26 ? 00:00:00 [kworker/u8:5-ev] root 718 2 0 18:26 ? 00:00:00 [kworker/u9:1-hc] root 719 1 0 18:26 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 3000000 flow - b8:27:eb:36:8a:e3 root 727 2 0 18:26 ? 00:00:00 [kworker/u9:2-hc] root 734 692 0 18:26 ? 00:00:00 udisks-daemon: not polling any devices root 741 1 0 18:26 ? 00:00:00 /usr/lib/bluetooth/bluetoothd root 744 1 0 18:26 tty1 00:00:00 /sbin/agetty --noclear tty1 linux root 748 1 0 18:26 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 754 1 0 18:26 ? 00:00:00 /usr/sbin/sshd -D root 831 1 0 18:26 ? 00:00:00 /usr/sbin/winbindd root 832 1 0 18:26 ? 00:00:00 /usr/sbin/nmbd -D root 833 832 0 18:26 ? 00:00:00 /usr/sbin/nmbd -D root 842 831 0 18:26 ? 00:00:00 /usr/sbin/winbindd root 892 1 0 18:26 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 904 1 0 18:26 ? 00:00:00 /usr/sbin/smbd -D root 907 831 0 18:26 ? 00:00:00 /usr/sbin/winbindd root 908 831 0 18:26 ? 00:00:00 /usr/sbin/winbindd root 909 904 0 18:26 ? 00:00:00 /usr/sbin/smbd -D root 1009 1 0 18:26 ? 00:00:00 /sbin/dhcpcd volumio 1025 1 28 18:26 ? 00:00:34 /usr/local/bin/node /volumio/index.js root 1051 754 0 18:27 ? 00:00:00 sshd: volumio [priv] volumio 1055 1 0 18:27 ? 00:00:00 /lib/systemd/systemd --user volumio 1058 1055 0 18:27 ? 00:00:00 (sd-pam) volumio 1061 1051 0 18:27 ? 00:00:00 sshd: volumio@pts/0 volumio 1063 1061 0 18:27 pts/0 00:00:00 -bash root 1084 2 0 18:27 ? 00:00:00 [cifsiod] root 1085 2 0 18:27 ? 00:00:00 [cifsoplockd] root 1089 2 0 18:27 ? 00:00:00 [cifsd] volumio 1130 1025 0 18:27 ? 00:00:00 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1138 1130 2 18:27 ? 00:00:02 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1140 1130 2 18:27 ? 00:00:02 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1145 1130 2 18:27 ? 00:00:02 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1221 1 0 18:27 ? 00:00:00 /bin/streaming-daemon volumio 1243 1 0 18:27 ? 00:00:00 /bin/sh /tmp/sshtunnel.sh volumio 1244 1243 0 18:27 ? 00:00:00 /usr/lib/autossh/autossh -M 0 -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R C0U4W8fJDSTqrloxERg8DbjYy2M2-09e673e6afb99162e54a527b631feceb.eu1.myvolumio.org:8091:localhost:3000 eu1.myvolumio.org volumio 1247 1244 0 18:27 ? 00:00:00 /usr/bin/ssh -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R C0U4W8fJDSTqrloxERg8DbjYy2M2-09e673e6afb99162e54a527b631feceb.eu1.myvolumio.org:8091:localhost:3000 eu1.myvolumio.org root 1265 1063 0 18:27 pts/0 00:00:00 sudo journalctl -f root 1269 1265 0 18:27 pts/0 00:00:00 journalctl -f volumio 1320 1025 0 18:28 ? 00:00:00 /bin/sh -c /usr/local/bin/node /volumio/logsubmit.js 'trying to play a track from hra stream' volumio 1321 1320 59 18:28 ? 00:00:00 /usr/local/bin/node /volumio/logsubmit.js trying to play a track from hra stream volumio 1346 1321 0 18:28 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1347 1346 0 18:28 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.2G 793M 1.3G 38% /imgpart /dev/loop0 337M 337M 0 100% /static overlay 13G 25M 12G 1% / devtmpfs 471M 0 471M 0% /dev tmpfs 485M 0 485M 0% /dev/shm tmpfs 485M 4.7M 481M 1% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 485M 0 485M 0% /sys/fs/cgroup tmpfs 485M 40K 485M 1% /tmp tmpfs 485M 0 485M 0% /var/spool/cups tmpfs 20M 40K 20M 1% /var/log tmpfs 485M 0 485M 0% /var/spool/cups/tmp /dev/mmcblk0p1 61M 58M 2.3M 97% /boot tmpfs 97M 0 97M 0% /run/user/1000 //192.168.1.5/music 7.7T 4.7T 3.0T 62% /mnt/NAS/qnap # 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=482196k,nr_inodes=120549,mode=755) 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) fusectl on /sys/fs/fuse/connections type fusectl (rw,relatime) configfs on /sys/kernel/config type configfs (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) 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) tmpfs on /run/user/1000 type tmpfs (rw,nosuid,nodev,relatime,size=99300k,mode=700,uid=1000,gid=1000) //192.168.1.5/music on /mnt/NAS/qnap type cifs (ro,relatime,vers=default,cache=strict,username=music,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.1.5,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 Sat 2019-11-30 18:26:20 UTC, end at Sat 2019-11-30 18:28:57 UTC. -- Nov 30 18:26:20 vio systemd-journal[174]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 72.7M free of 480.7M available → current limit 30.0M). Nov 30 18:26:20 vio kernel: Booting Linux on physical CPU 0x0 Nov 30 18:26:20 vio kernel: Linux version 4.19.79-v7+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1273 SMP Fri Oct 11 18:13:16 BST 2019 Nov 30 18:26:20 vio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Nov 30 18:26:20 vio kernel: CPU: div instructions available: patching division code Nov 30 18:26:20 vio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Nov 30 18:26:20 vio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3 Nov 30 18:26:20 vio kernel: Memory policy: Data cache writealloc Nov 30 18:26:20 vio kernel: cma: Reserved 8 MiB at 0x3d800000 Nov 30 18:26:20 vio kernel: On node 0 totalpages: 253952 Nov 30 18:26:20 vio kernel: Normal zone: 2232 pages used for memmap Nov 30 18:26:20 vio kernel: Normal zone: 0 pages reserved Nov 30 18:26:20 vio kernel: Normal zone: 253952 pages, LIFO batch:63 Nov 30 18:26:20 vio kernel: random: get_random_bytes called from start_kernel+0xac/0x4b4 with crng_init=0 Nov 30 18:26:20 vio kernel: percpu: Embedded 16 pages/cpu s35392 r8192 d21952 u65536 Nov 30 18:26:20 vio kernel: pcpu-alloc: s35392 r8192 d21952 u65536 alloc=16*4096 Nov 30 18:26:20 vio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 30 18:26:20 vio kernel: Built 1 zonelists, mobility grouping on. Total pages: 251720 Nov 30 18:26:20 vio kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 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 Nov 30 18:26:20 vio kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) Nov 30 18:26:20 vio kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) Nov 30 18:26:20 vio kernel: Memory: 964396K/1015808K available (8192K kernel code, 629K rwdata, 2176K rodata, 1024K init, 821K bss, 43220K reserved, 8192K cma-reserved) Nov 30 18:26:20 vio kernel: Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0xbe800000 - 0xff800000 (1040 MB) lowmem : 0x80000000 - 0xbe000000 ( 992 MB) modules : 0x7f000000 - 0x80000000 ( 16 MB) .text : 0x(ptrval) - 0x(ptrval) (9184 kB) .init : 0x(ptrval) - 0x(ptrval) (1024 kB) .data : 0x(ptrval) - 0x(ptrval) ( 630 kB) .bss : 0x(ptrval) - 0x(ptrval) ( 822 kB) Nov 30 18:26:20 vio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 30 18:26:20 vio kernel: ftrace: allocating 26405 entries in 78 pages Nov 30 18:26:20 vio kernel: rcu: Hierarchical RCU implementation. Nov 30 18:26:20 vio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Nov 30 18:26:20 vio kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Nov 30 18:26:20 vio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Nov 30 18:26:20 vio kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Nov 30 18:26:20 vio kernel: Switching to timer-based delay loop, resolution 52ns Nov 30 18:26:20 vio kernel: Console: colour dummy device 80x30 Nov 30 18:26:20 vio kernel: console [tty1] enabled Nov 30 18:26:20 vio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Nov 30 18:26:20 vio kernel: pid_max: default: 32768 minimum: 301 Nov 30 18:26:20 vio kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) Nov 30 18:26:20 vio kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) Nov 30 18:26:20 vio kernel: CPU: Testing write buffer coherency: ok Nov 30 18:26:20 vio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Nov 30 18:26:20 vio kernel: Setting up static identity map for 0x100000 - 0x10003c Nov 30 18:26:20 vio kernel: rcu: Hierarchical SRCU implementation. Nov 30 18:26:20 vio kernel: smp: Bringing up secondary CPUs ... Nov 30 18:26:20 vio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Nov 30 18:26:20 vio kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Nov 30 18:26:20 vio kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Nov 30 18:26:20 vio kernel: smp: Brought up 1 node, 4 CPUs Nov 30 18:26:20 vio kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Nov 30 18:26:20 vio kernel: CPU: All CPU(s) started in HYP mode. Nov 30 18:26:20 vio kernel: CPU: Virtualization extensions available. Nov 30 18:26:20 vio kernel: devtmpfs: initialized Nov 30 18:26:20 vio kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Nov 30 18:26:20 vio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Nov 30 18:26:20 vio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) Nov 30 18:26:20 vio kernel: pinctrl core: initialized pinctrl subsystem Nov 30 18:26:20 vio kernel: NET: Registered protocol family 16 Nov 30 18:26:20 vio kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Nov 30 18:26:20 vio kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Nov 30 18:26:20 vio kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Nov 30 18:26:20 vio kernel: Serial: AMBA PL011 UART driver Nov 30 18:26:20 vio kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Nov 30 18:26:20 vio kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1 Nov 30 18:26:20 vio kernel: SCSI subsystem initialized Nov 30 18:26:20 vio kernel: usbcore: registered new interface driver usbfs Nov 30 18:26:20 vio kernel: usbcore: registered new interface driver hub Nov 30 18:26:20 vio kernel: usbcore: registered new device driver usb Nov 30 18:26:20 vio kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2019-10-11 18:40, variant start Nov 30 18:26:20 vio kernel: raspberrypi-firmware soc:firmware: Firmware hash is 89cbed6a54e414b21b554ce245245744332c37e1 Nov 30 18:26:20 vio kernel: clocksource: Switched to clocksource arch_sys_counter Nov 30 18:26:20 vio kernel: VFS: Disk quotas dquot_6.6.0 Nov 30 18:26:20 vio kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Nov 30 18:26:20 vio kernel: FS-Cache: Loaded Nov 30 18:26:20 vio kernel: CacheFiles: Loaded Nov 30 18:26:20 vio kernel: NET: Registered protocol family 2 Nov 30 18:26:20 vio kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes) Nov 30 18:26:20 vio kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes) Nov 30 18:26:20 vio kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes) Nov 30 18:26:20 vio kernel: TCP: Hash tables configured (established 8192 bind 8192) Nov 30 18:26:20 vio kernel: UDP hash table entries: 512 (order: 2, 16384 bytes) Nov 30 18:26:20 vio kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) Nov 30 18:26:20 vio kernel: NET: Registered protocol family 1 Nov 30 18:26:20 vio kernel: RPC: Registered named UNIX socket transport module. Nov 30 18:26:20 vio kernel: RPC: Registered udp transport module. Nov 30 18:26:20 vio kernel: RPC: Registered tcp transport module. Nov 30 18:26:20 vio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Nov 30 18:26:20 vio kernel: Trying to unpack rootfs image as initramfs... Nov 30 18:26:20 vio kernel: Freeing initrd memory: 19368K Nov 30 18:26:20 vio kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Nov 30 18:26:20 vio kernel: Initialise system trusted keyrings Nov 30 18:26:20 vio kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Nov 30 18:26:20 vio kernel: FS-Cache: Netfs 'nfs' registered for caching Nov 30 18:26:20 vio kernel: NFS: Registering the id_resolver key type Nov 30 18:26:20 vio kernel: Key type id_resolver registered Nov 30 18:26:20 vio kernel: Key type id_legacy registered Nov 30 18:26:20 vio kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Nov 30 18:26:20 vio kernel: Key type asymmetric registered Nov 30 18:26:20 vio kernel: Asymmetric key parser 'x509' registered Nov 30 18:26:20 vio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 30 18:26:20 vio kernel: io scheduler noop registered (default) Nov 30 18:26:20 vio kernel: io scheduler deadline registered Nov 30 18:26:20 vio kernel: io scheduler cfq registered Nov 30 18:26:20 vio kernel: io scheduler mq-deadline registered Nov 30 18:26:20 vio kernel: io scheduler kyber registered Nov 30 18:26:20 vio kernel: bcm2708_fb soc:fb: FB found 1 display(s) Nov 30 18:26:20 vio kernel: Console: switching to colour frame buffer device 80x30 Nov 30 18:26:20 vio kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Nov 30 18:26:20 vio kernel: bcm2835-rng 3f104000.rng: hwrng registered Nov 30 18:26:20 vio kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Nov 30 18:26:20 vio kernel: vc-sm: Videocore shared memory driver Nov 30 18:26:20 vio kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 Nov 30 18:26:20 vio kernel: brd: module loaded Nov 30 18:26:20 vio kernel: loop: module loaded Nov 30 18:26:20 vio kernel: Loading iSCSI transport class v2.0-870. Nov 30 18:26:20 vio kernel: libphy: Fixed MDIO Bus: probed Nov 30 18:26:20 vio kernel: usbcore: registered new interface driver lan78xx Nov 30 18:26:20 vio kernel: usbcore: registered new interface driver smsc95xx Nov 30 18:26:20 vio kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Nov 30 18:26:20 vio kernel: dwc_otg 3f980000.usb: base=(ptrval) Nov 30 18:26:20 vio kernel: Core Release: 2.80a Nov 30 18:26:20 vio kernel: Setting default values for core params Nov 30 18:26:20 vio kernel: Finished setting default values for core params Nov 30 18:26:20 vio kernel: Using Buffer DMA mode Nov 30 18:26:20 vio kernel: Periodic Transfer Interrupt Enhancement - disabled Nov 30 18:26:20 vio kernel: Multiprocessor Interrupt Enhancement - disabled Nov 30 18:26:20 vio kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Nov 30 18:26:20 vio kernel: Dedicated Tx FIFOs mode Nov 30 18:26:20 vio kernel: WARN::dwc_otg_hcd_init:1045: FIQ DMA bounce buffers: virt = bd914000 dma = 0xfd914000 len=9024 Nov 30 18:26:20 vio kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Nov 30 18:26:20 vio kernel: dwc_otg: Microframe scheduler enabled Nov 30 18:26:20 vio kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Nov 30 18:26:20 vio kernel: WARN::hcd_init_fiq:458: FIQ ASM at 8065637c length 36 Nov 30 18:26:20 vio kernel: WARN::hcd_init_fiq:497: MPHI regs_base at be810000 Nov 30 18:26:20 vio kernel: dwc_otg 3f980000.usb: DWC OTG Controller Nov 30 18:26:20 vio kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Nov 30 18:26:20 vio kernel: dwc_otg 3f980000.usb: irq 56, io mem 0x00000000 Nov 30 18:26:20 vio kernel: Init: Port Power? op_state=1 Nov 30 18:26:20 vio kernel: Init: Power Port (0) Nov 30 18:26:20 vio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19 Nov 30 18:26:20 vio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Nov 30 18:26:20 vio kernel: usb usb1: Product: DWC OTG Controller Nov 30 18:26:20 vio kernel: usb usb1: Manufacturer: Linux 4.19.79-v7+ dwc_otg_hcd Nov 30 18:26:20 vio kernel: usb usb1: SerialNumber: 3f980000.usb Nov 30 18:26:20 vio kernel: hub 1-0:1.0: USB hub found Nov 30 18:26:20 vio kernel: hub 1-0:1.0: 1 port detected Nov 30 18:26:20 vio kernel: dwc_otg: FIQ enabled Nov 30 18:26:20 vio kernel: dwc_otg: NAK holdoff enabled Nov 30 18:26:20 vio kernel: dwc_otg: FIQ split-transaction FSM enabled Nov 30 18:26:20 vio kernel: Module dwc_common_port init Nov 30 18:26:20 vio kernel: usbcore: registered new interface driver usb-storage Nov 30 18:26:20 vio kernel: mousedev: PS/2 mouse device common for all mice Nov 30 18:26:20 vio kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Nov 30 18:26:20 vio kernel: bcm2835-cpufreq: min=600000 max=1400000 Nov 30 18:26:20 vio kernel: sdhci: Secure Digital Host Controller Interface driver Nov 30 18:26:20 vio kernel: sdhci: Copyright(c) Pierre Ossman Nov 30 18:26:20 vio kernel: mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe Nov 30 18:26:20 vio kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe Nov 30 18:26:20 vio kernel: sdhci-pltfm: SDHCI platform and OF driver helper Nov 30 18:26:20 vio kernel: ledtrig-cpu: registered to indicate activity on CPUs Nov 30 18:26:20 vio kernel: hidraw: raw HID events driver (C) Jiri Kosina Nov 30 18:26:20 vio kernel: usbcore: registered new interface driver usbhid Nov 30 18:26:20 vio kernel: usbhid: USB HID core driver Nov 30 18:26:20 vio kernel: vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0 Nov 30 18:26:20 vio kernel: [vc_sm_connected_init]: start Nov 30 18:26:20 vio kernel: [vc_sm_connected_init]: end - returning 0 Nov 30 18:26:20 vio kernel: Initializing XFRM netlink socket Nov 30 18:26:20 vio kernel: NET: Registered protocol family 17 Nov 30 18:26:20 vio kernel: Key type dns_resolver registered Nov 30 18:26:20 vio kernel: Registering SWP/SWPB emulation handler Nov 30 18:26:20 vio kernel: registered taskstats version 1 Nov 30 18:26:20 vio kernel: Loading compiled-in X.509 certificates Nov 30 18:26:20 vio kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Nov 30 18:26:20 vio kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2 Nov 30 18:26:20 vio kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Nov 30 18:26:20 vio kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Nov 30 18:26:20 vio kernel: sdhost: log_buf @ (ptrval) (fd913000) Nov 30 18:26:20 vio kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Nov 30 18:26:20 vio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Nov 30 18:26:20 vio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Nov 30 18:26:20 vio kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Nov 30 18:26:20 vio kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Nov 30 18:26:20 vio kernel: of_cfs_init Nov 30 18:26:20 vio kernel: of_cfs_init: OK Nov 30 18:26:20 vio kernel: Freeing unused kernel memory: 1024K Nov 30 18:26:20 vio kernel: Run /init as init process Nov 30 18:26:20 vio kernel: random: fast init done Nov 30 18:26:20 vio kernel: mmc0: host does not support reading read-only switch, assuming write-enable Nov 30 18:26:20 vio kernel: mmc0: new high speed SDHC card at address e624 Nov 30 18:26:20 vio kernel: mmc1: new high speed SDIO card at address 0001 Nov 30 18:26:20 vio kernel: mmcblk0: mmc0:e624 SU16G 14.8 GiB Nov 30 18:26:20 vio kernel: mmcblk0: p1 p2 p3 Nov 30 18:26:20 vio kernel: Indeed it is in host mode hprt0 = 00021501 Nov 30 18:26:20 vio initramfs: Booting Volumio for BCM2835 Nov 30 18:26:20 vio initramfs: This script mounts rootfs RO with an overlay RW layer. Nov 30 18:26:20 vio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 30 18:26:20 vio kernel: fuse init (API version 7.27) Nov 30 18:26:20 vio initramfs: IMGPART=/dev/mmcblk0p2 Nov 30 18:26:20 vio initramfs: IMGFILE=/volumio_current.sqsh Nov 30 18:26:20 vio initramfs: Boot delay (except first time) will be 5 seconds Nov 30 18:26:20 vio initramfs: /dev/mmcblk0p2: Nov 30 18:26:20 vio kernel: EXT4-fs (mmcblk0p2): recovery complete Nov 30 18:26:20 vio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Nov 30 18:26:20 vio initramfs: Doing a 5 second delay here to give kernel load a headstart Nov 30 18:26:20 vio kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Nov 30 18:26:20 vio kernel: Indeed it is in host mode hprt0 = 00001101 Nov 30 18:26:20 vio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3 Nov 30 18:26:20 vio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 30 18:26:20 vio kernel: hub 1-1:1.0: USB hub found Nov 30 18:26:20 vio kernel: hub 1-1:1.0: 4 ports detected Nov 30 18:26:20 vio kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Nov 30 18:26:20 vio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3 Nov 30 18:26:20 vio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 30 18:26:20 vio kernel: hub 1-1.1:1.0: USB hub found Nov 30 18:26:20 vio kernel: hub 1-1.1:1.0: 3 ports detected Nov 30 18:26:20 vio kernel: usb 1-1.3: new high-speed USB device number 4 using dwc_otg Nov 30 18:26:20 vio kernel: usb 1-1.3: New USB device found, idVendor=245f, idProduct=0815, bcdDevice= 1.97 Nov 30 18:26:20 vio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Nov 30 18:26:20 vio kernel: usb 1-1.3: Product: Qutest Nov 30 18:26:20 vio kernel: usb 1-1.3: Manufacturer: Chord Electronics Ltd Nov 30 18:26:20 vio kernel: usb 1-1.3: SerialNumber: 413-001 Nov 30 18:26:20 vio kernel: usb 1-1.1.1: new high-speed USB device number 5 using dwc_otg Nov 30 18:26:20 vio kernel: usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00 Nov 30 18:26:20 vio kernel: usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Nov 30 18:26:20 vio kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed Nov 30 18:26:20 vio kernel: libphy: lan78xx-mdiobus: probed Nov 30 18:26:20 vio kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64 Nov 30 18:26:20 vio initramfs: Checking for USB updates Nov 30 18:26:20 vio initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Nov 30 18:26:20 vio kernel: EXT4-fs (mmcblk0p3): recovery complete Nov 30 18:26:20 vio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Nov 30 18:26:20 vio initramfs: With Option: Nov 30 18:26:20 vio initramfs: VOLUMIO_VERSION="2.672" Nov 30 18:26:20 vio initramfs: Finish initramfs, continue booting Volumio Nov 30 18:26:20 vio kernel: random: systemd: uninitialized urandom read (16 bytes read) Nov 30 18:26:20 vio systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) Nov 30 18:26:20 vio systemd[1]: Detected architecture 'arm'. Nov 30 18:26:20 vio kernel: NET: Registered protocol family 10 Nov 30 18:26:20 vio kernel: Segment Routing with IPv6 Nov 30 18:26:20 vio systemd[1]: Inserted module 'ipv6' Nov 30 18:26:20 vio systemd[1]: Set hostname to . Nov 30 18:26:20 vio kernel: random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) Nov 30 18:26:20 vio kernel: random: systemd: uninitialized urandom read (16 bytes read) Nov 30 18:26:20 vio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Nov 30 18:26:20 vio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Nov 30 18:26:20 vio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Nov 30 18:26:20 vio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Nov 30 18:26:20 vio systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory. Nov 30 18:26:20 vio systemd[1]: Starting Forward Password Requests to Wall Directory Watch. Nov 30 18:26:20 vio kernel: i2c /dev entries driver Nov 30 18:26:20 vio systemd-journal[174]: Journal started Nov 30 18:26:20 vio systemd[1]: Starting Slices. Nov 30 18:26:20 vio systemd[1]: Reached target Slices. Nov 30 18:26:20 vio systemd[1]: Starting Remount Root and Kernel File Systems... Nov 30 18:26:20 vio systemd[1]: Started Create list of required static device nodes for the current kernel. Nov 30 18:26:20 vio systemd[1]: Starting Create Static Device Nodes in /dev... Nov 30 18:26:20 vio systemd[1]: Time has been changed Nov 30 18:26:20 vio systemd[1]: Started Restore / save the current clock. Nov 30 18:26:20 vio systemd[1]: Started Create Static Device Nodes in /dev. Nov 30 18:26:20 vio systemd-modules-load[172]: Inserted module 'i2c_dev' Nov 30 18:26:20 vio systemd[1]: Mounted Debug File System. Nov 30 18:26:20 vio systemd[1]: Mounted POSIX Message Queue File System. Nov 30 18:26:20 vio fake-hwclock[163]: Sat Nov 30 18:26:20 UTC 2019 Nov 30 18:26:20 vio systemd[1]: Started Various fixups to make systemd work better on Debian. Nov 30 18:26:20 vio systemd[1]: Starting Load/Save Random Seed... Nov 30 18:26:20 vio systemd[1]: Mounting FUSE Control File System... Nov 30 18:26:20 vio systemd[1]: Mounting Configuration File System... Nov 30 18:26:20 vio systemd[1]: Starting Apply Kernel Variables... Nov 30 18:26:20 vio systemd[1]: Starting udev Kernel Device Manager... Nov 30 18:26:20 vio systemd[1]: Starting Local File Systems (Pre). Nov 30 18:26:20 vio systemd[1]: Reached target Local File Systems (Pre). Nov 30 18:26:20 vio systemd[1]: Mounting /tmp... Nov 30 18:26:20 vio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Nov 30 18:26:20 vio systemd[1]: Mounting /var/spool/cups... Nov 30 18:26:20 vio systemd[1]: Mounting /var/log... Nov 30 18:26:20 vio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Nov 30 18:26:20 vio systemd[1]: Mounted Configuration File System. Nov 30 18:26:20 vio systemd[1]: Mounted FUSE Control File System. Nov 30 18:26:20 vio systemd[1]: Mounted /tmp. Nov 30 18:26:20 vio systemd[1]: Mounted /var/spool/cups. Nov 30 18:26:20 vio systemd[1]: Started Load/Save Random Seed. Nov 30 18:26:20 vio systemd[1]: Started Apply Kernel Variables. Nov 30 18:26:20 vio systemd[1]: Mounted /var/log. Nov 30 18:26:20 vio systemd-udevd[200]: starting version 215 Nov 30 18:26:20 vio systemd[1]: Started udev Kernel Device Manager. Nov 30 18:26:20 vio systemd[1]: Starting Show Plymouth Boot Screen... Nov 30 18:26:20 vio systemd[1]: Starting Copy rules generated while the root was ro... Nov 30 18:26:20 vio systemd[1]: Mounting /var/spool/cups/tmp... Nov 30 18:26:20 vio systemd[1]: Started Copy rules generated while the root was ro. Nov 30 18:26:20 vio systemd[1]: Mounted /var/spool/cups/tmp. Nov 30 18:26:20 vio systemd[1]: Started Show Plymouth Boot Screen. Nov 30 18:26:20 vio systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch. Nov 30 18:26:20 vio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Nov 30 18:26:20 vio systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Nov 30 18:26:20 vio systemd[1]: Starting Paths. Nov 30 18:26:20 vio systemd[1]: Reached target Paths. Nov 30 18:26:20 vio kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Nov 30 18:26:20 vio kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Nov 30 18:26:20 vio kernel: [vc_sm_connected_init]: start Nov 30 18:26:20 vio kernel: [vc_sm_connected_init]: installed successfully Nov 30 18:26:20 vio kernel: media: Linux media interface: v0.10 Nov 30 18:26:21 vio kernel: videodev: Linux video capture interface: v2.00 Nov 30 18:26:21 vio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Nov 30 18:26:21 vio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Nov 30 18:26:21 vio kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Nov 30 18:26:21 vio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Nov 30 18:26:21 vio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Nov 30 18:26:21 vio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Nov 30 18:26:21 vio kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Nov 30 18:26:21 vio kernel: snd_bcm2835: unknown parameter 'index' ignored Nov 30 18:26:21 vio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Nov 30 18:26:21 vio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Nov 30 18:26:21 vio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Nov 30 18:26:21 vio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Nov 30 18:26:21 vio kernel: bcm2835_audio soc:audio: card created with 8 channels Nov 30 18:26:21 vio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Nov 30 18:26:21 vio kernel: platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 Nov 30 18:26:21 vio kernel: cfg80211: failed to load regulatory.db Nov 30 18:26:21 vio kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Nov 30 18:26:21 vio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Nov 30 18:26:21 vio kernel: usbcore: registered new interface driver brcmfmac Nov 30 18:26:21 vio systemd[1]: Found device /dev/mmcblk0p1. Nov 30 18:26:21 vio systemd[1]: Starting Sound Card. Nov 30 18:26:21 vio systemd[1]: Reached target Sound Card. Nov 30 18:26:21 vio systemd[1]: Starting File System Check on /dev/mmcblk0p1... Nov 30 18:26:21 vio kernel: snd_usb_audio: unknown parameter 'nrpacks' ignored Nov 30 18:26:21 vio kernel: snd_usb_audio: unknown parameter 'nrpacks' ignored Nov 30 18:26:21 vio kernel: usb 1-1.3: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Nov 30 18:26:21 vio kernel: usb 1-1.3: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Nov 30 18:26:21 vio kernel: usb 1-1.3: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Nov 30 18:26:21 vio kernel: usb 1-1.3: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Nov 30 18:26:21 vio kernel: usbcore: registered new interface driver snd-usb-audio Nov 30 18:26:21 vio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Nov 30 18:26:21 vio 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 Nov 30 18:26:21 vio kernel: random: crng init done Nov 30 18:26:21 vio kernel: random: 7 urandom warning(s) missed due to ratelimiting Nov 30 18:26:21 vio systemd[1]: Starting system-systemd\x2drfkill.slice. Nov 30 18:26:21 vio systemd[1]: Created slice system-systemd\x2drfkill.slice. Nov 30 18:26:21 vio systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill0... Nov 30 18:26:21 vio systemd[1]: Starting system-ifup.slice. Nov 30 18:26:21 vio systemd[1]: Created slice system-ifup.slice. Nov 30 18:26:21 vio systemd-fsck[312]: fsck.fat 3.0.27 (2014-11-12) Nov 30 18:26:21 vio systemd-fsck[312]: /dev/mmcblk0p1: 225 files, 118343/123044 clusters Nov 30 18:26:21 vio systemd[1]: Started File System Check on /dev/mmcblk0p1. Nov 30 18:26:21 vio systemd[1]: Started Load/Save RF Kill Switch Status of rfkill0. Nov 30 18:26:22 vio systemd[1]: Mounting /boot... Nov 30 18:26:22 vio systemd[1]: Mounted /boot. Nov 30 18:26:22 vio systemd[1]: Starting Local File Systems. Nov 30 18:26:22 vio systemd[1]: Reached target Local File Systems. Nov 30 18:26:22 vio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Nov 30 18:26:22 vio systemd[1]: Starting Remote File Systems. Nov 30 18:26:22 vio systemd[1]: Reached target Remote File Systems. Nov 30 18:26:22 vio systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... Nov 30 18:26:22 vio systemd[1]: Starting Create Volatile Files and Directories... Nov 30 18:26:22 vio systemd[1]: Starting LSB: Raise network interfaces.... Nov 30 18:26:22 vio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Nov 30 18:26:22 vio systemd[1]: Started Create Volatile Files and Directories. Nov 30 18:26:22 vio systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. Nov 30 18:26:22 vio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Nov 30 18:26:22 vio systemd[1]: Started Update UTMP about System Boot/Shutdown. Nov 30 18:26:22 vio kernel: brcmfmac: power management disabled Nov 30 18:26:22 vio kernel: Under-voltage detected! (0x00050005) Nov 30 18:26:23 vio systemd[1]: Received SIGRTMIN+20 from PID 232 (plymouthd). Nov 30 18:26:23 vio kernel: brcmfmac: power management disabled Nov 30 18:26:23 vio networking[343]: Configuring network interfaces...done. Nov 30 18:26:23 vio systemd[1]: Started LSB: Raise network interfaces.. Nov 30 18:26:23 vio systemd[1]: Starting ifup for wlan0... Nov 30 18:26:23 vio systemd[1]: Started ifup for wlan0. Nov 30 18:26:23 vio systemd[1]: Starting ifup for eth0... Nov 30 18:26:23 vio systemd[1]: Started ifup for eth0. Nov 30 18:26:23 vio systemd[1]: Starting Network. Nov 30 18:26:23 vio systemd[1]: Reached target Network. Nov 30 18:26:23 vio systemd[1]: Starting Network is Online. Nov 30 18:26:23 vio systemd[1]: Reached target Network is Online. Nov 30 18:26:23 vio systemd[1]: Starting LSB: RPC portmapper replacement... Nov 30 18:26:23 vio ifup[430]: dhcpcd[464]: version 6.0.5 starting Nov 30 18:26:23 vio dhcpcd[464]: version 6.0.5 starting Nov 30 18:26:24 vio rpcbind[432]: Starting rpcbind daemon.... Nov 30 18:26:24 vio systemd[1]: Started LSB: RPC portmapper replacement. Nov 30 18:26:24 vio systemd[1]: Starting RPC Port Mapper. Nov 30 18:26:24 vio systemd[1]: Reached target RPC Port Mapper. Nov 30 18:26:24 vio systemd[1]: Starting LSB: NFS support files common to client and server... Nov 30 18:26:24 vio dhcpcd[464]: eth0: waiting for carrier Nov 30 18:26:24 vio ifup[430]: dhcpcd[464]: eth0: waiting for carrier Nov 30 18:26:24 vio rpc.statd[551]: Version 1.2.8 starting Nov 30 18:26:24 vio sm-notify[552]: Version 1.2.8 starting Nov 30 18:26:24 vio kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Nov 30 18:26:24 vio nfs-common[517]: Starting NFS common utilities: statd idmapd. Nov 30 18:26:24 vio systemd[1]: Started LSB: NFS support files common to client and server. Nov 30 18:26:24 vio systemd[1]: Starting System Initialization. Nov 30 18:26:24 vio systemd[1]: Reached target System Initialization. Nov 30 18:26:24 vio systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. Nov 30 18:26:24 vio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Nov 30 18:26:24 vio systemd[1]: Starting mpd.socket. Nov 30 18:26:24 vio systemd[1]: Listening on mpd.socket. Nov 30 18:26:24 vio systemd[1]: Starting D-Bus System Message Bus Socket. Nov 30 18:26:24 vio systemd[1]: Listening on D-Bus System Message Bus Socket. Nov 30 18:26:24 vio systemd[1]: Starting Sockets. Nov 30 18:26:24 vio systemd[1]: Reached target Sockets. Nov 30 18:26:24 vio systemd[1]: Starting Daily Cleanup of Temporary Directories. Nov 30 18:26:24 vio systemd[1]: Started Daily Cleanup of Temporary Directories. Nov 30 18:26:24 vio systemd[1]: Starting Timers. Nov 30 18:26:24 vio systemd[1]: Reached target Timers. Nov 30 18:26:24 vio systemd[1]: Started Manage Sound Card State (restore and store). Nov 30 18:26:24 vio systemd[1]: Starting Restore Sound Card State... Nov 30 18:26:24 vio systemd[1]: Starting Basic System. Nov 30 18:26:24 vio systemd[1]: Reached target Basic System. Nov 30 18:26:24 vio systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... Nov 30 18:26:24 vio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Nov 30 18:26:24 vio systemd[1]: Starting cd.service... Nov 30 18:26:24 vio systemd[1]: Started cd.service. Nov 30 18:26:24 vio systemd[1]: Starting Configure Bluetooth Modems connected by UART... Nov 30 18:26:24 vio systemd[1]: Starting Volumio Iptables Module... Nov 30 18:26:24 vio systemd[1]: Started Volumio Iptables Module. Nov 30 18:26:24 vio systemd[1]: Starting mount disks automatically with standby... Nov 30 18:26:24 vio systemd[1]: Started mount disks automatically with standby. Nov 30 18:26:24 vio systemd[1]: Starting UPnP Renderer front-end to MPD... Nov 30 18:26:24 vio systemd[1]: Started UPnP Renderer front-end to MPD. Nov 30 18:26:24 vio systemd[1]: Starting volumio-remote-updater.service... Nov 30 18:26:24 vio systemd[1]: Started volumio-remote-updater.service. Nov 30 18:26:24 vio systemd[1]: Starting Volumio SSH enabler... Nov 30 18:26:24 vio volumio[566]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Nov 30 18:26:24 vio systemd[1]: Started Volumio SSH enabler. Nov 30 18:26:24 vio systemd[1]: Starting Wireless Services... Nov 30 18:26:24 vio systemd[1]: Starting Xbindrc... Nov 30 18:26:24 vio systemd[1]: Started Xbindrc. Nov 30 18:26:24 vio systemd[1]: Starting /etc/rc.local Compatibility... Nov 30 18:26:24 vio systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. Nov 30 18:26:24 vio systemd[1]: Starting Login Service... Nov 30 18:26:24 vio volumiossh[576]: SSH file found, enabling SSH service Nov 30 18:26:24 vio systemd[1]: Starting LSB: start Winbind daemon... Nov 30 18:26:24 vio systemd[1]: Starting LSB: start Samba daemons for the AD DC... Nov 30 18:26:24 vio systemd[1]: Starting LSB: Start NTP daemon... Nov 30 18:26:24 vio systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... Nov 30 18:26:24 vio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Nov 30 18:26:24 vio systemd[1]: Starting LSB: Brings up/down network automatically... Nov 30 18:26:24 vio systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... Nov 30 18:26:24 vio systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... Nov 30 18:26:24 vio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Nov 30 18:26:24 vio systemd[1]: Starting Music Player Daemon... Nov 30 18:26:24 vio kernel: uart-pl011 3f201000.serial: no DMA platform data Nov 30 18:26:24 vio systemd[1]: Starting D-Bus System Message Bus... Nov 30 18:26:24 vio systemd[1]: Started D-Bus System Message Bus. Nov 30 18:26:24 vio dhcpcd[623]: Not running dhcpcd because there is aleady an interface specific instance ... failed! Nov 30 18:26:24 vio btuart[568]: Patch not found for BCM4345C0, continue anyway Nov 30 18:26:24 vio dhcpcd[623]: /var/run/dhcpcd-eth0.pid ... failed! Nov 30 18:26:25 vio ifplugd(eth0)[667]: ifplugd 0.28 initializing. Nov 30 18:26:25 vio ifplugd(eth0)[667]: Using interface eth0/B8:27:EB:9C:20:49 with driver (version: ) Nov 30 18:26:25 vio ifplugd(eth0)[667]: Using detection mode: SIOCETHTOOL Nov 30 18:26:25 vio ifplugd(eth0)[667]: Initialization complete, link beat not detected. Nov 30 18:26:25 vio avahi-daemon[630]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). Nov 30 18:26:25 vio avahi-daemon[630]: Successfully dropped root privileges. Nov 30 18:26:25 vio avahi-daemon[630]: avahi-daemon 0.6.31 starting up. Nov 30 18:26:25 vio ifplugd[608]: Network Interface Plugging Daemon...start eth0...done. Nov 30 18:26:25 vio loadcpufreq[603]: Loading cpufreq kernel modules...done (none). Nov 30 18:26:25 vio avahi-daemon[630]: Successfully called chroot(). Nov 30 18:26:25 vio avahi-daemon[630]: Successfully dropped remaining capabilities. Nov 30 18:26:25 vio avahi-daemon[630]: Loading service file /services/volumio.service. Nov 30 18:26:25 vio avahi-daemon[630]: Network interface enumeration completed. Nov 30 18:26:25 vio avahi-daemon[630]: Registering HINFO record with values 'ARMV7L'/'LINUX'. Nov 30 18:26:25 vio avahi-daemon[630]: Server startup complete. Host name is vio.local. Local service cookie is 3185740803. Nov 30 18:26:25 vio avahi-daemon[630]: Service "vio" (/services/volumio.service) successfully established. Nov 30 18:26:25 vio ntpd[655]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) Nov 30 18:26:25 vio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Nov 30 18:26:25 vio systemd[1]: Starting Permit User Sessions... Nov 30 18:26:25 vio systemd[1]: Started Restore Sound Card State. Nov 30 18:26:25 vio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Nov 30 18:26:25 vio systemd[1]: Unit cd.service entered failed state. Nov 30 18:26:25 vio systemd[1]: Started /etc/rc.local Compatibility. Nov 30 18:26:25 vio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Nov 30 18:26:25 vio systemd[1]: Started LSB: Brings up/down network automatically. Nov 30 18:26:25 vio systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. Nov 30 18:26:25 vio systemd[1]: Started LSB: Start NTP daemon. Nov 30 18:26:25 vio systemd[1]: dhcpcd.service: control process exited, code=exited status=6 Nov 30 18:26:25 vio systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. Nov 30 18:26:25 vio systemd[1]: Unit dhcpcd.service entered failed state. Nov 30 18:26:25 vio systemd[1]: Started Permit User Sessions. Nov 30 18:26:25 vio ntp[599]: Starting NTP server: ntpd. Nov 30 18:26:25 vio dbus[635]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' Nov 30 18:26:25 vio ntpd[690]: proto: precision = 1.093 usec Nov 30 18:26:25 vio systemd[1]: cd.service holdoff time over, scheduling restart. Nov 30 18:26:25 vio ntpd[690]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Nov 30 18:26:25 vio ntpd[690]: Listen and drop on 1 v6wildcard :: UDP 123 Nov 30 18:26:25 vio ntpd[690]: Listen normally on 2 lo 127.0.0.1 UDP 123 Nov 30 18:26:25 vio ntpd[690]: peers refreshed Nov 30 18:26:25 vio ntpd[690]: Listening on routing socket on fd #19 for interface updates Nov 30 18:26:25 vio kernel: Bluetooth: Core ver 2.22 Nov 30 18:26:25 vio kernel: NET: Registered protocol family 31 Nov 30 18:26:25 vio kernel: Bluetooth: HCI device and connection manager initialized Nov 30 18:26:25 vio kernel: Bluetooth: HCI socket layer initialized Nov 30 18:26:25 vio kernel: Bluetooth: L2CAP socket layer initialized Nov 30 18:26:25 vio kernel: Bluetooth: SCO socket layer initialized Nov 30 18:26:25 vio systemd-logind[591]: New seat seat0. Nov 30 18:26:25 vio systemd[1]: Started Login Service. Nov 30 18:26:25 vio systemd[1]: Starting Disk Manager (legacy version)... Nov 30 18:26:25 vio systemd[1]: Stopping cd.service... Nov 30 18:26:25 vio systemd[1]: Starting cd.service... Nov 30 18:26:25 vio systemd[1]: Started cd.service. Nov 30 18:26:25 vio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Nov 30 18:26:25 vio systemd[1]: Starting Hold until boot process finishes up... Nov 30 18:26:25 vio sudo[586]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Nov 30 18:26:25 vio sudo[585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ssh Nov 30 18:26:25 vio volumio[693]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Nov 30 18:26:25 vio systemd[1]: Starting Terminate Plymouth Boot Screen... Nov 30 18:26:25 vio sudo[585]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:26:25 vio sudo[586]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:26:25 vio systemd[1]: Received SIGRTMIN+21 from PID 232 (plymouthd). Nov 30 18:26:25 vio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Nov 30 18:26:25 vio systemd[1]: Unit cd.service entered failed state. Nov 30 18:26:25 vio sudo[586]: pam_unix(sudo:session): session closed for user root Nov 30 18:26:25 vio systemd[1]: Started Hold until boot process finishes up. Nov 30 18:26:25 vio kernel: Bluetooth: HCI UART driver ver 2.3 Nov 30 18:26:25 vio kernel: Bluetooth: HCI UART protocol H4 registered Nov 30 18:26:25 vio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Nov 30 18:26:25 vio kernel: Bluetooth: HCI UART protocol Broadcom registered Nov 30 18:26:25 vio systemd-udevd[720]: failed to execute '/usr/bin/hciconfig' '/usr/bin/hciconfig hci0 up': No such file or directory Nov 30 18:26:25 vio systemd[1]: Started Configure Bluetooth Modems connected by UART. Nov 30 18:26:25 vio systemd[1]: Started Terminate Plymouth Boot Screen. Nov 30 18:26:25 vio btuart[568]: bcm43xx_init Nov 30 18:26:25 vio btuart[568]: Set BDADDR UART: b8:27:eb:36:8a:e3 Nov 30 18:26:25 vio btuart[568]: Set Controller UART speed to 3000000 bit/s Nov 30 18:26:25 vio btuart[568]: Device setup complete Nov 30 18:26:25 vio volumio-remote-updater[575]: Error: No active session Nov 30 18:26:25 vio volumio-remote-updater[575]: [2019-11-30 18:26:25] [info] asio async_connect error: system:111 (Connection refused) Nov 30 18:26:25 vio volumio-remote-updater[575]: [2019-11-30 18:26:25] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 30 18:26:25 vio volumio-remote-updater[575]: [2019-11-30 18:26:25] [error] handle_connect error: Underlying Transport Error Nov 30 18:26:25 vio dbus[635]: [system] Successfully activated service 'org.freedesktop.UDisks' Nov 30 18:26:25 vio systemd[1]: cd.service holdoff time over, scheduling restart. Nov 30 18:26:25 vio cpufrequtils[699]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Nov 30 18:26:25 vio systemd[1]: Started LSB: set CPUFreq kernel parameters. Nov 30 18:26:25 vio systemd[1]: Started Disk Manager (legacy version). Nov 30 18:26:26 vio volumio[571]: Could not open config: /tmp/upmpdcli.conf Nov 30 18:26:26 vio systemd[1]: Starting OpenBSD Secure Shell server... Nov 30 18:26:26 vio systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill1... Nov 30 18:26:26 vio systemd[1]: Starting Bluetooth service... Nov 30 18:26:26 vio systemd[1]: Stopping cd.service... Nov 30 18:26:26 vio systemd[1]: Starting cd.service... Nov 30 18:26:26 vio systemd[1]: Started cd.service. Nov 30 18:26:26 vio systemd[1]: Starting Getty on tty1... Nov 30 18:26:26 vio systemd[1]: Started Getty on tty1. Nov 30 18:26:26 vio systemd[1]: Starting Login Prompts. Nov 30 18:26:26 vio systemd[1]: Reached target Login Prompts. Nov 30 18:26:26 vio systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE Nov 30 18:26:26 vio volumio[743]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Nov 30 18:26:26 vio systemd[1]: Unit upmpdcli.service entered failed state. Nov 30 18:26:26 vio systemd[1]: Started Load/Save RF Kill Switch Status of rfkill1. Nov 30 18:26:26 vio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Nov 30 18:26:26 vio systemd[1]: Unit cd.service entered failed state. Nov 30 18:26:26 vio dbus[635]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' Nov 30 18:26:26 vio systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... Nov 30 18:26:26 vio systemd[1]: cd.service holdoff time over, scheduling restart. Nov 30 18:26:26 vio systemd[1]: Stopping cd.service... Nov 30 18:26:26 vio systemd[1]: Starting cd.service... Nov 30 18:26:26 vio systemd[1]: Started cd.service. Nov 30 18:26:26 vio systemd[1]: Started OpenBSD Secure Shell server. Nov 30 18:26:26 vio sudo[585]: pam_unix(sudo:session): session closed for user root Nov 30 18:26:26 vio bluetoothd[741]: Bluetooth daemon 5.23 Nov 30 18:26:26 vio volumio[751]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Nov 30 18:26:26 vio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Nov 30 18:26:26 vio systemd[1]: Unit cd.service entered failed state. Nov 30 18:26:26 vio sshd[754]: Server listening on 0.0.0.0 port 22. Nov 30 18:26:26 vio sshd[754]: Server listening on :: port 22. Nov 30 18:26:26 vio bluetoothd[741]: Starting SDP server Nov 30 18:26:26 vio systemd[1]: Started Bluetooth service. Nov 30 18:26:26 vio systemd[1]: cd.service holdoff time over, scheduling restart. Nov 30 18:26:26 vio systemd[1]: Stopping cd.service... Nov 30 18:26:26 vio systemd[1]: Starting cd.service... Nov 30 18:26:26 vio systemd[1]: Started cd.service. Nov 30 18:26:26 vio systemd[1]: Starting Bluetooth. Nov 30 18:26:26 vio systemd[1]: Reached target Bluetooth. Nov 30 18:26:26 vio volumio[761]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory Nov 30 18:26:26 vio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a Nov 30 18:26:26 vio systemd[1]: Unit cd.service entered failed state. Nov 30 18:26:26 vio polkitd[748]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 30 18:26:26 vio dbus[635]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 30 18:26:26 vio systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. Nov 30 18:26:26 vio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Nov 30 18:26:26 vio kernel: Bluetooth: BNEP filters: protocol multicast Nov 30 18:26:26 vio kernel: Bluetooth: BNEP socket layer initialized Nov 30 18:26:26 vio bluetoothd[741]: Bluetooth management interface 1.14 initialized Nov 30 18:26:26 vio dbus[635]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' Nov 30 18:26:26 vio bluetoothd[741]: Sap driver initialization failed. Nov 30 18:26:26 vio bluetoothd[741]: sap-server: Operation not permitted (1) Nov 30 18:26:26 vio systemd[1]: cd.service holdoff time over, scheduling restart. Nov 30 18:26:26 vio systemd[1]: Stopping cd.service... Nov 30 18:26:26 vio systemd[1]: Starting cd.service... Nov 30 18:26:26 vio systemd[1]: cd.service start request repeated too quickly, refusing to start. Nov 30 18:26:26 vio systemd[1]: Failed to start cd.service. Nov 30 18:26:26 vio systemd[1]: Unit cd.service entered failed state. Nov 30 18:26:26 vio systemd[1]: Starting Hostname Service... Nov 30 18:26:26 vio udisks-glue[570]: Device file /dev/mmcblk0p3 inserted Nov 30 18:26:26 vio udisks-glue[570]: Device file /dev/mmcblk0 inserted Nov 30 18:26:26 vio systemd-hostnamed[764]: Warning: nss-myhostname is not installed. Changing the local hostname might make it unresolveable. Please install nss-myhostname! Nov 30 18:26:26 vio dbus[635]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 30 18:26:27 vio systemd[1]: Started Hostname Service. Nov 30 18:26:27 vio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Nov 30 18:26:27 vio systemd[1]: Stopping Xbindrc... Nov 30 18:26:27 vio systemd[1]: Starting Xbindrc... Nov 30 18:26:27 vio systemd[1]: Started Xbindrc. Nov 30 18:26:28 vio haveged[565]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K Nov 30 18:26:28 vio haveged[565]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244 Nov 30 18:26:28 vio haveged[565]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00159 Nov 30 18:26:28 vio haveged[565]: haveged: fills: 0, generated: 0 Nov 30 18:26:28 vio ntpd[690]: Deferring DNS for 0.debian.pool.ntp.org 1 Nov 30 18:26:28 vio ntpd[690]: Deferring DNS for 1.debian.pool.ntp.org 1 Nov 30 18:26:28 vio ntpd[690]: Deferring DNS for 2.debian.pool.ntp.org 1 Nov 30 18:26:28 vio ntpd[690]: Deferring DNS for 3.debian.pool.ntp.org 1 Nov 30 18:26:28 vio ntpd[775]: signal_no_reset: signal 17 had flags 4000000 Nov 30 18:26:28 vio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Nov 30 18:26:28 vio systemd[1]: Stopping Xbindrc... Nov 30 18:26:28 vio systemd[1]: Starting Xbindrc... Nov 30 18:26:28 vio systemd[1]: Started Xbindrc. Nov 30 18:26:28 vio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Nov 30 18:26:28 vio systemd[1]: Stopping Xbindrc... Nov 30 18:26:28 vio systemd[1]: Starting Xbindrc... Nov 30 18:26:28 vio systemd[1]: Started Xbindrc. Nov 30 18:26:29 vio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Nov 30 18:26:29 vio systemd[1]: Stopping Xbindrc... Nov 30 18:26:29 vio systemd[1]: Starting Xbindrc... Nov 30 18:26:29 vio systemd[1]: Started Xbindrc. Nov 30 18:26:29 vio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Nov 30 18:26:29 vio systemd[1]: Stopping Xbindrc... Nov 30 18:26:29 vio systemd[1]: Starting Xbindrc... Nov 30 18:26:29 vio systemd[1]: xbindkeysrc.service start request repeated too quickly, refusing to start. Nov 30 18:26:29 vio systemd[1]: Failed to start Xbindrc. Nov 30 18:26:29 vio systemd[1]: Unit xbindkeysrc.service entered failed state. Nov 30 18:26:30 vio ntpd_intres[775]: host name not found: 0.debian.pool.ntp.org Nov 30 18:26:30 vio ntpd_intres[775]: host name not found: 1.debian.pool.ntp.org Nov 30 18:26:30 vio ntpd_intres[775]: host name not found: 2.debian.pool.ntp.org Nov 30 18:26:30 vio ntpd_intres[775]: host name not found: 3.debian.pool.ntp.org Nov 30 18:26:30 vio volumio-remote-updater[575]: [2019-11-30 18:26:30] [info] asio async_connect error: system:111 (Connection refused) Nov 30 18:26:30 vio volumio-remote-updater[575]: [2019-11-30 18:26:30] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 30 18:26:30 vio volumio-remote-updater[575]: [2019-11-30 18:26:30] [error] handle_connect error: Underlying Transport Error Nov 30 18:26:31 vio wireless.js[733]: WIRELESS DAEMON: start Nov 30 18:26:31 vio wireless.js[733]: WIRELESS: Loaded configuration Nov 30 18:26:31 vio wireless.js[733]: Cleaning previous... Nov 30 18:26:31 vio systemd[1]: Stopped hotspot.service. Nov 30 18:26:31 vio sudo[798]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 30 18:26:31 vio sudo[798]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:26:31 vio sudo[798]: pam_unix(sudo:session): session closed for user root Nov 30 18:26:31 vio sudo[807]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 30 18:26:31 vio systemd[1]: Started Music Player Daemon. Nov 30 18:26:31 vio sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:26:31 vio systemd[1]: Started LSB: start Samba daemons for the AD DC. Nov 30 18:26:31 vio winbind[592]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Nov 30 18:26:31 vio winbind[592]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Nov 30 18:26:31 vio winbind[592]: Unable to setup corepath for winbindd: No such file or directory Nov 30 18:26:31 vio winbind[592]: mkdir failed on directory /var/log/samba/cores: No such file or directory Nov 30 18:26:31 vio winbind[592]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Nov 30 18:26:31 vio winbind[592]: Unable to setup corepath for winbindd: No such file or directory Nov 30 18:26:31 vio winbind[592]: [2019/11/30 18:26:31, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:31 vio winbind[592]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Nov 30 18:26:31 vio winbind[592]: [2019/11/30 18:26:31, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:31 vio winbind[592]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Nov 30 18:26:31 vio winbind[592]: [2019/11/30 18:26:31, 0] ../source3/winbindd/winbindd.c:1549(main) Nov 30 18:26:31 vio winbind[592]: winbindd version 4.2.14-Debian started. Nov 30 18:26:31 vio winbind[592]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Nov 30 18:26:31 vio winbind[592]: [2019/11/30 18:26:31, 0] ../lib/util/util.c:220(directory_create_or_exist) Nov 30 18:26:31 vio winbind[592]: mkdir failed on directory /var/log/samba/cores: No such file or directory Nov 30 18:26:31 vio winbind[592]: [2019/11/30 18:26:31, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Nov 30 18:26:31 vio winbind[592]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Nov 30 18:26:31 vio winbind[592]: [2019/11/30 18:26:31, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Nov 30 18:26:31 vio winbind[592]: Unable to setup corepath for winbindd: No such file or directory Nov 30 18:26:31 vio winbindd[824]: [2019/11/30 18:26:31.863437, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:31 vio winbindd[824]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Nov 30 18:26:31 vio winbindd[824]: [2019/11/30 18:26:31.865269, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:31 vio winbindd[824]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Nov 30 18:26:31 vio winbind[592]: [2019/11/30 18:26:31.863437, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:31 vio winbind[592]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Nov 30 18:26:31 vio winbind[592]: [2019/11/30 18:26:31.865269, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:31 vio winbind[592]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Nov 30 18:26:31 vio sudo[807]: pam_unix(sudo:session): session closed for user root Nov 30 18:26:31 vio nmbd[600]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Nov 30 18:26:31 vio nmbd[600]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Nov 30 18:26:31 vio nmbd[600]: Unable to setup corepath for nmbd: No such file or directory Nov 30 18:26:31 vio nmbd[827]: [2019/11/30 18:26:31.889312, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:31 vio nmbd[827]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Nov 30 18:26:31 vio nmbd[827]: [2019/11/30 18:26:31.893414, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:31 vio nmbd[827]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Nov 30 18:26:31 vio nmbd[832]: [2019/11/30 18:26:31.900835, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) Nov 30 18:26:31 vio nmbd[832]: started asyncdns process 833 Nov 30 18:26:31 vio systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Nov 30 18:26:31 vio systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Nov 30 18:26:31 vio systemd[1]: Started LSB: start Winbind daemon. Nov 30 18:26:31 vio nmbd[600]: [2019/11/30 18:26:31, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:31 vio nmbd[600]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Nov 30 18:26:31 vio nmbd[600]: [2019/11/30 18:26:31, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:31 vio nmbd[600]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Nov 30 18:26:31 vio nmbd[600]: [2019/11/30 18:26:31, 0] ../source3/nmbd/nmbd.c:908(main) Nov 30 18:26:31 vio nmbd[600]: nmbd version 4.2.14-Debian started. Nov 30 18:26:31 vio nmbd[600]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Nov 30 18:26:31 vio nmbd[600]: [2019/11/30 18:26:31.889312, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:31 vio nmbd[600]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Nov 30 18:26:31 vio nmbd[600]: [2019/11/30 18:26:31.893414, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:31 vio nmbd[600]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Nov 30 18:26:31 vio winbindd[831]: [2019/11/30 18:26:31.916446, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Nov 30 18:26:31 vio winbindd[831]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 30 18:26:31 vio nmbd[832]: [2019/11/30 18:26:31.924052, 0] ../lib/util/become_daemon.c:135(daemon_status) Nov 30 18:26:31 vio nmbd[832]: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Nov 30 18:26:31 vio winbind[592]: . Nov 30 18:26:31 vio nmbd[600]: . Nov 30 18:26:31 vio winbindd[831]: [2019/11/30 18:26:31.966718, 0] ../lib/util/become_daemon.c:124(daemon_ready) Nov 30 18:26:31 vio winbindd[831]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 30 18:26:31 vio winbindd[842]: [2019/11/30 18:26:31.974780, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:31 vio winbindd[842]: Unable to open new log file '/var/log/samba/log.wb-VIO': No such file or directory Nov 30 18:26:32 vio wireless.js[733]: Stopped aP Nov 30 18:26:32 vio wireless.js[733]: Start wireless flow Nov 30 18:26:32 vio wireless.js[733]: Stopped hotspot (if there).. Nov 30 18:26:32 vio sudo[846]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 30 18:26:32 vio sudo[846]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:26:32 vio sudo[846]: pam_unix(sudo:session): session closed for user root Nov 30 18:26:32 vio sudo[854]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 30 18:26:32 vio sudo[854]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:26:32 vio sudo[854]: pam_unix(sudo:session): session closed for user root Nov 30 18:26:32 vio wireless.js[733]: DHCP IP Nov 30 18:26:32 vio wireless.js[733]: Start ap Nov 30 18:26:32 vio sudo[863]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Nov 30 18:26:32 vio sudo[863]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:26:32 vio dhcpcd[867]: version 6.0.5 starting Nov 30 18:26:32 vio kernel: brcmfmac: power management disabled Nov 30 18:26:32 vio wpa_supplicant[862]: Successfully initialized wpa_supplicant Nov 30 18:26:32 vio dhcpcd[867]: eth0: waiting for carrier Nov 30 18:26:32 vio dhcpcd[867]: wlan0: waiting for carrier Nov 30 18:26:33 vio wireless.js[733]: trying... Nov 30 18:26:33 vio sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 18:26:33 vio sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:26:33 vio sudo[896]: pam_unix(sudo:session): session closed for user root Nov 30 18:26:33 vio smbd[834]: Starting SMB/CIFS daemon: smbd[2019/11/30 18:26:33, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:33 vio smbd[834]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Nov 30 18:26:33 vio smbd[834]: [2019/11/30 18:26:33, 0] ../lib/util/util.c:220(directory_create_or_exist) Nov 30 18:26:33 vio smbd[834]: mkdir failed on directory /var/log/samba/cores: No such file or directory Nov 30 18:26:33 vio smbd[834]: [2019/11/30 18:26:33, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Nov 30 18:26:33 vio smbd[834]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Nov 30 18:26:33 vio smbd[834]: [2019/11/30 18:26:33, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Nov 30 18:26:33 vio smbd[834]: Unable to setup corepath for smbd: No such file or directory Nov 30 18:26:33 vio smbd[834]: [2019/11/30 18:26:33, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:33 vio smbd[834]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Nov 30 18:26:33 vio smbd[834]: [2019/11/30 18:26:33, 0] ../source3/smbd/server.c:1241(main) Nov 30 18:26:33 vio smbd[834]: smbd version 4.2.14-Debian started. Nov 30 18:26:33 vio smbd[834]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Nov 30 18:26:33 vio smbd[894]: [2019/11/30 18:26:33.578527, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:33 vio smbd[894]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Nov 30 18:26:33 vio smbd[894]: [2019/11/30 18:26:33.581036, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:33 vio smbd[894]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Nov 30 18:26:33 vio smbd[894]: [2019/11/30 18:26:33.581347, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:33 vio smbd[894]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Nov 30 18:26:33 vio smbd[834]: [2019/11/30 18:26:33.578527, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:33 vio smbd[834]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Nov 30 18:26:33 vio smbd[834]: [2019/11/30 18:26:33.581036, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:33 vio smbd[834]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Nov 30 18:26:33 vio smbd[834]: [2019/11/30 18:26:33.581347, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:33 vio smbd[834]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Nov 30 18:26:33 vio smbd[834]: . Nov 30 18:26:33 vio systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Nov 30 18:26:33 vio winbindd[907]: [2019/11/30 18:26:33.664101, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:33 vio winbindd[907]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Nov 30 18:26:33 vio winbindd[908]: [2019/11/30 18:26:33.730539, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:33 vio winbindd[908]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory Nov 30 18:26:33 vio smbd[904]: [2019/11/30 18:26:33.797422, 0] ../lib/util/become_daemon.c:124(daemon_ready) Nov 30 18:26:33 vio smbd[904]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 30 18:26:33 vio smbd[909]: [2019/11/30 18:26:33.805614, 0] ../lib/util/debug.c:603(reopen_logs_internal) Nov 30 18:26:33 vio smbd[909]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Nov 30 18:26:34 vio wireless.js[733]: trying... Nov 30 18:26:34 vio sudo[912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 18:26:34 vio sudo[912]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:26:34 vio sudo[912]: pam_unix(sudo:session): session closed for user root Nov 30 18:26:35 vio wireless.js[733]: trying... Nov 30 18:26:35 vio sudo[921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 18:26:35 vio sudo[921]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:26:35 vio wpa_supplicant[892]: wlan0: Trying to associate with SSID 'padme' Nov 30 18:26:35 vio sudo[921]: pam_unix(sudo:session): session closed for user root Nov 30 18:26:36 vio wireless.js[733]: trying... Nov 30 18:26:36 vio sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 18:26:36 vio sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:26:36 vio sudo[930]: pam_unix(sudo:session): session closed for user root Nov 30 18:26:37 vio wireless.js[733]: trying... Nov 30 18:26:37 vio sudo[939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 18:26:37 vio sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:26:37 vio sudo[939]: pam_unix(sudo:session): session closed for user root Nov 30 18:26:38 vio volumio-remote-updater[575]: [2019-11-30 18:26:38] [info] asio async_connect error: system:111 (Connection refused) Nov 30 18:26:38 vio volumio-remote-updater[575]: [2019-11-30 18:26:38] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 30 18:26:38 vio volumio-remote-updater[575]: [2019-11-30 18:26:38] [error] handle_connect error: Underlying Transport Error Nov 30 18:26:38 vio wpa_supplicant[892]: wlan0: Associated with 44:d9:e7:f3:8c:48 Nov 30 18:26:38 vio wpa_supplicant[892]: wlan0: WPA: Key negotiation completed with 44:d9:e7:f3:8c:48 [PTK=CCMP GTK=TKIP] Nov 30 18:26:38 vio wpa_supplicant[892]: wlan0: CTRL-EVENT-CONNECTED - Connection to 44:d9:e7:f3:8c:48 completed [id=0 id_str=] Nov 30 18:26:38 vio dhcpcd[867]: wlan0: carrier acquired Nov 30 18:26:38 vio dhcpcd[867]: wlan0: rebinding lease of 192.168.1.149 Nov 30 18:26:38 vio dhcpcd[867]: wlan0: leased 192.168.1.149 for 86400 seconds Nov 30 18:26:38 vio avahi-daemon[630]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.149. Nov 30 18:26:38 vio avahi-daemon[630]: New relevant interface wlan0.IPv4 for mDNS. Nov 30 18:26:38 vio dhcpcd[867]: wlan0: adding host route to 192.168.1.149 via 127.0.0.1 Nov 30 18:26:38 vio avahi-daemon[630]: Registering new address record for 192.168.1.149 on wlan0.IPv4. Nov 30 18:26:38 vio dhcpcd[867]: wlan0: adding route to 192.168.1.0/24 Nov 30 18:26:38 vio dhcpcd[867]: wlan0: adding default route via 192.168.1.1 Nov 30 18:26:38 vio wireless.js[733]: trying... Nov 30 18:26:38 vio sudo[993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 30 18:26:38 vio sudo[993]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:26:38 vio sudo[993]: pam_unix(sudo:session): session closed for user root Nov 30 18:26:38 vio dhcpcd[867]: forked to background, child pid 1009 Nov 30 18:26:38 vio sudo[863]: pam_unix(sudo:session): session closed for user root Nov 30 18:26:38 vio wireless.js[733]: Connected to: ----padme Nov 30 18:26:38 vio wireless.js[733]: ---- Nov 30 18:26:38 vio sudo[1016]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 30 18:26:38 vio sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:26:38 vio sudo[1016]: pam_unix(sudo:session): session closed for user root Nov 30 18:26:38 vio wireless.js[733]: ... joined AP, wlan0 IPv4 is 192.168.1.149, ipV6 is undefined Nov 30 18:26:38 vio wireless.js[733]: It's done! AP Nov 30 18:26:38 vio systemd[1]: Started Wireless Services. Nov 30 18:26:38 vio systemd[1]: Starting Volumio Backend Module... Nov 30 18:26:38 vio systemd[1]: Started Volumio Backend Module. Nov 30 18:26:38 vio systemd[1]: Starting Volumio Streaming Daemon... Nov 30 18:26:38 vio systemd[1]: Started Volumio Streaming Daemon. Nov 30 18:26:38 vio systemd[1]: Starting Multi-User System. Nov 30 18:26:38 vio systemd[1]: Reached target Multi-User System. Nov 30 18:26:38 vio systemd[1]: Starting Graphical Interface. Nov 30 18:26:38 vio systemd[1]: Reached target Graphical Interface. Nov 30 18:26:38 vio systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 30 18:26:38 vio systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 30 18:26:38 vio systemd[1]: Startup finished in 8.760s (kernel) + 19.634s (userspace) = 28.394s. Nov 30 18:26:39 vio volumio-streaming-daemon[1026]: ############################ Nov 30 18:26:39 vio volumio-streaming-daemon[1026]: # Volumio Streaming Daemon # Nov 30 18:26:39 vio volumio-streaming-daemon[1026]: # Running on port 7777 # Nov 30 18:26:39 vio volumio-streaming-daemon[1026]: ############################ Nov 30 18:26:40 vio ntpd[690]: Listen normally on 3 wlan0 192.168.1.149 UDP 123 Nov 30 18:26:40 vio ntpd[690]: peers refreshed Nov 30 18:26:41 vio nmbd[832]: [2019/11/30 18:26:41.936100, 0] ../lib/util/become_daemon.c:124(daemon_ready) Nov 30 18:26:41 vio nmbd[832]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 30 18:26:42 vio ntpd_intres[775]: DNS 0.debian.pool.ntp.org -> 192.33.96.102 Nov 30 18:26:42 vio ntpd_intres[775]: DNS 1.debian.pool.ntp.org -> 193.33.30.39 Nov 30 18:26:42 vio ntpd_intres[775]: DNS 2.debian.pool.ntp.org -> 195.186.1.100 Nov 30 18:26:42 vio ntpd_intres[775]: DNS 3.debian.pool.ntp.org -> 195.186.1.101 Nov 30 18:26:43 vio volumio[1025]: info: ------------------------------------------- Nov 30 18:26:43 vio volumio[1025]: info: ----- Volumio2 ---- Nov 30 18:26:43 vio volumio[1025]: info: ------------------------------------------- Nov 30 18:26:43 vio volumio[1025]: info: ----- System startup ---- Nov 30 18:26:43 vio volumio[1025]: info: ------------------------------------------- Nov 30 18:26:43 vio volumio[1025]: info: MYVOLUMIO Environment detected Nov 30 18:26:44 vio sshd[1051]: Accepted publickey for volumio from 192.168.1.177 port 52605 ssh2: RSA bc:2d:e5:ae:8e:48:a2:01:e7:aa:7d:91:62:c2:b0:3e Nov 30 18:26:44 vio sshd[1051]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Nov 30 18:26:44 vio systemd-logind[591]: New session c1 of user volumio. Nov 30 18:26:44 vio systemd[1]: Starting user-1000.slice. Nov 30 18:26:44 vio systemd[1]: Created slice user-1000.slice. Nov 30 18:26:44 vio systemd[1]: Starting Session c1 of user volumio. Nov 30 18:26:44 vio systemd[1]: Started Session c1 of user volumio. Nov 30 18:26:44 vio systemd[1]: Starting User Manager for UID 1000... Nov 30 18:26:44 vio systemd[1055]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Nov 30 18:26:44 vio systemd[1055]: Starting Paths. Nov 30 18:26:44 vio systemd[1055]: Reached target Paths. Nov 30 18:26:44 vio systemd[1055]: Starting Timers. Nov 30 18:26:44 vio systemd[1055]: Reached target Timers. Nov 30 18:26:44 vio systemd[1055]: Starting Sockets. Nov 30 18:26:44 vio systemd[1055]: Reached target Sockets. Nov 30 18:26:44 vio systemd[1055]: Starting Basic System. Nov 30 18:26:44 vio systemd[1055]: Reached target Basic System. Nov 30 18:26:44 vio systemd[1055]: Starting Default. Nov 30 18:26:44 vio systemd[1055]: Reached target Default. Nov 30 18:26:44 vio systemd[1055]: Startup finished in 70ms. Nov 30 18:26:44 vio systemd[1]: Started User Manager for UID 1000. Nov 30 18:26:44 vio sshd[1051]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory Nov 30 18:26:44 vio sshd[1051]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory Nov 30 18:26:45 vio volumio[1025]: info: Plugin folders cleanup Nov 30 18:26:45 vio volumio[1025]: info: Scanning into folder /volumio/app/plugins/ Nov 30 18:26:45 vio volumio[1025]: info: Scanning category audio_interface Nov 30 18:26:45 vio volumio[1025]: info: Scanning category miscellanea Nov 30 18:26:45 vio volumio[1025]: info: Scanning category music_service Nov 30 18:26:45 vio volumio[1025]: info: Scanning category plugins.json Nov 30 18:26:45 vio volumio[1025]: info: Scanning category system_controller Nov 30 18:26:45 vio volumio[1025]: info: Scanning category user_interface Nov 30 18:26:45 vio volumio[1025]: info: Scanning into folder /data/plugins/ Nov 30 18:26:45 vio volumio[1025]: info: Plugin folders cleanup completed Nov 30 18:26:45 vio volumio[1025]: info: ------------------------------------------- Nov 30 18:26:45 vio volumio[1025]: info: ----- Core plugins startup ---- Nov 30 18:26:45 vio volumio[1025]: info: ------------------------------------------- Nov 30 18:26:45 vio volumio[1025]: info: Loading plugins from folder /volumio/app/plugins/ Nov 30 18:26:45 vio volumio[1025]: info: Adding plugin upnp to MyMusic Plugins Nov 30 18:26:45 vio volumio[1025]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 30 18:26:45 vio volumio[1025]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 30 18:26:45 vio volumio[1025]: info: Loading plugins from folder /data/plugins/ Nov 30 18:26:46 vio volumio[1025]: info: Loading plugin "system"... Nov 30 18:26:46 vio volumio[1025]: info: Loading plugin "appearance"... Nov 30 18:26:47 vio volumio[1025]: info: Loading plugin "network"... Nov 30 18:26:48 vio volumio-remote-updater[575]: [2019-11-30 18:26:48] [connect] Successful connection Nov 30 18:26:48 vio volumio[1025]: info: Loading plugin "services"... Nov 30 18:26:48 vio volumio[1025]: info: Loading plugin "alsa_controller"... Nov 30 18:26:49 vio volumio[1025]: info: Loading plugin "wizard"... Nov 30 18:26:49 vio volumio[1025]: info: Loading plugin "volumio_command_line_client"... Nov 30 18:26:49 vio volumio[1025]: info: Plugin upnp is not enabled Nov 30 18:26:49 vio volumio[1025]: info: Loading plugin "my_music"... Nov 30 18:26:49 vio volumio[1025]: info: Loading plugin "mpd"... Nov 30 18:27:09 vio systemd[1055]: Time has been changed Nov 30 18:27:09 vio systemd[1]: Time has been changed Nov 30 18:27:09 vio volumio[1025]: info: Plugin upnp_browser is not enabled Nov 30 18:27:09 vio volumio[1025]: info: Loading plugin "networkfs"... Nov 30 18:27:09 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 30 18:27:09 vio volumio[1025]: info: Loading plugin "alarm-clock"... Nov 30 18:27:10 vio sudo[1075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.5/music /mnt/NAS/qnap Nov 30 18:27:10 vio sudo[1075]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:27:10 vio kernel: FS-Cache: Netfs 'cifs' registered for caching Nov 30 18:27:10 vio kernel: Key type cifs.spnego registered Nov 30 18:27:10 vio kernel: Key type cifs.idmap registered Nov 30 18:27:10 vio 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. Nov 30 18:27:10 vio volumio[1025]: info: Plugin airplay_emulation is not enabled Nov 30 18:27:10 vio volumio[1025]: info: Loading plugin "last_100"... Nov 30 18:27:10 vio volumio[1025]: info: Loading plugin "webradio"... Nov 30 18:27:10 vio sudo[1075]: pam_unix(sudo:session): session closed for user root Nov 30 18:27:10 vio volumio[1025]: info: Loading plugin "i2s_dacs"... Nov 30 18:27:10 vio volumio[1025]: info: I2S DAC not set, start Auto-detection Nov 30 18:27:11 vio volumio[1025]: info: Loading plugin "volumiodiscovery"... Nov 30 18:27:11 vio volumio[1025]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 30 18:27:11 vio node[1025]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 30 18:27:11 vio node[1025]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 30 18:27:11 vio node[1025]: *** WARNING *** For more information see Nov 30 18:27:11 vio node[1025]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 30 18:27:11 vio node[1025]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 30 18:27:11 vio node[1025]: *** WARNING *** For more information see Nov 30 18:27:11 vio volumio[1025]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 30 18:27:11 vio volumio[1025]: *** WARNING *** For more information see Nov 30 18:27:11 vio volumio[1025]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 30 18:27:11 vio volumio[1025]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 30 18:27:11 vio volumio[1025]: *** WARNING *** For more information see Nov 30 18:27:11 vio volumio[1025]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 30 18:27:11 vio volumio[1025]: Discovery: StartAdv! undefined Nov 30 18:27:11 vio volumio[1025]: Discovery: Started advertising... vio - undefined Nov 30 18:27:11 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 30 18:27:11 vio volumio[1025]: info: Loading plugin "outputs"... Nov 30 18:27:11 vio volumio[1025]: info: Loading plugin "albumart"... Nov 30 18:27:11 vio volumio[1025]: info: Plugin example_plugin is not enabled Nov 30 18:27:11 vio volumio[1025]: info: Loading plugin "inputs"... Nov 30 18:27:11 vio volumio[1025]: info: Loading plugin "updater_comm"... Nov 30 18:27:11 vio volumio[1025]: info: Plugin mpdemulation is not enabled Nov 30 18:27:11 vio volumio[1025]: info: Loading plugin "rest_api"... Nov 30 18:27:11 vio volumio[1025]: info: Loading plugin "websocket"... Nov 30 18:27:11 vio volumio[1025]: info: ___________ START PLUGINS ___________ Nov 30 18:27:11 vio volumio[1025]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 30 18:27:11 vio volumio[1025]: info: [1575138431805] CoreMusicLibrary::Adding element Last_100 Nov 30 18:27:11 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 18:27:11 vio volumio[1025]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 30 18:27:11 vio volumio[1025]: info: [1575138431821] CoreMusicLibrary::Adding element Webradio Nov 30 18:27:11 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 18:27:11 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 30 18:27:11 vio volumio[1025]: info: ------------------------------------------- Nov 30 18:27:11 vio volumio[1025]: info: ----- MyVolumio plugins startup ---- Nov 30 18:27:11 vio volumio[1025]: info: ------------------------------------------- Nov 30 18:27:11 vio volumio[1025]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 30 18:27:11 vio volumio[1025]: info: Loading i18n strings for locale en Nov 30 18:27:11 vio volumio[1025]: Updating browse sources language Nov 30 18:27:11 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 18:27:11 vio volumio[1025]: Forking 3 albumart workers Nov 30 18:27:11 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 30 18:27:11 vio volumio[1025]: info: CoreCommandRouter::initPlayerControls Nov 30 18:27:11 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 18:27:11 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Nov 30 18:27:12 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 18:27:12 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 18:27:12 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 18:27:12 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 18:27:12 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 30 18:27:12 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 30 18:27:12 vio volumio[1025]: info: BOOT COMPLETED Nov 30 18:27:12 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 18:27:13 vio volumio[1025]: Starting albumart workers Nov 30 18:27:13 vio volumio[1025]: Starting albumart workers Nov 30 18:27:13 vio volumio[1025]: Starting albumart workers Nov 30 18:27:13 vio dhcpcd[464]: timed out Nov 30 18:27:13 vio dhcpcd[464]: exited Nov 30 18:27:13 vio ifup[430]: dhcpcd[464]: timed out Nov 30 18:27:13 vio ifup[430]: dhcpcd[464]: exited Nov 30 18:27:13 vio ifup[430]: Failed to bring up eth0. Nov 30 18:27:17 vio volumio[1025]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Nov 30 18:27:17 vio volumio[1025]: info: CoreCommandRouter::Close All Modals sent Nov 30 18:27:17 vio volumio[1025]: info: CoreCommandRouter::Close All Modals sent Nov 30 18:27:17 vio volumio[1025]: Express server listening on port 3000 Nov 30 18:27:17 vio volumio[1025]: Volumio Calling Home Nov 30 18:27:17 vio volumio[1025]: info: CoreStateMachine::resetVolumioState Nov 30 18:27:17 vio volumio[1025]: info: CoreStateMachine::getcurrentVolume Nov 30 18:27:17 vio volumio[1025]: info: CoreCommandRouter::volumioRetrievevolume Nov 30 18:27:17 vio volumio[1025]: info: CoreStateMachine::pushState Nov 30 18:27:17 vio volumio[1025]: info: CoreStateMachine::getState Nov 30 18:27:17 vio volumio[1025]: info: CorePlayQueue::getTrack 0 Nov 30 18:27:17 vio volumio[1025]: info: CoreCommandRouter::volumioPushState Nov 30 18:27:17 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 18:27:17 vio volumio[1025]: info: Volumio called home Nov 30 18:27:17 vio volumio[1025]: info: Setting Device type: Raspberry PI Nov 30 18:27:18 vio volumio[1025]: info: MPD running with PID634 ,establishing connection Nov 30 18:27:18 vio sudo[1185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Nov 30 18:27:18 vio sudo[1185]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:27:18 vio sudo[1185]: pam_unix(sudo:session): session closed for user root Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 18:27:18 vio volumio[1025]: info: Nov 30 18:27:18 vio volumio[1025]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Nov 30 18:27:18 vio volumio[1025]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Nov 30 18:27:18 vio volumio[1025]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Nov 30 18:27:18 vio volumio[1025]: at emitOne (events.js:116:13) Nov 30 18:27:18 vio volumio[1025]: at Socket.emit (events.js:211:7) Nov 30 18:27:18 vio volumio[1025]: at addChunk (_stream_readable.js:263:12) Nov 30 18:27:18 vio volumio[1025]: at readableAddChunk (_stream_readable.js:246:13) Nov 30 18:27:18 vio volumio[1025]: at Socket.Readable.push (_stream_readable.js:208:10) Nov 30 18:27:18 vio volumio[1025]: at Pipe.onread (net.js:607:20) Nov 30 18:27:18 vio volumio[1025]: info: CoreStateMachine::setRepeat null single undefined Nov 30 18:27:18 vio volumio[1025]: info: CoreStateMachine::pushState Nov 30 18:27:18 vio volumio[1025]: info: CoreStateMachine::getState Nov 30 18:27:18 vio volumio[1025]: info: CorePlayQueue::getTrack 0 Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::volumioPushState Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 18:27:18 vio volumio[1025]: info: CoreStateMachine::setRandom null Nov 30 18:27:18 vio volumio[1025]: info: CoreStateMachine::pushState Nov 30 18:27:18 vio volumio[1025]: info: CoreStateMachine::getState Nov 30 18:27:18 vio volumio[1025]: info: CorePlayQueue::getTrack 0 Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::volumioPushState Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::volumioGetVisibleSources Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::volumioGetState Nov 30 18:27:18 vio volumio[1025]: info: CoreStateMachine::getState Nov 30 18:27:18 vio volumio[1025]: info: CorePlayQueue::getTrack 0 Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 30 18:27:18 vio volumio[1025]: info: Listing playlists Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::volumioGetQueue Nov 30 18:27:18 vio volumio[1025]: info: CoreStateMachine::getQueue Nov 30 18:27:18 vio volumio[1025]: info: CorePlayQueue::getQueue Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::volumioGetVisibleSources Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::volumioGetState Nov 30 18:27:18 vio volumio[1025]: info: CoreStateMachine::getState Nov 30 18:27:18 vio volumio[1025]: info: CorePlayQueue::getTrack 0 Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 30 18:27:18 vio volumio[1025]: info: Listing playlists Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::volumioGetQueue Nov 30 18:27:18 vio volumio[1025]: info: CoreStateMachine::getQueue Nov 30 18:27:18 vio volumio[1025]: info: CorePlayQueue::getQueue Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::volumioGetVisibleSources Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::volumioGetState Nov 30 18:27:18 vio volumio[1025]: info: CoreStateMachine::getState Nov 30 18:27:18 vio volumio[1025]: info: CorePlayQueue::getTrack 0 Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 30 18:27:18 vio volumio[1025]: info: Listing playlists Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::volumioGetQueue Nov 30 18:27:18 vio volumio[1025]: info: CoreStateMachine::getQueue Nov 30 18:27:18 vio volumio[1025]: info: CorePlayQueue::getQueue Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::volumioGetVisibleSources Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::volumioGetState Nov 30 18:27:18 vio volumio[1025]: info: CoreStateMachine::getState Nov 30 18:27:18 vio volumio[1025]: info: CorePlayQueue::getTrack 0 Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 30 18:27:18 vio volumio[1025]: info: Listing playlists Nov 30 18:27:18 vio volumio[1025]: info: CoreCommandRouter::volumioGetQueue Nov 30 18:27:18 vio volumio[1025]: info: CoreStateMachine::getQueue Nov 30 18:27:18 vio volumio[1025]: info: CorePlayQueue::getQueue Nov 30 18:27:19 vio volumio[1025]: Discovery: adding 901fb7ab-9dd5-4599-be1b-6836d97e705d Nov 30 18:27:19 vio volumio[1025]: info: mDNS: Found device vio Nov 30 18:27:19 vio sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 30 18:27:19 vio sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:27:19 vio sudo[1197]: pam_unix(sudo:session): session closed for user root Nov 30 18:27:20 vio volumio[1025]: info: CoreCommandRouter::volumioGetState Nov 30 18:27:20 vio volumio[1025]: info: CoreStateMachine::getState Nov 30 18:27:20 vio volumio[1025]: info: CorePlayQueue::getTrack 0 Nov 30 18:27:20 vio sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 30 18:27:20 vio sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:27:20 vio sudo[1206]: pam_unix(sudo:session): session closed for user root Nov 30 18:27:21 vio volumio[1025]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 30 18:27:21 vio volumio[1025]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 30 18:27:21 vio volumio[1025]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 30 18:27:21 vio volumio[1025]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 30 18:27:21 vio volumio[1025]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 30 18:27:21 vio volumio[1025]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 30 18:27:21 vio volumio[1025]: info: Adding plugin bluetooth to MyMusic Plugins Nov 30 18:27:21 vio volumio[1025]: info: Adding plugin cd_controller to MyMusic Plugins Nov 30 18:27:21 vio volumio[1025]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 30 18:27:21 vio volumio[1025]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 30 18:27:22 vio volumio-remote-updater[575]: [2019-11-30 18:27:22] [connect] Successful connection Nov 30 18:27:25 vio volumio[1025]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 30 18:27:28 vio volumio[1025]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 30 18:27:28 vio volumio[1025]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 30 18:27:28 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 18:27:28 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 18:27:28 vio volumio[1025]: info: Starting MyVolumio Remote Streaming Endpoints Nov 30 18:27:28 vio volumio[1025]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 30 18:27:28 vio volumio[1025]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 30 18:27:28 vio volumio[1025]: info: Streaming services startup Nov 30 18:27:28 vio volumio[1025]: info: Starting Streaming Daemon Nov 30 18:27:28 vio volumio[1025]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 30 18:27:28 vio sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 30 18:27:28 vio sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:27:28 vio systemd[1]: Stopping Volumio Streaming Daemon... Nov 30 18:27:28 vio systemd[1]: Starting Volumio Streaming Daemon... Nov 30 18:27:28 vio systemd[1]: Started Volumio Streaming Daemon. Nov 30 18:27:28 vio sudo[1215]: pam_unix(sudo:session): session closed for user root Nov 30 18:27:28 vio volumio-streaming-daemon[1221]: ############################ Nov 30 18:27:28 vio volumio-streaming-daemon[1221]: # Volumio Streaming Daemon # Nov 30 18:27:28 vio volumio-streaming-daemon[1221]: # Running on port 7777 # Nov 30 18:27:28 vio volumio-streaming-daemon[1221]: ############################ Nov 30 18:27:29 vio volumio[1025]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 30 18:27:29 vio volumio[1025]: info: MYVOLUMIO: Adding device Nov 30 18:27:29 vio volumio[1025]: info: MYVOLUMIO: Evaluating Server Nov 30 18:27:31 vio volumio[1025]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Nov 30 18:27:31 vio volumio[1025]: info: Adding audio output: browser Nov 30 18:27:31 vio volumio[1025]: info: Remote config written successfully Nov 30 18:27:31 vio volumio[1025]: info: Starting Tunnel 1 Nov 30 18:27:31 vio volumio[1025]: info: Starting Tunnel Connection Checker Nov 30 18:27:31 vio sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 30 18:27:31 vio sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:27:31 vio volumio[1025]: info: Setting Geolocation for MyVolumio to eu1 Nov 30 18:27:31 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 18:27:31 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 18:27:31 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 18:27:31 vio systemd[1]: Starting MyVolumio SSH Tunnel... Nov 30 18:27:31 vio systemd[1]: Started MyVolumio SSH Tunnel. Nov 30 18:27:31 vio volumio[1025]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Nov 30 18:27:31 vio volumio[1025]: info: MyVolumio status changed Nov 30 18:27:31 vio volumio[1025]: info: Streaming services startup Nov 30 18:27:31 vio volumio[1025]: info: Re-Starting Streaming Daemon Nov 30 18:27:31 vio autossh[1244]: port set to 0, monitoring disabled Nov 30 18:27:31 vio autossh[1244]: starting ssh (count 1) Nov 30 18:27:31 vio autossh[1244]: ssh child pid is 1247 Nov 30 18:27:31 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 30 18:27:31 vio volumio-streaming-daemon[1221]: Restarting daemon Nov 30 18:27:31 vio volumio[1025]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Nov 30 18:27:31 vio sudo[1238]: pam_unix(sudo:session): session closed for user root Nov 30 18:27:31 vio volumiossh-tunnel[1243]: Warning: Permanently added '[eu1.myvolumio.org]:2222,[188.166.162.121]:2222' (RSA) to the list of known hosts. Nov 30 18:27:31 vio volumio[1025]: info: [1575138451887] Starting BluetoothController Nov 30 18:27:31 vio volumio-streaming-daemon[1221]: Enabled services: Nov 30 18:27:31 vio volumio-streaming-daemon[1221]: 1) tidal Nov 30 18:27:31 vio volumio-streaming-daemon[1221]: 2) qobuz Nov 30 18:27:32 vio volumio[1025]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Nov 30 18:27:32 vio volumio[1025]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Nov 30 18:27:32 vio volumio[1025]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Nov 30 18:27:35 vio volumio[1025]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Nov 30 18:27:35 vio volumio[1025]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Nov 30 18:27:35 vio volumio[1025]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Nov 30 18:27:35 vio volumio[1025]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Nov 30 18:27:35 vio volumio[1025]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Nov 30 18:27:35 vio volumio[1025]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Nov 30 18:27:35 vio volumio[1025]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Nov 30 18:27:35 vio volumio[1025]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Nov 30 18:27:35 vio volumio[1025]: info: Updating MyVolumio device info Nov 30 18:27:35 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 18:27:35 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 18:27:35 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 18:27:35 vio volumio[1025]: info: Remote SSH Started Nov 30 18:27:36 vio volumio[1025]: info: CoreCommandRouter::volumioGetState Nov 30 18:27:36 vio volumio[1025]: info: CoreStateMachine::getState Nov 30 18:27:36 vio volumio[1025]: info: CorePlayQueue::getTrack 0 Nov 30 18:27:36 vio sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 30 18:27:36 vio sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:27:36 vio volumio[1025]: info: Hi Res Audio Successful Login Nov 30 18:27:36 vio sudo[1254]: pam_unix(sudo:session): session closed for user root Nov 30 18:27:36 vio volumio[1025]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 30 18:27:36 vio volumio[1025]: info: [1575138456180] CoreMusicLibrary::Adding element High Res Audio Nov 30 18:27:36 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 18:27:36 vio volumio[1025]: info: Fetching Streaming Services browse cache Nov 30 18:27:36 vio volumio[1025]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Nov 30 18:27:36 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 18:27:36 vio volumio[1025]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Nov 30 18:27:36 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 18:27:37 vio volumio[1025]: info: Fetching Streaming Services browse cache Nov 30 18:27:37 vio volumio[1025]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Nov 30 18:27:37 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 18:27:37 vio volumio[1025]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Nov 30 18:27:37 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 18:27:37 vio volumio-remote-updater[575]: [2019-11-30 18:27:37] [connect] Successful connection Nov 30 18:27:38 vio volumio-remote-updater[575]: [2019-11-30 18:27:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1575138457 101 Nov 30 18:27:39 vio sudo[1265]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f Nov 30 18:27:39 vio sudo[1265]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) Nov 30 18:27:41 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 30 18:27:41 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 30 18:27:47 vio sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 30 18:27:47 vio sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:27:47 vio sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 30 18:27:47 vio sudo[1273]: pam_unix(sudo:session): session closed for user root Nov 30 18:27:47 vio sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:27:47 vio sudo[1276]: pam_unix(sudo:session): session closed for user root Nov 30 18:27:47 vio volumio[1025]: info: CoreCommandRouter::volumioGetVisibleSources Nov 30 18:27:47 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 30 18:27:47 vio volumio[1025]: info: CoreCommandRouter::volumioGetState Nov 30 18:27:47 vio volumio[1025]: info: CoreStateMachine::getState Nov 30 18:27:47 vio volumio[1025]: info: CorePlayQueue::getTrack 0 Nov 30 18:27:47 vio sudo[1291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 30 18:27:47 vio sudo[1291]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:27:47 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 30 18:27:47 vio volumio[1025]: info: Listing playlists Nov 30 18:27:47 vio volumio[1025]: info: CoreCommandRouter::volumioGetQueue Nov 30 18:27:47 vio volumio[1025]: info: CoreStateMachine::getQueue Nov 30 18:27:47 vio volumio[1025]: info: CorePlayQueue::getQueue Nov 30 18:27:47 vio sudo[1291]: pam_unix(sudo:session): session closed for user root Nov 30 18:27:47 vio sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 30 18:27:47 vio sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:27:47 vio sudo[1295]: pam_unix(sudo:session): session closed for user root Nov 30 18:27:51 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: hi_res_audio , handleBrowseUri Nov 30 18:27:54 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: hi_res_audio , handleBrowseUri Nov 30 18:27:59 vio volumio[1025]: info: CoreCommandRouter::volumioAddQueueItems Nov 30 18:27:59 vio volumio[1025]: info: CoreStateMachine::addQueueItems Nov 30 18:27:59 vio volumio[1025]: info: CorePlayQueue::addQueueItems Nov 30 18:27:59 vio volumio[1025]: info: Adding Item to queue: hires_audio/id/0dbd8f95-307e-4560-a92c-e739a40650a0_5419b475-ccfb-473a-a78c-afbae78ff030 Nov 30 18:27:59 vio volumio[1025]: info: Exploding uri hires_audio/id/0dbd8f95-307e-4560-a92c-e739a40650a0_5419b475-ccfb-473a-a78c-afbae78ff030 in service hi_res_audio Nov 30 18:27:59 vio volumio[1025]: info: HIRESAUDIO Exploding URI: hires_audio/id/0dbd8f95-307e-4560-a92c-e739a40650a0_5419b475-ccfb-473a-a78c-afbae78ff030 Nov 30 18:28:00 vio volumio[1025]: info: CorePlayQueue::saveQueue Nov 30 18:28:00 vio volumio[1025]: info: CoreCommandRouter::volumioPushQueue Nov 30 18:28:00 vio volumio[1025]: info: CoreStateMachine::updateTrackBlock Nov 30 18:28:00 vio volumio[1025]: info: CorePlayQueue::getTrackBlock Nov 30 18:28:00 vio volumio[1025]: info: CoreCommandRouter::volumioPlay Nov 30 18:28:00 vio volumio[1025]: UNSET VOLATILE Nov 30 18:28:00 vio volumio[1025]: info: CoreStateMachine::play index 0 Nov 30 18:28:00 vio volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 30 18:28:00 vio volumio[1025]: info: CoreStateMachine::stop Nov 30 18:28:00 vio volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 30 18:28:00 vio volumio[1025]: UNSET VOLATILE Nov 30 18:28:00 vio volumio[1025]: info: CoreStateMachine::play index undefined Nov 30 18:28:00 vio volumio[1025]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 30 18:28:00 vio volumio[1025]: info: CorePlayQueue::getTrack 0 Nov 30 18:28:00 vio volumio[1025]: info: CoreStateMachine::startPlaybackTimer Nov 30 18:28:00 vio volumio[1025]: info: CorePlayQueue::getTrack 0 Nov 30 18:28:00 vio volumio[1025]: info: ControllerMpd::sendMpdCommand stop Nov 30 18:28:00 vio volumio[1025]: info: sending command... Nov 30 18:28:00 vio volumio[1025]: info: parsing response... Nov 30 18:28:00 vio volumio[1025]: info: ControllerMpd::sendMpdCommand clear Nov 30 18:28:00 vio volumio[1025]: info: sending command... Nov 30 18:28:00 vio volumio[1025]: info: Nov 30 18:28:00 vio volumio[1025]: ---------------------------- MPD announces system playlist update Nov 30 18:28:00 vio volumio[1025]: info: Ignoring MPD Status Update Nov 30 18:28:00 vio volumio[1025]: info: parsing response... Nov 30 18:28:00 vio volumio[1025]: info: ControllerMpd::sendMpdCommand load "https://highresaudiomusic.akamaized.net//2019/11/20/kbczoe-maggiemayw-format-fl96.flac?__token__=st=1575138480~exp=1575145636~acl=%2F%2A~hmac=6f968ae7b200a5137fb055ee98036f498e00255b86c4f6df921404758d350d32&info=USER50a7fa96-57f8-4317-84e9-eacbd40f7cf6ALBUM5419b475-ccfb-473a-a78c-afbae78ff030TRACK0dbd8f95-307e-4560-a92c-e739a40650a0ISRC0TIME0" Nov 30 18:28:00 vio volumio[1025]: info: sending command... Nov 30 18:28:00 vio volumio[1025]: info: Nov 30 18:28:00 vio volumio[1025]: info: ------------------------------ 14ms Nov 30 18:28:01 vio volumio[1025]: error: Could not send mpd command: load "https://highresaudiomusic.akamaized.net//2019/11/20/kbczoe-maggiemayw-format-fl96.flac?__token__=st=1575138480~exp=1575145636~acl=%2F%2A~hmac=6f968ae7b200a5137fb055ee98036f498e00255b86c4f6df921404758d350d32&info=USER50a7fa96-57f8-4317-84e9-eacbd40f7cf6ALBUM5419b475-ccfb-473a-a78c-afbae78ff030TRACK0dbd8f95-307e-4560-a92c-e739a40650a0ISRC0TIME0" Nov 30 18:28:01 vio volumio[1025]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 30 18:28:01 vio volumio[1025]: info: ControllerMpd::sendMpdCommand play Nov 30 18:28:01 vio volumio[1025]: info: sending command... Nov 30 18:28:01 vio volumio[1025]: info: parsing response... Nov 30 18:28:15 vio volumio[1025]: info: CoreCommandRouter::volumioGetState Nov 30 18:28:15 vio volumio[1025]: info: CoreStateMachine::getState Nov 30 18:28:15 vio volumio[1025]: info: CoreCommandRouter::volumioGetQueue Nov 30 18:28:15 vio volumio[1025]: info: CoreStateMachine::getQueue Nov 30 18:28:15 vio volumio[1025]: info: CorePlayQueue::getQueue Nov 30 18:28:15 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Nov 30 18:28:15 vio sudo[1311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 30 18:28:15 vio sudo[1311]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:28:15 vio sudo[1311]: pam_unix(sudo:session): session closed for user root Nov 30 18:28:56 vio volumio[1025]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Nov 30 18:28:56 vio volumio[1025]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Nov 30 18:28:57 vio sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Nov 30 18:28:57 vio sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 30 18:28:57 vio sudo[1336]: pam_unix(sudo:session): session closed for user root Nov 30 18:28:57 vio sudo[1353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Nov 30 18:28:57 vio sudo[1353]: pam_unix(sudo:session): session opened for user root by (uid=0) # cat /var/log/mpd.log --------------- Nov 30 18:26 : zeroconf: No global port, disabling zeroconf Nov 30 18:27 : client: [0] opened from local Nov 30 18:27 : exception: No such directory Nov 30 18:28 : exception: No such playlist