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