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