Mon Dec 9 15:50:48 UTC 2019
Description="'Adding album to Queue - Browse - Artist - go to Album Artist (Compilations) - select album (1956). Log shows it is trying to add artists://Carl%20Perkins/1956. go to Albums scroll down to album adds correctly albums://Compilations/1956'"
# 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="2b418696ba9df7da37e66ecee4256e963897e4a2"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Sun Dec 1 01:32:00 CET 2019"
VOLUMIO_VERSION="2.673"
VOLUMIO_HARDWARE="pi"
VOLUMIO_HASH="195d359258dcdb7969a22e2c84f33e21"
# ifconfig ---------------
eth0 Link encap:Ethernet HWaddr b8:27:eb:73:4b:39
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:4634 errors:0 dropped:0 overruns:0 frame:0
TX packets:4634 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:441408 (431.0 KiB) TX bytes:441408 (431.0 KiB)
wlan0 Link encap:Ethernet HWaddr b8:27:eb:26:1e:6c
inet addr:192.168.3.101 Bcast:192.168.3.255 Mask:255.255.255.0
UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
RX packets:2586 errors:0 dropped:0 overruns:0 frame:0
TX packets:3357 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:1060693 (1.0 MiB) TX bytes:1117526 (1.0 MiB)
# sudo iwconfig ---------------
lo no wireless extensions.
eth0 no wireless extensions.
wlan0 IEEE 802.11 ESSID:"McHome"
Mode:Managed Frequency:5.2 GHz Access Point: 70:03:7E:E0:83:33
Bit Rate=390 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 1: sndrpihifiberry [snd_rpi_hifiberry_digi], device 0: HifiBerry Digi HiFi wm8804-spdif-0 [HifiBerry Digi HiFi wm8804-spdif-0]
Subdevices: 1/1
Subdevice #0: subdevice #0
# ps -ef ---------------
UID PID PPID C STIME TTY TIME CMD
root 1 0 0 15:38 ? 00:00:05 /sbin/init
root 2 0 0 15:38 ? 00:00:00 [kthreadd]
root 3 2 0 15:38 ? 00:00:00 [rcu_gp]
root 4 2 0 15:38 ? 00:00:00 [rcu_par_gp]
root 8 2 0 15:38 ? 00:00:00 [mm_percpu_wq]
root 9 2 0 15:38 ? 00:00:00 [ksoftirqd/0]
root 10 2 0 15:38 ? 00:00:00 [rcu_sched]
root 11 2 0 15:38 ? 00:00:00 [rcu_bh]
root 12 2 0 15:38 ? 00:00:00 [migration/0]
root 13 2 0 15:38 ? 00:00:00 [cpuhp/0]
root 14 2 0 15:38 ? 00:00:00 [cpuhp/1]
root 15 2 0 15:38 ? 00:00:00 [migration/1]
root 16 2 0 15:38 ? 00:00:00 [ksoftirqd/1]
root 17 2 0 15:38 ? 00:00:00 [kworker/1:0-eve]
root 18 2 0 15:38 ? 00:00:00 [kworker/1:0H-kb]
root 19 2 0 15:38 ? 00:00:00 [cpuhp/2]
root 20 2 0 15:38 ? 00:00:00 [migration/2]
root 21 2 0 15:38 ? 00:00:00 [ksoftirqd/2]
root 24 2 0 15:38 ? 00:00:00 [cpuhp/3]
root 25 2 0 15:38 ? 00:00:00 [migration/3]
root 26 2 0 15:38 ? 00:00:00 [ksoftirqd/3]
root 29 2 0 15:38 ? 00:00:00 [kdevtmpfs]
root 30 2 0 15:38 ? 00:00:00 [netns]
root 31 2 0 15:38 ? 00:00:00 [kworker/0:1-eve]
root 33 2 0 15:38 ? 00:00:00 [kworker/2:1-mm_]
root 34 2 0 15:38 ? 00:00:00 [khungtaskd]
root 35 2 0 15:38 ? 00:00:00 [oom_reaper]
root 36 2 0 15:38 ? 00:00:00 [writeback]
root 37 2 0 15:38 ? 00:00:00 [kcompactd0]
root 38 2 0 15:38 ? 00:00:00 [crypto]
root 39 2 0 15:38 ? 00:00:00 [kblockd]
root 40 2 0 15:38 ? 00:00:00 [watchdogd]
root 41 2 0 15:38 ? 00:00:00 [rpciod]
root 42 2 0 15:38 ? 00:00:00 [kworker/u9:0-hc]
root 43 2 0 15:38 ? 00:00:00 [xprtiod]
root 44 2 0 15:38 ? 00:00:00 [kworker/u8:1-br]
root 46 2 0 15:38 ? 00:00:00 [kswapd0]
root 47 2 0 15:38 ? 00:00:00 [nfsiod]
root 58 2 0 15:38 ? 00:00:00 [kthrotld]
root 59 2 0 15:38 ? 00:00:00 [kworker/3:1-cgr]
root 60 2 0 15:38 ? 00:00:00 [iscsi_eh]
root 61 2 0 15:38 ? 00:00:00 [dwc_otg]
root 62 2 0 15:38 ? 00:00:00 [DWC Notificatio]
root 63 2 0 15:38 ? 00:00:00 [vchiq-slot/0]
root 64 2 0 15:38 ? 00:00:00 [vchiq-recy/0]
root 65 2 0 15:38 ? 00:00:00 [vchiq-sync/0]
root 66 2 0 15:38 ? 00:00:00 [vchiq-keep/0]
root 67 2 0 15:38 ? 00:00:00 [SMIO]
root 68 2 0 15:38 ? 00:00:00 [irq/86-mmc1]
root 78 2 0 15:38 ? 00:00:00 [mmc_complete]
root 80 2 0 15:38 ? 00:00:00 [kworker/0:1H-mm]
root 103 2 0 15:38 ? 00:00:00 [kworker/2:1H-kb]
root 108 2 0 15:38 ? 00:00:00 [kworker/3:1H-kb]
root 109 2 0 15:38 ? 00:00:00 [kworker/3:2H-kb]
root 110 2 0 15:38 ? 00:00:00 [jbd2/mmcblk0p2-]
root 111 2 0 15:38 ? 00:00:00 [ext4-rsv-conver]
root 114 2 0 15:38 ? 00:00:00 [irq/166-usb-001]
root 120 2 0 15:39 ? 00:00:01 [loop0]
root 139 2 0 15:39 ? 00:00:00 [kworker/1:2H-kb]
root 140 2 0 15:39 ? 00:00:00 [jbd2/mmcblk0p3-]
root 141 2 0 15:39 ? 00:00:00 [ext4-rsv-conver]
root 143 2 0 15:39 ? 00:00:00 [kworker/0:2H-kb]
root 149 2 0 15:39 ? 00:00:00 [ipv6_addrconf]
root 176 1 0 15:39 ? 00:00:01 /lib/systemd/systemd-journald
root 199 2 0 15:39 ? 00:00:00 [kworker/3:2-mm_]
root 206 1 0 15:39 ? 00:00:00 /lib/systemd/systemd-udevd
root 221 2 0 15:39 ? 00:00:00 [kworker/u8:2-ev]
root 243 2 0 15:39 ? 00:00:00 [SMIO]
root 284 2 0 15:39 ? 00:00:00 [mmal-vchiq]
root 286 2 0 15:39 ? 00:00:00 [mmal-vchiq]
root 288 2 0 15:39 ? 00:00:00 [mmal-vchiq]
root 303 2 0 15:39 ? 00:00:00 [cfg80211]
root 319 2 0 15:39 ? 00:00:00 [brcmf_wq/mmc1:0]
root 323 2 0 15:39 ? 00:00:00 [brcmf_wdog/mmc1]
root 350 2 0 15:39 ? 00:00:00 [kworker/u8:3-ev]
root 410 1 0 15:39 ? 00:00:00 /sbin/wpa_supplicant -s -B -P /run/wpa_supplicant.wlan0.pid -i wlan0 -D nl80211,wext -C /run/wpa_supplicant
root 464 1 0 15:39 ? 00:00:00 dhcpcd wlan0
root 592 2 0 15:39 ? 00:00:00 [cifsiod]
root 593 2 0 15:39 ? 00:00:00 [cifsoplockd]
root 596 2 0 15:39 ? 00:00:00 [cifsd]
root 599 1 0 15:39 ? 00:00:00 /sbin/rpcbind -w
statd 642 1 0 15:39 ? 00:00:00 /sbin/rpc.statd
root 657 1 0 15:39 ? 00:00:00 /usr/sbin/rpc.idmapd
root 659 1 0 15:39 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 --write=1024
volumio 663 1 0 15:39 ? 00:00:00 /usr/bin/udisks-glue --foreground
volumio 668 1 0 15:39 ? 00:00:00 /usr/local/bin/volumio-remote-updater
root 686 1 0 15:39 ? 00:00:00 /usr/sbin/cron -f
root 694 1 0 15:39 ? 00:00:00 /lib/systemd/systemd-logind
avahi 717 1 0 15:39 ? 00:00:00 avahi-daemon: running [music.local]
mpd 724 1 0 15:39 ? 00:00:05 /usr/bin/mpd --no-daemon
message+ 736 1 0 15:39 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation
root 780 1 0 15:39 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I
ntp 822 1 0 15:39 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 100:104
avahi 855 717 0 15:39 ? 00:00:00 avahi-daemon: chroot helper
root 878 2 0 15:39 ? 00:00:00 [kworker/u9:1-hc]
root 879 1 0 15:39 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 3000000 flow - b8:27:eb:d9:e1:93
root 979 1 0 15:39 ? 00:00:00 /usr/sbin/sshd -D
root 1028 1 0 15:39 ? 00:00:00 /usr/lib/udisks/udisks-daemon --no-debug
root 1039 1 0 15:39 ? 00:00:00 /usr/lib/bluetooth/bluetoothd
root 1058 1028 0 15:39 ? 00:00:00 udisks-daemon: not polling any devices
root 1078 1 0 15:39 tty1 00:00:00 /sbin/agetty --noclear tty1 linux
root 1089 1 0 15:39 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug
root 1166 2 0 15:39 ? 00:00:00 [kworker/2:3-cgr]
root 1170 1 0 15:39 ? 00:00:00 /usr/sbin/nmbd -D
root 1171 1170 0 15:39 ? 00:00:00 /usr/sbin/nmbd -D
root 1186 1 0 15:39 ? 00:00:00 /usr/sbin/winbindd
root 1199 1186 0 15:39 ? 00:00:00 /usr/sbin/winbindd
root 1258 1 0 15:39 ? 00:00:00 /usr/sbin/smbd -D
root 1261 1186 0 15:39 ? 00:00:00 /usr/sbin/winbindd
root 1262 1186 0 15:39 ? 00:00:00 /usr/sbin/winbindd
root 1263 1258 0 15:39 ? 00:00:00 /usr/sbin/smbd -D
root 1460 1 0 15:39 ? 00:00:00 /sbin/dhcpcd
volumio 1481 1 6 15:39 ? 00:00:43 /usr/local/bin/node /volumio/index.js
volumio 1515 1481 0 15:39 ? 00:00:00 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 1527 1515 0 15:39 ? 00:00:02 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 1530 1515 0 15:39 ? 00:00:02 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 1535 1515 0 15:39 ? 00:00:02 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 1614 1 0 15:40 ? 00:00:00 /usr/local/bin/shairport-sync
volumio 1635 1 0 15:40 ? 00:00:01 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf
volumio 1660 1 0 15:40 ? 00:00:00 /bin/streaming-daemon
root 2143 979 0 15:43 ? 00:00:00 sshd: volumio [priv]
volumio 2164 1 0 15:43 ? 00:00:00 /lib/systemd/systemd --user
volumio 2167 2164 0 15:43 ? 00:00:00 (sd-pam)
volumio 2170 2143 0 15:43 ? 00:00:00 sshd: volumio@pts/0
volumio 2172 2170 0 15:43 pts/0 00:00:00 -bash
root 2200 2 0 15:44 ? 00:00:00 [kworker/1:1-eve]
root 2272 2 0 15:44 ? 00:00:00 [kworker/0:0-eve]
root 2327 2172 0 15:45 pts/0 00:00:00 sudo journalctl -f
root 2331 2327 0 15:45 pts/0 00:00:00 journalctl -f
root 2375 2 0 15:45 ? 00:00:00 [kworker/2:0H]
root 2507 2 0 15:46 ? 00:00:00 [kworker/u8:0-br]
root 2854 2 0 15:49 ? 00:00:00 [kworker/1:2-eve]
root 2956 2 0 15:50 ? 00:00:00 [kworker/0:2-eve]
volumio 3053 1481 1 15:50 ? 00:00:00 /bin/sh -c /usr/local/bin/node /volumio/logsubmit.js 'Adding album to Queue - Browse - Artist - go to Album Artist (Compilations) - select album (1956). Log shows it is trying to add artists://Carl%20Perkins/1956. go to Albums scroll down to album adds correctly albums://Compilations/1956'
volumio 3054 3053 63 15:50 ? 00:00:00 /usr/local/bin/node /volumio/logsubmit.js Adding album to Queue - Browse - Artist - go to Album Artist (Compilations) - select album (1956). Log shows it is trying to add artists://Carl%20Perkins/1956. go to Albums scroll down to album adds correctly albums://Compilations/1956
volumio 3081 3054 0 15:50 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1
volumio 3082 3081 0 15:50 ? 00:00:00 ps -ef
# df -h ---------------
Filesystem Size Used Avail Use% Mounted on
/dev/mmcblk0p2 2.2G 1.1G 987M 53% /imgpart
/dev/loop0 337M 337M 0 100% /static
overlay 115G 65G 45G 60% /
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 36K 20M 1% /var/log
tmpfs 485M 0 485M 0% /var/spool/cups/tmp
/dev/mmcblk0p1 61M 58M 2.3M 97% /boot
//192.168.3.100/Music 150G 80G 71G 53% /home/volumio/hp86
tmpfs 97M 0 97M 0% /run/user/1000
# 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)
//192.168.3.100/Music on /home/volumio/hp86 type cifs (rw,relatime,vers=default,cache=strict,username=rpi,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.3.100,file_mode=0755,dir_mode=0755,soft,nounix,serverino,mapposix,rsize=1048576,wsize=1048576,echo_interval=60,actimeo=1)
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)
# sudo journalctl -p 7 ---------------
-- Logs begin at Mon 2019-12-09 15:36:57 UTC, end at Mon 2019-12-09 15:50:48 UTC. --
Dec 09 15:36:57 music systemd-journal[176]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 72.7M free of 480.6M available → current limit 30.0M).
Dec 09 15:36:57 music kernel: Booting Linux on physical CPU 0x0
Dec 09 15:36:57 music 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
Dec 09 15:36:57 music kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
Dec 09 15:36:57 music kernel: CPU: div instructions available: patching division code
Dec 09 15:36:57 music kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Dec 09 15:36:57 music kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3
Dec 09 15:36:57 music kernel: Memory policy: Data cache writealloc
Dec 09 15:36:57 music kernel: cma: Reserved 8 MiB at 0x3d800000
Dec 09 15:36:57 music kernel: On node 0 totalpages: 253952
Dec 09 15:36:57 music kernel: Normal zone: 2232 pages used for memmap
Dec 09 15:36:57 music kernel: Normal zone: 0 pages reserved
Dec 09 15:36:57 music kernel: Normal zone: 253952 pages, LIFO batch:63
Dec 09 15:36:57 music kernel: random: get_random_bytes called from start_kernel+0xac/0x4b4 with crng_init=0
Dec 09 15:36:57 music kernel: percpu: Embedded 16 pages/cpu s35392 r8192 d21952 u65536
Dec 09 15:36:57 music kernel: pcpu-alloc: s35392 r8192 d21952 u65536 alloc=16*4096
Dec 09 15:36:57 music kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
Dec 09 15:36:57 music kernel: Built 1 zonelists, mobility grouping on. Total pages: 251720
Dec 09 15:36:57 music 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
Dec 09 15:36:57 music kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Dec 09 15:36:57 music kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Dec 09 15:36:57 music kernel: Memory: 964396K/1015808K available (8192K kernel code, 629K rwdata, 2176K rodata, 1024K init, 821K bss, 43220K reserved, 8192K cma-reserved)
Dec 09 15:36:57 music 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)
Dec 09 15:36:57 music kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Dec 09 15:36:57 music kernel: ftrace: allocating 26405 entries in 78 pages
Dec 09 15:36:57 music kernel: rcu: Hierarchical RCU implementation.
Dec 09 15:36:57 music kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Dec 09 15:36:57 music kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys).
Dec 09 15:36:57 music kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Dec 09 15:36:57 music kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Dec 09 15:36:57 music kernel: Switching to timer-based delay loop, resolution 52ns
Dec 09 15:36:57 music kernel: Console: colour dummy device 80x30
Dec 09 15:36:57 music kernel: console [tty1] enabled
Dec 09 15:36:57 music kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
Dec 09 15:36:57 music kernel: pid_max: default: 32768 minimum: 301
Dec 09 15:36:57 music kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
Dec 09 15:36:57 music kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
Dec 09 15:36:57 music kernel: CPU: Testing write buffer coherency: ok
Dec 09 15:36:57 music kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
Dec 09 15:36:57 music kernel: Setting up static identity map for 0x100000 - 0x10003c
Dec 09 15:36:57 music kernel: rcu: Hierarchical SRCU implementation.
Dec 09 15:36:57 music kernel: smp: Bringing up secondary CPUs ...
Dec 09 15:36:57 music kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
Dec 09 15:36:57 music kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
Dec 09 15:36:57 music kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
Dec 09 15:36:57 music kernel: smp: Brought up 1 node, 4 CPUs
Dec 09 15:36:57 music kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS).
Dec 09 15:36:57 music kernel: CPU: All CPU(s) started in HYP mode.
Dec 09 15:36:57 music kernel: CPU: Virtualization extensions available.
Dec 09 15:36:57 music kernel: devtmpfs: initialized
Dec 09 15:36:57 music kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
Dec 09 15:36:57 music kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Dec 09 15:36:57 music kernel: futex hash table entries: 1024 (order: 4, 65536 bytes)
Dec 09 15:36:57 music kernel: pinctrl core: initialized pinctrl subsystem
Dec 09 15:36:57 music kernel: NET: Registered protocol family 16
Dec 09 15:36:57 music kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations
Dec 09 15:36:57 music kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
Dec 09 15:36:57 music kernel: hw-breakpoint: maximum watchpoint size is 8 bytes.
Dec 09 15:36:57 music kernel: Serial: AMBA PL011 UART driver
Dec 09 15:36:57 music kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled
Dec 09 15:36:57 music kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1
Dec 09 15:36:57 music kernel: SCSI subsystem initialized
Dec 09 15:36:57 music kernel: usbcore: registered new interface driver usbfs
Dec 09 15:36:57 music kernel: usbcore: registered new interface driver hub
Dec 09 15:36:57 music kernel: usbcore: registered new device driver usb
Dec 09 15:36:57 music kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2019-10-11 18:40, variant start
Dec 09 15:36:57 music kernel: raspberrypi-firmware soc:firmware: Firmware hash is 89cbed6a54e414b21b554ce245245744332c37e1
Dec 09 15:36:57 music kernel: clocksource: Switched to clocksource arch_sys_counter
Dec 09 15:36:57 music kernel: VFS: Disk quotas dquot_6.6.0
Dec 09 15:36:57 music kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Dec 09 15:36:57 music kernel: FS-Cache: Loaded
Dec 09 15:36:57 music kernel: CacheFiles: Loaded
Dec 09 15:36:57 music kernel: NET: Registered protocol family 2
Dec 09 15:36:57 music kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes)
Dec 09 15:36:57 music kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes)
Dec 09 15:36:57 music kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
Dec 09 15:36:57 music kernel: TCP: Hash tables configured (established 8192 bind 8192)
Dec 09 15:36:57 music kernel: UDP hash table entries: 512 (order: 2, 16384 bytes)
Dec 09 15:36:57 music kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
Dec 09 15:36:57 music kernel: NET: Registered protocol family 1
Dec 09 15:36:57 music kernel: RPC: Registered named UNIX socket transport module.
Dec 09 15:36:57 music kernel: RPC: Registered udp transport module.
Dec 09 15:36:57 music kernel: RPC: Registered tcp transport module.
Dec 09 15:36:57 music kernel: RPC: Registered tcp NFSv4.1 backchannel transport module.
Dec 09 15:36:57 music kernel: Trying to unpack rootfs image as initramfs...
Dec 09 15:36:57 music kernel: Freeing initrd memory: 19368K
Dec 09 15:36:57 music kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
Dec 09 15:36:57 music kernel: Initialise system trusted keyrings
Dec 09 15:36:57 music kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4
Dec 09 15:36:57 music kernel: FS-Cache: Netfs 'nfs' registered for caching
Dec 09 15:36:57 music kernel: NFS: Registering the id_resolver key type
Dec 09 15:36:57 music kernel: Key type id_resolver registered
Dec 09 15:36:57 music kernel: Key type id_legacy registered
Dec 09 15:36:57 music kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Dec 09 15:36:57 music kernel: Key type asymmetric registered
Dec 09 15:36:57 music kernel: Asymmetric key parser 'x509' registered
Dec 09 15:36:57 music kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
Dec 09 15:36:57 music kernel: io scheduler noop registered (default)
Dec 09 15:36:57 music kernel: io scheduler deadline registered
Dec 09 15:36:57 music kernel: io scheduler cfq registered
Dec 09 15:36:57 music kernel: io scheduler mq-deadline registered
Dec 09 15:36:57 music kernel: io scheduler kyber registered
Dec 09 15:36:57 music kernel: bcm2708_fb soc:fb: FB found 1 display(s)
Dec 09 15:36:57 music kernel: Console: switching to colour frame buffer device 80x30
Dec 09 15:36:57 music kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480
Dec 09 15:36:57 music kernel: bcm2835-rng 3f104000.rng: hwrng registered
Dec 09 15:36:57 music kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
Dec 09 15:36:57 music kernel: vc-sm: Videocore shared memory driver
Dec 09 15:36:57 music kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
Dec 09 15:36:57 music kernel: brd: module loaded
Dec 09 15:36:57 music kernel: loop: module loaded
Dec 09 15:36:57 music kernel: Loading iSCSI transport class v2.0-870.
Dec 09 15:36:57 music kernel: libphy: Fixed MDIO Bus: probed
Dec 09 15:36:57 music kernel: usbcore: registered new interface driver lan78xx
Dec 09 15:36:57 music kernel: usbcore: registered new interface driver smsc95xx
Dec 09 15:36:57 music kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Dec 09 15:36:57 music kernel: dwc_otg 3f980000.usb: base=(ptrval)
Dec 09 15:36:57 music kernel: Core Release: 2.80a
Dec 09 15:36:57 music kernel: Setting default values for core params
Dec 09 15:36:57 music kernel: Finished setting default values for core params
Dec 09 15:36:57 music kernel: Using Buffer DMA mode
Dec 09 15:36:57 music kernel: Periodic Transfer Interrupt Enhancement - disabled
Dec 09 15:36:57 music kernel: Multiprocessor Interrupt Enhancement - disabled
Dec 09 15:36:57 music kernel: OTG VER PARAM: 0, OTG VER FLAG: 0
Dec 09 15:36:57 music kernel: Dedicated Tx FIFOs mode
Dec 09 15:36:57 music kernel: WARN::dwc_otg_hcd_init:1045: FIQ DMA bounce buffers: virt = bd914000 dma = 0xfd914000 len=9024
Dec 09 15:36:57 music kernel: FIQ FSM acceleration enabled for :
Non-periodic Split Transactions
Periodic Split Transactions
High-Speed Isochronous Endpoints
Interrupt/Control Split Transaction hack enabled
Dec 09 15:36:57 music kernel: dwc_otg: Microframe scheduler enabled
Dec 09 15:36:57 music kernel: WARN::hcd_init_fiq:457: FIQ on core 1
Dec 09 15:36:57 music kernel: WARN::hcd_init_fiq:458: FIQ ASM at 8065637c length 36
Dec 09 15:36:57 music kernel: WARN::hcd_init_fiq:497: MPHI regs_base at be810000
Dec 09 15:36:57 music kernel: dwc_otg 3f980000.usb: DWC OTG Controller
Dec 09 15:36:57 music kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Dec 09 15:36:57 music kernel: dwc_otg 3f980000.usb: irq 56, io mem 0x00000000
Dec 09 15:36:57 music kernel: Init: Port Power? op_state=1
Dec 09 15:36:57 music kernel: Init: Power Port (0)
Dec 09 15:36:57 music kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19
Dec 09 15:36:57 music kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Dec 09 15:36:57 music kernel: usb usb1: Product: DWC OTG Controller
Dec 09 15:36:57 music kernel: usb usb1: Manufacturer: Linux 4.19.79-v7+ dwc_otg_hcd
Dec 09 15:36:57 music kernel: usb usb1: SerialNumber: 3f980000.usb
Dec 09 15:36:57 music kernel: hub 1-0:1.0: USB hub found
Dec 09 15:36:57 music kernel: hub 1-0:1.0: 1 port detected
Dec 09 15:36:57 music kernel: dwc_otg: FIQ enabled
Dec 09 15:36:57 music kernel: dwc_otg: NAK holdoff enabled
Dec 09 15:36:57 music kernel: dwc_otg: FIQ split-transaction FSM enabled
Dec 09 15:36:57 music kernel: Module dwc_common_port init
Dec 09 15:36:57 music kernel: usbcore: registered new interface driver usb-storage
Dec 09 15:36:57 music kernel: mousedev: PS/2 mouse device common for all mice
Dec 09 15:36:57 music kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
Dec 09 15:36:57 music kernel: bcm2835-cpufreq: min=600000 max=1400000
Dec 09 15:36:57 music kernel: sdhci: Secure Digital Host Controller Interface driver
Dec 09 15:36:57 music kernel: sdhci: Copyright(c) Pierre Ossman
Dec 09 15:36:57 music kernel: mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe
Dec 09 15:36:57 music kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
Dec 09 15:36:57 music kernel: sdhci-pltfm: SDHCI platform and OF driver helper
Dec 09 15:36:57 music kernel: ledtrig-cpu: registered to indicate activity on CPUs
Dec 09 15:36:57 music kernel: hidraw: raw HID events driver (C) Jiri Kosina
Dec 09 15:36:57 music kernel: usbcore: registered new interface driver usbhid
Dec 09 15:36:57 music kernel: usbhid: USB HID core driver
Dec 09 15:36:57 music kernel: vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0
Dec 09 15:36:57 music kernel: [vc_sm_connected_init]: start
Dec 09 15:36:57 music kernel: [vc_sm_connected_init]: end - returning 0
Dec 09 15:36:57 music kernel: Initializing XFRM netlink socket
Dec 09 15:36:57 music kernel: NET: Registered protocol family 17
Dec 09 15:36:57 music kernel: Key type dns_resolver registered
Dec 09 15:36:57 music kernel: Registering SWP/SWPB emulation handler
Dec 09 15:36:57 music kernel: registered taskstats version 1
Dec 09 15:36:57 music kernel: Loading compiled-in X.509 certificates
Dec 09 15:36:57 music kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled
Dec 09 15:36:57 music kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2
Dec 09 15:36:57 music kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
Dec 09 15:36:57 music kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
Dec 09 15:36:57 music kernel: sdhost: log_buf @ (ptrval) (fd913000)
Dec 09 15:36:57 music kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
Dec 09 15:36:57 music kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Dec 09 15:36:57 music kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Dec 09 15:36:57 music kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
Dec 09 15:36:57 music kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Dec 09 15:36:57 music kernel: of_cfs_init
Dec 09 15:36:57 music kernel: of_cfs_init: OK
Dec 09 15:36:57 music kernel: Freeing unused kernel memory: 1024K
Dec 09 15:36:57 music kernel: Run /init as init process
Dec 09 15:36:57 music kernel: random: fast init done
Dec 09 15:36:57 music kernel: mmc1: new high speed SDIO card at address 0001
Dec 09 15:36:57 music kernel: mmc0: host does not support reading read-only switch, assuming write-enable
Dec 09 15:36:57 music kernel: mmc0: new high speed SDXC card at address aaaa
Dec 09 15:36:57 music kernel: mmcblk0: mmc0:aaaa SL128 119 GiB
Dec 09 15:36:57 music kernel: mmcblk0: p1 p2 p3
Dec 09 15:36:57 music kernel: Indeed it is in host mode hprt0 = 00021501
Dec 09 15:36:57 music initramfs: Booting Volumio for BCM2835
Dec 09 15:36:57 music initramfs: This script mounts rootfs RO with an overlay RW layer.
Dec 09 15:36:57 music kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Dec 09 15:36:57 music kernel: fuse init (API version 7.27)
Dec 09 15:36:57 music initramfs: IMGPART=/dev/mmcblk0p2
Dec 09 15:36:57 music initramfs: IMGFILE=/volumio_current.sqsh
Dec 09 15:36:57 music initramfs: Boot delay (except first time) will be 5 seconds
Dec 09 15:36:57 music initramfs: /dev/mmcblk0p2 not detected,retrying mdev in 5 seconds
Dec 09 15:36:57 music kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg
Dec 09 15:36:57 music kernel: Indeed it is in host mode hprt0 = 00001101
Dec 09 15:36:57 music kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
Dec 09 15:36:57 music kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Dec 09 15:36:57 music kernel: hub 1-1:1.0: USB hub found
Dec 09 15:36:57 music kernel: hub 1-1:1.0: 4 ports detected
Dec 09 15:36:57 music initramfs: /dev/mmcblk0p2:
Dec 09 15:36:57 music kernel: EXT4-fs (mmcblk0p2): recovery complete
Dec 09 15:36:57 music kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Dec 09 15:36:57 music kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Dec 09 15:36:57 music initramfs: Doing a 5 second delay here to give kernel load a headstart
Dec 09 15:36:57 music kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514, bcdDevice= b.b3
Dec 09 15:36:57 music kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Dec 09 15:36:57 music kernel: hub 1-1.1:1.0: USB hub found
Dec 09 15:36:57 music kernel: hub 1-1.1:1.0: 3 ports detected
Dec 09 15:36:57 music kernel: dwc_otg_handle_wakeup_detected_intr lxstate = 2
Dec 09 15:36:57 music kernel: usb 1-1.1.1: new high-speed USB device number 4 using dwc_otg
Dec 09 15:36:57 music kernel: usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800, bcdDevice= 3.00
Dec 09 15:36:57 music kernel: usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Dec 09 15:36:57 music kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): No External EEPROM. Setting MAC Speed
Dec 09 15:36:57 music kernel: libphy: lan78xx-mdiobus: probed
Dec 09 15:36:57 music kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64
Dec 09 15:36:57 music initramfs: Checking for USB updates
Dec 09 15:36:57 music initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline)
Dec 09 15:36:57 music kernel: EXT4-fs (mmcblk0p3): recovery complete
Dec 09 15:36:57 music kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null)
Dec 09 15:36:57 music initramfs: With Option:
Dec 09 15:36:57 music initramfs: VOLUMIO_VERSION="2.673"
Dec 09 15:36:57 music initramfs: Finish initramfs, continue booting Volumio
Dec 09 15:36:57 music kernel: random: systemd: uninitialized urandom read (16 bytes read)
Dec 09 15:36:57 music systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR)
Dec 09 15:36:57 music systemd[1]: Detected architecture 'arm'.
Dec 09 15:36:57 music kernel: NET: Registered protocol family 10
Dec 09 15:36:57 music kernel: Segment Routing with IPv6
Dec 09 15:36:57 music systemd[1]: Inserted module 'ipv6'
Dec 09 15:36:57 music systemd[1]: Set hostname to .
Dec 09 15:36:57 music kernel: random: systemd-sysv-ge: uninitialized urandom read (16 bytes read)
Dec 09 15:36:57 music kernel: random: systemd: uninitialized urandom read (16 bytes read)
Dec 09 15:36:57 music systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service'
Dec 09 15:36:57 music systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service'
Dec 09 15:36:57 music systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service'
Dec 09 15:36:57 music systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service'
Dec 09 15:36:57 music systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory.
Dec 09 15:36:57 music systemd[1]: Starting Forward Password Requests to Wall Directory Watch.
Dec 09 15:36:57 music kernel: i2c /dev entries driver
Dec 09 15:36:57 music systemd-journal[176]: Journal started
Dec 09 15:36:57 music systemd[1]: Starting Slices.
Dec 09 15:36:57 music systemd[1]: Reached target Slices.
Dec 09 15:36:57 music systemd[1]: Starting Remount Root and Kernel File Systems...
Dec 09 15:36:57 music systemd[1]: Started Create list of required static device nodes for the current kernel.
Dec 09 15:36:57 music systemd[1]: Starting Create Static Device Nodes in /dev...
Dec 09 15:36:57 music systemd[1]: Time has been changed
Dec 09 15:36:57 music systemd[1]: Started Restore / save the current clock.
Dec 09 15:36:57 music systemd[1]: Mounted Debug File System.
Dec 09 15:36:57 music systemd[1]: Mounted POSIX Message Queue File System.
Dec 09 15:36:57 music systemd[1]: Started Remount Root and Kernel File Systems.
Dec 09 15:36:57 music systemd[1]: Started Various fixups to make systemd work better on Debian.
Dec 09 15:36:57 music systemd-modules-load[174]: Inserted module 'i2c_dev'
Dec 09 15:36:57 music systemd[1]: Started Load Kernel Modules.
Dec 09 15:36:57 music systemd[1]: Mounting FUSE Control File System...
Dec 09 15:36:57 music systemd[1]: Mounting Configuration File System...
Dec 09 15:36:57 music systemd[1]: Starting Apply Kernel Variables...
Dec 09 15:36:57 music systemd[1]: Starting udev Kernel Device Manager...
Dec 09 15:36:57 music fake-hwclock[164]: Mon Dec 9 15:36:57 UTC 2019
Dec 09 15:36:57 music systemd[1]: Starting Local File Systems (Pre).
Dec 09 15:36:57 music systemd[1]: Reached target Local File Systems (Pre).
Dec 09 15:36:57 music systemd[1]: Mounting /tmp...
Dec 09 15:36:57 music systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway.
Dec 09 15:36:57 music systemd[1]: Mounting /var/spool/cups...
Dec 09 15:36:57 music systemd[1]: Mounting /var/log...
Dec 09 15:36:57 music systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway.
Dec 09 15:36:57 music systemd[1]: Mounted Configuration File System.
Dec 09 15:36:57 music systemd[1]: Mounted FUSE Control File System.
Dec 09 15:36:57 music systemd[1]: Mounted /var/spool/cups.
Dec 09 15:36:57 music systemd[1]: Mounted /tmp.
Dec 09 15:36:57 music systemd[1]: Mounted /var/log.
Dec 09 15:36:57 music systemd[1]: Started udev Kernel Device Manager.
Dec 09 15:36:57 music systemd-udevd[206]: starting version 215
Dec 09 15:36:57 music systemd[1]: Started Apply Kernel Variables.
Dec 09 15:36:57 music systemd[1]: Starting Show Plymouth Boot Screen...
Dec 09 15:36:57 music systemd[1]: Starting Copy rules generated while the root was ro...
Dec 09 15:36:57 music systemd[1]: Mounting /var/spool/cups/tmp...
Dec 09 15:36:57 music systemd[1]: Mounted /var/spool/cups/tmp.
Dec 09 15:36:57 music systemd[1]: Started Copy rules generated while the root was ro.
Dec 09 15:36:57 music systemd[1]: Started Show Plymouth Boot Screen.
Dec 09 15:36:57 music systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch.
Dec 09 15:36:57 music systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
Dec 09 15:36:57 music systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
Dec 09 15:36:57 music systemd[1]: Starting Paths.
Dec 09 15:36:57 music systemd[1]: Reached target Paths.
Dec 09 15:36:57 music kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
Dec 09 15:36:57 music kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver
Dec 09 15:36:57 music kernel: [vc_sm_connected_init]: start
Dec 09 15:36:57 music kernel: [vc_sm_connected_init]: installed successfully
Dec 09 15:36:57 music kernel: media: Linux media interface: v0.10
Dec 09 15:36:58 music kernel: videodev: Linux video capture interface: v2.00
Dec 09 15:36:58 music kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Dec 09 15:36:58 music kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
Dec 09 15:36:58 music kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
Dec 09 15:36:58 music kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10
Dec 09 15:36:58 music kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode
Dec 09 15:36:58 music kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
Dec 09 15:36:58 music kernel: snd_bcm2835: unknown parameter 'index' ignored
Dec 09 15:36:58 music kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11
Dec 09 15:36:58 music kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode
Dec 09 15:36:58 music kernel: bcm2835_audio soc:audio: card created with 8 channels
Dec 09 15:36:58 music kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12
Dec 09 15:36:58 music kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp
Dec 09 15:36:58 music kernel: snd-rpi-wm8804 soc:sound: ASoC: CODEC DAI wm8804-spdif not registered - will retry
Dec 09 15:36:58 music kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database
Dec 09 15:36:58 music kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
Dec 09 15:36:58 music kernel: platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
Dec 09 15:36:58 music kernel: cfg80211: failed to load regulatory.db
Dec 09 15:36:58 music systemd[1]: Starting Sound Card.
Dec 09 15:36:58 music systemd[1]: Reached target Sound Card.
Dec 09 15:36:58 music systemd[1]: Found device /dev/mmcblk0p1.
Dec 09 15:36:58 music systemd[1]: Starting File System Check on /dev/mmcblk0p1...
Dec 09 15:36:58 music kernel: wm8804 1-003b: Linked as a consumer to regulator.1
Dec 09 15:36:58 music kernel: wm8804 1-003b: revision E
Dec 09 15:36:58 music kernel: snd-rpi-wm8804 soc:sound: wm8804-spdif <-> 3f203000.i2s mapping ok
Dec 09 15:36:58 music kernel: brcmfmac: F1 signature read @0x18000000=0x15264345
Dec 09 15:36:58 music kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
Dec 09 15:36:58 music kernel: usbcore: registered new interface driver brcmfmac
Dec 09 15:36:58 music systemd[1]: Starting system-ifup.slice.
Dec 09 15:36:58 music systemd[1]: Created slice system-ifup.slice.
Dec 09 15:36:58 music systemd-fsck[309]: fsck.fat 3.0.27 (2014-11-12)
Dec 09 15:36:58 music systemd-fsck[309]: /dev/mmcblk0p1: 231 files, 118404/123044 clusters
Dec 09 15:36:58 music systemd[1]: Started File System Check on /dev/mmcblk0p1.
Dec 09 15:36:58 music systemd[1]: Mounting /boot...
Dec 09 15:36:59 music systemd[1]: Mounted /boot.
Dec 09 15:36:59 music systemd[1]: Starting Local File Systems.
Dec 09 15:36:59 music systemd[1]: Reached target Local File Systems.
Dec 09 15:36:59 music systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
Dec 09 15:36:59 music systemd[1]: Starting Create Volatile Files and Directories...
Dec 09 15:36:59 music systemd[1]: Starting LSB: Raise network interfaces....
Dec 09 15:36:59 music systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
Dec 09 15:36:59 music systemd[1]: Started Create Volatile Files and Directories.
Dec 09 15:36:59 music kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
Dec 09 15:36:59 music 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
Dec 09 15:36:59 music systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Dec 09 15:36:59 music systemd[1]: Starting system-systemd\x2drfkill.slice.
Dec 09 15:36:59 music systemd[1]: Created slice system-systemd\x2drfkill.slice.
Dec 09 15:36:59 music systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill0...
Dec 09 15:36:59 music systemd[1]: Started Update UTMP about System Boot/Shutdown.
Dec 09 15:36:59 music systemd[1]: Started Load/Save RF Kill Switch Status of rfkill0.
Dec 09 15:36:59 music kernel: random: crng init done
Dec 09 15:36:59 music kernel: random: 7 urandom warning(s) missed due to ratelimiting
Dec 09 15:36:59 music kernel: brcmfmac: power management disabled
Dec 09 15:37:00 music systemd[1]: Received SIGRTMIN+20 from PID 237 (plymouthd).
Dec 09 15:37:00 music wpa_supplicant[408]: Successfully initialized wpa_supplicant
Dec 09 15:37:00 music kernel: brcmfmac: power management disabled
Dec 09 15:37:00 music networking[336]: Configuring network interfaces...dhcpcd[419]: version 6.0.5 starting
Dec 09 15:37:00 music dhcpcd[419]: version 6.0.5 starting
Dec 09 15:37:01 music networking[336]: dhcpcd[419]: wlan0: waiting for carrier
Dec 09 15:37:01 music dhcpcd[419]: wlan0: waiting for carrier
Dec 09 15:37:03 music wpa_supplicant[410]: wlan0: Trying to associate with SSID 'McHome'
Dec 09 15:37:06 music wpa_supplicant[410]: wlan0: Associated with 70:03:7e:e0:83:33
Dec 09 15:37:06 music wpa_supplicant[410]: wlan0: WPA: Key negotiation completed with 70:03:7e:e0:83:33 [PTK=CCMP GTK=CCMP]
Dec 09 15:37:06 music wpa_supplicant[410]: wlan0: CTRL-EVENT-CONNECTED - Connection to 70:03:7e:e0:83:33 completed [id=0 id_str=]
Dec 09 15:37:06 music dhcpcd[419]: wlan0: carrier acquired
Dec 09 15:37:06 music networking[336]: dhcpcd[419]: wlan0: carrier acquired
Dec 09 15:37:06 music wpa_supplicant[410]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Dec 09 15:37:06 music dhcpcd[419]: wlan0: soliciting an IPv6 router
Dec 09 15:37:06 music networking[336]: dhcpcd[419]: wlan0: soliciting an IPv6 router
Dec 09 15:37:06 music networking[336]: dhcpcd[419]: wlan0: rebinding lease of 192.168.3.101
Dec 09 15:37:06 music dhcpcd[419]: wlan0: rebinding lease of 192.168.3.101
Dec 09 15:37:11 music dhcpcd[419]: wlan0: leased 192.168.3.101 for 604800 seconds
Dec 09 15:37:11 music networking[336]: dhcpcd[419]: wlan0: leased 192.168.3.101 for 604800 seconds
Dec 09 15:37:11 music dhcpcd[419]: wlan0: adding host route to 192.168.3.101 via 127.0.0.1
Dec 09 15:37:11 music dhcpcd[419]: wlan0: adding route to 192.168.3.0/24
Dec 09 15:37:11 music dhcpcd[419]: wlan0: adding default route via 192.168.3.1
Dec 09 15:37:11 music networking[336]: dhcpcd[419]: wlan0: adding host route to 192.168.3.101 via 127.0.0.1
Dec 09 15:37:11 music networking[336]: dhcpcd[419]: wlan0: adding route to 192.168.3.0/24
Dec 09 15:37:11 music networking[336]: dhcpcd[419]: wlan0: adding default route via 192.168.3.1
Dec 09 15:37:11 music networking[336]: dhcpcd[419]: forked to background, child pid 464
Dec 09 15:37:11 music dhcpcd[419]: forked to background, child pid 464
Dec 09 15:37:12 music networking[336]: ifup: interface wlan0 already configured
Dec 09 15:37:12 music networking[336]: done.
Dec 09 15:37:12 music systemd[1]: Started LSB: Raise network interfaces..
Dec 09 15:37:12 music systemd[1]: Starting ifup for wlan0...
Dec 09 15:37:12 music systemd[1]: Started ifup for wlan0.
Dec 09 15:37:12 music systemd[1]: Starting ifup for eth0...
Dec 09 15:37:12 music systemd[1]: Started ifup for eth0.
Dec 09 15:37:12 music systemd[1]: Starting Network.
Dec 09 15:37:12 music systemd[1]: Reached target Network.
Dec 09 15:37:12 music systemd[1]: Starting Network is Online.
Dec 09 15:37:12 music systemd[1]: Reached target Network is Online.
Dec 09 15:37:12 music systemd[1]: Mounting /home/volumio/hp86...
Dec 09 15:37:12 music ifup[545]: /sbin/ifup: interface wlan0 already configured
Dec 09 15:37:12 music systemd[1]: Starting LSB: RPC portmapper replacement...
Dec 09 15:37:12 music ifup[546]: dhcpcd[568]: version 6.0.5 starting
Dec 09 15:37:12 music dhcpcd[568]: version 6.0.5 starting
Dec 09 15:37:12 music ifup[546]: dhcpcd[568]: eth0: waiting for carrier
Dec 09 15:37:12 music dhcpcd[568]: eth0: waiting for carrier
Dec 09 15:37:12 music kernel: FS-Cache: Netfs 'cifs' registered for caching
Dec 09 15:37:12 music kernel: Key type cifs.spnego registered
Dec 09 15:37:12 music kernel: Key type cifs.idmap registered
Dec 09 15:37:12 music 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.
Dec 09 15:37:13 music rpcbind[554]: Starting rpcbind daemon....
Dec 09 15:37:13 music systemd[1]: Started LSB: RPC portmapper replacement.
Dec 09 15:37:13 music systemd[1]: Starting RPC Port Mapper.
Dec 09 15:37:13 music systemd[1]: Reached target RPC Port Mapper.
Dec 09 15:37:13 music systemd[1]: Starting LSB: NFS support files common to client and server...
Dec 09 15:37:13 music systemd[1]: Mounted /home/volumio/hp86.
Dec 09 15:37:13 music systemd[1]: Starting Remote File Systems.
Dec 09 15:37:13 music systemd[1]: Reached target Remote File Systems.
Dec 09 15:37:13 music systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage...
Dec 09 15:37:13 music rpc.statd[642]: Version 1.2.8 starting
Dec 09 15:37:13 music sm-notify[643]: Version 1.2.8 starting
Dec 09 15:37:13 music systemd[1]: Started Trigger Flushing of Journal to Persistent Storage.
Dec 09 15:37:13 music kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
Dec 09 15:37:13 music nfs-common[615]: Starting NFS common utilities: statd idmapd.
Dec 09 15:37:13 music systemd[1]: Started LSB: NFS support files common to client and server.
Dec 09 15:37:13 music systemd[1]: Starting System Initialization.
Dec 09 15:37:13 music systemd[1]: Reached target System Initialization.
Dec 09 15:37:13 music systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket.
Dec 09 15:37:13 music systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Dec 09 15:37:13 music systemd[1]: Starting mpd.socket.
Dec 09 15:37:13 music systemd[1]: Listening on mpd.socket.
Dec 09 15:37:13 music systemd[1]: Starting D-Bus System Message Bus Socket.
Dec 09 15:37:13 music systemd[1]: Listening on D-Bus System Message Bus Socket.
Dec 09 15:37:13 music systemd[1]: Starting Sockets.
Dec 09 15:37:13 music systemd[1]: Reached target Sockets.
Dec 09 15:37:13 music systemd[1]: Starting Daily Cleanup of Temporary Directories.
Dec 09 15:37:13 music systemd[1]: Started Daily Cleanup of Temporary Directories.
Dec 09 15:37:13 music systemd[1]: Starting Timers.
Dec 09 15:37:13 music systemd[1]: Reached target Timers.
Dec 09 15:37:13 music systemd[1]: Started Manage Sound Card State (restore and store).
Dec 09 15:37:13 music systemd[1]: Starting Restore Sound Card State...
Dec 09 15:37:13 music systemd[1]: Starting Basic System.
Dec 09 15:37:13 music systemd[1]: Reached target Basic System.
Dec 09 15:37:13 music systemd[1]: Starting Entropy daemon using the HAVEGE algorithm...
Dec 09 15:37:13 music systemd[1]: Started Entropy daemon using the HAVEGE algorithm.
Dec 09 15:37:13 music systemd[1]: Starting cd.service...
Dec 09 15:37:13 music systemd[1]: Started cd.service.
Dec 09 15:37:13 music systemd[1]: Starting Configure Bluetooth Modems connected by UART...
Dec 09 15:37:13 music systemd[1]: Starting Volumio Iptables Module...
Dec 09 15:37:13 music systemd[1]: Started Volumio Iptables Module.
Dec 09 15:37:13 music systemd[1]: Starting mount disks automatically with standby...
Dec 09 15:37:13 music systemd[1]: Started mount disks automatically with standby.
Dec 09 15:37:13 music systemd[1]: Starting UPnP Renderer front-end to MPD...
Dec 09 15:37:13 music systemd[1]: Started UPnP Renderer front-end to MPD.
Dec 09 15:37:13 music systemd[1]: Starting volumio-remote-updater.service...
Dec 09 15:37:13 music volumio[660]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory
Dec 09 15:37:13 music systemd[1]: Started volumio-remote-updater.service.
Dec 09 15:37:13 music systemd[1]: Starting Volumio SSH enabler...
Dec 09 15:37:13 music systemd[1]: Started Volumio SSH enabler.
Dec 09 15:37:13 music systemd[1]: Starting Wireless Services...
Dec 09 15:37:13 music systemd[1]: Starting Xbindrc...
Dec 09 15:37:13 music systemd[1]: Started Xbindrc.
Dec 09 15:37:13 music systemd[1]: Starting OpenBSD Secure Shell server...
Dec 09 15:37:13 music systemd[1]: Starting Regular background program processing daemon...
Dec 09 15:37:13 music systemd[1]: Started Regular background program processing daemon.
Dec 09 15:37:13 music systemd[1]: Starting /etc/rc.local Compatibility...
Dec 09 15:37:13 music systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available.
Dec 09 15:37:13 music systemd[1]: Starting Login Service...
Dec 09 15:37:13 music cron[686]: (CRON) INFO (pidfile fd = 3)
Dec 09 15:37:13 music systemd[1]: Starting LSB: exim Mail Transport Agent...
Dec 09 15:37:13 music cron[686]: (CRON) INFO (Running @reboot jobs)
Dec 09 15:37:13 music systemd[1]: Starting LSB: start Winbind daemon...
Dec 09 15:37:13 music systemd[1]: Starting LSB: start Samba daemons for the AD DC...
Dec 09 15:37:13 music systemd[1]: Starting LSB: Start NTP daemon...
Dec 09 15:37:13 music systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)...
Dec 09 15:37:13 music systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling...
Dec 09 15:37:13 music kernel: uart-pl011 3f201000.serial: no DMA platform data
Dec 09 15:37:13 music systemd[1]: Starting LSB: Brings up/down network automatically...
Dec 09 15:37:13 music systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon...
Dec 09 15:37:13 music systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support...
Dec 09 15:37:13 music systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Dec 09 15:37:13 music systemd[1]: Starting Music Player Daemon...
Dec 09 15:37:13 music systemd[1]: Starting D-Bus System Message Bus...
Dec 09 15:37:13 music systemd[1]: Started D-Bus System Message Bus.
Dec 09 15:37:13 music btuart[661]: Patch not found for BCM4345C0, continue anyway
Dec 09 15:37:14 music dhcpcd[716]: Not running dhcpcd because there is aleady an interface specific instance ... failed!
Dec 09 15:37:14 music dhcpcd[716]: /var/run/dhcpcd-eth0.pid ... failed!
Dec 09 15:37:14 music ifplugd(eth0)[780]: ifplugd 0.28 initializing.
Dec 09 15:37:14 music ifplugd(eth0)[780]: Using interface eth0/B8:27:EB:73:4B:39 with driver (version: )
Dec 09 15:37:14 music ifplugd(eth0)[780]: Using detection mode: SIOCETHTOOL
Dec 09 15:37:14 music ifplugd(eth0)[780]: Initialization complete, link beat not detected.
Dec 09 15:37:14 music ifplugd[711]: Network Interface Plugging Daemon...start eth0...done.
Dec 09 15:37:14 music loadcpufreq[707]: Loading cpufreq kernel modules...done (none).
Dec 09 15:37:14 music ntpd[761]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1)
Dec 09 15:37:14 music ntp[702]: Starting NTP server: ntpd.
Dec 09 15:37:14 music ntpd[822]: proto: precision = 1.094 usec
Dec 09 15:37:14 music ntpd[822]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123
Dec 09 15:37:14 music ntpd[822]: Listen and drop on 1 v6wildcard :: UDP 123
Dec 09 15:37:14 music ntpd[822]: Listen normally on 2 lo 127.0.0.1 UDP 123
Dec 09 15:37:14 music ntpd[822]: Listen normally on 3 wlan0 192.168.3.101 UDP 123
Dec 09 15:37:14 music ntpd[822]: peers refreshed
Dec 09 15:37:14 music ntpd[822]: Listening on routing socket on fd #20 for interface updates
Dec 09 15:37:14 music avahi-daemon[717]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106).
Dec 09 15:37:14 music avahi-daemon[717]: Successfully dropped root privileges.
Dec 09 15:37:14 music avahi-daemon[717]: avahi-daemon 0.6.31 starting up.
Dec 09 15:37:14 music kernel: Bluetooth: Core ver 2.22
Dec 09 15:37:14 music kernel: NET: Registered protocol family 31
Dec 09 15:37:14 music kernel: Bluetooth: HCI device and connection manager initialized
Dec 09 15:37:14 music kernel: Bluetooth: HCI socket layer initialized
Dec 09 15:37:14 music kernel: Bluetooth: L2CAP socket layer initialized
Dec 09 15:37:14 music kernel: Bluetooth: SCO socket layer initialized
Dec 09 15:37:14 music kernel: Bluetooth: HCI UART driver ver 2.3
Dec 09 15:37:14 music kernel: Bluetooth: HCI UART protocol H4 registered
Dec 09 15:37:14 music kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered
Dec 09 15:37:14 music kernel: Bluetooth: HCI UART protocol Broadcom registered
Dec 09 15:37:14 music btuart[661]: bcm43xx_init
Dec 09 15:37:14 music btuart[661]: Set BDADDR UART: b8:27:eb:d9:e1:93
Dec 09 15:37:14 music btuart[661]: Set Controller UART speed to 3000000 bit/s
Dec 09 15:37:14 music btuart[661]: Device setup complete
Dec 09 15:37:14 music systemd-udevd[880]: failed to execute '/usr/bin/hciconfig' '/usr/bin/hciconfig hci0 up': No such file or directory
Dec 09 15:37:14 music sudo[680]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off
Dec 09 15:37:14 music sudo[680]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:14 music sudo[680]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:14 music dbus-daemon[736]: Unknown group "bluetooth" in message bus configuration file
Dec 09 15:37:14 music dbus-daemon[736]: Unknown username "rtkit" in message bus configuration file
Dec 09 15:37:14 music dbus-daemon[736]: Unknown username "pulse" in message bus configuration file
Dec 09 15:37:14 music dbus-daemon[736]: Unknown username "pulse" in message bus configuration file
Dec 09 15:37:15 music avahi-daemon[717]: Successfully called chroot().
Dec 09 15:37:15 music avahi-daemon[717]: Successfully dropped remaining capabilities.
Dec 09 15:37:15 music systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Dec 09 15:37:15 music systemd[1]: Starting Permit User Sessions...
Dec 09 15:37:15 music systemd[1]: Started Restore Sound Card State.
Dec 09 15:37:15 music systemd[1]: cd.service: main process exited, code=exited, status=127/n/a
Dec 09 15:37:15 music systemd[1]: Unit cd.service entered failed state.
Dec 09 15:37:15 music systemd[1]: Started Configure Bluetooth Modems connected by UART.
Dec 09 15:37:15 music avahi-daemon[717]: Loading service file /services/volumio.service.
Dec 09 15:37:15 music avahi-daemon[717]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.101.
Dec 09 15:37:15 music avahi-daemon[717]: New relevant interface wlan0.IPv4 for mDNS.
Dec 09 15:37:15 music avahi-daemon[717]: Network interface enumeration completed.
Dec 09 15:37:15 music avahi-daemon[717]: Registering new address record for 192.168.3.101 on wlan0.IPv4.
Dec 09 15:37:15 music avahi-daemon[717]: Registering HINFO record with values 'ARMV7L'/'LINUX'.
Dec 09 15:37:15 music systemd[1]: Started OpenBSD Secure Shell server.
Dec 09 15:37:15 music systemd[1]: Started /etc/rc.local Compatibility.
Dec 09 15:37:15 music systemd[1]: Started LSB: Start NTP daemon.
Dec 09 15:37:15 music systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling.
Dec 09 15:37:15 music systemd[1]: Started LSB: Brings up/down network automatically.
Dec 09 15:37:15 music systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon.
Dec 09 15:37:15 music systemd[1]: dhcpcd.service: control process exited, code=exited status=6
Dec 09 15:37:15 music systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support.
Dec 09 15:37:15 music systemd[1]: Unit dhcpcd.service entered failed state.
Dec 09 15:37:15 music systemd[1]: Started Permit User Sessions.
Dec 09 15:37:15 music dbus[736]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service'
Dec 09 15:37:15 music sshd[979]: Server listening on 0.0.0.0 port 22.
Dec 09 15:37:15 music sshd[979]: Server listening on :: port 22.
Dec 09 15:37:15 music systemd[1]: cd.service holdoff time over, scheduling restart.
Dec 09 15:37:15 music systemd-logind[694]: New seat seat0.
Dec 09 15:37:15 music systemd[1]: Started Login Service.
Dec 09 15:37:15 music systemd[1]: Starting Disk Manager (legacy version)...
Dec 09 15:37:15 music systemd[1]: Stopping cd.service...
Dec 09 15:37:15 music systemd[1]: Starting cd.service...
Dec 09 15:37:15 music systemd[1]: Started cd.service.
Dec 09 15:37:15 music systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill1...
Dec 09 15:37:15 music systemd[1]: Starting Bluetooth service...
Dec 09 15:37:15 music systemd[1]: Starting LSB: set CPUFreq kernel parameters...
Dec 09 15:37:15 music systemd[1]: Starting Hold until boot process finishes up...
Dec 09 15:37:15 music systemd[1]: Starting Terminate Plymouth Boot Screen...
Dec 09 15:37:15 music volumio[1029]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory
Dec 09 15:37:15 music systemd[1]: cd.service: main process exited, code=exited, status=127/n/a
Dec 09 15:37:15 music systemd[1]: Unit cd.service entered failed state.
Dec 09 15:37:15 music systemd[1]: Started Load/Save RF Kill Switch Status of rfkill1.
Dec 09 15:37:15 music systemd[1]: Received SIGRTMIN+21 from PID 237 (plymouthd).
Dec 09 15:37:15 music systemd[1]: Started Hold until boot process finishes up.
Dec 09 15:37:15 music systemd[1]: Started Terminate Plymouth Boot Screen.
Dec 09 15:37:15 music systemd[1]: cd.service holdoff time over, scheduling restart.
Dec 09 15:37:15 music dbus[736]: [system] Successfully activated service 'org.freedesktop.UDisks'
Dec 09 15:37:15 music systemd[1]: Started Disk Manager (legacy version).
Dec 09 15:37:15 music systemd[1]: Stopping cd.service...
Dec 09 15:37:15 music systemd[1]: Starting cd.service...
Dec 09 15:37:15 music systemd[1]: Started cd.service.
Dec 09 15:37:15 music systemd[1]: Starting Getty on tty1...
Dec 09 15:37:15 music cpufrequtils[1041]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done.
Dec 09 15:37:15 music systemd[1]: Started Getty on tty1.
Dec 09 15:37:15 music systemd[1]: Starting Login Prompts.
Dec 09 15:37:15 music systemd[1]: Reached target Login Prompts.
Dec 09 15:37:15 music systemd[1]: Started LSB: set CPUFreq kernel parameters.
Dec 09 15:37:15 music volumio[1076]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory
Dec 09 15:37:15 music systemd[1]: cd.service: main process exited, code=exited, status=127/n/a
Dec 09 15:37:15 music systemd[1]: Unit cd.service entered failed state.
Dec 09 15:37:15 music avahi-daemon[717]: Server startup complete. Host name is music.local. Local service cookie is 1179160512.
Dec 09 15:37:15 music bluetoothd[1039]: Bluetooth daemon 5.23
Dec 09 15:37:15 music systemd[1]: cd.service holdoff time over, scheduling restart.
Dec 09 15:37:15 music systemd[1]: Stopping cd.service...
Dec 09 15:37:15 music systemd[1]: Starting cd.service...
Dec 09 15:37:15 music systemd[1]: Started cd.service.
Dec 09 15:37:15 music systemd[1]: cd.service: main process exited, code=exited, status=127/n/a
Dec 09 15:37:15 music volumio[1080]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory
Dec 09 15:37:15 music systemd[1]: Unit cd.service entered failed state.
Dec 09 15:37:15 music systemd[1]: Started Bluetooth service.
Dec 09 15:37:15 music bluetoothd[1039]: Starting SDP server
Dec 09 15:37:16 music systemd[1]: Starting Bluetooth.
Dec 09 15:37:16 music systemd[1]: Reached target Bluetooth.
Dec 09 15:37:16 music kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Dec 09 15:37:16 music kernel: Bluetooth: BNEP filters: protocol multicast
Dec 09 15:37:16 music kernel: Bluetooth: BNEP socket layer initialized
Dec 09 15:37:16 music systemd[1]: cd.service holdoff time over, scheduling restart.
Dec 09 15:37:16 music systemd[1]: Stopping cd.service...
Dec 09 15:37:16 music systemd[1]: Starting cd.service...
Dec 09 15:37:16 music bluetoothd[1039]: Bluetooth management interface 1.14 initialized
Dec 09 15:37:16 music dbus[736]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service'
Dec 09 15:37:16 music systemd[1]: Started cd.service.
Dec 09 15:37:16 music bluetoothd[1039]: Sap driver initialization failed.
Dec 09 15:37:16 music bluetoothd[1039]: sap-server: Operation not permitted (1)
Dec 09 15:37:16 music systemd[1]: Starting Hostname Service...
Dec 09 15:37:16 music volumio[1083]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory
Dec 09 15:37:16 music volumio-remote-updater[668]: Error: No active session
Dec 09 15:37:16 music volumio-remote-updater[668]: [2019-12-09 15:37:16] [info] asio async_connect error: system:111 (Connection refused)
Dec 09 15:37:16 music volumio-remote-updater[668]: [2019-12-09 15:37:16] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Dec 09 15:37:16 music volumio-remote-updater[668]: [2019-12-09 15:37:16] [error] handle_connect error: Underlying Transport Error
Dec 09 15:37:16 music systemd[1]: cd.service: main process exited, code=exited, status=127/n/a
Dec 09 15:37:16 music systemd[1]: Unit cd.service entered failed state.
Dec 09 15:37:16 music kernel: Under-voltage detected! (0x00050005)
Dec 09 15:37:16 music systemd-hostnamed[1085]: Warning: nss-myhostname is not installed. Changing the local hostname might make it unresolveable. Please install nss-myhostname!
Dec 09 15:37:16 music dbus[736]: [system] Successfully activated service 'org.freedesktop.hostname1'
Dec 09 15:37:16 music systemd[1]: Started Hostname Service.
Dec 09 15:37:16 music systemd[1]: cd.service holdoff time over, scheduling restart.
Dec 09 15:37:16 music systemd[1]: Stopping cd.service...
Dec 09 15:37:16 music systemd[1]: Starting cd.service...
Dec 09 15:37:16 music systemd[1]: cd.service start request repeated too quickly, refusing to start.
Dec 09 15:37:16 music systemd[1]: Failed to start cd.service.
Dec 09 15:37:16 music systemd[1]: Unit cd.service entered failed state.
Dec 09 15:37:16 music dbus[736]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service'
Dec 09 15:37:16 music systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks...
Dec 09 15:37:16 music avahi-daemon[717]: Service "music" (/services/volumio.service) successfully established.
Dec 09 15:37:16 music polkitd[1089]: started daemon version 0.105 using authority implementation `local' version `0.105'
Dec 09 15:37:16 music dbus[736]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Dec 09 15:37:16 music systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks.
Dec 09 15:37:16 music haveged[659]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K
Dec 09 15:37:16 music haveged[659]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244
Dec 09 15:37:16 music haveged[659]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00061
Dec 09 15:37:16 music haveged[659]: haveged: fills: 0, generated: 0
Dec 09 15:37:16 music volumio[666]: Could not open config: /tmp/upmpdcli.conf
Dec 09 15:37:16 music systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE
Dec 09 15:37:16 music systemd[1]: Unit upmpdcli.service entered failed state.
Dec 09 15:37:16 music systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart.
Dec 09 15:37:16 music systemd[1]: Stopping Xbindrc...
Dec 09 15:37:16 music systemd[1]: Starting Xbindrc...
Dec 09 15:37:16 music systemd[1]: Started Xbindrc.
Dec 09 15:37:17 music udisks-glue[663]: Device file /dev/mmcblk0 inserted
Dec 09 15:37:17 music udisks-glue[663]: Device file /dev/mmcblk0p3 inserted
Dec 09 15:37:17 music systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart.
Dec 09 15:37:17 music systemd[1]: Stopping Xbindrc...
Dec 09 15:37:17 music systemd[1]: Starting Xbindrc...
Dec 09 15:37:17 music systemd[1]: Started Xbindrc.
Dec 09 15:37:18 music systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart.
Dec 09 15:37:18 music systemd[1]: Stopping Xbindrc...
Dec 09 15:37:18 music systemd[1]: Starting Xbindrc...
Dec 09 15:37:18 music systemd[1]: Started Xbindrc.
Dec 09 15:37:18 music systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart.
Dec 09 15:37:18 music systemd[1]: Stopping Xbindrc...
Dec 09 15:37:18 music systemd[1]: Starting Xbindrc...
Dec 09 15:37:18 music systemd[1]: Started Xbindrc.
Dec 09 15:37:18 music exim4[695]: Starting MTA: exim4.
Dec 09 15:37:18 music exim[1126]: 2019-12-09 15:37:18 socket bind() to port 25 for address ::1 failed: Cannot assign requested address: waiting 30s before trying again (9 more tries)
Dec 09 15:37:18 music exim[1126]: 2019-12-09 15:37:18 Cannot open main log file "/var/log/exim4/mainlog": No such file or directory: euid=0 egid=116
Dec 09 15:37:18 music exim[1126]: exim: could not open panic log - aborting: see message(s) above
Dec 09 15:37:18 music systemd[1]: Started LSB: exim Mail Transport Agent.
Dec 09 15:37:18 music systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart.
Dec 09 15:37:18 music systemd[1]: Stopping Xbindrc...
Dec 09 15:37:18 music systemd[1]: Starting Xbindrc...
Dec 09 15:37:18 music systemd[1]: xbindkeysrc.service start request repeated too quickly, refusing to start.
Dec 09 15:37:18 music systemd[1]: Failed to start Xbindrc.
Dec 09 15:37:18 music systemd[1]: Unit xbindkeysrc.service entered failed state.
Dec 09 15:37:20 music wireless.js[977]: WIRELESS DAEMON: start
Dec 09 15:37:20 music wireless.js[977]: WIRELESS: Loaded configuration
Dec 09 15:37:20 music wireless.js[977]: Cleaning previous...
Dec 09 15:37:20 music systemd[1]: Stopped hotspot.service.
Dec 09 15:37:21 music sudo[1141]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Dec 09 15:37:21 music sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:21 music dhcpcd[464]: wlan0: removing IP address 192.168.3.101/24
Dec 09 15:37:21 music avahi-daemon[717]: Withdrawing address record for 192.168.3.101 on wlan0.
Dec 09 15:37:21 music avahi-daemon[717]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.101.
Dec 09 15:37:21 music dhcpcd[464]: wlan0: removing route to 192.168.3.0/24
Dec 09 15:37:21 music sudo[1141]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:21 music avahi-daemon[717]: Interface wlan0.IPv4 no longer relevant for mDNS.
Dec 09 15:37:21 music sudo[1152]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Dec 09 15:37:21 music sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:21 music systemd[1]: Started LSB: start Samba daemons for the AD DC.
Dec 09 15:37:21 music winbind[698]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory
Dec 09 15:37:21 music winbind[698]: Failed to create /var/log/samba/cores for user 0 with mode 0700
Dec 09 15:37:21 music winbind[698]: Unable to setup corepath for winbindd: No such file or directory
Dec 09 15:37:21 music winbind[698]: mkdir failed on directory /var/log/samba/cores: No such file or directory
Dec 09 15:37:21 music winbind[698]: Failed to create /var/log/samba/cores for user 0 with mode 0700
Dec 09 15:37:21 music winbind[698]: Unable to setup corepath for winbindd: No such file or directory
Dec 09 15:37:21 music winbind[698]: [2019/12/09 15:37:21, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:21 music winbind[698]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Dec 09 15:37:21 music winbind[698]: [2019/12/09 15:37:21, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:21 music winbind[698]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Dec 09 15:37:21 music winbind[698]: [2019/12/09 15:37:21, 0] ../source3/winbindd/winbindd.c:1549(main)
Dec 09 15:37:21 music winbind[698]: winbindd version 4.2.14-Debian started.
Dec 09 15:37:21 music winbind[698]: Copyright Andrew Tridgell and the Samba Team 1992-2014
Dec 09 15:37:21 music winbind[698]: [2019/12/09 15:37:21, 0] ../lib/util/util.c:220(directory_create_or_exist)
Dec 09 15:37:21 music winbind[698]: mkdir failed on directory /var/log/samba/cores: No such file or directory
Dec 09 15:37:21 music nmbd[1169]: [2019/12/09 15:37:21.620665, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:21 music nmbd[1169]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Dec 09 15:37:21 music winbindd[1165]: [2019/12/09 15:37:21.627224, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:21 music winbindd[1165]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Dec 09 15:37:21 music nmbd[1169]: [2019/12/09 15:37:21.628011, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:21 music nmbd[1169]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Dec 09 15:37:21 music winbindd[1165]: [2019/12/09 15:37:21.629090, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:21 music winbindd[1165]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Dec 09 15:37:21 music nmbd[1170]: [2019/12/09 15:37:21.634185, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns)
Dec 09 15:37:21 music nmbd[1170]: started asyncdns process 1171
Dec 09 15:37:21 music systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd).
Dec 09 15:37:21 music systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)...
Dec 09 15:37:21 music winbind[698]: [2019/12/09 15:37:21, 0] ../source3/lib/dumpcore.c:59(get_default_corepath)
Dec 09 15:37:21 music winbind[698]: Failed to create /var/log/samba/cores for user 0 with mode 0700
Dec 09 15:37:21 music winbind[698]: [2019/12/09 15:37:21, 0] ../source3/lib/dumpcore.c:250(dump_core_setup)
Dec 09 15:37:21 music winbind[698]: Unable to setup corepath for winbindd: No such file or directory
Dec 09 15:37:21 music winbind[698]: [2019/12/09 15:37:21.627224, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:21 music winbind[698]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Dec 09 15:37:21 music winbind[698]: [2019/12/09 15:37:21.629090, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:21 music winbind[698]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Dec 09 15:37:21 music nmbd[704]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory
Dec 09 15:37:21 music nmbd[704]: Failed to create /var/log/samba/cores for user 0 with mode 0700
Dec 09 15:37:21 music nmbd[704]: Unable to setup corepath for nmbd: No such file or directory
Dec 09 15:37:21 music nmbd[704]: [2019/12/09 15:37:21, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:21 music nmbd[704]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Dec 09 15:37:21 music nmbd[704]: [2019/12/09 15:37:21, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:21 music nmbd[704]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Dec 09 15:37:21 music nmbd[704]: [2019/12/09 15:37:21, 0] ../source3/nmbd/nmbd.c:908(main)
Dec 09 15:37:21 music nmbd[704]: nmbd version 4.2.14-Debian started.
Dec 09 15:37:21 music nmbd[704]: Copyright Andrew Tridgell and the Samba Team 1992-2014
Dec 09 15:37:21 music nmbd[704]: [2019/12/09 15:37:21.620665, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:21 music nmbd[704]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Dec 09 15:37:21 music nmbd[704]: [2019/12/09 15:37:21.628011, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:21 music nmbd[704]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Dec 09 15:37:21 music nmbd[704]: .
Dec 09 15:37:21 music dhcpcd[464]: wlan0: carrier lost
Dec 09 15:37:21 music dhcpcd[464]: wlan0: deleting host route to 192.168.3.101 via 127.0.0.1
Dec 09 15:37:21 music dhcpcd[464]: wlan0: deleting default route via 192.168.3.1
Dec 09 15:37:21 music wpa_supplicant[410]: wlan0: CTRL-EVENT-DISCONNECTED bssid=70:03:7e:e0:83:33 reason=3 locally_generated=1
Dec 09 15:37:21 music sudo[1152]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:21 music nmbd[1170]: [2019/12/09 15:37:21.770552, 0] ../lib/util/become_daemon.c:135(daemon_status)
Dec 09 15:37:21 music nmbd[1170]: 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).
Dec 09 15:37:21 music volumio-remote-updater[668]: [2019-12-09 15:37:21] [info] asio async_connect error: system:111 (Connection refused)
Dec 09 15:37:21 music volumio-remote-updater[668]: [2019-12-09 15:37:21] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Dec 09 15:37:21 music volumio-remote-updater[668]: [2019-12-09 15:37:21] [error] handle_connect error: Underlying Transport Error
Dec 09 15:37:21 music winbindd[1186]: [2019/12/09 15:37:21.795513, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache)
Dec 09 15:37:21 music winbindd[1186]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Dec 09 15:37:21 music systemd[1]: Started LSB: start Winbind daemon.
Dec 09 15:37:21 music winbind[698]: .
Dec 09 15:37:21 music winbindd[1186]: [2019/12/09 15:37:21.844724, 0] ../lib/util/become_daemon.c:124(daemon_ready)
Dec 09 15:37:21 music winbindd[1186]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
Dec 09 15:37:21 music winbindd[1199]: [2019/12/09 15:37:21.854225, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:21 music winbindd[1199]: Unable to open new log file '/var/log/samba/log.wb-MUSIC': No such file or directory
Dec 09 15:37:21 music wireless.js[977]: Stopped aP
Dec 09 15:37:21 music wireless.js[977]: Start wireless flow
Dec 09 15:37:21 music wireless.js[977]: Stopped hotspot (if there)..
Dec 09 15:37:22 music sudo[1203]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Dec 09 15:37:22 music sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:22 music sudo[1203]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:22 music sudo[1211]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Dec 09 15:37:22 music sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:22 music sudo[1211]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:22 music wireless.js[977]: DHCP IP
Dec 09 15:37:22 music wpa_supplicant[1219]: Successfully initialized wpa_supplicant
Dec 09 15:37:22 music wireless.js[977]: Start ap
Dec 09 15:37:22 music sudo[1220]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd
Dec 09 15:37:22 music sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:22 music dhcpcd[1224]: version 6.0.5 starting
Dec 09 15:37:22 music kernel: brcmfmac: power management disabled
Dec 09 15:37:22 music dhcpcd[1224]: eth0: waiting for carrier
Dec 09 15:37:22 music dhcpcd[1224]: wlan0: waiting for carrier
Dec 09 15:37:22 music wpa_supplicant[1219]: ctrl_iface exists and seems to be in use - cannot override it
Dec 09 15:37:22 music wpa_supplicant[1219]: Delete '/var/run/wpa_supplicant/wlan0' manually if it is not used anymore
Dec 09 15:37:22 music wpa_supplicant[1219]: Failed to initialize control interface '/var/run/wpa_supplicant'.
You may have another wpa_supplicant process already running or the file was
left by an unclean termination of wpa_supplicant in which case you will need
to manually remove this file before starting wpa_supplicant again.
Dec 09 15:37:23 music wireless.js[977]: trying...
Dec 09 15:37:23 music sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 09 15:37:23 music sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:23 music sudo[1250]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:23 music smbd[1172]: Starting SMB/CIFS daemon: smbd[2019/12/09 15:37:23, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:23 music smbd[1172]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Dec 09 15:37:23 music smbd[1172]: [2019/12/09 15:37:23, 0] ../lib/util/util.c:220(directory_create_or_exist)
Dec 09 15:37:23 music smbd[1172]: mkdir failed on directory /var/log/samba/cores: No such file or directory
Dec 09 15:37:23 music smbd[1172]: [2019/12/09 15:37:23, 0] ../source3/lib/dumpcore.c:59(get_default_corepath)
Dec 09 15:37:23 music smbd[1172]: Failed to create /var/log/samba/cores for user 0 with mode 0700
Dec 09 15:37:23 music smbd[1172]: [2019/12/09 15:37:23, 0] ../source3/lib/dumpcore.c:250(dump_core_setup)
Dec 09 15:37:23 music smbd[1172]: Unable to setup corepath for smbd: No such file or directory
Dec 09 15:37:23 music smbd[1172]: [2019/12/09 15:37:23, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:23 music smbd[1172]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Dec 09 15:37:23 music smbd[1172]: [2019/12/09 15:37:23, 0] ../source3/smbd/server.c:1241(main)
Dec 09 15:37:23 music smbd[1172]: smbd version 4.2.14-Debian started.
Dec 09 15:37:23 music smbd[1172]: Copyright Andrew Tridgell and the Samba Team 1992-2014
Dec 09 15:37:23 music smbd[1248]: [2019/12/09 15:37:23.514955, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:23 music smbd[1248]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Dec 09 15:37:23 music smbd[1248]: [2019/12/09 15:37:23.518157, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:23 music smbd[1248]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Dec 09 15:37:23 music smbd[1248]: [2019/12/09 15:37:23.518515, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:23 music smbd[1248]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Dec 09 15:37:23 music smbd[1172]: [2019/12/09 15:37:23.514955, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:23 music smbd[1172]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Dec 09 15:37:23 music smbd[1172]: [2019/12/09 15:37:23.518157, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:23 music smbd[1172]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Dec 09 15:37:23 music smbd[1172]: [2019/12/09 15:37:23.518515, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:23 music smbd[1172]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Dec 09 15:37:23 music smbd[1172]: .
Dec 09 15:37:23 music systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd).
Dec 09 15:37:23 music winbindd[1261]: [2019/12/09 15:37:23.606694, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:23 music winbindd[1261]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory
Dec 09 15:37:23 music winbindd[1262]: [2019/12/09 15:37:23.682684, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:23 music winbindd[1262]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory
Dec 09 15:37:23 music smbd[1258]: [2019/12/09 15:37:23.754423, 0] ../lib/util/become_daemon.c:124(daemon_ready)
Dec 09 15:37:23 music smbd[1258]: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Dec 09 15:37:23 music smbd[1263]: [2019/12/09 15:37:23.763382, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Dec 09 15:37:23 music smbd[1263]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Dec 09 15:37:23 music systemd[1]: Started Music Player Daemon.
Dec 09 15:37:24 music wireless.js[977]: trying...
Dec 09 15:37:24 music sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 09 15:37:24 music sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:24 music sudo[1267]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:24 music ntpd[822]: Deleting interface #3 wlan0, 192.168.3.101#123, interface stats: received=10, sent=10, dropped=8, active_time=10 secs
Dec 09 15:37:24 music ntpd[822]: 192.3.29.143 interface 192.168.3.101 -> (none)
Dec 09 15:37:24 music ntpd[822]: 216.126.233.109 interface 192.168.3.101 -> (none)
Dec 09 15:37:24 music ntpd[822]: 199.223.248.100 interface 192.168.3.101 -> (none)
Dec 09 15:37:24 music ntpd[822]: 138.68.46.177 interface 192.168.3.101 -> (none)
Dec 09 15:37:24 music ntpd[822]: peers refreshed
Dec 09 15:37:25 music wpa_supplicant[410]: wlan0: Trying to associate with SSID 'McHome'
Dec 09 15:37:25 music wireless.js[977]: trying...
Dec 09 15:37:25 music sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 09 15:37:25 music sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:25 music sudo[1276]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:26 music wireless.js[977]: trying...
Dec 09 15:37:26 music sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 09 15:37:26 music sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:26 music sudo[1285]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:27 music wireless.js[977]: trying...
Dec 09 15:37:27 music sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 09 15:37:27 music sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:27 music sudo[1294]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:28 music dhcpcd[464]: wlan0: carrier acquired
Dec 09 15:37:28 music dhcpcd[1224]: wlan0: carrier acquired
Dec 09 15:37:28 music wpa_supplicant[410]: wlan0: Associated with 70:03:7e:e0:83:33
Dec 09 15:37:28 music wpa_supplicant[410]: wlan0: WPA: Key negotiation completed with 70:03:7e:e0:83:33 [PTK=CCMP GTK=CCMP]
Dec 09 15:37:28 music wpa_supplicant[410]: wlan0: CTRL-EVENT-CONNECTED - Connection to 70:03:7e:e0:83:33 completed [id=0 id_str=]
Dec 09 15:37:28 music dhcpcd[1224]: wlan0: soliciting an IPv6 router
Dec 09 15:37:28 music dhcpcd[1224]: wlan0: rebinding lease of 192.168.3.101
Dec 09 15:37:28 music dhcpcd[1224]: wlan0: carrier lost
Dec 09 15:37:28 music dhcpcd[464]: wlan0: soliciting an IPv6 router
Dec 09 15:37:28 music dhcpcd[464]: wlan0: rebinding lease of 192.168.3.101
Dec 09 15:37:28 music dhcpcd[464]: wlan0: carrier lost
Dec 09 15:37:28 music wpa_supplicant[410]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US
Dec 09 15:37:28 music dhcpcd[1224]: wlan0: carrier acquired
Dec 09 15:37:28 music dhcpcd[1224]: wlan0: soliciting an IPv6 router
Dec 09 15:37:28 music dhcpcd[464]: wlan0: carrier acquired
Dec 09 15:37:28 music dhcpcd[1224]: wlan0: rebinding lease of 192.168.3.101
Dec 09 15:37:28 music dhcpcd[464]: wlan0: soliciting an IPv6 router
Dec 09 15:37:28 music dhcpcd[464]: wlan0: rebinding lease of 192.168.3.101
Dec 09 15:37:28 music wireless.js[977]: trying...
Dec 09 15:37:28 music sudo[1373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 09 15:37:28 music sudo[1373]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:28 music sudo[1373]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:29 music volumio-remote-updater[668]: [2019-12-09 15:37:29] [info] asio async_connect error: system:111 (Connection refused)
Dec 09 15:37:29 music volumio-remote-updater[668]: [2019-12-09 15:37:29] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Dec 09 15:37:29 music volumio-remote-updater[668]: [2019-12-09 15:37:29] [error] handle_connect error: Underlying Transport Error
Dec 09 15:37:29 music wireless.js[977]: trying...
Dec 09 15:37:29 music sudo[1382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 09 15:37:29 music sudo[1382]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:29 music sudo[1382]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:30 music wireless.js[977]: trying...
Dec 09 15:37:30 music sudo[1391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 09 15:37:30 music sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:30 music sudo[1391]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:31 music wireless.js[977]: trying...
Dec 09 15:37:31 music sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 09 15:37:31 music sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:31 music sudo[1400]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:32 music wireless.js[977]: trying...
Dec 09 15:37:32 music sudo[1409]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 09 15:37:32 music sudo[1409]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:32 music sudo[1409]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:33 music dhcpcd[464]: wlan0: leased 192.168.3.101 for 604800 seconds
Dec 09 15:37:33 music avahi-daemon[717]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.3.101.
Dec 09 15:37:33 music dhcpcd[464]: wlan0: adding host route to 192.168.3.101 via 127.0.0.1
Dec 09 15:37:33 music dhcpcd[464]: wlan0: adding route to 192.168.3.0/24
Dec 09 15:37:33 music avahi-daemon[717]: New relevant interface wlan0.IPv4 for mDNS.
Dec 09 15:37:33 music dhcpcd[464]: wlan0: adding default route via 192.168.3.1
Dec 09 15:37:33 music avahi-daemon[717]: Registering new address record for 192.168.3.101 on wlan0.IPv4.
Dec 09 15:37:33 music dhcpcd[1224]: wlan0: leased 192.168.3.101 for 604800 seconds
Dec 09 15:37:33 music dhcpcd[1224]: wlan0: adding host route to 192.168.3.101 via 127.0.0.1
Dec 09 15:37:33 music dhcpcd[1224]: wlan0: adding route to 192.168.3.0/24
Dec 09 15:37:33 music dhcpcd[1224]: wlan0: adding default route via 192.168.3.1
Dec 09 15:37:33 music dhcpcd[464]: wlan0: removing route to 192.168.3.0/24
Dec 09 15:37:33 music dhcpcd[464]: wlan0: removing default route via 192.168.3.1
Dec 09 15:37:33 music wireless.js[977]: trying...
Dec 09 15:37:33 music dhcpcd[1224]: forked to background, child pid 1460
Dec 09 15:37:33 music sudo[1220]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:33 music sudo[1458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Dec 09 15:37:33 music sudo[1458]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:33 music dhcpcd[1460]: wlan0: removing route to 192.168.3.0/24
Dec 09 15:37:33 music dhcpcd[1460]: wlan0: removing default route via 192.168.3.1
Dec 09 15:37:33 music sudo[1458]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:33 music wireless.js[977]: Connected to: ----McHome
Dec 09 15:37:33 music wireless.js[977]: ----
Dec 09 15:37:34 music sudo[1472]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 09 15:37:34 music sudo[1472]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:37:34 music sudo[1472]: pam_unix(sudo:session): session closed for user root
Dec 09 15:37:34 music wireless.js[977]: ... joined AP, wlan0 IPv4 is 192.168.3.101, ipV6 is undefined
Dec 09 15:37:34 music wireless.js[977]: It's done! AP
Dec 09 15:37:34 music systemd[1]: Started Wireless Services.
Dec 09 15:37:34 music systemd[1]: Starting Volumio Backend Module...
Dec 09 15:37:34 music systemd[1]: Started Volumio Backend Module.
Dec 09 15:37:34 music systemd[1]: Starting Volumio Streaming Daemon...
Dec 09 15:37:34 music systemd[1]: Started Volumio Streaming Daemon.
Dec 09 15:37:34 music systemd[1]: Starting Multi-User System.
Dec 09 15:37:34 music systemd[1]: Reached target Multi-User System.
Dec 09 15:37:34 music systemd[1]: Starting Graphical Interface.
Dec 09 15:37:34 music systemd[1]: Reached target Graphical Interface.
Dec 09 15:37:34 music systemd[1]: Starting Update UTMP about System Runlevel Changes...
Dec 09 15:37:34 music systemd[1]: Started Update UTMP about System Runlevel Changes.
Dec 09 15:37:34 music systemd[1]: Startup finished in 9.515s (kernel) + 37.892s (userspace) = 47.408s.
Dec 09 15:37:34 music volumio-streaming-daemon[1482]: ############################
Dec 09 15:37:34 music volumio-streaming-daemon[1482]: # Volumio Streaming Daemon #
Dec 09 15:37:34 music volumio-streaming-daemon[1482]: # Running on port 7777 #
Dec 09 15:37:34 music volumio-streaming-daemon[1482]: ############################
Dec 09 15:37:35 music ntpd[822]: Listen normally on 4 wlan0 192.168.3.101 UDP 123
Dec 09 15:37:35 music ntpd[822]: peers refreshed
Dec 09 15:37:36 music nmbd[1170]: [2019/12/09 15:37:36.777943, 0] ../lib/util/become_daemon.c:124(daemon_ready)
Dec 09 15:37:36 music nmbd[1170]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Dec 09 15:37:38 music volumio[1481]: info: -------------------------------------------
Dec 09 15:37:38 music volumio[1481]: info: ----- Volumio2 ----
Dec 09 15:37:38 music volumio[1481]: info: -------------------------------------------
Dec 09 15:37:38 music volumio[1481]: info: ----- System startup ----
Dec 09 15:37:38 music volumio[1481]: info: -------------------------------------------
Dec 09 15:37:38 music volumio[1481]: info: MYVOLUMIO Environment detected
Dec 09 15:37:39 music volumio-remote-updater[668]: [2019-12-09 15:37:39] [connect] Successful connection
Dec 09 15:37:40 music volumio[1481]: info: Plugin folders cleanup
Dec 09 15:37:40 music volumio[1481]: info: Scanning into folder /volumio/app/plugins/
Dec 09 15:37:40 music volumio[1481]: info: Scanning category audio_interface
Dec 09 15:37:40 music volumio[1481]: info: Scanning category miscellanea
Dec 09 15:37:40 music volumio[1481]: info: Scanning category music_service
Dec 09 15:37:40 music volumio[1481]: info: Scanning category plugins.json
Dec 09 15:37:40 music volumio[1481]: info: Scanning category system_controller
Dec 09 15:37:40 music volumio[1481]: info: Scanning category user_interface
Dec 09 15:37:40 music volumio[1481]: info: Scanning into folder /data/plugins/
Dec 09 15:37:40 music volumio[1481]: info: Plugin folders cleanup completed
Dec 09 15:37:40 music volumio[1481]: info: -------------------------------------------
Dec 09 15:37:40 music volumio[1481]: info: ----- Core plugins startup ----
Dec 09 15:37:40 music volumio[1481]: info: -------------------------------------------
Dec 09 15:37:40 music volumio[1481]: info: Loading plugins from folder /volumio/app/plugins/
Dec 09 15:37:40 music volumio[1481]: info: Adding plugin upnp to MyMusic Plugins
Dec 09 15:37:40 music volumio[1481]: info: Adding plugin airplay_emulation to MyMusic Plugins
Dec 09 15:37:40 music volumio[1481]: info: Adding plugin upnp_browser to MyMusic Plugins
Dec 09 15:37:40 music volumio[1481]: info: Loading plugins from folder /data/plugins/
Dec 09 15:37:40 music volumio[1481]: info: Loading plugin "system"...
Dec 09 15:37:40 music volumio[1481]: info: Loading plugin "appearance"...
Dec 09 15:37:42 music volumio[1481]: info: Loading plugin "network"...
Dec 09 15:37:42 music dhcpcd[568]: timed out
Dec 09 15:37:42 music dhcpcd[568]: allowing 8 seconds for IPv4LL timeout
Dec 09 15:37:42 music ifup[546]: dhcpcd[568]: timed out
Dec 09 15:37:42 music ifup[546]: dhcpcd[568]: allowing 8 seconds for IPv4LL timeout
Dec 09 15:37:43 music volumio[1481]: info: Loading plugin "services"...
Dec 09 15:37:43 music volumio[1481]: info: Loading plugin "alsa_controller"...
Dec 09 15:37:43 music volumio[1481]: info: Loading plugin "wizard"...
Dec 09 15:37:43 music volumio[1481]: info: Loading plugin "volumio_command_line_client"...
Dec 09 15:37:43 music volumio[1481]: info: Loading plugin "upnp"...
Dec 09 15:37:43 music volumio[1481]: info: [1575905863549] Starting Upmpd Daemon
Dec 09 15:37:43 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 09 15:37:43 music volumio[1481]: info: Loading plugin "my_music"...
Dec 09 15:37:43 music volumio[1481]: info: Loading plugin "mpd"...
Dec 09 15:39:50 music systemd[1]: Time has been changed
Dec 09 15:39:50 music volumio[1481]: info: Loading plugin "upnp_browser"...
Dec 09 15:39:51 music volumio[1481]: info: Loading plugin "networkfs"...
Dec 09 15:39:51 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 09 15:39:51 music volumio[1481]: info: Loading plugin "alarm-clock"...
Dec 09 15:39:52 music volumio[1481]: info: Loading plugin "airplay_emulation"...
Dec 09 15:39:52 music volumio[1481]: info: Starting Shairport Sync
Dec 09 15:39:52 music volumio[1481]: info: Loading plugin "last_100"...
Dec 09 15:39:52 music volumio[1481]: info: Loading plugin "webradio"...
Dec 09 15:39:52 music volumio[1481]: info: Loading plugin "i2s_dacs"...
Dec 09 15:39:52 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 15:39:52 music volumio[1481]: info: Loading plugin "volumiodiscovery"...
Dec 09 15:39:52 music volumio[1481]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Dec 09 15:39:52 music node[1481]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Dec 09 15:39:52 music node[1481]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 09 15:39:52 music node[1481]: *** WARNING *** For more information see
Dec 09 15:39:52 music node[1481]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Dec 09 15:39:52 music node[1481]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 09 15:39:52 music node[1481]: *** WARNING *** For more information see
Dec 09 15:39:52 music volumio[1481]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 09 15:39:52 music volumio[1481]: *** WARNING *** For more information see
Dec 09 15:39:52 music volumio[1481]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Dec 09 15:39:52 music volumio[1481]: *** WARNING *** Please fix your application to use the native API of Avahi!
Dec 09 15:39:52 music volumio[1481]: *** WARNING *** For more information see
Dec 09 15:39:52 music volumio[1481]: info: Applying required configuration parameters for plugin volumiodiscovery
Dec 09 15:39:52 music volumio[1481]: Discovery: StartAdv! undefined
Dec 09 15:39:52 music volumio[1481]: Discovery: Started advertising... music - undefined
Dec 09 15:39:52 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Dec 09 15:39:52 music volumio[1481]: info: Loading plugin "outputs"...
Dec 09 15:39:52 music volumio[1481]: info: Loading plugin "albumart"...
Dec 09 15:39:52 music volumio[1481]: info: Plugin example_plugin is not enabled
Dec 09 15:39:52 music volumio[1481]: info: Loading plugin "inputs"...
Dec 09 15:39:52 music volumio[1481]: info: Loading plugin "updater_comm"...
Dec 09 15:39:52 music volumio[1481]: info: Plugin mpdemulation is not enabled
Dec 09 15:39:52 music volumio[1481]: info: Loading plugin "rest_api"...
Dec 09 15:39:53 music volumio[1481]: info: Loading plugin "websocket"...
Dec 09 15:39:53 music volumio[1481]: info: ___________ START PLUGINS ___________
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 09 15:39:53 music volumio[1481]: info: [1575905993106] CoreMusicLibrary::Adding element Media Servers
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 09 15:39:53 music volumio[1481]: Forking 3 albumart workers
Dec 09 15:39:53 music volumio[1481]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 09 15:39:53 music volumio[1481]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 09 15:39:53 music volumio[1481]: info: [1575905993408] CoreMusicLibrary::Adding element Last_100
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Dec 09 15:39:53 music volumio[1481]: info: [1575905993415] CoreMusicLibrary::Adding element Webradio
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 09 15:39:53 music volumio[1481]: info: -------------------------------------------
Dec 09 15:39:53 music volumio[1481]: info: ----- MyVolumio plugins startup ----
Dec 09 15:39:53 music volumio[1481]: info: -------------------------------------------
Dec 09 15:39:53 music volumio[1481]: info: [MyVolumio PluginManager] Fetching plans data....
Dec 09 15:39:53 music volumio[1481]: info: Loading i18n strings for locale en
Dec 09 15:39:53 music volumio[1481]: Updating browse sources language
Dec 09 15:39:53 music volumio[1481]: Cannot find translation for sourceMedia Servers
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::initPlayerControls
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 09 15:39:53 music volumio[1481]: info: BOOT COMPLETED
Dec 09 15:39:53 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 15:39:54 music volumio[1481]: Starting albumart workers
Dec 09 15:39:54 music volumio[1481]: Starting albumart workers
Dec 09 15:39:54 music volumio[1481]: Starting albumart workers
Dec 09 15:39:57 music dhcpcd[568]: timed out
Dec 09 15:39:57 music dhcpcd[568]: exited
Dec 09 15:39:57 music ifup[546]: dhcpcd[568]: timed out
Dec 09 15:39:57 music ifup[546]: dhcpcd[568]: exited
Dec 09 15:39:57 music ifup[546]: Failed to bring up eth0.
Dec 09 15:39:59 music volumio[1481]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo
Dec 09 15:39:59 music volumio[1481]: info: CoreCommandRouter::Close All Modals sent
Dec 09 15:39:59 music volumio[1481]: info: CoreCommandRouter::Close All Modals sent
Dec 09 15:39:59 music volumio[1481]: Express server listening on port 3000
Dec 09 15:39:59 music volumio[1481]: Volumio Calling Home
Dec 09 15:39:59 music volumio[1481]: info: CoreStateMachine::resetVolumioState
Dec 09 15:39:59 music volumio[1481]: info: CoreStateMachine::getcurrentVolume
Dec 09 15:39:59 music volumio[1481]: info: CoreCommandRouter::volumioRetrievevolume
Dec 09 15:39:59 music volumio[1481]: info: CoreStateMachine::pushState
Dec 09 15:39:59 music volumio[1481]: info: CoreStateMachine::getState
Dec 09 15:39:59 music volumio[1481]: info: CorePlayQueue::getTrack 0
Dec 09 15:39:59 music volumio[1481]: info: CoreCommandRouter::volumioPushState
Dec 09 15:39:59 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 09 15:39:59 music volumio[1481]: info: Setting Device type: Raspberry PI
Dec 09 15:39:59 music volumio[1481]: info: MPD running with PID724 ,establishing connection
Dec 09 15:39:59 music volumio[1481]: info: Volumio called home
Dec 09 15:39:59 music volumio[1481]: info: Reloading queue from file
Dec 09 15:39:59 music volumio[1481]: info:
Dec 09 15:39:59 music volumio[1481]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory
Dec 09 15:39:59 music volumio[1481]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14)
Dec 09 15:39:59 music volumio[1481]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10)
Dec 09 15:39:59 music volumio[1481]: at emitOne (events.js:116:13)
Dec 09 15:39:59 music volumio[1481]: at Socket.emit (events.js:211:7)
Dec 09 15:39:59 music volumio[1481]: at addChunk (_stream_readable.js:263:12)
Dec 09 15:39:59 music volumio[1481]: at readableAddChunk (_stream_readable.js:246:13)
Dec 09 15:39:59 music volumio[1481]: at Socket.Readable.push (_stream_readable.js:208:10)
Dec 09 15:39:59 music volumio[1481]: at Pipe.onread (net.js:607:20)
Dec 09 15:40:00 music volumio[1481]: info: CoreStateMachine::setRepeat false single undefined
Dec 09 15:40:00 music volumio[1481]: info: CoreStateMachine::pushState
Dec 09 15:40:00 music volumio[1481]: info: CoreStateMachine::getState
Dec 09 15:40:00 music volumio[1481]: info: CorePlayQueue::getTrack 0
Dec 09 15:40:00 music volumio[1481]: info: CoreCommandRouter::volumioPushState
Dec 09 15:40:00 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 09 15:40:00 music volumio[1481]: info: CoreStateMachine::setRandom true
Dec 09 15:40:00 music volumio[1481]: info: CoreStateMachine::pushState
Dec 09 15:40:00 music volumio[1481]: info: CoreStateMachine::getState
Dec 09 15:40:00 music volumio[1481]: info: CorePlayQueue::getTrack 0
Dec 09 15:40:00 music volumio[1481]: info: CoreCommandRouter::volumioPushState
Dec 09 15:40:00 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 09 15:40:00 music sudo[1576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 09 15:40:00 music sudo[1576]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:40:00 music sudo[1576]: pam_unix(sudo:session): session closed for user root
Dec 09 15:40:00 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Dec 09 15:40:00 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: raat , onStop
Dec 09 15:40:00 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Dec 09 15:40:00 music sudo[1587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay
Dec 09 15:40:00 music sudo[1587]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:40:00 music sudo[1589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay
Dec 09 15:40:00 music sudo[1589]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:40:00 music systemd[1]: Starting ShairportSync AirTunes receiver...
Dec 09 15:40:00 music systemd[1]: Started ShairportSync AirTunes receiver.
Dec 09 15:40:00 music volumio[1481]: Discovery: adding abac01d6-6870-4e46-b613-f88acca73efb
Dec 09 15:40:00 music volumio[1481]: info: mDNS: Found device music
Dec 09 15:40:00 music shairport-sync[1597]: Successful Startup
Dec 09 15:40:00 music sudo[1602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 09 15:40:00 music sudo[1602]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:40:00 music sudo[1602]: pam_unix(sudo:session): session closed for user root
Dec 09 15:40:00 music sudo[1587]: pam_unix(sudo:session): session closed for user root
Dec 09 15:40:00 music systemd[1]: Stopping ShairportSync AirTunes receiver...
Dec 09 15:40:00 music systemd[1]: Starting ShairportSync AirTunes receiver...
Dec 09 15:40:00 music systemd[1]: Started ShairportSync AirTunes receiver.
Dec 09 15:40:00 music sudo[1589]: pam_unix(sudo:session): session closed for user root
Dec 09 15:40:00 music volumio-remote-updater[668]: [2019-12-09 15:40:00] [connect] Successful connection
Dec 09 15:40:00 music shairport-sync[1614]: Successful Startup
Dec 09 15:40:02 music volumio[1481]: info: CoreCommandRouter::volumioGetState
Dec 09 15:40:02 music volumio[1481]: info: CoreStateMachine::getState
Dec 09 15:40:02 music volumio[1481]: info: CorePlayQueue::getTrack 0
Dec 09 15:40:02 music volumio-remote-updater[668]: [2019-12-09 15:40:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1575906000 101
Dec 09 15:40:02 music sudo[1621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 09 15:40:02 music sudo[1621]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:40:02 music volumio[1481]: info: Shairport-Sync Started
Dec 09 15:40:02 music volumio[1481]: Error adding Membership: Error: addMembership EINVAL
Dec 09 15:40:02 music volumio[1481]: info: Shairport-Sync Started
Dec 09 15:40:02 music sudo[1621]: pam_unix(sudo:session): session closed for user root
Dec 09 15:40:02 music sudo[1630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Dec 09 15:40:02 music sudo[1630]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:40:02 music systemd[1]: Starting UPnP Renderer front-end to MPD...
Dec 09 15:40:02 music systemd[1]: Started UPnP Renderer front-end to MPD.
Dec 09 15:40:02 music sudo[1630]: pam_unix(sudo:session): session closed for user root
Dec 09 15:40:03 music volumio[1481]: info: Upmpdcli Daemon Started
Dec 09 15:40:04 music volumio[1481]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Dec 09 15:40:04 music volumio[1481]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Dec 09 15:40:04 music volumio[1481]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Dec 09 15:40:04 music volumio[1481]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Dec 09 15:40:04 music volumio[1481]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Dec 09 15:40:04 music volumio[1481]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Dec 09 15:40:04 music volumio[1481]: info: Adding plugin bluetooth to MyMusic Plugins
Dec 09 15:40:04 music volumio[1481]: info: Adding plugin cd_controller to MyMusic Plugins
Dec 09 15:40:04 music volumio[1481]: info: Adding plugin smart_inputs to MyMusic Plugins
Dec 09 15:40:04 music volumio[1481]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Dec 09 15:40:08 music volumio[1481]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Dec 09 15:40:10 music volumio[1481]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Dec 09 15:40:10 music volumio[1481]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Dec 09 15:40:10 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 15:40:10 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 15:40:10 music volumio[1481]: info: Starting MyVolumio Remote Streaming Endpoints
Dec 09 15:40:10 music volumio[1481]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Dec 09 15:40:10 music volumio[1481]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Dec 09 15:40:10 music volumio[1481]: info: Streaming services startup
Dec 09 15:40:10 music volumio[1481]: info: Starting Streaming Daemon
Dec 09 15:40:10 music volumio[1481]: info: MyVolumio not started
Dec 09 15:40:10 music volumio[1481]: info: Initializing device activation check
Dec 09 15:40:10 music sudo[1654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Dec 09 15:40:10 music sudo[1654]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:40:10 music volumio[1481]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Dec 09 15:40:10 music systemd[1]: Stopping Volumio Streaming Daemon...
Dec 09 15:40:10 music systemd[1]: Starting Volumio Streaming Daemon...
Dec 09 15:40:10 music systemd[1]: Started Volumio Streaming Daemon.
Dec 09 15:40:10 music sudo[1654]: pam_unix(sudo:session): session closed for user root
Dec 09 15:40:10 music volumio-streaming-daemon[1660]: ############################
Dec 09 15:40:10 music volumio-streaming-daemon[1660]: # Volumio Streaming Daemon #
Dec 09 15:40:10 music volumio-streaming-daemon[1660]: # Running on port 7777 #
Dec 09 15:40:10 music volumio-streaming-daemon[1660]: ############################
Dec 09 15:40:13 music volumio[1481]: info: Fetching Streaming Services browse cache
Dec 09 15:40:13 music volumio[1481]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL
Dec 09 15:40:13 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 09 15:40:13 music volumio[1481]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ
Dec 09 15:40:13 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 09 15:40:17 music kernel: Voltage normalised (0x00000000)
Dec 09 15:40:23 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Dec 09 15:40:23 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Dec 09 15:40:39 music kernel: Under-voltage detected! (0x00050005)
Dec 09 15:40:50 music kernel: Voltage normalised (0x00000000)
Dec 09 15:41:48 music kernel: Under-voltage detected! (0x00050005)
Dec 09 15:41:52 music kernel: Voltage normalised (0x00000000)
Dec 09 15:42:59 music sudo[2013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Dec 09 15:42:59 music sudo[2013]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:42:59 music sudo[2015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 09 15:42:59 music sudo[2013]: pam_unix(sudo:session): session closed for user root
Dec 09 15:42:59 music sudo[2015]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:42:59 music sudo[2015]: pam_unix(sudo:session): session closed for user root
Dec 09 15:42:59 music volumio[1481]: info: CoreCommandRouter::volumioGetVisibleSources
Dec 09 15:42:59 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Dec 09 15:42:59 music volumio[1481]: info: CoreCommandRouter::volumioGetState
Dec 09 15:42:59 music volumio[1481]: info: CoreStateMachine::getState
Dec 09 15:42:59 music volumio[1481]: info: CorePlayQueue::getTrack 0
Dec 09 15:43:00 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Dec 09 15:43:00 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Dec 09 15:43:00 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Dec 09 15:43:00 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Dec 09 15:43:00 music sudo[2031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 09 15:43:00 music sudo[2031]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:43:00 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Dec 09 15:43:00 music volumio[1481]: info: Listing playlists
Dec 09 15:43:00 music sudo[2031]: pam_unix(sudo:session): session closed for user root
Dec 09 15:43:00 music sudo[2036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 09 15:43:00 music sudo[2036]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:43:00 music sudo[2036]: pam_unix(sudo:session): session closed for user root
Dec 09 15:43:01 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Dec 09 15:43:02 music volumio[1481]: info: CoreCommandRouter::volumioGetQueue
Dec 09 15:43:02 music volumio[1481]: info: CoreStateMachine::getQueue
Dec 09 15:43:02 music volumio[1481]: info: CorePlayQueue::getQueue
Dec 09 15:43:13 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 09 15:43:13 music volumio[1481]: info: CURURI: artists://
Dec 09 15:43:54 music sshd[2143]: Accepted password for volumio from 192.168.3.100 port 51337 ssh2
Dec 09 15:43:54 music sshd[2143]: pam_unix(sshd:session): session opened for user volumio by (uid=0)
Dec 09 15:43:54 music systemd[1]: Starting user-1000.slice.
Dec 09 15:43:54 music systemd[1]: Created slice user-1000.slice.
Dec 09 15:43:54 music systemd[1]: Starting User Manager for UID 1000...
Dec 09 15:43:54 music systemd[1]: Starting Session c1 of user volumio.
Dec 09 15:43:54 music systemd-logind[694]: New session c1 of user volumio.
Dec 09 15:43:54 music systemd[1]: Started Session c1 of user volumio.
Dec 09 15:43:54 music systemd[2164]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0)
Dec 09 15:43:54 music systemd[2164]: Starting Paths.
Dec 09 15:43:54 music systemd[2164]: Reached target Paths.
Dec 09 15:43:54 music systemd[2164]: Starting Timers.
Dec 09 15:43:54 music systemd[2164]: Reached target Timers.
Dec 09 15:43:54 music systemd[2164]: Starting Sockets.
Dec 09 15:43:54 music systemd[2164]: Reached target Sockets.
Dec 09 15:43:54 music systemd[2164]: Starting Basic System.
Dec 09 15:43:54 music systemd[2164]: Reached target Basic System.
Dec 09 15:43:54 music systemd[2164]: Starting Default.
Dec 09 15:43:54 music systemd[2164]: Reached target Default.
Dec 09 15:43:54 music systemd[2164]: Startup finished in 58ms.
Dec 09 15:43:54 music systemd[1]: Started User Manager for UID 1000.
Dec 09 15:43:54 music sshd[2143]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory
Dec 09 15:43:54 music sshd[2143]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory
Dec 09 15:44:37 music kernel: rpi_firmware_get_throttled: 6 callbacks suppressed
Dec 09 15:44:37 music kernel: Under-voltage detected! (0x00050005)
Dec 09 15:45:05 music sudo[2327]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f
Dec 09 15:45:05 music sudo[2327]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Dec 09 15:45:06 music kernel: Under-voltage detected! (0x00050005)
Dec 09 15:45:25 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 09 15:45:25 music volumio[1481]: info: CURURI: artists://Compilations
Dec 09 15:45:26 music kernel: Under-voltage detected! (0x00050005)
Dec 09 15:45:31 music kernel: rpi_firmware_get_throttled: 8 callbacks suppressed
Dec 09 15:45:31 music kernel: Voltage normalised (0x00000000)
Dec 09 15:45:47 music volumio[1481]: info: CoreCommandRouter::volumioAddQueueItems
Dec 09 15:45:47 music volumio[1481]: info: CoreStateMachine::addQueueItems
Dec 09 15:45:47 music volumio[1481]: info: CorePlayQueue::addQueueItems
Dec 09 15:45:47 music volumio[1481]: info: Adding Item to queue: artists://Carl%20Perkins/1956
Dec 09 15:45:47 music volumio[1481]: info: Exploding uri artists://Carl%20Perkins/1956 in service mpd
Dec 09 15:45:47 music volumio[1481]: info:
Dec 09 15:45:47 music volumio[1481]: info: CorePlayQueue::saveQueue
Dec 09 15:45:47 music volumio[1481]: info: CoreCommandRouter::volumioPushQueue
Dec 09 15:45:47 music volumio[1481]: info: CoreStateMachine::updateTrackBlock
Dec 09 15:45:47 music volumio[1481]: info: CorePlayQueue::getTrackBlock
Dec 09 15:45:53 music kernel: Voltage normalised (0x00000000)
Dec 09 15:45:56 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 09 15:45:56 music volumio[1481]: info: CURURI: albums://
Dec 09 15:45:56 music volumio[1481]: listAlbums - loading Albums from cache
Dec 09 15:46:07 music volumio[1481]: info: CoreCommandRouter::volumioAddQueueItems
Dec 09 15:46:07 music volumio[1481]: info: CoreStateMachine::addQueueItems
Dec 09 15:46:07 music volumio[1481]: info: CorePlayQueue::addQueueItems
Dec 09 15:46:07 music volumio[1481]: info: Adding Item to queue: albums://Compilations/1956
Dec 09 15:46:07 music volumio[1481]: info: Exploding uri albums://Compilations/1956 in service mpd
Dec 09 15:46:07 music volumio[1481]: info: CorePlayQueue::saveQueue
Dec 09 15:46:07 music volumio[1481]: info: CoreCommandRouter::volumioPushQueue
Dec 09 15:46:07 music volumio[1481]: info: CoreStateMachine::updateTrackBlock
Dec 09 15:46:07 music volumio[1481]: info: CorePlayQueue::getTrackBlock
Dec 09 15:46:14 music kernel: Voltage normalised (0x00000000)
Dec 09 15:46:24 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 09 15:46:24 music volumio[1481]: info: CURURI: genres://
Dec 09 15:46:28 music volumio[1481]: info: CoreCommandRouter::volumioClearQueue
Dec 09 15:46:28 music volumio[1481]: info: CoreStateMachine::ClearQueue
Dec 09 15:46:28 music volumio[1481]: info: CoreStateMachine::stop
Dec 09 15:46:28 music volumio[1481]: info: CoreStateMachine::setConsumeUpdateService undefined
Dec 09 15:46:28 music volumio[1481]: UNSET VOLATILE
Dec 09 15:46:28 music volumio[1481]: info: CorePlayQueue::clearPlayQueue
Dec 09 15:46:28 music volumio[1481]: info: CorePlayQueue::saveQueue
Dec 09 15:46:28 music volumio[1481]: info: CoreStateMachine::pushEmptyState
Dec 09 15:46:28 music volumio[1481]: info: CoreCommandRouter::volumioPushState
Dec 09 15:46:28 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Dec 09 15:46:28 music volumio[1481]: info: CoreCommandRouter::volumioPushQueue
Dec 09 15:46:28 music sudo[2501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 09 15:46:28 music sudo[2501]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:46:28 music sudo[2501]: pam_unix(sudo:session): session closed for user root
Dec 09 15:46:32 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Dec 09 15:46:32 music volumio[1481]: info: CURURI: genres://Christmas
Dec 09 15:47:43 music volumio[1481]: info: CoreCommandRouter::volumioGetState
Dec 09 15:47:43 music volumio[1481]: info: CoreStateMachine::getState
Dec 09 15:47:43 music volumio[1481]: info: CorePlayQueue::getTrack 0
Dec 09 15:47:43 music volumio[1481]: info: CoreCommandRouter::volumioGetQueue
Dec 09 15:47:43 music volumio[1481]: info: CoreStateMachine::getQueue
Dec 09 15:47:43 music volumio[1481]: info: CorePlayQueue::getQueue
Dec 09 15:47:43 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid
Dec 09 15:47:43 music sudo[2667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Dec 09 15:47:43 music sudo[2667]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:47:43 music sudo[2667]: pam_unix(sudo:session): session closed for user root
Dec 09 15:49:38 music kernel: rpi_firmware_get_throttled: 8 callbacks suppressed
Dec 09 15:49:38 music kernel: Under-voltage detected! (0x00050005)
Dec 09 15:50:20 music kernel: Under-voltage detected! (0x00050005)
Dec 09 15:50:47 music kernel: Under-voltage detected! (0x00050005)
Dec 09 15:50:48 music volumio[1481]: info: CALLMETHOD: system_controller system sendBugReport [object Object]
Dec 09 15:50:48 music volumio[1481]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport
Dec 09 15:50:48 music sudo[3071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig
Dec 09 15:50:48 music sudo[3071]: pam_unix(sudo:session): session opened for user root by (uid=0)
Dec 09 15:50:48 music sudo[3071]: pam_unix(sudo:session): session closed for user root
Dec 09 15:50:48 music sudo[3089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7
Dec 09 15:50:48 music sudo[3089]: pam_unix(sudo:session): session opened for user root by (uid=0)
# cat /var/log/mpd.log ---------------
Dec 09 15:37 : zeroconf: No global port, disabling zeroconf
Dec 09 15:39 : client: [0] opened from local
Dec 09 15:39 : exception: No such directory
Dec 09 15:40 : client: [1] opened from 127.0.0.1:32964