Sat Jul 6 19:40:34 UTC 2019
Description="'RPi 3+ with Hifiberry AMP+ won'\''t play streams'"
# cat /proc/version ---------------
Linux version 4.14.92-v7+ (dom@dom-XPS-13-9370) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1187 SMP Wed Jan 9 20:01:54 GMT 2019
# cat /etc/os-release ---------------
PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)"
NAME="Raspbian GNU/Linux"
VERSION_ID="8"
VERSION="8 (jessie)"
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="074a8b52bd03468c65e5a209cdebba5007f34703"
VOLUMIO_FE_VERSION="5db94767d659330af647ae45872bf36d33ac66f0"
VOLUMIO_BE_VERSION="3471ca4b1cd31c92377257fc1329bec5decac71b"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue Jun 11 23:06:27 CEST 2019"
VOLUMIO_VERSION="2.587"
VOLUMIO_HARDWARE="pi"
VOLUMIO_HASH="35bc50d6dcafb6f68a8cd72776953365"
# ifconfig ---------------
eth0 Link encap:Ethernet HWaddr b8:27:eb:c3:86:de
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:2018 errors:0 dropped:0 overruns:0 frame:0
TX packets:2018 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:271803 (265.4 KiB) TX bytes:271803 (265.4 KiB)
wlan0 Link encap:Ethernet HWaddr b8:27:eb:96:d3:8b
inet addr:192.168.0.115 Bcast:192.168.0.255 Mask:255.255.255.0
UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
RX packets:202026 errors:0 dropped:0 overruns:0 frame:0
TX packets:190931 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:96178278 (91.7 MiB) TX bytes:40796805 (38.9 MiB)
# sudo iwconfig ---------------
eth0 no wireless extensions.
lo no wireless extensions.
wlan0 IEEE 802.11 ESSID:"Left_Field"
Mode:Managed Frequency:2.417 GHz Access Point: EC:08:6B:A8:D0:DC
Bit Rate=72.2 Mb/s Tx-Power=31 dBm
Retry short limit:7 RTS thr:off Fragment thr:off
Encryption key:off
Power Management:off
Link Quality=54/70 Signal level=-56 dBm
Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0
Tx excessive retries:100 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 ALSA [bcm2835 IEC958/HDMI]
Subdevices: 1/1
Subdevice #0: subdevice #0
card 1: sndrpihifiberry [snd_rpi_hifiberry_dacplus], device 0: HiFiBerry DAC+ HiFi pcm512x-hifi-0 []
Subdevices: 1/1
Subdevice #0: subdevice #0
# ps -ef ---------------
UID PID PPID C STIME TTY TIME CMD
root 1 0 0 19:18 ? 00:00:04 /sbin/init
root 2 0 0 19:18 ? 00:00:00 [kthreadd]
root 3 2 0 19:18 ? 00:00:00 [kworker/0:0]
root 4 2 0 19:18 ? 00:00:00 [kworker/0:0H]
root 5 2 0 19:18 ? 00:00:03 [kworker/u8:0]
root 6 2 0 19:18 ? 00:00:00 [mm_percpu_wq]
root 7 2 0 19:18 ? 00:00:00 [ksoftirqd/0]
root 8 2 0 19:18 ? 00:00:00 [rcu_sched]
root 9 2 0 19:18 ? 00:00:00 [rcu_bh]
root 10 2 0 19:18 ? 00:00:00 [migration/0]
root 11 2 0 19:18 ? 00:00:00 [cpuhp/0]
root 12 2 0 19:18 ? 00:00:00 [cpuhp/1]
root 13 2 0 19:18 ? 00:00:00 [migration/1]
root 14 2 0 19:18 ? 00:00:00 [ksoftirqd/1]
root 16 2 0 19:18 ? 00:00:00 [kworker/1:0H]
root 17 2 0 19:18 ? 00:00:00 [cpuhp/2]
root 18 2 0 19:18 ? 00:00:00 [migration/2]
root 19 2 0 19:18 ? 00:00:00 [ksoftirqd/2]
root 21 2 0 19:18 ? 00:00:00 [kworker/2:0H]
root 22 2 0 19:18 ? 00:00:00 [cpuhp/3]
root 23 2 0 19:18 ? 00:00:00 [migration/3]
root 24 2 0 19:18 ? 00:00:00 [ksoftirqd/3]
root 26 2 0 19:18 ? 00:00:00 [kworker/3:0H]
root 27 2 0 19:18 ? 00:00:00 [kdevtmpfs]
root 28 2 0 19:18 ? 00:00:00 [netns]
root 31 2 0 19:18 ? 00:00:00 [kworker/2:1]
root 32 2 0 19:18 ? 00:00:00 [kworker/3:1]
root 33 2 0 19:18 ? 00:00:00 [khungtaskd]
root 34 2 0 19:18 ? 00:00:00 [oom_reaper]
root 35 2 0 19:18 ? 00:00:00 [writeback]
root 36 2 0 19:18 ? 00:00:00 [kcompactd0]
root 37 2 0 19:18 ? 00:00:00 [crypto]
root 38 2 0 19:18 ? 00:00:00 [kblockd]
root 39 2 0 19:18 ? 00:00:00 [watchdogd]
root 40 2 0 19:18 ? 00:00:00 [rpciod]
root 41 2 0 19:18 ? 00:00:00 [xprtiod]
root 42 2 0 19:18 ? 00:00:07 [kworker/u8:1]
root 44 2 0 19:18 ? 00:00:00 [kswapd0]
root 45 2 0 19:18 ? 00:00:00 [nfsiod]
root 55 2 0 19:18 ? 00:00:00 [kthrotld]
root 56 2 0 19:18 ? 00:00:00 [iscsi_eh]
root 57 2 0 19:18 ? 00:00:00 [dwc_otg]
root 58 2 0 19:18 ? 00:00:00 [DWC Notificatio]
root 59 2 0 19:18 ? 00:00:00 [vchiq-slot/0]
root 60 2 0 19:18 ? 00:00:00 [vchiq-recy/0]
root 61 2 0 19:18 ? 00:00:00 [vchiq-sync/0]
root 62 2 0 19:18 ? 00:00:00 [vchiq-keep/0]
root 63 2 0 19:18 ? 00:00:00 [SMIO]
root 64 2 0 19:18 ? 00:00:08 [irq/92-mmc1]
root 87 2 0 19:18 ? 00:00:00 [mmcqd/0]
root 99 2 0 19:18 ? 00:00:00 [jbd2/mmcblk0p2-]
root 100 2 0 19:18 ? 00:00:00 [ext4-rsv-conver]
root 103 2 0 19:18 ? 00:00:00 [irq/169-usb-001]
root 109 2 0 19:18 ? 00:00:00 [loop0]
root 112 2 0 19:18 ? 00:00:00 [kworker/2:1H]
root 116 2 0 19:18 ? 00:00:00 [kworker/3:1H]
root 130 2 0 19:18 ? 00:00:00 [jbd2/mmcblk0p3-]
root 131 2 0 19:18 ? 00:00:00 [ext4-rsv-conver]
root 138 2 0 19:18 ? 00:00:00 [kworker/0:1H]
root 139 2 0 19:18 ? 00:00:00 [ipv6_addrconf]
root 151 2 0 19:18 ? 00:00:00 [kworker/1:1H]
root 168 1 0 19:18 ? 00:00:01 /lib/systemd/systemd-journald
root 173 2 0 19:18 ? 00:00:00 [kworker/2:2]
root 177 1 0 19:18 ? 00:00:00 /lib/systemd/systemd-udevd
root 274 2 0 19:18 ? 00:00:00 [cfg80211]
root 287 2 0 19:18 ? 00:00:00 [brcmf_wq/mmc1:0]
root 288 2 0 19:18 ? 00:00:00 [brcmf_wdog/mmc1]
root 294 2 0 19:18 ? 00:00:07 [kworker/u8:2]
root 406 2 0 19:18 ? 00:00:00 [kworker/3:3]
root 500 1 0 19:18 ? 00:00:00 /sbin/rpcbind -w
statd 510 1 0 19:18 ? 00:00:00 /sbin/rpc.statd
root 522 1 0 19:18 ? 00:00:00 /usr/sbin/rpc.idmapd
root 524 1 0 19:18 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 --write=1024
volumio 528 1 0 19:18 ? 00:00:00 /usr/bin/udisks-glue --foreground
volumio 530 1 0 19:18 ? 00:00:00 /usr/local/bin/volumio-remote-updater
root 535 1 0 19:18 ? 00:00:00 /lib/systemd/systemd-logind
avahi 550 1 0 19:18 ? 00:00:00 avahi-daemon: running [outside.local]
message+ 559 1 0 19:18 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation
root 606 1 0 19:18 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I
ntp 623 1 0 19:18 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 100:104
avahi 624 550 0 19:18 ? 00:00:00 avahi-daemon: chroot helper
root 631 1 0 19:18 ? 00:00:00 /usr/lib/udisks/udisks-daemon --no-debug
root 643 631 0 19:18 ? 00:00:00 udisks-daemon: not polling any devices
root 653 1 0 19:18 tty1 00:00:00 /sbin/agetty --noclear tty1 linux
root 671 1 0 19:18 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug
root 742 1 0 19:18 ? 00:00:00 /usr/sbin/nmbd -D
root 743 742 0 19:18 ? 00:00:00 /usr/sbin/nmbd -D
root 756 1 0 19:18 ? 00:00:00 /usr/sbin/winbindd
root 758 756 0 19:18 ? 00:00:00 /usr/sbin/winbindd
root 805 1 0 19:18 ? 00:00:00 /usr/sbin/smbd -D
root 808 756 0 19:18 ? 00:00:00 /usr/sbin/winbindd
root 809 1 0 19:18 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0
root 810 805 0 19:18 ? 00:00:00 /usr/sbin/smbd -D
root 871 1 0 19:18 ? 00:00:00 /sbin/dhcpcd
volumio 894 1 1 19:18 ? 00:00:16 /usr/local/bin/node /volumio/index.js
root 934 2 0 19:18 ? 00:00:00 [cifsiod]
root 935 2 0 19:18 ? 00:00:00 [cifsoplockd]
root 938 2 0 19:18 ? 00:00:03 [cifsd]
volumio 976 894 0 19:18 ? 00:00:00 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 985 976 0 19:18 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 986 976 0 19:18 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 991 976 0 19:18 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 1016 1 0 19:18 ? 00:00:00 /bin/streaming-daemon
volumio 1069 1 0 19:18 ? 00:00:00 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf
root 1586 2 0 19:23 ? 00:00:00 [kworker/1:0]
root 1661 2 0 19:33 ? 00:00:00 [kworker/1:2]
root 1665 2 0 19:33 ? 00:00:00 [kworker/0:2]
volumio 1735 894 0 19:37 ? 00:00:00 /bin/sh -c /usr/bin/sudo /bin/systemctl restart mpd.service
root 1736 1735 0 19:37 ? 00:00:00 /usr/bin/sudo /bin/systemctl restart mpd.service
root 1750 1736 0 19:37 ? 00:00:00 /bin/systemctl restart mpd.service
volumio 1805 894 0 19:37 ? 00:00:00 /bin/sh -c /usr/bin/sudo /bin/systemctl restart mpd.service
root 1806 1805 0 19:37 ? 00:00:00 /usr/bin/sudo /bin/systemctl restart mpd.service
root 1820 1806 0 19:37 ? 00:00:00 /bin/systemctl restart mpd.service
volumio 1873 1 0 19:37 ? 00:00:00 /usr/local/bin/shairport-sync
root 1879 2 0 19:37 ? 00:00:00 [kworker/2:0]
volumio 1885 894 0 19:37 ? 00:00:00 /bin/sh -c /usr/bin/sudo /bin/systemctl restart mpd.service
root 1886 1885 0 19:37 ? 00:00:00 /usr/bin/sudo /bin/systemctl restart mpd.service
root 1890 1886 0 19:37 ? 00:00:00 /bin/systemctl restart mpd.service
root 1902 2 0 19:37 ? 00:00:00 [kworker/3:0]
mpd 1938 1 1 19:38 ? 00:00:01 /usr/bin/mpd --no-daemon
root 1942 2 0 19:38 ? 00:00:00 [kworker/0:1]
root 1970 2 0 19:40 ? 00:00:00 [kworker/1:1]
volumio 1971 894 0 19:40 ? 00:00:00 /bin/sh -c /usr/local/bin/node /volumio/logsubmit.js 'RPi 3+ with Hifiberry AMP+ won'\''t play streams'
volumio 1972 1971 0 19:40 ? 00:00:00 /usr/local/bin/node /volumio/logsubmit.js RPi 3+ with Hifiberry AMP+ won't play streams
volumio 1997 1972 0 19:40 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1
volumio 1998 1997 0 19:40 ? 00:00:00 ps -ef
# df -h ---------------
Filesystem Size Used Avail Use% Mounted on
/dev/mmcblk0p2 2.2G 661M 1.4G 32% /imgpart
/dev/loop0 292M 292M 0 100% /static
overlay 13G 38M 12G 1% /
devtmpfs 474M 0 474M 0% /dev
tmpfs 486M 0 486M 0% /dev/shm
tmpfs 486M 4.6M 481M 1% /run
tmpfs 5.0M 4.0K 5.0M 1% /run/lock
tmpfs 486M 0 486M 0% /sys/fs/cgroup
tmpfs 486M 36K 486M 1% /tmp
tmpfs 486M 0 486M 0% /var/spool/cups
tmpfs 20M 92K 20M 1% /var/log
tmpfs 486M 0 486M 0% /var/spool/cups/tmp
/dev/mmcblk0p1 61M 37M 24M 61% /boot
//192.168.0.122/USB 13G 1.1G 12G 9% /mnt/NAS/MediaNAS
# mount ---------------
/dev/mmcblk0p2 on /imgpart type ext4 (rw,relatime,stripe=1024,data=ordered)
/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=485020k,nr_inodes=121255,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/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)
systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=22,pgrp=1,timeout=300,minproto=5,maxproto=5,direct)
debugfs on /sys/kernel/debug type debugfs (rw,relatime)
mqueue on /dev/mqueue type mqueue (rw,relatime)
tmpfs on /tmp type tmpfs (rw,noatime,mode=755)
tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755)
tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4)
fusectl on /sys/fs/fuse/connections type fusectl (rw,relatime)
configfs on /sys/kernel/config type configfs (rw,relatime)
tmpfs on /var/spool/cups/tmp type tmpfs (rw,noatime,mode=755)
/dev/mmcblk0p1 on /boot type vfat (rw,nosuid,nodev,noexec,relatime,fmask=0111,dmask=0000,allow_utime=0022,codepage=437,iocharset=ascii,shortname=mixed,utf8,errors=remount-ro)
rpc_pipefs on /run/rpc_pipefs type rpc_pipefs (rw,relatime)
//192.168.0.122/USB on /mnt/NAS/MediaNAS type cifs (ro,relatime,vers=default,sec=none,cache=strict,domain=,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.0.122,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=1048576,wsize=1048576,echo_interval=60,actimeo=1)
# sudo journalctl -p 7 ---------------
-- Logs begin at Tue 2019-06-11 21:06:27 UTC, end at Sat 2019-07-06 19:40:35 UTC. --
Jun 11 21:06:27 outside systemd-journal[168]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 72.8M free of 481.2M available → current limit 30.0M).
Jun 11 21:06:27 outside kernel: Booting Linux on physical CPU 0x0
Jun 11 21:06:27 outside kernel: Linux version 4.14.92-v7+ (dom@dom-XPS-13-9370) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1187 SMP Wed Jan 9 20:01:54 GMT 2019
Jun 11 21:06:27 outside kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
Jun 11 21:06:27 outside kernel: CPU: div instructions available: patching division code
Jun 11 21:06:27 outside kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Jun 11 21:06:27 outside kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3
Jun 11 21:06:27 outside kernel: Memory policy: Data cache writealloc
Jun 11 21:06:27 outside kernel: cma: Reserved 8 MiB at 0x3d800000
Jun 11 21:06:27 outside kernel: On node 0 totalpages: 253952
Jun 11 21:06:27 outside kernel: free_area_init_node: node 0, pgdat 80c85400, node_mem_map bcf3c000
Jun 11 21:06:27 outside kernel: Normal zone: 2232 pages used for memmap
Jun 11 21:06:27 outside kernel: Normal zone: 0 pages reserved
Jun 11 21:06:27 outside kernel: Normal zone: 253952 pages, LIFO batch:31
Jun 11 21:06:27 outside kernel: percpu: Embedded 17 pages/cpu @bcee4000 s38720 r8192 d22720 u69632
Jun 11 21:06:27 outside kernel: pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17*4096
Jun 11 21:06:27 outside kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
Jun 11 21:06:27 outside kernel: Built 1 zonelists, mobility grouping on. Total pages: 251720
Jun 11 21:06:27 outside kernel: Kernel command line: 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
Jun 11 21:06:27 outside kernel: PID hash table entries: 4096 (order: 2, 16384 bytes)
Jun 11 21:06:27 outside kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Jun 11 21:06:27 outside kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Jun 11 21:06:27 outside kernel: Memory: 970040K/1015808K available (7168K kernel code, 576K rwdata, 2080K rodata, 1024K init, 698K bss, 37576K reserved, 8192K cma-reserved)
Jun 11 21:06:27 outside 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 : 0x80008000 - 0x80800000 (8160 kB)
.init : 0x80b00000 - 0x80c00000 (1024 kB)
.data : 0x80c00000 - 0x80c90334 ( 577 kB)
.bss : 0x80c97ef8 - 0x80d468f0 ( 699 kB)
Jun 11 21:06:27 outside kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Jun 11 21:06:27 outside kernel: ftrace: allocating 25296 entries in 75 pages
Jun 11 21:06:27 outside kernel: Hierarchical RCU implementation.
Jun 11 21:06:27 outside kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Jun 11 21:06:27 outside kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys).
Jun 11 21:06:27 outside kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Jun 11 21:06:27 outside kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Jun 11 21:06:27 outside kernel: Switching to timer-based delay loop, resolution 52ns
Jun 11 21:06:27 outside kernel: Console: colour dummy device 80x30
Jun 11 21:06:27 outside kernel: console [tty1] enabled
Jun 11 21:06:27 outside kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
Jun 11 21:06:27 outside kernel: pid_max: default: 32768 minimum: 301
Jun 11 21:06:27 outside kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes)
Jun 11 21:06:27 outside kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes)
Jun 11 21:06:27 outside kernel: Disabling memory control group subsystem
Jun 11 21:06:27 outside kernel: CPU: Testing write buffer coherency: ok
Jun 11 21:06:27 outside kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
Jun 11 21:06:27 outside kernel: Setting up static identity map for 0x100000 - 0x10003c
Jun 11 21:06:27 outside kernel: Hierarchical SRCU implementation.
Jun 11 21:06:27 outside kernel: smp: Bringing up secondary CPUs ...
Jun 11 21:06:27 outside kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
Jun 11 21:06:27 outside kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
Jun 11 21:06:27 outside kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
Jun 11 21:06:27 outside kernel: smp: Brought up 1 node, 4 CPUs
Jun 11 21:06:27 outside kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS).
Jun 11 21:06:27 outside kernel: CPU: All CPU(s) started in HYP mode.
Jun 11 21:06:27 outside kernel: CPU: Virtualization extensions available.
Jun 11 21:06:27 outside kernel: devtmpfs: initialized
Jun 11 21:06:27 outside kernel: random: get_random_u32 called from bucket_table_alloc+0xfc/0x24c with crng_init=0
Jun 11 21:06:27 outside kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
Jun 11 21:06:27 outside kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Jun 11 21:06:27 outside kernel: futex hash table entries: 1024 (order: 4, 65536 bytes)
Jun 11 21:06:27 outside kernel: pinctrl core: initialized pinctrl subsystem
Jun 11 21:06:27 outside kernel: NET: Registered protocol family 16
Jun 11 21:06:27 outside kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations
Jun 11 21:06:27 outside kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
Jun 11 21:06:27 outside kernel: hw-breakpoint: maximum watchpoint size is 8 bytes.
Jun 11 21:06:27 outside kernel: Serial: AMBA PL011 UART driver
Jun 11 21:06:27 outside kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled
Jun 11 21:06:27 outside kernel: uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe
Jun 11 21:06:27 outside kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager at be813000, dmachans=0x1
Jun 11 21:06:27 outside kernel: SCSI subsystem initialized
Jun 11 21:06:27 outside kernel: usbcore: registered new interface driver usbfs
Jun 11 21:06:27 outside kernel: usbcore: registered new interface driver hub
Jun 11 21:06:27 outside kernel: usbcore: registered new device driver usb
Jun 11 21:06:27 outside kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2019-01-09 20:04
Jun 11 21:06:27 outside kernel: clocksource: Switched to clocksource arch_sys_counter
Jun 11 21:06:27 outside kernel: VFS: Disk quotas dquot_6.6.0
Jun 11 21:06:27 outside kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Jun 11 21:06:27 outside kernel: FS-Cache: Loaded
Jun 11 21:06:27 outside kernel: CacheFiles: Loaded
Jun 11 21:06:27 outside kernel: NET: Registered protocol family 2
Jun 11 21:06:27 outside kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes)
Jun 11 21:06:27 outside kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
Jun 11 21:06:27 outside kernel: TCP: Hash tables configured (established 8192 bind 8192)
Jun 11 21:06:27 outside kernel: UDP hash table entries: 512 (order: 2, 16384 bytes)
Jun 11 21:06:27 outside kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
Jun 11 21:06:27 outside kernel: NET: Registered protocol family 1
Jun 11 21:06:27 outside kernel: RPC: Registered named UNIX socket transport module.
Jun 11 21:06:27 outside kernel: RPC: Registered udp transport module.
Jun 11 21:06:27 outside kernel: RPC: Registered tcp transport module.
Jun 11 21:06:27 outside kernel: RPC: Registered tcp NFSv4.1 backchannel transport module.
Jun 11 21:06:27 outside kernel: Trying to unpack rootfs image as initramfs...
Jun 11 21:06:27 outside kernel: Freeing initrd memory: 14848K
Jun 11 21:06:27 outside kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
Jun 11 21:06:27 outside kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4
Jun 11 21:06:27 outside kernel: FS-Cache: Netfs 'nfs' registered for caching
Jun 11 21:06:27 outside kernel: NFS: Registering the id_resolver key type
Jun 11 21:06:27 outside kernel: Key type id_resolver registered
Jun 11 21:06:27 outside kernel: Key type id_legacy registered
Jun 11 21:06:27 outside kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Jun 11 21:06:27 outside kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251)
Jun 11 21:06:27 outside kernel: io scheduler noop registered (default)
Jun 11 21:06:27 outside kernel: io scheduler deadline registered
Jun 11 21:06:27 outside kernel: io scheduler cfq registered
Jun 11 21:06:27 outside kernel: io scheduler mq-deadline registered
Jun 11 21:06:27 outside kernel: io scheduler kyber registered
Jun 11 21:06:27 outside kernel: BCM2708FB: allocated DMA memory fd900000
Jun 11 21:06:27 outside kernel: BCM2708FB: allocated DMA channel 0 @ be813000
Jun 11 21:06:27 outside kernel: Console: switching to colour frame buffer device 80x30
Jun 11 21:06:27 outside kernel: bcm2835-rng 3f104000.rng: hwrng registered
Jun 11 21:06:27 outside kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
Jun 11 21:06:27 outside kernel: vc-sm: Videocore shared memory driver
Jun 11 21:06:27 outside kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
Jun 11 21:06:27 outside kernel: brd: module loaded
Jun 11 21:06:27 outside kernel: loop: module loaded
Jun 11 21:06:27 outside kernel: Loading iSCSI transport class v2.0-870.
Jun 11 21:06:27 outside kernel: libphy: Fixed MDIO Bus: probed
Jun 11 21:06:27 outside kernel: usbcore: registered new interface driver lan78xx
Jun 11 21:06:27 outside kernel: usbcore: registered new interface driver smsc95xx
Jun 11 21:06:27 outside kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Jun 11 21:06:27 outside kernel: dwc_otg 3f980000.usb: base=0xf0980000
Jun 11 21:06:27 outside kernel: Core Release: 2.80a
Jun 11 21:06:27 outside kernel: Setting default values for core params
Jun 11 21:06:27 outside kernel: Finished setting default values for core params
Jun 11 21:06:27 outside kernel: Using Buffer DMA mode
Jun 11 21:06:27 outside kernel: Periodic Transfer Interrupt Enhancement - disabled
Jun 11 21:06:27 outside kernel: Multiprocessor Interrupt Enhancement - disabled
Jun 11 21:06:27 outside kernel: OTG VER PARAM: 0, OTG VER FLAG: 0
Jun 11 21:06:27 outside kernel: Dedicated Tx FIFOs mode
Jun 11 21:06:27 outside kernel: WARN::dwc_otg_hcd_init:1046: FIQ DMA bounce buffers: virt = 0xbd914000 dma = 0xfd914000 len=9024
Jun 11 21:06:27 outside kernel: FIQ FSM acceleration enabled for :
Non-periodic Split Transactions
Periodic Split Transactions
High-Speed Isochronous Endpoints
Interrupt/Control Split Transaction hack enabled
Jun 11 21:06:27 outside kernel: dwc_otg: Microframe scheduler enabled
Jun 11 21:06:27 outside kernel: WARN::hcd_init_fiq:459: FIQ on core 1 at 0x805ed07c
Jun 11 21:06:27 outside kernel: WARN::hcd_init_fiq:460: FIQ ASM at 0x805ed3e4 length 36
Jun 11 21:06:27 outside kernel: WARN::hcd_init_fiq:486: MPHI regs_base at 0xf0006000
Jun 11 21:06:27 outside kernel: dwc_otg 3f980000.usb: DWC OTG Controller
Jun 11 21:06:27 outside kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Jun 11 21:06:27 outside kernel: dwc_otg 3f980000.usb: irq 62, io mem 0x00000000
Jun 11 21:06:27 outside kernel: Init: Port Power? op_state=1
Jun 11 21:06:27 outside kernel: Init: Power Port (0)
Jun 11 21:06:27 outside kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Jun 11 21:06:27 outside kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Jun 11 21:06:27 outside kernel: usb usb1: Product: DWC OTG Controller
Jun 11 21:06:27 outside kernel: usb usb1: Manufacturer: Linux 4.14.92-v7+ dwc_otg_hcd
Jun 11 21:06:27 outside kernel: usb usb1: SerialNumber: 3f980000.usb
Jun 11 21:06:27 outside kernel: hub 1-0:1.0: USB hub found
Jun 11 21:06:27 outside kernel: hub 1-0:1.0: 1 port detected
Jun 11 21:06:27 outside kernel: dwc_otg: FIQ enabled
Jun 11 21:06:27 outside kernel: dwc_otg: NAK holdoff enabled
Jun 11 21:06:27 outside kernel: dwc_otg: FIQ split-transaction FSM enabled
Jun 11 21:06:27 outside kernel: Module dwc_common_port init
Jun 11 21:06:27 outside kernel: usbcore: registered new interface driver usb-storage
Jun 11 21:06:27 outside kernel: mousedev: PS/2 mouse device common for all mice
Jun 11 21:06:27 outside kernel: IR NEC protocol handler initialized
Jun 11 21:06:27 outside kernel: IR RC5(x/sz) protocol handler initialized
Jun 11 21:06:27 outside kernel: IR RC6 protocol handler initialized
Jun 11 21:06:27 outside kernel: IR JVC protocol handler initialized
Jun 11 21:06:27 outside kernel: IR Sony protocol handler initialized
Jun 11 21:06:27 outside kernel: IR SANYO protocol handler initialized
Jun 11 21:06:27 outside kernel: IR Sharp protocol handler initialized
Jun 11 21:06:27 outside kernel: IR MCE Keyboard/mouse protocol handler initialized
Jun 11 21:06:27 outside kernel: IR XMP protocol handler initialized
Jun 11 21:06:27 outside kernel: bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer
Jun 11 21:06:27 outside kernel: bcm2835-cpufreq: min=600000 max=1400000
Jun 11 21:06:27 outside kernel: sdhci: Secure Digital Host Controller Interface driver
Jun 11 21:06:27 outside kernel: sdhci: Copyright(c) Pierre Ossman
Jun 11 21:06:27 outside kernel: mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe
Jun 11 21:06:27 outside kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
Jun 11 21:06:27 outside kernel: sdhci-pltfm: SDHCI platform and OF driver helper
Jun 11 21:06:27 outside kernel: ledtrig-cpu: registered to indicate activity on CPUs
Jun 11 21:06:27 outside kernel: hidraw: raw HID events driver (C) Jiri Kosina
Jun 11 21:06:27 outside kernel: usbcore: registered new interface driver usbhid
Jun 11 21:06:27 outside kernel: usbhid: USB HID core driver
Jun 11 21:06:27 outside kernel: vchiq: vchiq_init_state: slot_zero = bd980000, is_master = 0
Jun 11 21:06:27 outside kernel: [vc_sm_connected_init]: start
Jun 11 21:06:27 outside kernel: [vc_sm_connected_init]: end - returning 0
Jun 11 21:06:27 outside kernel: Initializing XFRM netlink socket
Jun 11 21:06:27 outside kernel: NET: Registered protocol family 17
Jun 11 21:06:27 outside kernel: Key type dns_resolver registered
Jun 11 21:06:27 outside kernel: Registering SWP/SWPB emulation handler
Jun 11 21:06:27 outside kernel: registered taskstats version 1
Jun 11 21:06:27 outside kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled
Jun 11 21:06:27 outside kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2
Jun 11 21:06:27 outside kernel: mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0
Jun 11 21:06:27 outside kernel: mmc-bcm2835 3f300000.mmc: DMA channel allocated
Jun 11 21:06:27 outside kernel: sdhost: log_buf @ bd913000 (fd913000)
Jun 11 21:06:27 outside kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
Jun 11 21:06:27 outside kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Jun 11 21:06:27 outside kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Jun 11 21:06:27 outside kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
Jun 11 21:06:27 outside kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Jun 11 21:06:27 outside kernel: of_cfs_init
Jun 11 21:06:27 outside kernel: of_cfs_init: OK
Jun 11 21:06:27 outside kernel: Freeing unused kernel memory: 1024K
Jun 11 21:06:27 outside kernel: random: fast init done
Jun 11 21:06:27 outside kernel: Indeed it is in host mode hprt0 = 00021501
Jun 11 21:06:27 outside kernel: mmc1: new high speed SDIO card at address 0001
Jun 11 21:06:27 outside kernel: mmc0: host does not support reading read-only switch, assuming write-enable
Jun 11 21:06:27 outside kernel: mmc0: new high speed SDHC card at address aaaa
Jun 11 21:06:27 outside kernel: mmcblk0: mmc0:aaaa SS16G 14.8 GiB
Jun 11 21:06:27 outside kernel: mmcblk0: p1 p2 p3
Jun 11 21:06:27 outside initramfs: Booting Volumio for BCM2835
Jun 11 21:06:27 outside initramfs: This script mounts rootfs RO with an overlay RW layer.
Jun 11 21:06:27 outside kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Jun 11 21:06:27 outside initramfs: IMGPART=/dev/mmcblk0p2
Jun 11 21:06:27 outside initramfs: IMGFILE=/volumio_current.sqsh
Jun 11 21:06:27 outside initramfs: Boot delay (except first time) will be 5 seconds
Jun 11 21:06:27 outside initramfs: /dev/mmcblk0p2 not detected,retrying mdev in 5 seconds
Jun 11 21:06:27 outside kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg
Jun 11 21:06:27 outside kernel: Indeed it is in host mode hprt0 = 00001101
Jun 11 21:06:27 outside kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=2514
Jun 11 21:06:27 outside kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jun 11 21:06:27 outside kernel: hub 1-1:1.0: USB hub found
Jun 11 21:06:27 outside kernel: hub 1-1:1.0: 4 ports detected
Jun 11 21:06:27 outside kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Jun 11 21:06:27 outside initramfs: /dev/mmcblk0p2:
Jun 11 21:06:27 outside kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514
Jun 11 21:06:27 outside kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jun 11 21:06:27 outside kernel: hub 1-1.1:1.0: USB hub found
Jun 11 21:06:27 outside kernel: hub 1-1.1:1.0: 3 ports detected
Jun 11 21:06:27 outside kernel: EXT4-fs (mmcblk0p2): recovery complete
Jun 11 21:06:27 outside kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Jun 11 21:06:27 outside initramfs: Doing a 5 second delay here to give kernel load a headstart
Jun 11 21:06:27 outside kernel: dwc_otg_handle_wakeup_detected_intr lxstate = 2
Jun 11 21:06:27 outside kernel: usb 1-1.1.1: new high-speed USB device number 4 using dwc_otg
Jun 11 21:06:27 outside kernel: usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800
Jun 11 21:06:27 outside kernel: usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Jun 11 21:06:27 outside kernel: libphy: lan78xx-mdiobus: probed
Jun 11 21:06:27 outside kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64
Jun 11 21:06:27 outside initramfs: Checking for USB updates
Jun 11 21:06:27 outside initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline)
Jun 11 21:06:27 outside kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Jun 11 21:06:27 outside kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Jun 11 21:06:27 outside kernel: EXT4-fs (mmcblk0p3): recovery complete
Jun 11 21:06:27 outside kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null)
Jun 11 21:06:27 outside initramfs: With Option:
Jun 11 21:06:27 outside initramfs: VOLUMIO_VERSION="2.587"
Jun 11 21:06:27 outside initramfs: Finish initramfs, continue booting Volumio
Jun 11 21:06:27 outside kernel: random: systemd: uninitialized urandom read (16 bytes read)
Jun 11 21:06:27 outside systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR)
Jun 11 21:06:27 outside systemd[1]: Detected architecture 'arm'.
Jun 11 21:06:27 outside kernel: NET: Registered protocol family 10
Jun 11 21:06:27 outside kernel: Segment Routing with IPv6
Jun 11 21:06:27 outside systemd[1]: Inserted module 'ipv6'
Jun 11 21:06:27 outside systemd[1]: Set hostname to .
Jun 11 21:06:27 outside kernel: random: systemd-sysv-ge: uninitialized urandom read (16 bytes read)
Jun 11 21:06:27 outside kernel: random: systemd: uninitialized urandom read (16 bytes read)
Jun 11 21:06:27 outside systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service'
Jun 11 21:06:27 outside systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service'
Jun 11 21:06:27 outside systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service'
Jun 11 21:06:27 outside systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service'
Jun 11 21:06:27 outside systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory.
Jun 11 21:06:27 outside systemd[1]: Starting Forward Password Requests to Wall Directory Watch.
Jun 11 21:06:27 outside systemd-udevd[177]: starting version 215
Jun 11 21:06:27 outside kernel: fuse init (API version 7.26)
Jun 11 21:06:27 outside systemd-journal[168]: Journal started
Jun 11 21:06:27 outside systemd[1]: Starting Slices.
Jun 11 21:06:27 outside systemd[1]: Reached target Slices.
Jun 11 21:06:27 outside systemd[1]: Starting Remount Root and Kernel File Systems...
Jun 11 21:06:27 outside systemd[1]: Started Restore / save the current clock.
Jun 11 21:06:27 outside systemd[1]: Started Create list of required static device nodes for the current kernel.
Jun 11 21:06:27 outside systemd[1]: Time has been changed
Jun 11 21:06:27 outside systemd[1]: Starting Create Static Device Nodes in /dev...
Jun 11 21:06:27 outside systemd[1]: Started Create Static Device Nodes in /dev.
Jun 11 21:06:27 outside systemd[1]: Starting udev Kernel Device Manager...
Jun 11 21:06:27 outside systemd[1]: Mounted Debug File System.
Jun 11 21:06:27 outside systemd[1]: Mounted POSIX Message Queue File System.
Jun 11 21:06:27 outside systemd-modules-load[165]: Inserted module 'fuse'
Jun 11 21:06:27 outside systemd[1]: Starting Load/Save Random Seed...
Jun 11 21:06:27 outside systemd[1]: Starting Local File Systems (Pre).
Jun 11 21:06:27 outside systemd[1]: Reached target Local File Systems (Pre).
Jun 11 21:06:27 outside systemd[1]: Mounting /tmp...
Jun 11 21:06:27 outside systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway.
Jun 11 21:06:27 outside systemd[1]: Mounting /var/spool/cups...
Jun 11 21:06:27 outside systemd-modules-load[165]: Inserted module 'i2c_dev'
Jun 11 21:06:27 outside systemd[1]: Mounting /var/log...
Jun 11 21:06:27 outside systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway.
Jun 11 21:06:27 outside systemd[1]: Starting Show Plymouth Boot Screen...
Jun 11 21:06:27 outside systemd[1]: Starting Copy rules generated while the root was ro...
Jun 11 21:06:27 outside kernel: i2c /dev entries driver
Jun 11 21:06:27 outside fake-hwclock[155]: Tue Jun 11 21:06:27 UTC 2019
Jun 11 21:06:27 outside systemd[1]: Mounted /var/log.
Jun 11 21:06:27 outside systemd[1]: Mounted /var/spool/cups.
Jun 11 21:06:27 outside systemd[1]: Mounted /tmp.
Jun 11 21:06:27 outside systemd[1]: Started Load Kernel Modules.
Jun 11 21:06:27 outside systemd[1]: Started Load/Save Random Seed.
Jun 11 21:06:27 outside systemd[1]: Started Copy rules generated while the root was ro.
Jun 11 21:06:27 outside systemd[1]: Started Show Plymouth Boot Screen.
Jun 11 21:06:27 outside systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch.
Jun 11 21:06:27 outside systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
Jun 11 21:06:27 outside systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
Jun 11 21:06:27 outside systemd[1]: Starting Paths.
Jun 11 21:06:27 outside systemd[1]: Reached target Paths.
Jun 11 21:06:27 outside systemd[1]: Mounting FUSE Control File System...
Jun 11 21:06:27 outside systemd[1]: Mounting Configuration File System...
Jun 11 21:06:27 outside systemd[1]: Starting Apply Kernel Variables...
Jun 11 21:06:27 outside systemd[1]: Mounting /var/spool/cups/tmp...
Jun 11 21:06:27 outside systemd[1]: Mounted Configuration File System.
Jun 11 21:06:27 outside systemd[1]: Mounted FUSE Control File System.
Jun 11 21:06:27 outside systemd[1]: Mounted /var/spool/cups/tmp.
Jun 11 21:06:27 outside systemd[1]: Started Apply Kernel Variables.
Jun 11 21:06:27 outside kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
Jun 11 21:06:27 outside kernel: snd_bcm2835: unknown parameter 'index' ignored
Jun 11 21:06:27 outside kernel: bcm2835_alsa bcm2835_alsa: card created with 8 channels
Jun 11 21:06:27 outside kernel: snd-rpi-hifiberry-dacplus soc:sound: ASoC: CODEC DAI pcm512x-hifi not registered - will retry
Jun 11 21:06:28 outside kernel: snd-rpi-hifiberry-dacplus soc:sound: ASoC: CODEC DAI pcm512x-hifi not registered - will retry
Jun 11 21:06:28 outside kernel: brcmfmac: F1 signature read @0x18000000=0x15264345
Jun 11 21:06:28 outside kernel: brcmfmac: brcmf_fw_map_chip_to_name: using brcm/brcmfmac43455-sdio.bin for chip 0x004345(17221) rev 0x000006
Jun 11 21:06:28 outside kernel: usbcore: registered new interface driver brcmfmac
Jun 11 21:06:28 outside kernel: snd-rpi-hifiberry-dacplus soc:sound: ASoC: CODEC DAI pcm512x-hifi not registered - will retry
Jun 11 21:06:28 outside systemd[1]: Starting Sound Card.
Jun 11 21:06:28 outside systemd[1]: Reached target Sound Card.
Jun 11 21:06:28 outside systemd[1]: Found device /dev/mmcblk0p1.
Jun 11 21:06:28 outside systemd[1]: Starting File System Check on /dev/mmcblk0p1...
Jun 11 21:06:28 outside kernel: pcm512x 1-004d: 1-004d supply AVDD not found, using dummy regulator
Jun 11 21:06:28 outside kernel: pcm512x 1-004d: 1-004d supply DVDD not found, using dummy regulator
Jun 11 21:06:28 outside kernel: pcm512x 1-004d: 1-004d supply CPVDD not found, using dummy regulator
Jun 11 21:06:28 outside kernel: snd-rpi-hifiberry-dacplus soc:sound: pcm512x-hifi <-> 3f203000.i2s mapping ok
Jun 11 21:06:28 outside systemd[1]: Starting system-ifup.slice.
Jun 11 21:06:28 outside systemd[1]: Created slice system-ifup.slice.
Jun 11 21:06:28 outside kernel: random: crng init done
Jun 11 21:06:28 outside kernel: random: 7 urandom warning(s) missed due to ratelimiting
Jun 11 21:06:28 outside kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Feb 27 2018 03:15:32 version 7.45.154 (r684107 CY) FWID 01-4fbe0b04
Jun 11 21:06:28 outside kernel: brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 9.10.105 Compiler: 1.29.4 ClmImport: 1.36.3 Creation: 2018-03-09 18:56:28
Jun 11 21:06:28 outside systemd[1]: Starting system-systemd\x2drfkill.slice.
Jun 11 21:06:28 outside systemd[1]: Created slice system-systemd\x2drfkill.slice.
Jun 11 21:06:28 outside systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill0...
Jun 11 21:06:28 outside systemd[1]: Started Load/Save RF Kill Switch Status of rfkill0.
Jun 11 21:06:28 outside systemd-fsck[295]: fsck.fat 3.0.27 (2014-11-12)
Jun 11 21:06:28 outside systemd-fsck[295]: 0x41: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt.
Jun 11 21:06:28 outside systemd-fsck[295]: Automatically removing dirty bit.
Jun 11 21:06:28 outside systemd-fsck[295]: Performing changes.
Jun 11 21:06:28 outside systemd-fsck[295]: /dev/mmcblk0p1: 184 files, 74375/123044 clusters
Jun 11 21:06:28 outside systemd[1]: Started File System Check on /dev/mmcblk0p1.
Jun 11 21:06:28 outside systemd[1]: Mounting /boot...
Jun 11 21:06:28 outside systemd[1]: Mounted /boot.
Jun 11 21:06:28 outside systemd[1]: Starting Local File Systems.
Jun 11 21:06:28 outside systemd[1]: Reached target Local File Systems.
Jun 11 21:06:28 outside systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
Jun 11 21:06:28 outside systemd[1]: Starting Remote File Systems.
Jun 11 21:06:28 outside systemd[1]: Reached target Remote File Systems.
Jun 11 21:06:28 outside systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage...
Jun 11 21:06:28 outside systemd[1]: Starting Create Volatile Files and Directories...
Jun 11 21:06:28 outside systemd[1]: Starting LSB: Raise network interfaces....
Jun 11 21:06:28 outside systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
Jun 11 21:06:28 outside systemd[1]: Started Create Volatile Files and Directories.
Jun 11 21:06:28 outside systemd[1]: Started Trigger Flushing of Journal to Persistent Storage.
Jun 11 21:06:29 outside systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Jun 11 21:06:29 outside systemd[1]: Started Update UTMP about System Boot/Shutdown.
Jun 11 21:06:29 outside kernel: brcmfmac: power management disabled
Jun 11 21:06:30 outside systemd[1]: Received SIGRTMIN+20 from PID 217 (plymouthd).
Jun 11 21:06:30 outside kernel: brcmfmac: power management disabled
Jun 11 21:06:30 outside networking[323]: Configuring network interfaces...done.
Jun 11 21:06:30 outside systemd[1]: Started LSB: Raise network interfaces..
Jun 11 21:06:30 outside systemd[1]: Starting ifup for wlan0...
Jun 11 21:06:30 outside systemd[1]: Started ifup for wlan0.
Jun 11 21:06:30 outside systemd[1]: Starting ifup for eth0...
Jun 11 21:06:30 outside systemd[1]: Started ifup for eth0.
Jun 11 21:06:30 outside systemd[1]: Starting Network.
Jun 11 21:06:30 outside systemd[1]: Reached target Network.
Jun 11 21:06:30 outside systemd[1]: Starting Network is Online.
Jun 11 21:06:30 outside systemd[1]: Reached target Network is Online.
Jun 11 21:06:30 outside systemd[1]: Starting LSB: RPC portmapper replacement...
Jun 11 21:06:30 outside ifup[410]: dhcpcd[444]: version 6.0.5 starting
Jun 11 21:06:30 outside dhcpcd[444]: version 6.0.5 starting
Jun 11 21:06:30 outside dhcpcd[444]: eth0: waiting for carrier
Jun 11 21:06:30 outside ifup[410]: dhcpcd[444]: eth0: waiting for carrier
Jun 11 21:06:31 outside rpcbind[412]: Starting rpcbind daemon....
Jun 11 21:06:31 outside systemd[1]: Started LSB: RPC portmapper replacement.
Jun 11 21:06:31 outside systemd[1]: Starting RPC Port Mapper.
Jun 11 21:06:31 outside systemd[1]: Reached target RPC Port Mapper.
Jun 11 21:06:31 outside systemd[1]: Starting LSB: NFS support files common to client and server...
Jun 11 21:06:31 outside rpc.statd[510]: Version 1.2.8 starting
Jun 11 21:06:31 outside sm-notify[511]: Version 1.2.8 starting
Jun 11 21:06:31 outside kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
Jun 11 21:06:31 outside nfs-common[504]: Starting NFS common utilities: statd idmapd.
Jun 11 21:06:31 outside systemd[1]: Started LSB: NFS support files common to client and server.
Jun 11 21:06:31 outside systemd[1]: Starting System Initialization.
Jun 11 21:06:31 outside systemd[1]: Reached target System Initialization.
Jun 11 21:06:31 outside systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket.
Jun 11 21:06:31 outside systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Jun 11 21:06:31 outside systemd[1]: Starting mpd.socket.
Jun 11 21:06:31 outside systemd[1]: Listening on mpd.socket.
Jun 11 21:06:31 outside systemd[1]: Starting D-Bus System Message Bus Socket.
Jun 11 21:06:31 outside systemd[1]: Listening on D-Bus System Message Bus Socket.
Jun 11 21:06:31 outside systemd[1]: Starting Sockets.
Jun 11 21:06:31 outside systemd[1]: Reached target Sockets.
Jun 11 21:06:31 outside systemd[1]: Starting Daily Cleanup of Temporary Directories.
Jun 11 21:06:31 outside systemd[1]: Started Daily Cleanup of Temporary Directories.
Jun 11 21:06:31 outside systemd[1]: Starting Timers.
Jun 11 21:06:31 outside systemd[1]: Reached target Timers.
Jun 11 21:06:31 outside systemd[1]: Started Manage Sound Card State (restore and store).
Jun 11 21:06:31 outside systemd[1]: Starting Restore Sound Card State...
Jun 11 21:06:31 outside systemd[1]: Starting Basic System.
Jun 11 21:06:31 outside systemd[1]: Reached target Basic System.
Jun 11 21:06:31 outside systemd[1]: Starting Entropy daemon using the HAVEGE algorithm...
Jun 11 21:06:31 outside systemd[1]: Started Entropy daemon using the HAVEGE algorithm.
Jun 11 21:06:31 outside systemd[1]: Starting cd.service...
Jun 11 21:06:31 outside systemd[1]: Started cd.service.
Jun 11 21:06:31 outside systemd[1]: Starting Volumio Iptables Module...
Jun 11 21:06:31 outside systemd[1]: Started Volumio Iptables Module.
Jun 11 21:06:31 outside systemd[1]: Starting mount disks automatically with standby...
Jun 11 21:06:31 outside systemd[1]: Started mount disks automatically with standby.
Jun 11 21:06:31 outside systemd[1]: Starting UPnP Renderer front-end to MPD...
Jun 11 21:06:31 outside systemd[1]: Started UPnP Renderer front-end to MPD.
Jun 11 21:06:31 outside systemd[1]: Starting volumio-remote-updater.service...
Jun 11 21:06:31 outside systemd[1]: Started volumio-remote-updater.service.
Jun 11 21:06:31 outside systemd[1]: Starting Volumio SSH enabler...
Jun 11 21:06:31 outside systemd[1]: Started Volumio SSH enabler.
Jun 11 21:06:31 outside systemd[1]: Starting Wireless Services...
Jun 11 21:06:31 outside systemd[1]: Starting Xbindrc...
Jun 11 21:06:31 outside systemd[1]: Started Xbindrc.
Jun 11 21:06:31 outside systemd[1]: Starting /etc/rc.local Compatibility...
Jun 11 21:06:31 outside systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available.
Jun 11 21:06:31 outside systemd[1]: Starting Login Service...
Jun 11 21:06:31 outside systemd[1]: Starting LSB: start Winbind daemon...
Jun 11 21:06:31 outside systemd[1]: Starting LSB: start Samba daemons for the AD DC...
Jun 11 21:06:31 outside systemd[1]: Starting LSB: Start NTP daemon...
Jun 11 21:06:31 outside systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)...
Jun 11 21:06:31 outside alsactl[523]: No state is present for card ALSA
Jun 11 21:06:31 outside alsactl[523]: Found hardware: "bcm2835_alsa" "Broadcom Mixer" "" "" ""
Jun 11 21:06:31 outside alsactl[523]: Hardware is initialized using a generic method
Jun 11 21:06:31 outside alsactl[523]: No state is present for card ALSA
Jun 11 21:06:31 outside alsactl[523]: No state is present for card sndrpihifiberry
Jun 11 21:06:31 outside alsactl[523]: Found hardware: "HifiberryDacp" "" "" "" ""
Jun 11 21:06:31 outside alsactl[523]: Hardware is initialized using a generic method
Jun 11 21:06:31 outside alsactl[523]: No state is present for card sndrpihifiberry
Jun 11 21:06:31 outside systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling...
Jun 11 21:06:31 outside systemd[1]: Starting LSB: Brings up/down network automatically...
Jun 11 21:06:31 outside systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon...
Jun 11 21:06:31 outside systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support...
Jun 11 21:06:31 outside systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Jun 11 21:06:31 outside systemd[1]: Starting Music Player Daemon...
Jun 11 21:06:31 outside systemd[1]: Starting D-Bus System Message Bus...
Jun 11 21:06:31 outside systemd[1]: Started D-Bus System Message Bus.
Jun 11 21:06:31 outside volumio[525]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory
Jun 11 21:06:31 outside dhcpcd[549]: Not running dhcpcd because there is aleady an interface specific instance ... failed!
Jun 11 21:06:31 outside dhcpcd[549]: /var/run/dhcpcd-eth0.pid ... failed!
Jun 11 21:06:31 outside ifplugd(eth0)[606]: ifplugd 0.28 initializing.
Jun 11 21:06:31 outside ifplugd(eth0)[606]: Using interface eth0/B8:27:EB:C3:86:DE with driver (version: 1.0.6)
Jun 11 21:06:31 outside ifplugd(eth0)[606]: Using detection mode: SIOCETHTOOL
Jun 11 21:06:31 outside ifplugd(eth0)[606]: Initialization complete, link beat not detected.
Jun 11 21:06:31 outside kernel: ip_tables: (C) 2000-2006 Netfilter Core Team
Jun 11 21:06:31 outside loadcpufreq[542]: Loading cpufreq kernel modules...done (none).
Jun 11 21:06:32 outside ntpd[608]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1)
Jun 11 21:06:32 outside ntp[538]: Starting NTP server: ntpd.
Jun 11 21:06:32 outside ifplugd[543]: Network Interface Plugging Daemon...start eth0...done.
Jun 11 21:06:32 outside avahi-daemon[550]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106).
Jun 11 21:06:32 outside avahi-daemon[550]: Successfully dropped root privileges.
Jun 11 21:06:32 outside avahi-daemon[550]: avahi-daemon 0.6.31 starting up.
Jun 11 21:06:32 outside ntpd[623]: proto: precision = 1.041 usec
Jun 11 21:06:32 outside ntpd[623]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123
Jun 11 21:06:32 outside ntpd[623]: Listen and drop on 1 v6wildcard :: UDP 123
Jun 11 21:06:32 outside ntpd[623]: Listen normally on 2 lo 127.0.0.1 UDP 123
Jun 11 21:06:32 outside ntpd[623]: peers refreshed
Jun 11 21:06:32 outside ntpd[623]: Listening on routing socket on fd #19 for interface updates
Jun 11 21:06:32 outside kernel: nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
Jun 11 21:06:32 outside avahi-daemon[550]: Successfully called chroot().
Jun 11 21:06:32 outside avahi-daemon[550]: Successfully dropped remaining capabilities.
Jun 11 21:06:32 outside dbus[559]: [system] Successfully activated service 'org.freedesktop.systemd1'
Jun 11 21:06:32 outside avahi-daemon[550]: Loading service file /services/volumio.service.
Jun 11 21:06:32 outside systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Jun 11 21:06:32 outside systemd[1]: Starting Permit User Sessions...
Jun 11 21:06:32 outside avahi-daemon[550]: Network interface enumeration completed.
Jun 11 21:06:32 outside avahi-daemon[550]: Registering HINFO record with values 'ARMV7L'/'LINUX'.
Jun 11 21:06:32 outside systemd[1]: Started Restore Sound Card State.
Jun 11 21:06:32 outside systemd[1]: cd.service: main process exited, code=exited, status=127/n/a
Jun 11 21:06:32 outside systemd[1]: Unit cd.service entered failed state.
Jun 11 21:06:32 outside systemd[1]: Started /etc/rc.local Compatibility.
Jun 11 21:06:32 outside systemd[1]: Started LSB: Start NTP daemon.
Jun 11 21:06:32 outside systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling.
Jun 11 21:06:32 outside systemd[1]: Started LSB: Brings up/down network automatically.
Jun 11 21:06:32 outside systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon.
Jun 11 21:06:32 outside systemd[1]: dhcpcd.service: control process exited, code=exited status=6
Jun 11 21:06:32 outside systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support.
Jun 11 21:06:32 outside systemd[1]: Unit dhcpcd.service entered failed state.
Jun 11 21:06:32 outside systemd[1]: Started Permit User Sessions.
Jun 11 21:06:32 outside avahi-daemon[550]: Server startup complete. Host name is outside.local. Local service cookie is 2958315184.
Jun 11 21:06:32 outside avahi-daemon[550]: Service "outside" (/services/volumio.service) successfully established.
Jun 11 21:06:32 outside dbus[559]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service'
Jun 11 21:06:32 outside systemd[1]: cd.service holdoff time over, scheduling restart.
Jun 11 21:06:32 outside systemd-logind[535]: New seat seat0.
Jun 11 21:06:32 outside systemd[1]: Started Login Service.
Jun 11 21:06:32 outside systemd[1]: Starting Disk Manager (legacy version)...
Jun 11 21:06:32 outside systemd[1]: Stopping cd.service...
Jun 11 21:06:32 outside systemd[1]: Starting cd.service...
Jun 11 21:06:32 outside systemd[1]: Started cd.service.
Jun 11 21:06:32 outside systemd[1]: Starting LSB: set CPUFreq kernel parameters...
Jun 11 21:06:32 outside systemd[1]: Starting Hold until boot process finishes up...
Jun 11 21:06:32 outside volumio[632]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory
Jun 11 21:06:32 outside systemd[1]: Starting Terminate Plymouth Boot Screen...
Jun 11 21:06:32 outside systemd[1]: cd.service: main process exited, code=exited, status=127/n/a
Jun 11 21:06:32 outside systemd[1]: Unit cd.service entered failed state.
Jun 11 21:06:32 outside dbus[559]: [system] Successfully activated service 'org.freedesktop.UDisks'
Jun 11 21:06:33 outside systemd[1]: Received SIGRTMIN+21 from PID 217 (plymouthd).
Jun 11 21:06:33 outside systemd[1]: Started Disk Manager (legacy version).
Jun 11 21:06:33 outside sudo[548]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off
Jun 11 21:06:33 outside systemd[1]: Started Hold until boot process finishes up.
Jun 11 21:06:33 outside systemd[1]: Started Terminate Plymouth Boot Screen.
Jun 11 21:06:33 outside cpufrequtils[633]: CPUFreq Utilities: Setting performance CPUFreq governor...error: unexpectedly disconnected from boot status daemon
Jun 11 21:06:33 outside systemd[1]: cd.service holdoff time over, scheduling restart.
Jun 11 21:06:33 outside sudo[548]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 11 21:06:33 outside systemd[1]: Stopping cd.service...
Jun 11 21:06:33 outside systemd[1]: Starting cd.service...
Jun 11 21:06:33 outside systemd[1]: Started cd.service.
Jun 11 21:06:33 outside systemd[1]: Starting Getty on tty1...
Jun 11 21:06:33 outside systemd[1]: Started Getty on tty1.
Jun 11 21:06:33 outside systemd[1]: Starting Login Prompts.
Jun 11 21:06:33 outside systemd[1]: Reached target Login Prompts.
Jun 11 21:06:33 outside sudo[548]: pam_unix(sudo:session): session closed for user root
Jun 11 21:06:33 outside cpufrequtils[633]: CPU0...CPU1...CPU2...CPU3...done.
Jun 11 21:06:33 outside systemd[1]: Started LSB: set CPUFreq kernel parameters.
Jun 11 21:06:33 outside volumio[652]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory
Jun 11 21:06:33 outside systemd[1]: cd.service: main process exited, code=exited, status=127/n/a
Jun 11 21:06:33 outside systemd[1]: Unit cd.service entered failed state.
Jun 11 21:06:33 outside systemd[1]: cd.service holdoff time over, scheduling restart.
Jun 11 21:06:33 outside systemd[1]: Stopping cd.service...
Jun 11 21:06:33 outside systemd[1]: Starting cd.service...
Jun 11 21:06:33 outside systemd[1]: Started cd.service.
Jun 11 21:06:33 outside volumio[666]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory
Jun 11 21:06:33 outside systemd[1]: cd.service: main process exited, code=exited, status=127/n/a
Jun 11 21:06:33 outside systemd[1]: Unit cd.service entered failed state.
Jun 11 21:06:33 outside systemd[1]: cd.service holdoff time over, scheduling restart.
Jun 11 21:06:33 outside systemd[1]: Stopping cd.service...
Jun 11 21:06:33 outside systemd[1]: Starting cd.service...
Jun 11 21:06:33 outside systemd[1]: Started cd.service.
Jun 11 21:06:33 outside dbus[559]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service'
Jun 11 21:06:33 outside systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks...
Jun 11 21:06:33 outside volumio-remote-updater[530]: Error: No active session
Jun 11 21:06:33 outside volumio-remote-updater[530]: [2019-06-11 21:06:33] [info] asio async_connect error: system:111 (Connection refused)
Jun 11 21:06:33 outside volumio-remote-updater[530]: [2019-06-11 21:06:33] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Jun 11 21:06:33 outside volumio-remote-updater[530]: [2019-06-11 21:06:33] [error] handle_connect error: Underlying Transport Error
Jun 11 21:06:33 outside systemd[1]: cd.service: main process exited, code=exited, status=127/n/a
Jun 11 21:06:33 outside systemd[1]: Unit cd.service entered failed state.
Jun 11 21:06:33 outside volumio[670]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory
Jun 11 21:06:33 outside polkitd[671]: started daemon version 0.105 using authority implementation `local' version `0.105'
Jun 11 21:06:33 outside dbus[559]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Jun 11 21:06:33 outside systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks.
Jun 11 21:06:33 outside udisks-glue[528]: Device file /dev/mmcblk0 inserted
Jun 11 21:06:33 outside udisks-glue[528]: Device file /dev/mmcblk0p3 inserted
Jun 11 21:06:33 outside haveged[524]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K
Jun 11 21:06:33 outside haveged[524]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244
Jun 11 21:06:33 outside haveged[524]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00079
Jun 11 21:06:33 outside haveged[524]: haveged: fills: 0, generated: 0
Jun 11 21:06:33 outside volumio[529]: Could not open config: /tmp/upmpdcli.conf
Jun 11 21:06:33 outside systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE
Jun 11 21:06:33 outside systemd[1]: Unit upmpdcli.service entered failed state.
Jun 11 21:06:33 outside systemd[1]: cd.service holdoff time over, scheduling restart.
Jun 11 21:06:33 outside systemd[1]: Stopping cd.service...
Jun 11 21:06:33 outside systemd[1]: Starting cd.service...
Jun 11 21:06:33 outside systemd[1]: cd.service start request repeated too quickly, refusing to start.
Jun 11 21:06:33 outside systemd[1]: Failed to start cd.service.
Jun 11 21:06:33 outside systemd[1]: Unit cd.service entered failed state.
Jun 11 21:06:34 outside systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart.
Jun 11 21:06:34 outside systemd[1]: Stopping Xbindrc...
Jun 11 21:06:34 outside systemd[1]: Starting Xbindrc...
Jun 11 21:06:34 outside systemd[1]: Started Xbindrc.
Jun 11 21:06:34 outside ntpd[623]: Deferring DNS for 0.debian.pool.ntp.org 1
Jun 11 21:06:34 outside ntpd[623]: Deferring DNS for 1.debian.pool.ntp.org 1
Jun 11 21:06:34 outside ntpd[623]: Deferring DNS for 2.debian.pool.ntp.org 1
Jun 11 21:06:34 outside ntpd[623]: Deferring DNS for 3.debian.pool.ntp.org 1
Jun 11 21:06:34 outside ntpd[686]: signal_no_reset: signal 17 had flags 4000000
Jun 11 21:06:34 outside systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart.
Jun 11 21:06:34 outside systemd[1]: Stopping Xbindrc...
Jun 11 21:06:34 outside systemd[1]: Starting Xbindrc...
Jun 11 21:06:34 outside systemd[1]: Started Xbindrc.
Jun 11 21:06:35 outside systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart.
Jun 11 21:06:35 outside systemd[1]: Stopping Xbindrc...
Jun 11 21:06:35 outside systemd[1]: Starting Xbindrc...
Jun 11 21:06:35 outside systemd[1]: Started Xbindrc.
Jun 11 21:06:35 outside systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart.
Jun 11 21:06:35 outside systemd[1]: Stopping Xbindrc...
Jun 11 21:06:35 outside systemd[1]: Starting Xbindrc...
Jun 11 21:06:35 outside systemd[1]: Started Xbindrc.
Jun 11 21:06:36 outside systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart.
Jun 11 21:06:36 outside systemd[1]: Stopping Xbindrc...
Jun 11 21:06:36 outside systemd[1]: Starting Xbindrc...
Jun 11 21:06:36 outside systemd[1]: xbindkeysrc.service start request repeated too quickly, refusing to start.
Jun 11 21:06:36 outside systemd[1]: Failed to start Xbindrc.
Jun 11 21:06:36 outside systemd[1]: Unit xbindkeysrc.service entered failed state.
Jun 11 21:06:36 outside systemd[1]: Started Music Player Daemon.
Jun 11 21:06:36 outside systemd[1]: Started LSB: start Samba daemons for the AD DC.
Jun 11 21:06:36 outside wireless.js[662]: WIRELESS DAEMON: start
Jun 11 21:06:36 outside wireless.js[662]: WIRELESS: Loaded configuration
Jun 11 21:06:36 outside wireless.js[662]: Cleaning previous...
Jun 11 21:06:36 outside systemd[1]: Stopped hotspot.service.
Jun 11 21:06:36 outside sudo[726]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Jun 11 21:06:36 outside sudo[726]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 11 21:06:36 outside sudo[726]: pam_unix(sudo:session): session closed for user root
Jun 11 21:06:36 outside sudo[736]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Jun 11 21:06:36 outside sudo[736]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 11 21:06:36 outside winbind[536]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory
Jun 11 21:06:36 outside winbind[536]: Failed to create /var/log/samba/cores for user 0 with mode 0700
Jun 11 21:06:36 outside winbind[536]: Unable to setup corepath for winbindd: No such file or directory
Jun 11 21:06:36 outside winbind[536]: mkdir failed on directory /var/log/samba/cores: No such file or directory
Jun 11 21:06:36 outside winbind[536]: Failed to create /var/log/samba/cores for user 0 with mode 0700
Jun 11 21:06:36 outside winbind[536]: Unable to setup corepath for winbindd: No such file or directory
Jun 11 21:06:36 outside winbind[536]: [2019/06/11 21:06:36, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:36 outside winbind[536]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Jun 11 21:06:36 outside winbind[536]: [2019/06/11 21:06:36, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:36 outside winbind[536]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Jun 11 21:06:36 outside winbind[536]: [2019/06/11 21:06:36, 0] ../source3/winbindd/winbindd.c:1549(main)
Jun 11 21:06:36 outside winbind[536]: winbindd version 4.2.14-Debian started.
Jun 11 21:06:36 outside winbind[536]: Copyright Andrew Tridgell and the Samba Team 1992-2014
Jun 11 21:06:36 outside winbind[536]: [2019/06/11 21:06:36, 0] ../lib/util/util.c:220(directory_create_or_exist)
Jun 11 21:06:36 outside winbind[536]: mkdir failed on directory /var/log/samba/cores: No such file or directory
Jun 11 21:06:36 outside winbind[536]: [2019/06/11 21:06:36, 0] ../source3/lib/dumpcore.c:59(get_default_corepath)
Jun 11 21:06:36 outside winbind[536]: Failed to create /var/log/samba/cores for user 0 with mode 0700
Jun 11 21:06:36 outside winbind[536]: [2019/06/11 21:06:36, 0] ../source3/lib/dumpcore.c:250(dump_core_setup)
Jun 11 21:06:36 outside winbind[536]: Unable to setup corepath for winbindd: No such file or directory
Jun 11 21:06:36 outside winbindd[722]: [2019/06/11 21:06:36.314213, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:36 outside winbindd[722]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Jun 11 21:06:36 outside winbindd[722]: [2019/06/11 21:06:36.315046, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:36 outside winbindd[722]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Jun 11 21:06:36 outside nmbd[737]: [2019/06/11 21:06:36.319930, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:36 outside nmbd[737]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Jun 11 21:06:36 outside nmbd[737]: [2019/06/11 21:06:36.321215, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:36 outside nmbd[737]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Jun 11 21:06:36 outside winbind[536]: [2019/06/11 21:06:36.314213, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:36 outside winbind[536]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Jun 11 21:06:36 outside winbind[536]: [2019/06/11 21:06:36.315046, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:36 outside winbind[536]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Jun 11 21:06:36 outside nmbd[742]: [2019/06/11 21:06:36.324261, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns)
Jun 11 21:06:36 outside nmbd[742]: started asyncdns process 743
Jun 11 21:06:36 outside systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd).
Jun 11 21:06:36 outside systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)...
Jun 11 21:06:36 outside nmbd[541]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory
Jun 11 21:06:36 outside nmbd[541]: Failed to create /var/log/samba/cores for user 0 with mode 0700
Jun 11 21:06:36 outside nmbd[541]: Unable to setup corepath for nmbd: No such file or directory
Jun 11 21:06:36 outside nmbd[541]: [2019/06/11 21:06:36, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:36 outside nmbd[541]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Jun 11 21:06:36 outside nmbd[541]: [2019/06/11 21:06:36, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:36 outside nmbd[541]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Jun 11 21:06:36 outside nmbd[541]: [2019/06/11 21:06:36, 0] ../source3/nmbd/nmbd.c:908(main)
Jun 11 21:06:36 outside nmbd[541]: nmbd version 4.2.14-Debian started.
Jun 11 21:06:36 outside nmbd[541]: Copyright Andrew Tridgell and the Samba Team 1992-2014
Jun 11 21:06:36 outside nmbd[541]: [2019/06/11 21:06:36.319930, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:36 outside nmbd[541]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Jun 11 21:06:36 outside nmbd[541]: [2019/06/11 21:06:36.321215, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:36 outside nmbd[541]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Jun 11 21:06:36 outside nmbd[541]: .
Jun 11 21:06:36 outside ntpd_intres[686]: host name not found: 0.debian.pool.ntp.org
Jun 11 21:06:36 outside ntpd_intres[686]: host name not found: 1.debian.pool.ntp.org
Jun 11 21:06:36 outside ntpd_intres[686]: host name not found: 2.debian.pool.ntp.org
Jun 11 21:06:36 outside ntpd_intres[686]: host name not found: 3.debian.pool.ntp.org
Jun 11 21:06:36 outside nmbd[742]: [2019/06/11 21:06:36.778617, 0] ../lib/util/become_daemon.c:135(daemon_status)
Jun 11 21:06:36 outside nmbd[742]: 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).
Jun 11 21:06:36 outside sudo[736]: pam_unix(sudo:session): session closed for user root
Jun 11 21:06:36 outside winbind[536]: .
Jun 11 21:06:36 outside systemd[1]: Started LSB: start Winbind daemon.
Jun 11 21:06:36 outside winbindd[756]: [2019/06/11 21:06:36.799138, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache)
Jun 11 21:06:36 outside winbindd[756]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Jun 11 21:06:36 outside winbindd[756]: [2019/06/11 21:06:36.858638, 0] ../lib/util/become_daemon.c:124(daemon_ready)
Jun 11 21:06:36 outside winbindd[756]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
Jun 11 21:06:36 outside winbindd[758]: [2019/06/11 21:06:36.862714, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:36 outside winbindd[758]: Unable to open new log file '/var/log/samba/log.wb-OUTSIDE': No such file or directory
Jun 11 21:06:36 outside wireless.js[662]: Stopped aP
Jun 11 21:06:36 outside wireless.js[662]: Start wireless flow
Jun 11 21:06:36 outside wireless.js[662]: Stopped hotspot (if there)..
Jun 11 21:06:36 outside sudo[763]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Jun 11 21:06:36 outside sudo[763]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 11 21:06:36 outside sudo[763]: pam_unix(sudo:session): session closed for user root
Jun 11 21:06:36 outside sudo[772]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Jun 11 21:06:36 outside sudo[772]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 11 21:06:36 outside sudo[772]: pam_unix(sudo:session): session closed for user root
Jun 11 21:06:37 outside wireless.js[662]: DHCP IP
Jun 11 21:06:37 outside wireless.js[662]: Start ap
Jun 11 21:06:37 outside sudo[781]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd
Jun 11 21:06:37 outside sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 11 21:06:37 outside dhcpcd[785]: version 6.0.5 starting
Jun 11 21:06:37 outside kernel: brcmfmac: power management disabled
Jun 11 21:06:37 outside smbd[744]: Starting SMB/CIFS daemon: smbd[2019/06/11 21:06:37, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:37 outside smbd[744]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Jun 11 21:06:37 outside smbd[744]: [2019/06/11 21:06:37, 0] ../lib/util/util.c:220(directory_create_or_exist)
Jun 11 21:06:37 outside smbd[744]: mkdir failed on directory /var/log/samba/cores: No such file or directory
Jun 11 21:06:37 outside smbd[744]: [2019/06/11 21:06:37, 0] ../source3/lib/dumpcore.c:59(get_default_corepath)
Jun 11 21:06:37 outside smbd[744]: Failed to create /var/log/samba/cores for user 0 with mode 0700
Jun 11 21:06:37 outside smbd[744]: [2019/06/11 21:06:37, 0] ../source3/lib/dumpcore.c:250(dump_core_setup)
Jun 11 21:06:37 outside smbd[744]: Unable to setup corepath for smbd: No such file or directory
Jun 11 21:06:37 outside smbd[744]: [2019/06/11 21:06:37, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:37 outside smbd[744]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Jun 11 21:06:37 outside smbd[744]: [2019/06/11 21:06:37, 0] ../source3/smbd/server.c:1241(main)
Jun 11 21:06:37 outside smbd[744]: smbd version 4.2.14-Debian started.
Jun 11 21:06:37 outside smbd[744]: Copyright Andrew Tridgell and the Samba Team 1992-2014
Jun 11 21:06:37 outside wpa_supplicant[780]: Successfully initialized wpa_supplicant
Jun 11 21:06:37 outside dhcpcd[785]: eth0: waiting for carrier
Jun 11 21:06:37 outside dhcpcd[785]: wlan0: waiting for carrier
Jun 11 21:06:37 outside smbd[764]: [2019/06/11 21:06:37.118068, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:37 outside smbd[764]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Jun 11 21:06:37 outside smbd[764]: [2019/06/11 21:06:37.119349, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:37 outside smbd[764]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Jun 11 21:06:37 outside smbd[764]: [2019/06/11 21:06:37.119498, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:37 outside smbd[764]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Jun 11 21:06:37 outside smbd[744]: [2019/06/11 21:06:37.118068, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:37 outside smbd[744]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Jun 11 21:06:37 outside smbd[744]: [2019/06/11 21:06:37.119349, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:37 outside smbd[744]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Jun 11 21:06:37 outside smbd[744]: [2019/06/11 21:06:37.119498, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:37 outside smbd[744]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Jun 11 21:06:37 outside systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd).
Jun 11 21:06:37 outside smbd[744]: .
Jun 11 21:06:37 outside winbindd[808]: [2019/06/11 21:06:37.165990, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:37 outside winbindd[808]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory
Jun 11 21:06:37 outside smbd[805]: [2019/06/11 21:06:37.307093, 0] ../lib/util/become_daemon.c:124(daemon_ready)
Jun 11 21:06:37 outside smbd[805]: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Jun 11 21:06:37 outside smbd[810]: [2019/06/11 21:06:37.310515, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Jun 11 21:06:37 outside smbd[810]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Jun 11 21:06:38 outside wireless.js[662]: trying...
Jun 11 21:06:38 outside sudo[813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 11 21:06:38 outside sudo[813]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 11 21:06:38 outside sudo[813]: pam_unix(sudo:session): session closed for user root
Jun 11 21:06:38 outside volumio-remote-updater[530]: [2019-06-11 21:06:38] [info] asio async_connect error: system:111 (Connection refused)
Jun 11 21:06:38 outside volumio-remote-updater[530]: [2019-06-11 21:06:38] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Jun 11 21:06:38 outside volumio-remote-updater[530]: [2019-06-11 21:06:38] [error] handle_connect error: Underlying Transport Error
Jun 11 21:06:39 outside wireless.js[662]: trying...
Jun 11 21:06:39 outside sudo[823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 11 21:06:39 outside sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 11 21:06:39 outside sudo[823]: pam_unix(sudo:session): session closed for user root
Jun 11 21:06:40 outside wireless.js[662]: trying...
Jun 11 21:06:40 outside sudo[832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 11 21:06:40 outside sudo[832]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 11 21:06:40 outside sudo[832]: pam_unix(sudo:session): session closed for user root
Jun 11 21:06:40 outside wpa_supplicant[809]: wlan0: Trying to associate with ec:08:6b:a8:d0:dc (SSID='Left_Field' freq=2417 MHz)
Jun 11 21:06:40 outside wpa_supplicant[809]: wlan0: Associated with ec:08:6b:a8:d0:dc
Jun 11 21:06:40 outside wpa_supplicant[809]: wlan0: WPA: Key negotiation completed with ec:08:6b:a8:d0:dc [PTK=CCMP GTK=TKIP]
Jun 11 21:06:40 outside wpa_supplicant[809]: wlan0: CTRL-EVENT-CONNECTED - Connection to ec:08:6b:a8:d0:dc completed [id=0 id_str=]
Jun 11 21:06:40 outside dhcpcd[785]: wlan0: carrier acquired
Jun 11 21:06:40 outside dhcpcd[785]: wlan0: rebinding lease of 192.168.0.115
Jun 11 21:06:40 outside dhcpcd[785]: wlan0: leased 192.168.0.115 for 7200 seconds
Jun 11 21:06:40 outside avahi-daemon[550]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.115.
Jun 11 21:06:40 outside avahi-daemon[550]: New relevant interface wlan0.IPv4 for mDNS.
Jun 11 21:06:40 outside avahi-daemon[550]: Registering new address record for 192.168.0.115 on wlan0.IPv4.
Jun 11 21:06:40 outside dhcpcd[785]: wlan0: adding host route to 192.168.0.115 via 127.0.0.1
Jun 11 21:06:40 outside dhcpcd[785]: wlan0: adding route to 192.168.0.0/24
Jun 11 21:06:40 outside dhcpcd[785]: wlan0: adding default route via 192.168.0.1
Jun 11 21:06:40 outside dhcpcd[785]: forked to background, child pid 871
Jun 11 21:06:40 outside sudo[781]: pam_unix(sudo:session): session closed for user root
Jun 11 21:06:41 outside wireless.js[662]: trying...
Jun 11 21:06:41 outside sudo[876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Jun 11 21:06:41 outside sudo[876]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 11 21:06:41 outside sudo[876]: pam_unix(sudo:session): session closed for user root
Jun 11 21:06:41 outside wireless.js[662]: Connected to: ----Left_Field
Jun 11 21:06:41 outside wireless.js[662]: ----
Jun 11 21:06:41 outside sudo[885]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 11 21:06:41 outside sudo[885]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 11 21:06:41 outside sudo[885]: pam_unix(sudo:session): session closed for user root
Jun 11 21:06:41 outside wireless.js[662]: ... joined AP, wlan0 IPv4 is 192.168.0.115, ipV6 is undefined
Jun 11 21:06:41 outside wireless.js[662]: It's done! AP
Jun 11 21:06:41 outside systemd[1]: Started Wireless Services.
Jun 11 21:06:41 outside systemd[1]: Starting Volumio Backend Module...
Jun 11 21:06:41 outside systemd[1]: Started Volumio Backend Module.
Jun 11 21:06:41 outside systemd[1]: Starting Volumio Streaming Daemon...
Jun 11 21:06:41 outside systemd[1]: Started Volumio Streaming Daemon.
Jun 11 21:06:41 outside systemd[1]: Starting Multi-User System.
Jun 11 21:06:41 outside systemd[1]: Reached target Multi-User System.
Jun 11 21:06:41 outside systemd[1]: Starting Graphical Interface.
Jun 11 21:06:41 outside systemd[1]: Reached target Graphical Interface.
Jun 11 21:06:41 outside systemd[1]: Starting Update UTMP about System Runlevel Changes...
Jun 11 21:06:41 outside systemd[1]: Started Update UTMP about System Runlevel Changes.
Jun 11 21:06:41 outside systemd[1]: Startup finished in 9.508s (kernel) + 14.981s (userspace) = 24.490s.
Jun 11 21:06:41 outside volumio-streaming-daemon[895]: ############################
Jun 11 21:06:41 outside volumio-streaming-daemon[895]: # Volumio Streaming Daemon #
Jun 11 21:06:41 outside volumio-streaming-daemon[895]: # Running on port 7777 #
Jun 11 21:06:41 outside volumio-streaming-daemon[895]: ############################
Jun 11 21:06:41 outside nmbd[742]: [2019/06/11 21:06:41.781168, 0] ../lib/util/become_daemon.c:124(daemon_ready)
Jun 11 21:06:41 outside nmbd[742]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Jun 11 21:06:41 outside nmbd[742]: [2019/06/11 21:06:41.906917, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Jun 11 21:06:41 outside nmbd[742]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.115 for name WORKGROUP<1d>.
Jun 11 21:06:41 outside nmbd[742]: This response was from IP 192.168.0.119, reporting an IP address of 192.168.0.119.
Jun 11 21:06:42 outside ntpd[623]: Listen normally on 3 wlan0 192.168.0.115 UDP 123
Jun 11 21:06:42 outside ntpd[623]: peers refreshed
Jun 11 21:06:43 outside volumio[894]: info: -------------------------------------------
Jun 11 21:06:43 outside volumio[894]: info: ----- Volumio2 ----
Jun 11 21:06:43 outside volumio[894]: info: -------------------------------------------
Jun 11 21:06:43 outside volumio[894]: info: ----- System startup ----
Jun 11 21:06:43 outside volumio[894]: info: -------------------------------------------
Jun 11 21:06:43 outside volumio[894]: info: MYVOLUMIO Environment detected
Jun 11 21:06:43 outside volumio[894]: info: Plugin folders cleanup
Jun 11 21:06:43 outside volumio[894]: info: Scanning into folder /volumio/app/plugins/
Jun 11 21:06:43 outside volumio[894]: info: Scanning category audio_interface
Jun 11 21:06:43 outside volumio[894]: info: Scanning category miscellanea
Jun 11 21:06:43 outside volumio[894]: info: Scanning category music_service
Jun 11 21:06:43 outside volumio[894]: info: Scanning category plugins.json
Jun 11 21:06:43 outside volumio[894]: info: Scanning category system_controller
Jun 11 21:06:43 outside volumio[894]: info: Scanning category user_interface
Jun 11 21:06:43 outside volumio[894]: info: Scanning into folder /data/plugins/
Jun 11 21:06:43 outside volumio[894]: info: Scanning category music_service
Jun 11 21:06:43 outside volumio[894]: info: Plugin folders cleanup completed
Jun 11 21:06:43 outside volumio[894]: info: -------------------------------------------
Jun 11 21:06:43 outside volumio[894]: info: ----- Core plugins startup ----
Jun 11 21:06:43 outside volumio[894]: info: -------------------------------------------
Jun 11 21:06:43 outside volumio[894]: info: Loading plugins from folder /volumio/app/plugins/
Jun 11 21:06:43 outside volumio[894]: info: Adding plugin upnp to MyMusic Plugins
Jun 11 21:06:43 outside volumio[894]: info: Adding plugin airplay_emulation to MyMusic Plugins
Jun 11 21:06:43 outside volumio[894]: info: Adding plugin upnp_browser to MyMusic Plugins
Jun 11 21:06:43 outside volumio[894]: info: Loading plugins from folder /data/plugins/
Jun 11 21:06:43 outside volumio[894]: info: Loading plugin "system"...
Jun 11 21:06:43 outside volumio[894]: info: Loading plugin "appearance"...
Jun 11 21:06:44 outside ntpd_intres[686]: DNS 0.debian.pool.ntp.org -> 173.230.144.109
Jun 11 21:06:44 outside ntpd_intres[686]: DNS 1.debian.pool.ntp.org -> 198.60.22.240
Jun 11 21:06:44 outside ntpd_intres[686]: DNS 2.debian.pool.ntp.org -> 4.53.160.75
Jun 11 21:06:44 outside volumio[894]: info: Loading plugin "network"...
Jun 11 21:06:45 outside volumio[894]: info: Loading plugin "services"...
Jun 11 21:06:45 outside volumio[894]: info: Loading plugin "alsa_controller"...
Jun 11 21:06:45 outside volumio[894]: info: Loading plugin "wizard"...
Jun 11 21:06:45 outside volumio[894]: info: Loading plugin "volumio_command_line_client"...
Jun 11 21:06:45 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 11 21:06:45 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 11 21:06:45 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 11 21:06:45 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 11 21:06:45 outside volumio[894]: info: Loading plugin "upnp"...
Jun 11 21:06:45 outside volumio[894]: info: [1560287205667] Starting Upmpd Daemon
Jun 11 21:06:45 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 11 21:06:45 outside volumio[894]: info: Loading plugin "my_music"...
Jun 11 21:06:45 outside volumio[894]: info: Loading plugin "mpd"...
Jun 11 21:06:46 outside volumio-remote-updater[530]: [2019-06-11 21:06:46] [connect] Successful connection
Jun 11 21:06:46 outside volumio[894]: info: Loading plugin "upnp_browser"...
Jun 11 21:06:47 outside volumio[894]: info: Loading plugin "networkfs"...
Jun 11 21:06:47 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 11 21:06:47 outside volumio[894]: info: Loading plugin "alarm-clock"...
Jun 11 21:06:47 outside sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.122/USB /mnt/NAS/MediaNAS
Jun 11 21:06:47 outside sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 11 21:06:47 outside kernel: FS-Cache: Netfs 'cifs' registered for caching
Jun 11 21:06:47 outside kernel: Key type cifs.spnego registered
Jun 11 21:06:47 outside kernel: Key type cifs.idmap registered
Jun 11 21:06:47 outside 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.
Jun 11 21:06:47 outside volumio[894]: info: Loading plugin "airplay_emulation"...
Jun 11 21:06:47 outside volumio[894]: info: Starting Shairport Sync
Jun 11 21:06:47 outside volumio[894]: info: Loading plugin "last_100"...
Jun 11 21:06:47 outside volumio[894]: info: Loading plugin "webradio"...
Jun 11 21:06:47 outside volumio[894]: info: Loading plugin "i2s_dacs"...
Jun 11 21:06:47 outside volumio[894]: info: I2S DAC not set, start Auto-detection
Jun 11 21:06:47 outside volumio[894]: info: Loading plugin "volumiodiscovery"...
Jun 11 21:06:47 outside kernel: CIFS VFS: ioctl error in smb2_get_dfs_refer rc=-2
Jun 11 21:06:47 outside volumio[894]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jun 11 21:06:47 outside node[894]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Jun 11 21:06:47 outside node[894]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 11 21:06:47 outside node[894]: *** WARNING *** For more information see
Jun 11 21:06:47 outside node[894]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jun 11 21:06:47 outside node[894]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 11 21:06:47 outside node[894]: *** WARNING *** For more information see
Jun 11 21:06:47 outside volumio[894]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 11 21:06:47 outside volumio[894]: *** WARNING *** For more information see
Jun 11 21:06:47 outside volumio[894]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Jun 11 21:06:47 outside volumio[894]: *** WARNING *** Please fix your application to use the native API of Avahi!
Jun 11 21:06:47 outside volumio[894]: *** WARNING *** For more information see
Jun 11 21:06:47 outside volumio[894]: info: Applying required configuration parameters for plugin volumiodiscovery
Jun 11 21:06:47 outside volumio[894]: Discovery: StartAdv! undefined
Jun 11 21:06:47 outside volumio[894]: Discovery: Started advertising... outside - undefined
Jun 11 21:06:47 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 11 21:06:47 outside volumio[894]: info: Loading plugin "outputs"...
Jun 11 21:06:47 outside volumio[894]: info: Loading plugin "albumart"...
Jun 11 21:06:47 outside sudo[925]: pam_unix(sudo:session): session closed for user root
Jun 11 21:06:47 outside volumio[894]: info: Plugin example_plugin is not enabled
Jun 11 21:06:47 outside volumio[894]: info: Loading plugin "inputs"...
Jun 11 21:06:47 outside volumio[894]: info: Loading plugin "updater_comm"...
Jun 11 21:06:48 outside volumio[894]: info: Plugin mpdemulation is not enabled
Jun 11 21:06:48 outside volumio[894]: info: Loading plugin "rest_api"...
Jun 11 21:06:48 outside volumio[894]: info: Loading plugin "websocket"...
Jun 11 21:06:48 outside volumio[894]: info: Loading plugin "radio_paradise"...
Jun 11 21:06:48 outside volumio[894]: Forking 3 albumart workers
Jun 11 21:06:48 outside volumio[894]: info: Applying required configuration parameters for plugin radio_paradise
Jun 11 21:06:48 outside volumio[894]: info: Loading plugin "tunein_radio"...
Jun 11 21:06:49 outside volumio[894]: Starting albumart workers
Jun 11 21:06:49 outside volumio[894]: Starting albumart workers
Jun 11 21:06:49 outside volumio[894]: Starting albumart workers
Jun 11 21:06:49 outside ntpd_intres[686]: DNS 3.debian.pool.ntp.org -> 192.111.144.114
Jun 11 21:06:49 outside volumio[894]: info: Applying required configuration parameters for plugin tunein_radio
Jun 11 21:06:49 outside volumio[894]: info: ___________ START PLUGINS ___________
Jun 11 21:06:49 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 11 21:06:49 outside volumio[894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 11 21:06:49 outside volumio[894]: info: [1560287209644] CoreMusicLibrary::Adding element Media Servers
Jun 11 21:06:49 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 11 21:06:49 outside volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 11 21:06:49 outside volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 11 21:06:49 outside volumio[894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 11 21:06:49 outside volumio[894]: info: [1560287209712] CoreMusicLibrary::Adding element Last_100
Jun 11 21:06:49 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 11 21:06:49 outside volumio[894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 11 21:06:49 outside volumio[894]: info: [1560287209716] CoreMusicLibrary::Adding element Webradio
Jun 11 21:06:49 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 11 21:06:49 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 11 21:06:49 outside volumio[894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 11 21:06:49 outside volumio[894]: info: [1560287209730] CoreMusicLibrary::Adding element Radio Paradise
Jun 11 21:06:49 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 11 21:06:49 outside volumio[894]: info: TuneIn addToBrowseSources
Jun 11 21:06:49 outside volumio[894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Jun 11 21:06:49 outside volumio[894]: info: [1560287209753] CoreMusicLibrary::Adding element TuneIn Radio
Jun 11 21:06:49 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 11 21:06:49 outside volumio[894]: info: $ref=$, _isPromise=true, _successFn=undefined, _failFn=undefined, $ref=$, _boundArgs=null, _hasContext=false, _nextContext=undefined, _currentContext=undefined
Jun 11 21:06:49 outside volumio[894]: info: -------------------------------------------
Jun 11 21:06:49 outside volumio[894]: info: ----- MyVolumio plugins startup ----
Jun 11 21:06:49 outside volumio[894]: info: -------------------------------------------
Jun 11 21:06:49 outside volumio[894]: info: Loading plugins from folder /myvolumio/plugins
Jun 11 21:06:49 outside volumio[894]: info: Loading plugins from folder /data/myvolumio/plugins
Jun 11 21:06:49 outside volumio[894]: info: Loading plugin "cd_controller"...
Jun 11 21:06:49 outside volumio[894]: info: Loading plugin "streaming_services"...
Jun 11 21:06:50 outside volumio[894]: info: Loading plugin "my_volumio"...
Jun 11 21:06:50 outside volumio[894]: info: Adding CD REST API Endpoints
Jun 11 21:06:50 outside volumio[894]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller
Jun 11 21:06:50 outside volumio[894]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller
Jun 11 21:06:50 outside volumio[894]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller
Jun 11 21:06:50 outside volumio[894]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
Jun 11 21:06:50 outside volumio[894]: info: Detecting CD presence
Jun 11 21:06:50 outside volumio[894]: info: Streaming services startup
Jun 11 21:06:50 outside volumio[894]: info: Starting Streaming Daemon
Jun 11 21:06:50 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 11 21:06:50 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 11 21:06:50 outside volumio[894]: info: Starting MyVolumio Remote Streaming Endpoints
Jun 11 21:06:50 outside sudo[1010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Jun 11 21:06:50 outside sudo[1010]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 11 21:06:50 outside systemd[1]: Stopping Volumio Streaming Daemon...
Jun 11 21:06:50 outside systemd[1]: Starting Volumio Streaming Daemon...
Jun 11 21:06:50 outside volumio[894]: info: Loading i18n strings for locale en
Jun 11 21:06:50 outside systemd[1]: Started Volumio Streaming Daemon.
Jun 11 21:06:50 outside sudo[1010]: pam_unix(sudo:session): session closed for user root
Jun 11 21:06:50 outside volumio[894]: Updating browse sources language
Jun 11 21:06:50 outside volumio[894]: Cannot find translation for sourceMedia Servers
Jun 11 21:06:50 outside volumio[894]: Cannot find translation for sourceRadio Paradise
Jun 11 21:06:50 outside volumio[894]: Cannot find translation for sourceTuneIn Radio
Jun 11 21:06:50 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 11 21:06:50 outside volumio-streaming-daemon[1016]: ############################
Jun 11 21:06:50 outside volumio-streaming-daemon[1016]: # Volumio Streaming Daemon #
Jun 11 21:06:50 outside volumio-streaming-daemon[1016]: # Running on port 7777 #
Jun 11 21:06:50 outside volumio-streaming-daemon[1016]: ############################
Jun 11 21:06:50 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 11 21:06:50 outside volumio[894]: info: CoreCommandRouter::initPlayerControls
Jun 11 21:06:50 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 11 21:06:50 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards
Jun 11 21:06:50 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 11 21:06:50 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 11 21:06:50 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 11 21:06:50 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 11 21:06:50 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 11 21:06:50 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 11 21:06:50 outside volumio[894]: info: BOOT COMPLETED
Jun 11 21:06:50 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 11 21:06:50 outside kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Jul 06 19:18:34 outside systemd[1]: Time has been changed
Jul 06 19:18:39 outside volumio[894]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo
Jul 06 19:18:39 outside volumio[894]: info: CoreCommandRouter::Close All Modals sent
Jul 06 19:18:39 outside volumio[894]: info: CoreCommandRouter::Close All Modals sent
Jul 06 19:18:39 outside volumio[894]: Express server listening on port 3000
Jul 06 19:18:39 outside volumio[894]: Volumio Calling Home
Jul 06 19:18:39 outside volumio[894]: info: MyVolumio not started
Jul 06 19:18:39 outside volumio[894]: info: Initializing device activation check
Jul 06 19:18:39 outside volumio[894]: info: CoreStateMachine::resetVolumioState
Jul 06 19:18:39 outside volumio[894]: info: CoreStateMachine::getcurrentVolume
Jul 06 19:18:39 outside volumio[894]: info: CoreCommandRouter::volumioRetrievevolume
Jul 06 19:18:39 outside sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:18:39 outside sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:18:39 outside sudo[1038]: pam_unix(sudo:session): session closed for user root
Jul 06 19:18:40 outside volumio[894]: info: Setting Device type: Raspberry PI
Jul 06 19:18:40 outside volumio[894]: info: MPD running with PID558 ,establishing connection
Jul 06 19:18:40 outside sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1
Jul 06 19:18:40 outside sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:18:40 outside sudo[1048]: pam_unix(sudo:session): session closed for user root
Jul 06 19:18:40 outside volumio[894]: info: VolumeController:: Volume=100 Mute =false
Jul 06 19:18:40 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:18:40 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:18:40 outside volumio[894]: info: CorePlayQueue::getTrack 0
Jul 06 19:18:40 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:18:40 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:18:40 outside volumio[894]: info: Volumio called home
Jul 06 19:18:40 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 19:18:40 outside volumio[894]: info: Reloading queue from file
Jul 06 19:18:40 outside volumio[894]: info:
Jul 06 19:18:40 outside volumio[894]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory
Jul 06 19:18:40 outside volumio[894]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14)
Jul 06 19:18:40 outside volumio[894]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10)
Jul 06 19:18:40 outside volumio[894]: at emitOne (events.js:116:13)
Jul 06 19:18:40 outside volumio[894]: at Socket.emit (events.js:211:7)
Jul 06 19:18:40 outside volumio[894]: at addChunk (_stream_readable.js:263:12)
Jul 06 19:18:40 outside volumio[894]: at readableAddChunk (_stream_readable.js:246:13)
Jul 06 19:18:40 outside volumio[894]: at Socket.Readable.push (_stream_readable.js:208:10)
Jul 06 19:18:40 outside volumio[894]: at Pipe.onread (net.js:607:20)
Jul 06 19:18:40 outside volumio[894]: info: CoreStateMachine::setRepeat null single undefined
Jul 06 19:18:40 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:18:40 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:18:40 outside volumio[894]: info: CorePlayQueue::getTrack 0
Jul 06 19:18:40 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:18:40 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:18:40 outside volumio[894]: info: CoreStateMachine::setRandom null
Jul 06 19:18:40 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:18:40 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:18:40 outside volumio[894]: info: CorePlayQueue::getTrack 0
Jul 06 19:18:40 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:18:40 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:18:40 outside volumio[894]: info: [TuneIn] Pushing element: local
Jul 06 19:18:40 outside volumio[894]: info: [TuneIn] Pushing element: music
Jul 06 19:18:40 outside volumio[894]: info: [TuneIn] Pushing element: talk
Jul 06 19:18:40 outside volumio[894]: info: [TuneIn] Pushing element: sports
Jul 06 19:18:40 outside volumio[894]: info: [TuneIn] Pushing element: location
Jul 06 19:18:40 outside volumio[894]: info: [TuneIn] Pushing element: language
Jul 06 19:18:40 outside volumio[894]: info: [TuneIn] Pushing element: podcast
Jul 06 19:18:40 outside volumio[894]: info: [TuneIn] Pushed elements: 7
Jul 06 19:18:40 outside volumio[894]: info: [TuneIn] Element: local
Jul 06 19:18:40 outside volumio[894]: info: [TuneIn] Element: music
Jul 06 19:18:40 outside volumio[894]: info: [TuneIn] Element: talk
Jul 06 19:18:40 outside volumio[894]: info: [TuneIn] Element: sports
Jul 06 19:18:40 outside volumio[894]: info: [TuneIn] Element: location
Jul 06 19:18:40 outside volumio[894]: info: [TuneIn] Element: language
Jul 06 19:18:40 outside volumio[894]: info: [TuneIn] Element: podcast
Jul 06 19:18:40 outside sudo[1059]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Jul 06 19:18:40 outside sudo[1059]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:18:40 outside sudo[1062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay
Jul 06 19:18:40 outside volumio[894]: info: mDNS: A device disapperared from network
Jul 06 19:18:40 outside volumio[894]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined
Jul 06 19:18:40 outside systemd[1]: Starting UPnP Renderer front-end to MPD...
Jul 06 19:18:40 outside systemd[1]: Started UPnP Renderer front-end to MPD.
Jul 06 19:18:40 outside sudo[1062]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:18:40 outside sudo[1067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay
Jul 06 19:18:40 outside sudo[1059]: pam_unix(sudo:session): session closed for user root
Jul 06 19:18:40 outside sudo[1067]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:18:40 outside volumio[894]: info: mDNS: A device disapperared from network
Jul 06 19:18:40 outside volumio[894]: info: mDNS Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined
Jul 06 19:18:40 outside systemd[1]: Starting ShairportSync AirTunes receiver...
Jul 06 19:18:40 outside systemd[1]: Started ShairportSync AirTunes receiver.
Jul 06 19:18:40 outside sudo[1062]: pam_unix(sudo:session): session closed for user root
Jul 06 19:18:40 outside sudo[1067]: pam_unix(sudo:session): session closed for user root
Jul 06 19:18:40 outside shairport-sync[1081]: Successful Startup
Jul 06 19:18:40 outside volumio[894]: info: Upmpdcli Daemon Started
Jul 06 19:18:40 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Jul 06 19:18:40 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Jul 06 19:18:40 outside volumio[894]: Discovery: adding c4246b10-ae0d-465d-8feb-be273765b9e2
Jul 06 19:18:40 outside volumio[894]: info: mDNS: Found device outside
Jul 06 19:18:40 outside volumio[894]: Discovery: adding 890c593b-bacb-44e8-bc06-946d79be4051
Jul 06 19:18:40 outside volumio[894]: info: mDNS: Found device sunroom
Jul 06 19:18:40 outside sudo[1093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:18:40 outside sudo[1093]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:18:40 outside sudo[1093]: pam_unix(sudo:session): session closed for user root
Jul 06 19:18:40 outside sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:18:40 outside sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:18:40 outside sudo[1097]: pam_unix(sudo:session): session closed for user root
Jul 06 19:18:40 outside volumio[894]: info: Shairport-Sync Started
Jul 06 19:18:40 outside volumio[894]: Error adding Membership: Error: addMembership EINVAL
Jul 06 19:18:40 outside volumio[894]: info: Shairport-Sync Started
Jul 06 19:18:40 outside volumio[894]: info: CoreCommandRouter::volumioGetState
Jul 06 19:18:40 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:18:40 outside volumio[894]: info: CorePlayQueue::getTrack 0
Jul 06 19:18:40 outside sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:18:40 outside sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:18:40 outside sudo[1113]: pam_unix(sudo:session): session closed for user root
Jul 06 19:18:41 outside volumio[894]: Discovery: this is already registered, c4246b10-ae0d-465d-8feb-be273765b9e2
Jul 06 19:18:41 outside volumio[894]: info: mDNS: Found device outside
Jul 06 19:18:41 outside volumio[894]: Discovery: this is already registered, 890c593b-bacb-44e8-bc06-946d79be4051
Jul 06 19:18:41 outside volumio[894]: info: mDNS: Found device sunroom
Jul 06 19:18:41 outside sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:18:41 outside sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:18:41 outside sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:18:41 outside sudo[1129]: pam_unix(sudo:session): session closed for user root
Jul 06 19:18:41 outside sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:18:41 outside sudo[1132]: pam_unix(sudo:session): session closed for user root
Jul 06 19:18:41 outside nmbd[742]: [2019/07/06 19:18:41.597326, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Jul 06 19:18:41 outside nmbd[742]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.115 for name WORKGROUP<1d>.
Jul 06 19:18:41 outside nmbd[742]: This response was from IP 192.168.0.119, reporting an IP address of 192.168.0.119.
Jul 06 19:18:42 outside sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 06 19:18:42 outside sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:18:42 outside sudo[1174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:18:42 outside sudo[1171]: pam_unix(sudo:session): session closed for user root
Jul 06 19:18:42 outside sudo[1174]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:18:42 outside sudo[1174]: pam_unix(sudo:session): session closed for user root
Jul 06 19:18:42 outside volumio[894]: info: Fetching Streaming Services browse cache
Jul 06 19:18:42 outside volumio[894]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL
Jul 06 19:18:42 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 19:18:42 outside volumio[894]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ
Jul 06 19:18:42 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 19:18:43 outside volumio[894]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 06 19:18:43 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 19:18:43 outside volumio[894]: info: CoreCommandRouter::volumioGetState
Jul 06 19:18:43 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:18:43 outside volumio[894]: info: CorePlayQueue::getTrack 0
Jul 06 19:18:43 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jul 06 19:18:43 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Jul 06 19:18:43 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Jul 06 19:18:43 outside sudo[1197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:18:43 outside sudo[1197]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:18:43 outside sudo[1197]: pam_unix(sudo:session): session closed for user root
Jul 06 19:18:43 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jul 06 19:18:43 outside volumio[894]: info: Listing playlists
Jul 06 19:18:43 outside sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:18:43 outside sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:18:43 outside sudo[1205]: pam_unix(sudo:session): session closed for user root
Jul 06 19:18:44 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 06 19:18:44 outside ifup[410]: dhcpcd[444]: timed out
Jul 06 19:18:44 outside dhcpcd[444]: timed out
Jul 06 19:18:44 outside dhcpcd[444]: exited
Jul 06 19:18:44 outside ifup[410]: dhcpcd[444]: exited
Jul 06 19:18:44 outside ifup[410]: Failed to bring up eth0.
Jul 06 19:18:44 outside volumio-remote-updater[530]: [2019-07-06 19:18:44] [connect] Successful connection
Jul 06 19:18:44 outside volumio-remote-updater[530]: [2019-07-06 19:18:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1562440724 101
Jul 06 19:18:53 outside volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 19:18:53 outside volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 19:18:53 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 06 19:18:53 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 06 19:18:53 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 06 19:18:53 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 06 19:18:53 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:18:53 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:18:53 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:18:53 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:18:53 outside volumio[894]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 06 19:18:53 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 06 19:18:53 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Jul 06 19:18:57 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 06 19:18:58 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb
Jul 06 19:18:58 outside volumio[894]: info: ControllerMpd::sendMpdCommand update
Jul 06 19:18:58 outside volumio[894]: info: sending command...
Jul 06 19:18:58 outside volumio[894]: info: ControllerMpd::sendMpdCommand status
Jul 06 19:18:58 outside volumio[894]: info:
Jul 06 19:18:58 outside volumio[894]: ---------------------------- MPD announces state update: update
Jul 06 19:18:58 outside volumio[894]: info: parsing response...
Jul 06 19:18:58 outside volumio[894]: info: sending command...
Jul 06 19:18:58 outside volumio[894]: info: ControllerMpd::getState
Jul 06 19:18:58 outside volumio[894]: info: ControllerMpd::sendMpdCommand status
Jul 06 19:18:58 outside volumio[894]: info: sending command...
Jul 06 19:18:58 outside volumio[894]: info: parsing response...
Jul 06 19:18:58 outside volumio[894]: info: parsing response...
Jul 06 19:18:58 outside volumio[894]: info: ControllerMpd::parseState
Jul 06 19:18:59 outside volumio[894]: info: Command Router : Notfying DB Updatetrue
Jul 06 19:18:59 outside volumio[894]: info: ControllerMpd::parseState
Jul 06 19:18:59 outside volumio[894]: info: ControllerMpd::pushState
Jul 06 19:18:59 outside volumio[894]: info: CoreCommandRouter::servicePushState
Jul 06 19:18:59 outside volumio[894]: info: CoreStateMachine::syncState
Jul 06 19:18:59 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:18:59 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:18:59 outside volumio[894]: info: CorePlayQueue::getTrack 0
Jul 06 19:18:59 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:18:59 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:18:59 outside volumio[894]: info: CorePlayQueue::getTrack 0
Jul 06 19:18:59 outside volumio[894]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Jul 06 19:18:59 outside sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:18:59 outside volumio[894]: info: ------------------------------ 1072ms
Jul 06 19:18:59 outside sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:18:59 outside sudo[1328]: pam_unix(sudo:session): session closed for user root
Jul 06 19:19:01 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 06 19:19:03 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 06 19:19:03 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 19:19:05 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 06 19:19:08 outside volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 19:19:08 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Jul 06 19:19:08 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 19:19:08 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 06 19:19:08 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:19:08 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:19:08 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:19:08 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:19:08 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:19:08 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:19:08 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:19:08 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 06 19:19:08 outside volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 19:19:14 outside volumio[894]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object]
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 06 19:19:14 outside volumio[894]: info: Output device has changed, restarting MPD
Jul 06 19:19:14 outside volumio[894]: info: Output device has changed, restarting Shairport Sync
Jul 06 19:19:14 outside sudo[1441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jul 06 19:19:14 outside sudo[1441]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:19:14 outside sudo[1443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jul 06 19:19:14 outside sudo[1441]: pam_unix(sudo:session): session closed for user root
Jul 06 19:19:14 outside sudo[1443]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:19:14 outside volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jul 06 19:19:14 outside systemd[1]: Stopping Music Player Daemon...
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 06 19:19:14 outside volumio[894]: info: Setting mixer Analogue for card snd_rpi_hifiberry_dacplus
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Jul 06 19:19:14 outside volumio[894]: info: Updating Volume Controller Parameters: Device: 1 Name: snd_rpi_hifiberry_dacplus Mixer: Analogue Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 19:19:14 outside volumio[894]: error: Upnp client error: Error: This socket has been ended by the other party
Jul 06 19:19:14 outside volumio[894]: info: MPD Permissions set
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:14 outside systemd[1]: Starting Music Player Daemon...
Jul 06 19:19:14 outside volumio[894]: info: VolumeController:: Volume=100 Mute =false
Jul 06 19:19:14 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:19:14 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:19:14 outside volumio[894]: info: CorePlayQueue::getTrack 0
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:19:14 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:19:14 outside sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:19:14 outside sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:19:14 outside sudo[1480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay
Jul 06 19:19:14 outside sudo[1477]: pam_unix(sudo:session): session closed for user root
Jul 06 19:19:14 outside sudo[1480]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:19:14 outside systemd[1]: Stopping ShairportSync AirTunes receiver...
Jul 06 19:19:14 outside systemd[1]: Starting ShairportSync AirTunes receiver...
Jul 06 19:19:14 outside systemd[1]: Started ShairportSync AirTunes receiver.
Jul 06 19:19:14 outside sudo[1480]: pam_unix(sudo:session): session closed for user root
Jul 06 19:19:14 outside volumio[894]: info: Shairport-Sync Started
Jul 06 19:19:14 outside shairport-sync[1492]: Successful Startup
Jul 06 19:19:17 outside volumio[894]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Jul 06 19:19:17 outside volumio[894]: info: CoreStateMachine::getcurrentVolume
Jul 06 19:19:17 outside volumio[894]: info: CoreCommandRouter::volumioRetrievevolume
Jul 06 19:19:17 outside volumio[894]: info: VolumeController:: Volume=100 Mute =false
Jul 06 19:19:17 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:19:17 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:19:17 outside volumio[894]: info: CorePlayQueue::getTrack 0
Jul 06 19:19:17 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:19:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:19:17 outside sudo[1504]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:19:17 outside sudo[1504]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:19:17 outside sudo[1504]: pam_unix(sudo:session): session closed for user root
Jul 06 19:19:49 outside volumio[894]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object]
Jul 06 19:19:49 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions
Jul 06 19:19:49 outside sudo[1515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jul 06 19:19:49 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:49 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:49 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:49 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:49 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:49 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:49 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:19:49 outside sudo[1515]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:19:49 outside sudo[1517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jul 06 19:19:49 outside sudo[1515]: pam_unix(sudo:session): session closed for user root
Jul 06 19:19:49 outside sudo[1517]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:19:49 outside volumio[894]: info: MPD Permissions set
Jul 06 19:19:49 outside systemd[1]: Stopping Music Player Daemon...
Jul 06 19:20:17 outside volumio[894]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object]
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::volumioGetState
Jul 06 19:20:17 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:20:17 outside volumio[894]: info: CorePlayQueue::getTrack 0
Jul 06 19:20:17 outside volumio[894]: info: Restoring Previous Volume level: 100 false false
Jul 06 19:20:17 outside volumio[894]: info: Volume configurations have been set
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Jul 06 19:20:17 outside volumio[894]: info: Updating Volume Controller Parameters: Device: 1 Name: snd_rpi_hifiberry_dacplus Mixer: Analogue Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 06 19:20:17 outside volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 19:20:18 outside volumio[894]: info: VolumeController:: Volume=100 Mute =false
Jul 06 19:20:18 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:20:18 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:20:18 outside volumio[894]: info: CorePlayQueue::getTrack 0
Jul 06 19:20:18 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:20:18 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:20:18 outside sudo[1542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:20:18 outside sudo[1542]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:20:18 outside sudo[1542]: pam_unix(sudo:session): session closed for user root
Jul 06 19:20:22 outside volumio[894]: info: VolumeController::SetAlsaVolume100
Jul 06 19:20:22 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:20:22 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:20:22 outside volumio[894]: info: CorePlayQueue::getTrack 0
Jul 06 19:20:22 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:20:22 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:20:22 outside sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:20:22 outside sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:20:22 outside sudo[1554]: pam_unix(sudo:session): session closed for user root
Jul 06 19:20:28 outside volumio[894]: info: CoreCommandRouter::volumioPlay
Jul 06 19:20:28 outside volumio[894]: UNSET VOLATILE
Jul 06 19:20:28 outside volumio[894]: info: CoreStateMachine::play index undefined
Jul 06 19:20:28 outside volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 19:20:28 outside volumio[894]: info: CorePlayQueue::getTrack 0
Jul 06 19:20:28 outside volumio[894]: info: CoreStateMachine::startPlaybackTimer
Jul 06 19:20:28 outside volumio[894]: info: CorePlayQueue::getTrack 0
Jul 06 19:20:28 outside volumio[894]: info: ControllerMpd::sendMpdCommand stop
Jul 06 19:20:28 outside volumio[894]: info: sending command...
Jul 06 19:20:28 outside volumio[894]: error: MPD error: Error: This socket has been ended by the other party
Jul 06 19:20:28 outside volumio[894]: error: MPD error: Error: This socket has been ended by the other party
Jul 06 19:20:28 outside volumio[894]: error: MPD error: Error: This socket has been ended by the other party
Jul 06 19:20:32 outside volumio[894]: info: VolumeController::SetAlsaVolume42
Jul 06 19:20:32 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:20:32 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:20:32 outside volumio[894]: info: CorePlayQueue::getTrack 0
Jul 06 19:20:32 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:20:32 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:20:33 outside sudo[1564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:20:33 outside sudo[1564]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:20:33 outside sudo[1564]: pam_unix(sudo:session): session closed for user root
Jul 06 19:20:37 outside volumio[894]: info: CoreCommandRouter::volumioGetQueue
Jul 06 19:20:37 outside volumio[894]: info: CoreStateMachine::getQueue
Jul 06 19:20:37 outside volumio[894]: info: CorePlayQueue::getQueue
Jul 06 19:20:39 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri
Jul 06 19:20:41 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: tunein_radio , handleBrowseUri
Jul 06 19:20:41 outside volumio[894]: info: TuneIn handleBrowseUri: tunein
Jul 06 19:20:41 outside volumio[894]: info: [TuneIn] Added new entry local => Local Radio
Jul 06 19:20:41 outside volumio[894]: info: [TuneIn] Added new entry music => Music
Jul 06 19:20:41 outside volumio[894]: info: [TuneIn] Added new entry talk => Talk
Jul 06 19:20:41 outside volumio[894]: info: [TuneIn] Added new entry sports => Sports
Jul 06 19:20:41 outside volumio[894]: info: [TuneIn] Added new entry location => By Location
Jul 06 19:20:41 outside volumio[894]: info: [TuneIn] Added new entry language => By Language
Jul 06 19:20:43 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: tunein_radio , handleBrowseUri
Jul 06 19:20:43 outside volumio[894]: info: TuneIn handleBrowseUri: tunein/music
Jul 06 19:20:43 outside volumio[894]: info: [TuneIn] Calling browse function for: music
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Parsing results for: music
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=g2754 => 00's => http://opml.radiotime.com/Browse.ashx?id=g2754
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=g390 => 50's => http://opml.radiotime.com/Browse.ashx?id=g390
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=g407 => 60's => http://opml.radiotime.com/Browse.ashx?id=g407
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c100000783 => 70's => http://opml.radiotime.com/Browse.ashx?id=c100000783
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c100000781 => 80's => http://opml.radiotime.com/Browse.ashx?id=c100000781
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c100000946 => 90's => http://opml.radiotime.com/Browse.ashx?id=c100000946
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c57935 => Adult Hits => http://opml.radiotime.com/Browse.ashx?id=c57935
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c100001870 => Blues Music => http://opml.radiotime.com/Browse.ashx?id=c100001870
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c530749 => Children's Music => http://opml.radiotime.com/Browse.ashx?id=c530749
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=g2755 => Classic Hits => http://opml.radiotime.com/Browse.ashx?id=g2755
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=g54 => Classic Rock Music => http://opml.radiotime.com/Browse.ashx?id=g54
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c57939 => Classical Music => http://opml.radiotime.com/Browse.ashx?id=c57939
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=g6 => College Radio => http://opml.radiotime.com/Browse.ashx?id=g6
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c57940 => Country Music => http://opml.radiotime.com/Browse.ashx?id=c57940
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c57941 => Dance & Electronic => http://opml.radiotime.com/Browse.ashx?id=c57941
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c10635888 => Easy Listening => http://opml.radiotime.com/Browse.ashx?id=c10635888
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=g79 => Folk Music => http://opml.radiotime.com/Browse.ashx?id=g79
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c100002460 => Funk => http://opml.radiotime.com/Browse.ashx?id=c100002460
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c57942 => Hip Hop Music => http://opml.radiotime.com/Browse.ashx?id=c57942
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c100001750 => Holiday Music => http://opml.radiotime.com/Browse.ashx?id=c100001750
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c100000952 => Indie Music => http://opml.radiotime.com/Browse.ashx?id=c100000952
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c57944 => Jazz Music => http://opml.radiotime.com/Browse.ashx?id=c57944
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c100002533 => Latin Music => http://opml.radiotime.com/Browse.ashx?id=c100002533
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c100002314 => Moods and Activities => http://opml.radiotime.com/Browse.ashx?id=c100002314
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c100000086 => Music Podcasts => http://opml.radiotime.com/Browse.ashx?id=c100000086
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=g4152 => R&B Music => http://opml.radiotime.com/Browse.ashx?id=g4152
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=g85 => Reggae Music => http://opml.radiotime.com/Browse.ashx?id=g85
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c57950 => Religious Music => http://opml.radiotime.com/Browse.ashx?id=c57950
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c57951 => Rock Music => http://opml.radiotime.com/Browse.ashx?id=c57951
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c1367173 => Soul => http://opml.radiotime.com/Browse.ashx?id=c1367173
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=c57943 => Top 40 & Pop Music => http://opml.radiotime.com/Browse.ashx?id=c57943
Jul 06 19:20:44 outside volumio[894]: info: [TuneIn] Added new music entry tunein/browse/?id=g22 => World Music => http://opml.radiotime.com/Browse.ashx?id=g22
Jul 06 19:20:46 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: tunein_radio , handleBrowseUri
Jul 06 19:20:46 outside volumio[894]: info: TuneIn handleBrowseUri: tunein/browse/?id=c100000946
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Fetching (browse) results For tunein/browse/?id=c100000946
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Parsing results for: ?id=c100000946
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s192770 => 181.FM 90s Dance (US) => http://opml.radiotime.com/Tune.ashx?id=s192770
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s90414 => 181.FM Lite 90's (US) => http://opml.radiotime.com/Tune.ashx?id=s90414
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s256169 => Positivity 90's (United Arab Emirates) => http://opml.radiotime.com/Tune.ashx?id=s256169
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s55019 => 181.FM Star 90's (US) => http://opml.radiotime.com/Tune.ashx?id=s55019
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s307867 => 100hitz - 90's Hitz (US) => http://opml.radiotime.com/Tune.ashx?id=s307867
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s290587 => Kue 90s - Kue Radio (Australia) => http://opml.radiotime.com/Tune.ashx?id=s290587
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s249946 => 90's Hits (US) => http://opml.radiotime.com/Tune.ashx?id=s249946
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s115770 => Sky Radio 90's Hits (Netherlands) => http://opml.radiotime.com/Tune.ashx?id=s115770
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s231123 => ANTENNE BAYERN 90er Hits (Germany) => http://opml.radiotime.com/Tune.ashx?id=s231123
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s297225 => XS90s (New Zealand) => http://opml.radiotime.com/Tune.ashx?id=s297225
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s124154 => Hotmixradio 90 (France) => http://opml.radiotime.com/Tune.ashx?id=s124154
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s237962 => sunshine live - Die 90er (Germany) => http://opml.radiotime.com/Tune.ashx?id=s237962
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s290619 => 90's (Germany) => http://opml.radiotime.com/Tune.ashx?id=s290619
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s155673 => Guldkanalen 90-tal (Sweden) => http://opml.radiotime.com/Tune.ashx?id=s155673
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s255339 => Radio Regenbogen 90er (Germany) => http://opml.radiotime.com/Tune.ashx?id=s255339
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s281990 => 90s90s HITS (Germany) => http://opml.radiotime.com/Tune.ashx?id=s281990
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s88604 => Radio 10 - 90's Hits (Netherlands) => http://opml.radiotime.com/Tune.ashx?id=s88604
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s299853 => ENERGY Hits 90 (Germany) => http://opml.radiotime.com/Tune.ashx?id=s299853
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s245031 => Rewind Hit Radio (Canada) => http://opml.radiotime.com/Tune.ashx?id=s245031
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s112804 => Antenne Vorarlberg 90er Hits (Austria) => http://opml.radiotime.com/Tune.ashx?id=s112804
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s48969 => PulsRadio 90 (France) => http://opml.radiotime.com/Tune.ashx?id=s48969
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s250286 => 1A 90er Hits (Germany) => http://opml.radiotime.com/Tune.ashx?id=s250286
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s171072 => Hitrádio Devadesátka (Czech Republic) => http://opml.radiotime.com/Tune.ashx?id=s171072
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s304387 => 90's Party (US) => http://opml.radiotime.com/Tune.ashx?id=s304387
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry http://opml.radiotime.com/Tune.ashx?id=s243396 => 1MORE Gold (France) => http://opml.radiotime.com/Tune.ashx?id=s243396
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry tunein/browse/?offset=26&id=c100000946&filter=s => More Stations => http://opml.radiotime.com/Browse.ashx?offset=26&id=c100000946&filter=s
Jul 06 19:20:46 outside volumio[894]: info: Experimental fetaures disabled, skipping podcast item [object Object]
Jul 06 19:20:46 outside volumio[894]: info: Experimental fetaures disabled, skipping podcast item [object Object]
Jul 06 19:20:46 outside volumio[894]: info: Experimental fetaures disabled, skipping podcast item [object Object]
Jul 06 19:20:46 outside volumio[894]: info: Experimental fetaures disabled, skipping podcast item [object Object]
Jul 06 19:20:46 outside volumio[894]: info: Experimental fetaures disabled, skipping podcast item [object Object]
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry tunein/browse/?offset=6&id=c100000946&filter=p => More Shows => http://opml.radiotime.com/Browse.ashx?offset=6&id=c100000946&filter=p
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry tunein/browse/?id=c100000946&filter=p:topic => Recent Episodes => http://opml.radiotime.com/Browse.ashx?id=c100000946&filter=p:topic
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry tunein/browse/?id=g4134 => Classic Dance => http://opml.radiotime.com/Browse.ashx?id=g4134
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry tunein/browse/?id=c100000946&filter=s:popular => Most Popular => http://opml.radiotime.com/Browse.ashx?id=c100000946&filter=s:popular
Jul 06 19:20:46 outside volumio[894]: info: [TuneIn] Added new ?id=c100000946 entry tunein/browse/?id=c100000946&pivot=country&filter=s => By Location => http://opml.radiotime.com/Browse.ashx?id=c100000946&pivot=country&filter=s
Jul 06 19:20:49 outside volumio[894]: info: CoreCommandRouter::volumioAddQueueItems
Jul 06 19:20:49 outside volumio[894]: info: CoreStateMachine::addQueueItems
Jul 06 19:20:49 outside volumio[894]: info: CorePlayQueue::addQueueItems
Jul 06 19:20:49 outside volumio[894]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s297225
Jul 06 19:20:49 outside volumio[894]: info: Exploding uri http://opml.radiotime.com/Tune.ashx?id=s297225 in service tunein_radio
Jul 06 19:20:49 outside volumio[894]: error: [TuneIn] Fetching details for stream: s297225
Jul 06 19:20:49 outside volumio[894]: info: CorePlayQueue::saveQueue
Jul 06 19:20:49 outside volumio[894]: info: CoreCommandRouter::volumioPushQueue
Jul 06 19:20:49 outside volumio[894]: info: CoreStateMachine::updateTrackBlock
Jul 06 19:20:49 outside volumio[894]: info: CorePlayQueue::getTrackBlock
Jul 06 19:20:49 outside volumio[894]: info: CoreCommandRouter::volumioPlay
Jul 06 19:20:49 outside volumio[894]: UNSET VOLATILE
Jul 06 19:20:49 outside volumio[894]: info: CoreStateMachine::play index 1
Jul 06 19:20:49 outside volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 19:20:49 outside volumio[894]: info: CoreStateMachine::stop
Jul 06 19:20:49 outside volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 19:20:49 outside volumio[894]: UNSET VOLATILE
Jul 06 19:20:49 outside volumio[894]: info: CoreStateMachine::play index undefined
Jul 06 19:20:49 outside volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 19:20:49 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:20:49 outside volumio[894]: info: CoreStateMachine::startPlaybackTimer
Jul 06 19:20:49 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:20:49 outside volumio[894]: info: [1562440849289] ControllerWebradio::clearAddPlayTrack
Jul 06 19:20:49 outside volumio[894]: info: ControllerMpd::sendMpdCommand stop
Jul 06 19:21:00 outside volumio[894]: info: CoreCommandRouter::volumioAddQueueItems
Jul 06 19:21:00 outside volumio[894]: info: CoreStateMachine::addQueueItems
Jul 06 19:21:00 outside volumio[894]: info: CorePlayQueue::addQueueItems
Jul 06 19:21:00 outside volumio[894]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s297225
Jul 06 19:21:00 outside volumio[894]: info: Exploding uri http://opml.radiotime.com/Tune.ashx?id=s297225 in service tunein_radio
Jul 06 19:21:00 outside volumio[894]: error: [TuneIn] Fetching details for stream: s297225
Jul 06 19:21:00 outside volumio[894]: info: CorePlayQueue::saveQueue
Jul 06 19:21:00 outside volumio[894]: info: CoreCommandRouter::volumioPushQueue
Jul 06 19:21:00 outside volumio[894]: info: CoreStateMachine::updateTrackBlock
Jul 06 19:21:00 outside volumio[894]: info: CorePlayQueue::getTrackBlock
Jul 06 19:21:00 outside volumio[894]: info: CoreCommandRouter::volumioPlay
Jul 06 19:21:00 outside volumio[894]: UNSET VOLATILE
Jul 06 19:21:00 outside volumio[894]: info: CoreStateMachine::play index 2
Jul 06 19:21:00 outside volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 19:21:00 outside volumio[894]: info: CoreStateMachine::stop
Jul 06 19:21:00 outside volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 19:21:00 outside volumio[894]: UNSET VOLATILE
Jul 06 19:21:00 outside volumio[894]: info: CoreStateMachine::play index undefined
Jul 06 19:21:00 outside volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 19:21:00 outside volumio[894]: info: CorePlayQueue::getTrack 2
Jul 06 19:21:00 outside volumio[894]: info: CoreStateMachine::startPlaybackTimer
Jul 06 19:21:00 outside volumio[894]: info: CorePlayQueue::getTrack 2
Jul 06 19:21:00 outside volumio[894]: info: [1562440860500] ControllerWebradio::clearAddPlayTrack
Jul 06 19:21:00 outside volumio[894]: info: ControllerMpd::sendMpdCommand stop
Jul 06 19:21:06 outside volumio[894]: info: CoreCommandRouter::volumioPlay
Jul 06 19:21:06 outside volumio[894]: UNSET VOLATILE
Jul 06 19:21:06 outside volumio[894]: info: CoreStateMachine::play index 1
Jul 06 19:21:06 outside volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 19:21:06 outside volumio[894]: info: CoreStateMachine::stop
Jul 06 19:21:06 outside volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 19:21:06 outside volumio[894]: UNSET VOLATILE
Jul 06 19:21:06 outside volumio[894]: info: CoreStateMachine::play index undefined
Jul 06 19:21:06 outside volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 19:21:06 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:21:06 outside volumio[894]: info: CoreStateMachine::startPlaybackTimer
Jul 06 19:21:06 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:21:06 outside volumio[894]: info: [1562440866014] ControllerWebradio::clearAddPlayTrack
Jul 06 19:21:06 outside volumio[894]: info: ControllerMpd::sendMpdCommand stop
Jul 06 19:21:07 outside volumio[894]: info: CoreCommandRouter::volumioPlay
Jul 06 19:21:07 outside volumio[894]: UNSET VOLATILE
Jul 06 19:21:07 outside volumio[894]: info: CoreStateMachine::play index 1
Jul 06 19:21:07 outside volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 19:21:07 outside volumio[894]: info: CoreStateMachine::stop
Jul 06 19:21:07 outside volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 19:21:07 outside volumio[894]: UNSET VOLATILE
Jul 06 19:21:07 outside volumio[894]: info: CoreStateMachine::play index undefined
Jul 06 19:21:07 outside volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 19:21:07 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:21:07 outside volumio[894]: info: CoreStateMachine::startPlaybackTimer
Jul 06 19:21:07 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:21:07 outside volumio[894]: info: [1562440867094] ControllerWebradio::clearAddPlayTrack
Jul 06 19:21:07 outside volumio[894]: info: ControllerMpd::sendMpdCommand stop
Jul 06 19:21:19 outside systemd[1]: mpd.service stop-sigterm timed out. Killing.
Jul 06 19:21:19 outside kernel: CIFS VFS: Close unmatched open
Jul 06 19:21:19 outside systemd[1]: mpd.service: main process exited, code=killed, status=9/KILL
Jul 06 19:21:19 outside systemd[1]: Unit mpd.service entered failed state.
Jul 06 19:21:19 outside systemd[1]: Starting Music Player Daemon...
Jul 06 19:22:49 outside systemd[1]: mpd.service start operation timed out. Terminating.
Jul 06 19:23:42 outside nmbd[742]: [2019/07/06 19:23:42.236367, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Jul 06 19:23:42 outside nmbd[742]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.115 for name WORKGROUP<1d>.
Jul 06 19:23:42 outside nmbd[742]: This response was from IP 192.168.0.119, reporting an IP address of 192.168.0.119.
Jul 06 19:23:49 outside volumio[894]: Upnp client error: Error: This socket has been ended by the other party
Jul 06 19:23:49 outside volumio[894]: error: MPD error: Error: read ECONNRESET
Jul 06 19:23:49 outside systemd[1]: Failed to start Music Player Daemon.
Jul 06 19:23:49 outside systemd[1]: Unit mpd.service entered failed state.
Jul 06 19:23:49 outside sudo[1443]: pam_unix(sudo:session): session closed for user root
Jul 06 19:23:49 outside sudo[1517]: pam_unix(sudo:session): session closed for user root
Jul 06 19:23:49 outside volumio[894]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service
Jul 06 19:23:49 outside volumio[894]: Job for mpd.service failed. See 'systemctl status mpd.service' and 'journalctl -xn' for details.
Jul 06 19:23:49 outside systemd[1]: Starting Music Player Daemon...
Jul 06 19:23:49 outside volumio[894]: error: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service
Jul 06 19:23:49 outside volumio[894]: Job for mpd.service failed. See 'systemctl status mpd.service' and 'journalctl -xn' for details.
Jul 06 19:25:03 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:25:19 outside systemd[1]: mpd.service start operation timed out. Terminating.
Jul 06 19:25:33 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:26:09 outside volumio[894]: Upnp client error: Error: This socket has been ended by the other party
Jul 06 19:26:09 outside volumio[894]: error: MPD error: Error: read ECONNRESET
Jul 06 19:26:09 outside systemd[1]: Failed to start Music Player Daemon.
Jul 06 19:26:09 outside systemd[1]: Unit mpd.service entered failed state.
Jul 06 19:26:09 outside systemd[1]: Starting Music Player Daemon...
Jul 06 19:26:35 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:27:06 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:27:36 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:27:40 outside systemd[1]: mpd.service start operation timed out. Terminating.
Jul 06 19:28:38 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:28:42 outside volumio[894]: info: sending command...
Jul 06 19:28:42 outside volumio[894]: info: sending command...
Jul 06 19:28:42 outside volumio[894]: info: sending command...
Jul 06 19:28:42 outside volumio[894]: info: sending command...
Jul 06 19:28:42 outside volumio[894]: info: ControllerMpd::sendMpdCommand add "http://s39.myradiostream.com:15392/"
Jul 06 19:28:42 outside volumio[894]: info: ControllerMpd::sendMpdCommand add "http://s39.myradiostream.com:15392/"
Jul 06 19:28:42 outside volumio[894]: info: ControllerMpd::sendMpdCommand add "http://s39.myradiostream.com:15392/"
Jul 06 19:28:42 outside volumio[894]: info: ControllerMpd::sendMpdCommand add "http://s39.myradiostream.com:15392/"
Jul 06 19:28:42 outside volumio[894]: Upnp client error: Error: This socket has been ended by the other party
Jul 06 19:28:42 outside volumio[894]: error: MPD error: Error: read ECONNRESET
Jul 06 19:28:42 outside systemd[1]: Failed to start Music Player Daemon.
Jul 06 19:28:42 outside systemd[1]: Unit mpd.service entered failed state.
Jul 06 19:28:42 outside systemd[1]: Starting Music Player Daemon...
Jul 06 19:29:01 outside nmbd[742]: [2019/07/06 19:29:01.217693, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Jul 06 19:29:01 outside nmbd[742]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.115 for name WORKGROUP<1d>.
Jul 06 19:29:01 outside nmbd[742]: This response was from IP 192.168.0.119, reporting an IP address of 192.168.0.119.
Jul 06 19:29:09 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:29:39 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:30:10 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:30:12 outside systemd[1]: mpd.service start operation timed out. Terminating.
Jul 06 19:31:16 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:31:26 outside volumio[894]: Upnp client error: Error: This socket has been ended by the other party
Jul 06 19:31:27 outside volumio[894]: error: MPD error: Error: read ECONNRESET
Jul 06 19:31:27 outside systemd[1]: Failed to start Music Player Daemon.
Jul 06 19:31:27 outside systemd[1]: Unit mpd.service entered failed state.
Jul 06 19:31:27 outside systemd[1]: Starting Music Player Daemon...
Jul 06 19:31:47 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:32:18 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:32:49 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:32:57 outside systemd[1]: mpd.service start operation timed out. Terminating.
Jul 06 19:33:00 outside systemd[1]: Starting Cleanup of Temporary Directories...
Jul 06 19:33:00 outside systemd[1]: Started Cleanup of Temporary Directories.
Jul 06 19:33:19 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:34:01 outside nmbd[742]: [2019/07/06 19:34:01.800011, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Jul 06 19:34:01 outside nmbd[742]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.115 for name WORKGROUP<1d>.
Jul 06 19:34:01 outside nmbd[742]: This response was from IP 192.168.0.119, reporting an IP address of 192.168.0.119.
Jul 06 19:34:06 outside volumio[894]: Upnp client error: Error: This socket has been ended by the other party
Jul 06 19:34:06 outside volumio[894]: error: MPD error: Error: read ECONNRESET
Jul 06 19:34:06 outside systemd[1]: Failed to start Music Player Daemon.
Jul 06 19:34:06 outside systemd[1]: Unit mpd.service entered failed state.
Jul 06 19:34:06 outside systemd[1]: Starting Music Player Daemon...
Jul 06 19:34:21 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:34:52 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:35:22 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:35:36 outside systemd[1]: mpd.service start operation timed out. Terminating.
Jul 06 19:35:53 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:36:45 outside sudo[1688]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 06 19:36:45 outside sudo[1688]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:36:45 outside sudo[1690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:36:45 outside sudo[1688]: pam_unix(sudo:session): session closed for user root
Jul 06 19:36:45 outside sudo[1690]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:36:45 outside sudo[1690]: pam_unix(sudo:session): session closed for user root
Jul 06 19:36:47 outside volumio[894]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 06 19:36:47 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 06 19:36:47 outside volumio[894]: info: CoreCommandRouter::volumioGetState
Jul 06 19:36:47 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:36:47 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:36:47 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jul 06 19:36:47 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Jul 06 19:36:47 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Jul 06 19:36:47 outside sudo[1706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:36:47 outside sudo[1706]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:36:47 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jul 06 19:36:47 outside volumio[894]: info: Listing playlists
Jul 06 19:36:47 outside sudo[1706]: pam_unix(sudo:session): session closed for user root
Jul 06 19:36:47 outside sudo[1710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:36:47 outside sudo[1710]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:36:47 outside sudo[1710]: pam_unix(sudo:session): session closed for user root
Jul 06 19:36:47 outside volumio[894]: info: No input data
Jul 06 19:36:47 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 06 19:36:52 outside volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 19:36:52 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Jul 06 19:36:52 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 19:36:52 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 06 19:36:52 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:36:52 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:36:52 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:36:52 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:36:52 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:36:52 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:36:52 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:36:52 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 06 19:36:52 outside volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 19:36:54 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:37:05 outside volumio[894]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object]
Jul 06 19:37:05 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions
Jul 06 19:37:05 outside volumio[894]: info: CoreCommandRouter::volumioGetState
Jul 06 19:37:05 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:37:05 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:37:05 outside volumio[894]: info: Restoring Previous Volume level: 42 false true
Jul 06 19:37:05 outside volumio[894]: info: VolumeController::SetAlsaVolume100
Jul 06 19:37:05 outside volumio[894]: info: Enable softmixer device for audio device number 1
Jul 06 19:37:05 outside volumio[894]: info: Enable softmixer device for audio device number 1
Jul 06 19:37:05 outside volumio[894]: info: CoreCommandRouter::volumioStop
Jul 06 19:37:05 outside volumio[894]: info: CoreStateMachine::stop
Jul 06 19:37:05 outside volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 19:37:05 outside volumio[894]: UNSET VOLATILE
Jul 06 19:37:05 outside volumio[894]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Jul 06 19:37:05 outside volumio[894]: info: Updating Volume Controller Parameters: Device: 1 Name: snd_rpi_hifiberry_dacplus Mixer: Analogue Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5
Jul 06 19:37:05 outside volumio[894]: info: Output device has changed, restarting MPD
Jul 06 19:37:05 outside volumio[894]: info: Output device has changed, restarting Shairport Sync
Jul 06 19:37:05 outside sudo[1734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jul 06 19:37:05 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:05 outside sudo[1734]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:37:05 outside sudo[1736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jul 06 19:37:05 outside sudo[1734]: pam_unix(sudo:session): session closed for user root
Jul 06 19:37:05 outside sudo[1736]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:37:05 outside volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jul 06 19:37:05 outside volumio[894]: info: Volume configurations have been set
Jul 06 19:37:05 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:05 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:05 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:05 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:05 outside systemd[1]: Stopping Music Player Daemon...
Jul 06 19:37:05 outside volumio[894]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Jul 06 19:37:05 outside volumio[894]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: Analogue Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5
Jul 06 19:37:05 outside volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 19:37:05 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:37:05 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:37:05 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:37:05 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:37:05 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:37:05 outside sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:37:05 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:37:05 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:37:05 outside sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:37:05 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:37:05 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:37:05 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:37:05 outside sudo[1766]: pam_unix(sudo:session): session closed for user root
Jul 06 19:37:05 outside volumio[894]: info: MPD Permissions set
Jul 06 19:37:05 outside sudo[1775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:37:05 outside sudo[1775]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:37:05 outside sudo[1775]: pam_unix(sudo:session): session closed for user root
Jul 06 19:37:05 outside volumio[894]: info: Asound.conf file written
Jul 06 19:37:05 outside sudo[1785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Jul 06 19:37:05 outside sudo[1785]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:37:05 outside sudo[1785]: pam_unix(sudo:session): session closed for user root
Jul 06 19:37:05 outside volumio[894]: No state is present for card ALSA
Jul 06 19:37:05 outside volumio[894]: Found hardware: "bcm2835_alsa" "Broadcom Mixer" "" "" ""
Jul 06 19:37:05 outside volumio[894]: Hardware is initialized using a generic method
Jul 06 19:37:05 outside volumio[894]: No state is present for card ALSA
Jul 06 19:37:05 outside volumio[894]: No state is present for card sndrpihifiberry
Jul 06 19:37:05 outside volumio[894]: Found hardware: "HifiberryDacp" "" "" "" ""
Jul 06 19:37:05 outside volumio[894]: Hardware is initialized using a generic method
Jul 06 19:37:05 outside volumio[894]: No state is present for card sndrpihifiberry
Jul 06 19:37:05 outside kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Jul 06 19:37:06 outside volumio[894]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo
Jul 06 19:37:06 outside volumio[894]: No state is present for card ALSA
Jul 06 19:37:06 outside volumio[894]: Found hardware: "bcm2835_alsa" "Broadcom Mixer" "" "" ""
Jul 06 19:37:06 outside volumio[894]: Hardware is initialized using a generic method
Jul 06 19:37:06 outside volumio[894]: No state is present for card ALSA
Jul 06 19:37:06 outside volumio[894]: No state is present for card sndrpihifiberry
Jul 06 19:37:06 outside volumio[894]: Found hardware: "HifiberryDacp" "" "" "" ""
Jul 06 19:37:06 outside volumio[894]: Hardware is initialized using a generic method
Jul 06 19:37:06 outside volumio[894]: No state is present for card sndrpihifiberry
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: Output device has changed, restarting MPD
Jul 06 19:37:06 outside sudo[1804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jul 06 19:37:06 outside volumio[894]: info: Output device has changed, restarting Shairport Sync
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside sudo[1804]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:37:06 outside sudo[1806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jul 06 19:37:06 outside sudo[1804]: pam_unix(sudo:session): session closed for user root
Jul 06 19:37:06 outside sudo[1806]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:37:06 outside volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Jul 06 19:37:06 outside volumio[894]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5
Jul 06 19:37:06 outside volumio[894]: info: VolumeController:: Volume=100 Mute =false
Jul 06 19:37:06 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:37:06 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:37:06 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:37:06 outside sudo[1832]: pam_unix(sudo:session): session closed for user root
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 19:37:06 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:37:06 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:37:06 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:37:06 outside volumio[894]: info: MPD Permissions set
Jul 06 19:37:06 outside sudo[1845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:37:06 outside sudo[1845]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:37:06 outside sudo[1845]: pam_unix(sudo:session): session closed for user root
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:06 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:37:07 outside sudo[1855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay
Jul 06 19:37:07 outside sudo[1855]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:37:07 outside sudo[1859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay
Jul 06 19:37:07 outside systemd[1]: mpd.service stop-final-sigterm timed out. Killing.
Jul 06 19:37:07 outside sudo[1859]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:37:07 outside systemd[1]: Stopping ShairportSync AirTunes receiver...
Jul 06 19:37:07 outside systemd[1]: Starting ShairportSync AirTunes receiver...
Jul 06 19:37:07 outside systemd[1]: Started ShairportSync AirTunes receiver.
Jul 06 19:37:07 outside sudo[1855]: pam_unix(sudo:session): session closed for user root
Jul 06 19:37:07 outside shairport-sync[1867]: Successful Startup
Jul 06 19:37:07 outside systemd[1]: Stopping ShairportSync AirTunes receiver...
Jul 06 19:37:07 outside volumio[894]: info: Shairport-Sync Started
Jul 06 19:37:07 outside systemd[1]: Starting ShairportSync AirTunes receiver...
Jul 06 19:37:07 outside systemd[1]: Started ShairportSync AirTunes receiver.
Jul 06 19:37:07 outside sudo[1859]: pam_unix(sudo:session): session closed for user root
Jul 06 19:37:07 outside volumio[894]: info: Shairport-Sync Started
Jul 06 19:37:07 outside shairport-sync[1873]: Successful Startup
Jul 06 19:37:07 outside kernel: CIFS VFS: Close unmatched open
Jul 06 19:37:07 outside systemd[1]: mpd.service: main process exited, code=killed, status=9/KILL
Jul 06 19:37:07 outside systemd[1]: Unit mpd.service entered failed state.
Jul 06 19:37:07 outside systemd[1]: Starting Music Player Daemon...
Jul 06 19:37:08 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd
Jul 06 19:37:08 outside sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jul 06 19:37:08 outside sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:37:08 outside systemd[1]: Stopping Music Player Daemon...
Jul 06 19:37:09 outside volumio[894]: info: VolumeController::SetAlsaVolume42
Jul 06 19:37:09 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:37:09 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:37:09 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:37:09 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:37:09 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:37:09 outside sudo[1894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:37:09 outside sudo[1894]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:37:09 outside sudo[1894]: pam_unix(sudo:session): session closed for user root
Jul 06 19:37:25 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:37:56 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:38:13 outside volumio[894]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object]
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::volumioGetState
Jul 06 19:38:13 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:38:13 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:38:13 outside volumio[894]: info: Volume configurations have been set
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Jul 06 19:38:13 outside volumio[894]: info: Updating Volume Controller Parameters: Device: 1 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 06 19:38:13 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:38:13 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:38:13 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:38:13 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:38:13 outside sudo[1925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:38:13 outside sudo[1925]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:38:13 outside sudo[1925]: pam_unix(sudo:session): session closed for user root
Jul 06 19:38:27 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:38:38 outside systemd[1]: mpd.service stop-sigterm timed out. Killing.
Jul 06 19:38:38 outside kernel: CIFS VFS: Close unmatched open
Jul 06 19:38:38 outside kernel: CIFS VFS: Close unmatched open
Jul 06 19:38:38 outside systemd[1]: mpd.service: main process exited, code=killed, status=9/KILL
Jul 06 19:38:38 outside systemd[1]: Unit mpd.service entered failed state.
Jul 06 19:38:38 outside systemd[1]: Starting Music Player Daemon...
Jul 06 19:38:54 outside nmbd[742]: [2019/07/06 19:38:54.229917, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Jul 06 19:38:54 outside nmbd[742]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.115 for name WORKGROUP<1d>.
Jul 06 19:38:54 outside nmbd[742]: This response was from IP 192.168.0.119, reporting an IP address of 192.168.0.119.
Jul 06 19:38:57 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:39:05 outside volumio[894]: info: CoreCommandRouter::volumioGetQueue
Jul 06 19:39:05 outside volumio[894]: info: CoreStateMachine::getQueue
Jul 06 19:39:05 outside volumio[894]: info: CorePlayQueue::getQueue
Jul 06 19:39:05 outside volumio[894]: info: No input data
Jul 06 19:39:05 outside volumio[894]: info: No input data
Jul 06 19:39:09 outside volumio[894]: info: CoreCommandRouter::volumioPlay
Jul 06 19:39:09 outside volumio[894]: UNSET VOLATILE
Jul 06 19:39:09 outside volumio[894]: info: CoreStateMachine::play index undefined
Jul 06 19:39:09 outside volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 06 19:39:09 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:39:09 outside volumio[894]: info: CoreStateMachine::startPlaybackTimer
Jul 06 19:39:09 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:39:09 outside volumio[894]: info: [1562441949422] ControllerWebradio::clearAddPlayTrack
Jul 06 19:39:09 outside volumio[894]: info: ControllerMpd::sendMpdCommand stop
Jul 06 19:39:12 outside volumio[894]: info: VolumeController::SetAlsaVolume45
Jul 06 19:39:12 outside volumio[894]: info: CoreStateMachine::pushState
Jul 06 19:39:12 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:39:12 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:39:12 outside volumio[894]: info: CoreCommandRouter::volumioPushState
Jul 06 19:39:12 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 06 19:39:12 outside sudo[1947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:39:12 outside sudo[1947]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:39:12 outside sudo[1947]: pam_unix(sudo:session): session closed for user root
Jul 06 19:39:27 outside volumio[894]: info: CoreCommandRouter::volumioGetState
Jul 06 19:39:27 outside volumio[894]: info: CoreStateMachine::getState
Jul 06 19:39:27 outside volumio[894]: info: CorePlayQueue::getTrack 1
Jul 06 19:39:27 outside volumio[894]: info: CoreCommandRouter::volumioGetQueue
Jul 06 19:39:27 outside sudo[1958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 06 19:39:27 outside volumio[894]: info: CoreStateMachine::getQueue
Jul 06 19:39:27 outside volumio[894]: info: CorePlayQueue::getQueue
Jul 06 19:39:27 outside sudo[1958]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:39:27 outside sudo[1958]: pam_unix(sudo:session): session closed for user root
Jul 06 19:39:33 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:40:04 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
Jul 06 19:40:08 outside systemd[1]: mpd.service start operation timed out. Terminating.
Jul 06 19:40:34 outside volumio[894]: info: CALLMETHOD: system_controller system sendBugReport [object Object]
Jul 06 19:40:34 outside volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport
Jul 06 19:40:34 outside sudo[1987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig
Jul 06 19:40:34 outside sudo[1987]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:40:34 outside sudo[1987]: pam_unix(sudo:session): session closed for user root
Jul 06 19:40:35 outside sudo[2004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7
Jul 06 19:40:35 outside sudo[2004]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 06 19:40:35 outside volumio[894]: error: Upnp client error: Error: connect ETIMEDOUT 127.0.0.1:6600
# cat /var/log/mpd.log ---------------
Jun 11 21:06 : zeroconf: No global port, disabling zeroconf
Jul 06 19:18 : client: [0] opened from local
Jul 06 19:18 : exception: No such directory
Jul 06 19:18 : client: [1] opened from 127.0.0.1:40642
Jul 06 19:18 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Jonathan Edwards/Jonathan Edwards/01 Everybody Knows Her.mp3
Jul 06 19:18 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Jonathan Edwards/Jonathan Edwards/02 Cold Snow.mp3
Jul 06 19:18 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Jonathan Edwards/Jonathan Edwards/03 Athens County.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Jonathan Edwards/Jonathan Edwards/04 Dusty Morning.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Jonathan Edwards/Jonathan Edwards/05 Emma.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Jonathan Edwards/Jonathan Edwards/06 Shanty.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Jonathan Edwards/Jonathan Edwards/07 Sunshine.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Jonathan Edwards/Jonathan Edwards/08 The King.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Jonathan Edwards/Jonathan Edwards/09 Don't Cry Blue.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Jonathan Edwards/Jonathan Edwards/10 Jesse.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Jonathan Edwards/Jonathan Edwards/11 Sometimes.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Jonathan Edwards/Jonathan Edwards/12 Train of Glory.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Righteous Brothers/RighteousBrothers_UnchainedMelody/01_The Righteous Brothers_Unchained Melody.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Righteous Brothers/RighteousBrothers_UnchainedMelody/02_The Righteous Brothers_You've Lost That Lovin' Feelin'.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Righteous Brothers/RighteousBrothers_UnchainedMelody/03_The Righteous Brothers_(You're My) Soul and Inspiration.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Righteous Brothers/RighteousBrothers_UnchainedMelody/04_The Righteous Brothers_Ebb Tide.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Righteous Brothers/RighteousBrothers_UnchainedMelody/05_The Righteous Brothers_Just Once in My Life.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Righteous Brothers/RighteousBrothers_UnchainedMelody/06_The Righteous Brothers_Little Latin Lupe Lu.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Righteous Brothers/RighteousBrothers_UnchainedMelody/07_The Righteous Brothers_My Babe.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Righteous Brothers/RighteousBrothers_UnchainedMelody/08_The Righteous Brothers_Try to Find Another Woman.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Righteous Brothers/RighteousBrothers_UnchainedMelody/09_The Righteous Brothers_American Rock & Roll.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/Righteous Brothers/RighteousBrothers_UnchainedMelody/10_The Righteous Brothers_Georgia on My Mind.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 CC/10 CC's Greatest Hits 1972-1978/01 Rubber Bullets.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 CC/10 CC's Greatest Hits 1972-1978/02 Donna.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 CC/10 CC's Greatest Hits 1972-1978/03 Silly Love.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 CC/10 CC's Greatest Hits 1972-1978/04 The Dean And I.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 CC/10 CC's Greatest Hits 1972-1978/05 Life Is A Minestrone.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 CC/10 CC's Greatest Hits 1972-1978/06 The Wall Street Shuffle.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 CC/10 CC's Greatest Hits 1972-1978/07 Art For Art's Sake.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 CC/10 CC's Greatest Hits 1972-1978/08 I'm Mandy, Fly Me.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 CC/10 CC's Greatest Hits 1972-1978/09 Good Morning Judge.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 CC/10 CC's Greatest Hits 1972-1978/10 The Things We Do For Love.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 CC/10 CC's Greatest Hits 1972-1978/11 Dreadlock Holiday.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 CC/10 CC's Greatest Hits 1972-1978/12 I'm Not In Love.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 Years/The Autumn Effect/01 Waking Up.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 Years/The Autumn Effect/02 Fault Line.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 Years/The Autumn Effect/03 The Recipe.mp3
Jul 06 19:19 : update: added NAS/MediaNAS/FreeAgent GoFlex Drive/music/10 Years/The Autumn Effect/04 Cast It Out.mp3
Jul 06 19:19 : zeroconf: No global port, disabling zeroconf
Jul 06 19:21 : zeroconf: No global port, disabling zeroconf
Jul 06 19:23 : client: [0] opened from local
Jul 06 19:23 : client: [1] opened from 127.0.0.1:40758
Jul 06 19:23 : zeroconf: No global port, disabling zeroconf
Jul 06 19:26 : client: [0] opened from local
Jul 06 19:26 : client: [1] opened from 127.0.0.1:40776
Jul 06 19:26 : zeroconf: No global port, disabling zeroconf
Jul 06 19:28 : client: [0] opened from local
Jul 06 19:28 : client: [1] opened from 127.0.0.1:40796
Jul 06 19:28 : zeroconf: No global port, disabling zeroconf
Jul 06 19:31 : client: [0] opened from local
Jul 06 19:31 : client: [1] opened from 127.0.0.1:40816
Jul 06 19:31 : zeroconf: No global port, disabling zeroconf
Jul 06 19:34 : client: [0] opened from local
Jul 06 19:34 : client: [1] opened from 127.0.0.1:40832
Jul 06 19:34 : zeroconf: No global port, disabling zeroconf
Jul 06 19:37 : zeroconf: No global port, disabling zeroconf
Jul 06 19:38 : zeroconf: No global port, disabling zeroconf