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