Thu Jun 11 20:22:59 UTC 2020 Description="'trying to mount a network drive, I'\''ve also tried to mount the drive accessing ssh with the following command which works fine in another linux computer, command is sudo mount -t cifs -o usename=miguel //192.168.1.4/media /mnt'" # cat /proc/version --------------- Linux version 4.19.86-v7+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1283 SMP Fri Nov 29 18:32:25 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="b9cd485c2d7861a79d18f42ff0fdb052338b2239" VOLUMIO_FE_VERSION="2932a2aa65665ab9ec21cd0bdf3d21669bbbf643" VOLUMIO_BE_VERSION="8583c814bdc88e8c28c10464bec8226bce2b0634" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon May 25 18:53:02 CEST 2020" VOLUMIO_VERSION="2.777" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="c75975235b22f56393d7c4516aa10590" # ifconfig --------------- eth0 Link encap:Ethernet HWaddr b8:27:eb:9e:20:38 inet addr:192.168.1.20 Bcast:192.168.1.255 Mask:255.255.255.0 UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:28244 errors:0 dropped:1 overruns:0 frame:0 TX packets:17260 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:33538850 (31.9 MiB) TX bytes:5826267 (5.5 MiB) 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:9035 errors:0 dropped:0 overruns:0 frame:0 TX packets:9035 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:6013922 (5.7 MiB) TX bytes:6013922 (5.7 MiB) wlan0 Link encap:Ethernet HWaddr b8:27:eb:cb:75:6d inet addr:192.168.1.12 Bcast:192.168.1.255 Mask:255.255.255.0 UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:1627 errors:0 dropped:0 overruns:0 frame:0 TX packets:110 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:299452 (292.4 KiB) TX bytes:27644 (26.9 KiB) # sudo iwconfig --------------- eth0 no wireless extensions. lo no wireless extensions. wlan0 IEEE 802.11 ESSID:"Living 2G" Mode:Managed Frequency:2.457 GHz Access Point: 10:DA:43:88:B1:A7 Bit Rate=65 Mb/s Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off Link Quality=70/70 Signal level=-32 dBm Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0 Tx excessive retries:0 Invalid misc:0 Missed beacon:0 # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 0: ALSA [bcm2835 ALSA], device 0: bcm2835 ALSA [bcm2835 ALSA] Subdevices: 7/7 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 Subdevice #4: subdevice #4 Subdevice #5: subdevice #5 Subdevice #6: subdevice #6 card 0: ALSA [bcm2835 ALSA], device 1: bcm2835 IEC958/HDMI [bcm2835 IEC958/HDMI] Subdevices: 1/1 Subdevice #0: subdevice #0 card 0: ALSA [bcm2835 ALSA], device 2: bcm2835 IEC958/HDMI1 [bcm2835 IEC958/HDMI1] Subdevices: 1/1 Subdevice #0: subdevice #0 card 1: sndrpihifiberry [snd_rpi_hifiberry_dacplus], device 0: HiFiBerry DAC+ HiFi pcm512x-hifi-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 20:05 ? 00:00:06 /sbin/init root 2 0 0 20:05 ? 00:00:00 [kthreadd] root 3 2 0 20:05 ? 00:00:00 [rcu_gp] root 4 2 0 20:05 ? 00:00:00 [rcu_par_gp] root 8 2 0 20:05 ? 00:00:00 [mm_percpu_wq] root 9 2 0 20:05 ? 00:00:00 [ksoftirqd/0] root 10 2 0 20:05 ? 00:00:00 [rcu_sched] root 11 2 0 20:05 ? 00:00:00 [rcu_bh] root 12 2 0 20:05 ? 00:00:00 [migration/0] root 13 2 0 20:05 ? 00:00:00 [cpuhp/0] root 14 2 0 20:05 ? 00:00:00 [cpuhp/1] root 15 2 0 20:05 ? 00:00:00 [migration/1] root 16 2 0 20:05 ? 00:00:00 [ksoftirqd/1] root 19 2 0 20:05 ? 00:00:00 [cpuhp/2] root 20 2 0 20:05 ? 00:00:00 [migration/2] root 21 2 0 20:05 ? 00:00:00 [ksoftirqd/2] root 23 2 0 20:05 ? 00:00:00 [kworker/2:0H-kb] root 24 2 0 20:05 ? 00:00:00 [cpuhp/3] root 25 2 0 20:05 ? 00:00:00 [migration/3] root 26 2 0 20:05 ? 00:00:00 [ksoftirqd/3] root 29 2 0 20:05 ? 00:00:00 [kdevtmpfs] root 30 2 0 20:05 ? 00:00:00 [netns] root 31 2 0 20:05 ? 00:00:00 [kworker/0:1-eve] root 32 2 0 20:05 ? 00:00:00 [kworker/1:1-mm_] root 33 2 0 20:05 ? 00:00:00 [kworker/2:1-eve] root 34 2 0 20:05 ? 00:00:00 [khungtaskd] root 35 2 0 20:05 ? 00:00:00 [oom_reaper] root 36 2 0 20:05 ? 00:00:00 [writeback] root 37 2 0 20:05 ? 00:00:00 [kcompactd0] root 38 2 0 20:05 ? 00:00:00 [crypto] root 39 2 0 20:05 ? 00:00:00 [kblockd] root 40 2 0 20:05 ? 00:00:00 [watchdogd] root 41 2 0 20:05 ? 00:00:00 [rpciod] root 42 2 0 20:05 ? 00:00:00 [kworker/u9:0-hc] root 43 2 0 20:05 ? 00:00:00 [xprtiod] root 44 2 0 20:05 ? 00:00:00 [kworker/u8:1-ev] root 46 2 0 20:05 ? 00:00:00 [kswapd0] root 47 2 0 20:05 ? 00:00:00 [nfsiod] root 58 2 0 20:05 ? 00:00:00 [kthrotld] root 59 2 0 20:05 ? 00:00:00 [kworker/3:1-cgr] root 60 2 0 20:05 ? 00:00:00 [iscsi_eh] root 61 2 0 20:05 ? 00:00:00 [dwc_otg] root 62 2 0 20:05 ? 00:00:00 [DWC Notificatio] root 63 2 0 20:05 ? 00:00:00 [kworker/2:2-eve] root 64 2 0 20:05 ? 00:00:00 [vchiq-slot/0] root 65 2 0 20:05 ? 00:00:00 [vchiq-recy/0] root 66 2 0 20:05 ? 00:00:00 [vchiq-sync/0] root 67 2 0 20:05 ? 00:00:00 [vchiq-keep/0] root 68 2 0 20:05 ? 00:00:00 [SMIO] root 69 2 0 20:05 ? 00:00:00 [irq/86-mmc1] root 78 2 0 20:05 ? 00:00:00 [mmc_complete] root 80 2 0 20:05 ? 00:00:00 [kworker/0:1H-mm] root 101 2 0 20:05 ? 00:00:00 [kworker/3:1H-kb] root 105 2 0 20:05 ? 00:00:00 [kworker/1:1H-kb] root 106 2 0 20:05 ? 00:00:00 [jbd2/mmcblk0p2-] root 107 2 0 20:05 ? 00:00:00 [ext4-rsv-conver] root 117 2 0 20:06 ? 00:00:00 [kworker/u8:2-ev] root 125 2 0 20:06 ? 00:00:00 [loop0] root 159 2 0 20:06 ? 00:00:00 [jbd2/mmcblk0p3-] root 160 2 0 20:06 ? 00:00:00 [ext4-rsv-conver] root 161 2 0 20:06 ? 00:00:00 [ext4lazyinit] root 172 2 0 20:06 ? 00:00:00 [ipv6_addrconf] root 196 2 0 20:06 ? 00:00:00 [kworker/0:2-eve] root 200 1 0 20:06 ? 00:00:02 /lib/systemd/systemd-journald root 204 2 0 20:06 ? 00:00:00 [kworker/3:2-eve] root 233 1 0 20:06 ? 00:00:00 /lib/systemd/systemd-udevd root 285 2 0 20:06 ? 00:00:00 [SMIO] root 320 2 0 20:06 ? 00:00:00 [mmal-vchiq] root 324 2 0 20:06 ? 00:00:00 [mmal-vchiq] root 331 2 0 20:06 ? 00:00:00 [mmal-vchiq] root 380 2 0 20:06 ? 00:00:00 [cfg80211] root 390 2 0 20:06 ? 00:00:00 [brcmf_wq/mmc1:0] root 392 2 0 20:06 ? 00:00:00 [brcmf_wdog/mmc1] root 507 2 0 20:06 ? 00:00:00 [kworker/1:3-eve] root 587 1 0 20:06 ? 00:00:00 dhcpcd eth0 root 674 1 0 20:06 ? 00:00:00 /sbin/rpcbind -w statd 718 1 0 20:06 ? 00:00:00 /sbin/rpc.statd root 734 1 0 20:06 ? 00:00:00 /usr/sbin/rpc.idmapd root 736 1 0 20:06 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 --write=1024 volumio 744 1 0 20:06 ? 00:00:00 /usr/bin/udisks-glue --foreground volumio 746 1 0 20:06 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 747 1 0 20:06 ? 00:00:00 /bin/bash /bin/volumiologrotate root 777 1 0 20:06 ? 00:00:00 /lib/systemd/systemd-logind avahi 801 1 0 20:06 ? 00:00:00 avahi-daemon: running [volumio.local] message+ 804 1 0 20:06 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation root 850 1 0 20:06 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I avahi 868 801 0 20:06 ? 00:00:00 avahi-daemon: chroot helper ntp 875 1 0 20:06 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 100:104 root 890 1 0 20:06 ? 00:00:00 /usr/lib/udisks/udisks-daemon --no-debug root 909 890 0 20:06 ? 00:00:00 udisks-daemon: not polling any devices root 912 1 0 20:06 tty1 00:00:00 /sbin/agetty --noclear tty1 linux root 921 1 0 20:06 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 994 1 0 20:06 ? 00:00:00 /usr/sbin/nmbd -D root 995 994 0 20:06 ? 00:00:00 /usr/sbin/nmbd -D root 999 2 0 20:06 ? 00:00:00 [kworker/u9:1-hc] root 1000 1 0 20:06 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 460800 noflow - b8:27:eb:34:8a:92 root 1009 1 0 20:06 ? 00:00:00 /usr/sbin/winbindd root 1016 1 0 20:06 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap volumio 1053 1 3 20:06 ? 00:00:30 /usr/local/bin/node /volumio/index.js root 1064 1009 0 20:06 ? 00:00:00 /usr/sbin/winbindd root 1065 1 0 20:06 ? 00:00:00 /usr/sbin/smbd -D root 1092 1009 0 20:06 ? 00:00:00 /usr/sbin/winbindd root 1093 1009 0 20:06 ? 00:00:00 /usr/sbin/winbindd root 1094 1065 0 20:06 ? 00:00:00 /usr/sbin/smbd -D volumio 1248 1053 0 20:07 ? 00:00:00 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1273 1248 0 20:07 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1274 1248 0 20:07 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1279 1248 0 20:07 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1515 1 0 20:07 ? 00:00:00 /bin/streaming-daemon mpd 2104 1 0 20:10 ? 00:00:00 /usr/bin/mpd --no-daemon shairpo+ 2121 1 0 20:10 ? 00:00:03 /usr/local/bin/shairport-sync --configfile=/tmp/shairport-sync.conf root 2337 1 0 20:10 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 2371 1 0 20:10 ? 00:00:00 /sbin/dhcpcd root 2534 2 0 20:11 ? 00:00:00 [cifsiod] root 2535 2 0 20:11 ? 00:00:00 [cifsoplockd] volumio 2579 1 0 20:11 ? 00:00:01 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf root 2736 2 0 20:12 ? 00:00:00 [kworker/2:1H] volumio 2818 1 0 20:12 ? 00:00:00 /bin/bash /data/plugins/music_service/volspotconnect2/startconnect.sh volumio 2820 2818 0 20:12 ? 00:00:00 ./vollibrespot -c volspotify.toml root 3009 2 0 20:13 ? 00:00:00 [kworker/3:0H] root 3849 2 0 20:15 ? 00:00:00 [kworker/0:2H] root 4242 2 0 20:17 ? 00:00:00 [kworker/1:2H] root 4754 2 0 20:21 ? 00:00:00 [kworker/2:0-mm_] root 5019 747 0 20:22 ? 00:00:00 sleep 60 volumio 5037 1053 1 20:22 ? 00:00:00 /bin/sh -c /usr/local/bin/node /volumio/logsubmit.js 'trying to mount a network drive, I'\''ve also tried to mount the drive accessing ssh with the following command which works fine in another linux computer, command is sudo mount -t cifs -o usename=miguel //192.168.1.4/media /mnt' volumio 5038 5037 33 20:22 ? 00:00:00 /usr/local/bin/node /volumio/logsubmit.js trying to mount a network drive, I've also tried to mount the drive accessing ssh with the following command which works fine in another linux computer, command is sudo mount -t cifs -o usename=miguel //192.168.1.4/media /mnt volumio 5063 5038 0 20:22 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 5064 5063 0 20:22 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.2G 788M 1.3G 38% /imgpart /dev/loop0 334M 334M 0 100% /static overlay 12G 191M 12G 2% / devtmpfs 471M 0 471M 0% /dev tmpfs 485M 0 485M 0% /dev/shm tmpfs 485M 4.7M 481M 1% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 485M 0 485M 0% /sys/fs/cgroup tmpfs 485M 68K 485M 1% /tmp tmpfs 485M 0 485M 0% /var/spool/cups tmpfs 20M 64K 20M 1% /var/log tmpfs 485M 0 485M 0% /var/spool/cups/tmp /dev/mmcblk0p1 61M 59M 1.9M 97% /boot # mount --------------- /dev/mmcblk0p2 on /imgpart type ext4 (rw,relatime,stripe=1024) /dev/loop0 on /static type squashfs (ro,relatime) overlay on / type overlay (rw,relatime,lowerdir=/mnt/static,upperdir=/mnt/ext/dyn,workdir=/mnt/ext/work) sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime) proc on /proc type proc (rw,relatime) devtmpfs on /dev type devtmpfs (rw,nosuid,size=482156k,nr_inodes=120539,mode=755) tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev,noexec) devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000) tmpfs on /run type tmpfs (rw,nosuid,nodev,mode=755) tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k) tmpfs on /sys/fs/cgroup type tmpfs (ro,nosuid,nodev,noexec,mode=755) cgroup on /sys/fs/cgroup/systemd type cgroup (rw,nosuid,nodev,noexec,relatime,xattr,release_agent=/lib/systemd/systemd-cgroups-agent,name=systemd) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/memory type cgroup (rw,nosuid,nodev,noexec,relatime,memory) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/net_cls type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=22,pgrp=1,timeout=300,minproto=5,maxproto=5,direct) debugfs on /sys/kernel/debug type debugfs (rw,relatime) mqueue on /dev/mqueue type mqueue (rw,relatime) fusectl on /sys/fs/fuse/connections type fusectl (rw,relatime) configfs on /sys/kernel/config type configfs (rw,relatime) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) tmpfs on /var/spool/cups/tmp type tmpfs (rw,noatime,mode=755) /dev/mmcblk0p1 on /boot type vfat (rw,nosuid,nodev,noexec,relatime,fmask=0111,dmask=0000,allow_utime=0022,codepage=437,iocharset=ascii,shortname=mixed,utf8,errors=remount-ro) rpc_pipefs on /run/rpc_pipefs type rpc_pipefs (rw,relatime) # sudo journalctl -p 7 --------------- -- Logs begin at Mon 2020-05-25 16:53:02 UTC, end at Thu 2020-06-11 20:22:59 UTC. -- May 25 16:53:02 volumio systemd-journal[200]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 72.7M free of 480.6M available → current limit 30.0M). May 25 16:53:02 volumio kernel: Booting Linux on physical CPU 0x0 May 25 16:53:02 volumio kernel: Linux version 4.19.86-v7+ (dom@buildbot) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1283 SMP Fri Nov 29 18:32:25 GMT 2019 May 25 16:53:02 volumio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d May 25 16:53:02 volumio kernel: CPU: div instructions available: patching division code May 25 16:53:02 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache May 25 16:53:02 volumio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2 May 25 16:53:02 volumio kernel: Memory policy: Data cache writealloc May 25 16:53:02 volumio kernel: cma: Reserved 8 MiB at 0x3d800000 May 25 16:53:02 volumio kernel: On node 0 totalpages: 253952 May 25 16:53:02 volumio kernel: Normal zone: 2232 pages used for memmap May 25 16:53:02 volumio kernel: Normal zone: 0 pages reserved May 25 16:53:02 volumio kernel: Normal zone: 253952 pages, LIFO batch:63 May 25 16:53:02 volumio kernel: random: get_random_bytes called from start_kernel+0xac/0x4b4 with crng_init=0 May 25 16:53:02 volumio kernel: percpu: Embedded 16 pages/cpu s36864 r8192 d20480 u65536 May 25 16:53:02 volumio kernel: pcpu-alloc: s36864 r8192 d20480 u65536 alloc=16*4096 May 25 16:53:02 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 May 25 16:53:02 volumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 251720 May 25 16:53:02 volumio kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash quiet plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 console=ttyS0,115200 kgdboc=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh elevator=noop rootwait bootdelay=5 logo.nologo vt.global_cursor_default=0 loglevel=0 May 25 16:53:02 volumio kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) May 25 16:53:02 volumio kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) May 25 16:53:02 volumio kernel: Memory: 964316K/1015808K available (8192K kernel code, 653K rwdata, 2220K rodata, 1024K init, 822K bss, 43300K reserved, 8192K cma-reserved) May 25 16:53:02 volumio kernel: Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0xbe800000 - 0xff800000 (1040 MB) lowmem : 0x80000000 - 0xbe000000 ( 992 MB) modules : 0x7f000000 - 0x80000000 ( 16 MB) .text : 0x(ptrval) - 0x(ptrval) (9184 kB) .init : 0x(ptrval) - 0x(ptrval) (1024 kB) .data : 0x(ptrval) - 0x(ptrval) ( 654 kB) .bss : 0x(ptrval) - 0x(ptrval) ( 823 kB) May 25 16:53:02 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 May 25 16:53:02 volumio kernel: ftrace: allocating 26847 entries in 79 pages May 25 16:53:02 volumio kernel: rcu: Hierarchical RCU implementation. May 25 16:53:02 volumio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 May 25 16:53:02 volumio kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). May 25 16:53:02 volumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns May 25 16:53:02 volumio kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns May 25 16:53:02 volumio kernel: Switching to timer-based delay loop, resolution 52ns May 25 16:53:02 volumio kernel: Console: colour dummy device 80x30 May 25 16:53:02 volumio kernel: console [tty1] enabled May 25 16:53:02 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) May 25 16:53:02 volumio kernel: pid_max: default: 32768 minimum: 301 May 25 16:53:02 volumio kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) May 25 16:53:02 volumio kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) May 25 16:53:02 volumio kernel: CPU: Testing write buffer coherency: ok May 25 16:53:02 volumio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 May 25 16:53:02 volumio kernel: Setting up static identity map for 0x100000 - 0x10003c May 25 16:53:02 volumio kernel: rcu: Hierarchical SRCU implementation. May 25 16:53:02 volumio kernel: smp: Bringing up secondary CPUs ... May 25 16:53:02 volumio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 May 25 16:53:02 volumio kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 May 25 16:53:02 volumio kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 May 25 16:53:02 volumio kernel: smp: Brought up 1 node, 4 CPUs May 25 16:53:02 volumio kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). May 25 16:53:02 volumio kernel: CPU: All CPU(s) started in HYP mode. May 25 16:53:02 volumio kernel: CPU: Virtualization extensions available. May 25 16:53:02 volumio kernel: devtmpfs: initialized May 25 16:53:02 volumio kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 May 25 16:53:02 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns May 25 16:53:02 volumio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) May 25 16:53:02 volumio kernel: pinctrl core: initialized pinctrl subsystem May 25 16:53:02 volumio kernel: NET: Registered protocol family 16 May 25 16:53:02 volumio kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations May 25 16:53:02 volumio kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. May 25 16:53:02 volumio kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. May 25 16:53:02 volumio kernel: Serial: AMBA PL011 UART driver May 25 16:53:02 volumio kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled May 25 16:53:02 volumio kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager at (ptrval), dmachans=0x1 May 25 16:53:02 volumio kernel: SCSI subsystem initialized May 25 16:53:02 volumio kernel: usbcore: registered new interface driver usbfs May 25 16:53:02 volumio kernel: usbcore: registered new interface driver hub May 25 16:53:02 volumio kernel: usbcore: registered new device driver usb May 25 16:53:02 volumio kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2019-11-29 18:46, variant start May 25 16:53:02 volumio kernel: raspberrypi-firmware soc:firmware: Firmware hash is 7d5bdbd77968098c981dadefae06aaec79354c69 May 25 16:53:02 volumio kernel: clocksource: Switched to clocksource arch_sys_counter May 25 16:53:02 volumio kernel: VFS: Disk quotas dquot_6.6.0 May 25 16:53:02 volumio kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) May 25 16:53:02 volumio kernel: FS-Cache: Loaded May 25 16:53:02 volumio kernel: CacheFiles: Loaded May 25 16:53:02 volumio kernel: NET: Registered protocol family 2 May 25 16:53:02 volumio kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes) May 25 16:53:02 volumio kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes) May 25 16:53:02 volumio kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes) May 25 16:53:02 volumio kernel: TCP: Hash tables configured (established 8192 bind 8192) May 25 16:53:02 volumio kernel: UDP hash table entries: 512 (order: 2, 16384 bytes) May 25 16:53:02 volumio kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) May 25 16:53:02 volumio kernel: NET: Registered protocol family 1 May 25 16:53:02 volumio kernel: RPC: Registered named UNIX socket transport module. May 25 16:53:02 volumio kernel: RPC: Registered udp transport module. May 25 16:53:02 volumio kernel: RPC: Registered tcp transport module. May 25 16:53:02 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 25 16:53:02 volumio kernel: Trying to unpack rootfs image as initramfs... May 25 16:53:02 volumio kernel: Freeing initrd memory: 19424K May 25 16:53:02 volumio kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available May 25 16:53:02 volumio kernel: Initialise system trusted keyrings May 25 16:53:02 volumio kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 May 25 16:53:02 volumio kernel: FS-Cache: Netfs 'nfs' registered for caching May 25 16:53:02 volumio kernel: NFS: Registering the id_resolver key type May 25 16:53:02 volumio kernel: Key type id_resolver registered May 25 16:53:02 volumio kernel: Key type id_legacy registered May 25 16:53:02 volumio kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... May 25 16:53:02 volumio kernel: Key type asymmetric registered May 25 16:53:02 volumio kernel: Asymmetric key parser 'x509' registered May 25 16:53:02 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 25 16:53:02 volumio kernel: io scheduler noop registered (default) May 25 16:53:02 volumio kernel: io scheduler deadline registered May 25 16:53:02 volumio kernel: io scheduler cfq registered May 25 16:53:02 volumio kernel: io scheduler mq-deadline registered May 25 16:53:02 volumio kernel: io scheduler kyber registered May 25 16:53:02 volumio kernel: bcm2708_fb soc:fb: FB found 1 display(s) May 25 16:53:02 volumio kernel: Console: switching to colour frame buffer device 80x30 May 25 16:53:02 volumio kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 May 25 16:53:02 volumio kernel: bcm2835-rng 3f104000.rng: hwrng registered May 25 16:53:02 volumio kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) May 25 16:53:02 volumio kernel: vc-sm: Videocore shared memory driver May 25 16:53:02 volumio kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 May 25 16:53:02 volumio kernel: brd: module loaded May 25 16:53:02 volumio kernel: loop: module loaded May 25 16:53:02 volumio kernel: Loading iSCSI transport class v2.0-870. May 25 16:53:02 volumio kernel: libphy: Fixed MDIO Bus: probed May 25 16:53:02 volumio kernel: usbcore: registered new interface driver lan78xx May 25 16:53:02 volumio kernel: usbcore: registered new interface driver smsc95xx May 25 16:53:02 volumio kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) May 25 16:53:02 volumio kernel: dwc_otg 3f980000.usb: base=(ptrval) May 25 16:53:02 volumio kernel: Core Release: 2.80a May 25 16:53:02 volumio kernel: Setting default values for core params May 25 16:53:02 volumio kernel: Finished setting default values for core params May 25 16:53:02 volumio kernel: Using Buffer DMA mode May 25 16:53:02 volumio kernel: Periodic Transfer Interrupt Enhancement - disabled May 25 16:53:02 volumio kernel: Multiprocessor Interrupt Enhancement - disabled May 25 16:53:02 volumio kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 May 25 16:53:02 volumio kernel: Dedicated Tx FIFOs mode May 25 16:53:02 volumio kernel: WARN::dwc_otg_hcd_init:1045: FIQ DMA bounce buffers: virt = bd904000 dma = 0xfd904000 len=9024 May 25 16:53:02 volumio kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled May 25 16:53:02 volumio kernel: dwc_otg: Microframe scheduler enabled May 25 16:53:02 volumio kernel: WARN::hcd_init_fiq:457: FIQ on core 1 May 25 16:53:02 volumio kernel: WARN::hcd_init_fiq:458: FIQ ASM at 8067e550 length 36 May 25 16:53:02 volumio kernel: WARN::hcd_init_fiq:497: MPHI regs_base at be810000 May 25 16:53:02 volumio kernel: dwc_otg 3f980000.usb: DWC OTG Controller May 25 16:53:02 volumio kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 May 25 16:53:02 volumio kernel: dwc_otg 3f980000.usb: irq 56, io mem 0x00000000 May 25 16:53:02 volumio kernel: Init: Port Power? op_state=1 May 25 16:53:02 volumio kernel: Init: Power Port (0) May 25 16:53:02 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 4.19 May 25 16:53:02 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 May 25 16:53:02 volumio kernel: usb usb1: Product: DWC OTG Controller May 25 16:53:02 volumio kernel: usb usb1: Manufacturer: Linux 4.19.86-v7+ dwc_otg_hcd May 25 16:53:02 volumio kernel: usb usb1: SerialNumber: 3f980000.usb May 25 16:53:02 volumio kernel: hub 1-0:1.0: USB hub found May 25 16:53:02 volumio kernel: hub 1-0:1.0: 1 port detected May 25 16:53:02 volumio kernel: dwc_otg: FIQ enabled May 25 16:53:02 volumio kernel: dwc_otg: NAK holdoff enabled May 25 16:53:02 volumio kernel: dwc_otg: FIQ split-transaction FSM enabled May 25 16:53:02 volumio kernel: Module dwc_common_port init May 25 16:53:02 volumio kernel: usbcore: registered new interface driver usb-storage May 25 16:53:02 volumio kernel: mousedev: PS/2 mouse device common for all mice May 25 16:53:02 volumio kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer May 25 16:53:02 volumio kernel: bcm2835-cpufreq: min=600000 max=1200000 May 25 16:53:02 volumio kernel: sdhci: Secure Digital Host Controller Interface driver May 25 16:53:02 volumio kernel: sdhci: Copyright(c) Pierre Ossman May 25 16:53:02 volumio kernel: mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe May 25 16:53:02 volumio kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe May 25 16:53:02 volumio kernel: sdhci-pltfm: SDHCI platform and OF driver helper May 25 16:53:02 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs May 25 16:53:02 volumio kernel: hidraw: raw HID events driver (C) Jiri Kosina May 25 16:53:02 volumio kernel: usbcore: registered new interface driver usbhid May 25 16:53:02 volumio kernel: usbhid: USB HID core driver May 25 16:53:02 volumio kernel: vchiq: vchiq_init_state: slot_zero = (ptrval), is_master = 0 May 25 16:53:02 volumio kernel: [vc_sm_connected_init]: start May 25 16:53:02 volumio kernel: [vc_sm_connected_init]: end - returning 0 May 25 16:53:02 volumio kernel: Initializing XFRM netlink socket May 25 16:53:02 volumio kernel: NET: Registered protocol family 17 May 25 16:53:02 volumio kernel: Key type dns_resolver registered May 25 16:53:02 volumio kernel: Registering SWP/SWPB emulation handler May 25 16:53:02 volumio kernel: registered taskstats version 1 May 25 16:53:02 volumio kernel: Loading compiled-in X.509 certificates May 25 16:53:02 volumio kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled May 25 16:53:02 volumio kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 81, base_baud = 0) is a PL011 rev2 May 25 16:53:02 volumio kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 May 25 16:53:02 volumio kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated May 25 16:53:02 volumio kernel: sdhost: log_buf @ (ptrval) (fd907000) May 25 16:53:02 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) May 25 16:53:02 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) May 25 16:53:02 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) May 25 16:53:02 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) May 25 16:53:02 volumio kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) May 25 16:53:02 volumio kernel: of_cfs_init May 25 16:53:02 volumio kernel: of_cfs_init: OK May 25 16:53:02 volumio kernel: Freeing unused kernel memory: 1024K May 25 16:53:02 volumio kernel: Run /init as init process May 25 16:53:02 volumio kernel: mmc0: host does not support reading read-only switch, assuming write-enable May 25 16:53:02 volumio kernel: mmc0: new high speed SDHC card at address 0007 May 25 16:53:02 volumio kernel: mmcblk0: mmc0:0007 SD16G 14.5 GiB May 25 16:53:02 volumio kernel: random: fast init done May 25 16:53:02 volumio kernel: mmcblk0: p1 p2 p3 May 25 16:53:02 volumio kernel: mmc1: new high speed SDIO card at address 0001 May 25 16:53:02 volumio kernel: Indeed it is in host mode hprt0 = 00021501 May 25 16:53:02 volumio initramfs: Booting Volumio for BCM2835 May 25 16:53:02 volumio initramfs: This script mounts rootfs RO with an overlay RW layer. May 25 16:53:02 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 25 16:53:02 volumio kernel: fuse init (API version 7.27) May 25 16:53:02 volumio initramfs: IMGPART=/dev/mmcblk0p2 May 25 16:53:02 volumio initramfs: IMGFILE=/volumio_current.sqsh May 25 16:53:02 volumio initramfs: Boot delay (except first time) will be 5 seconds May 25 16:53:02 volumio initramfs: /dev/mmcblk0p2: May 25 16:53:02 volumio kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg May 25 16:53:02 volumio kernel: Indeed it is in host mode hprt0 = 00001101 May 25 16:53:02 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 May 25 16:53:02 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 25 16:53:02 volumio kernel: hub 1-1:1.0: USB hub found May 25 16:53:02 volumio kernel: hub 1-1:1.0: 5 ports detected May 25 16:53:02 volumio kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg May 25 16:53:02 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 May 25 16:53:02 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 May 25 16:53:02 volumio kernel: smsc95xx v1.0.6 May 25 16:53:02 volumio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:9e:20:38 May 25 16:53:02 volumio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) May 25 16:53:02 volumio initramfs: Creating factory image, this will take a minute, please wait... May 25 16:53:02 volumio initramfs: Factory image created May 25 16:53:02 volumio initramfs: Creating archive for factory kernel... May 25 16:53:02 volumio kernel: random: crng init done May 25 16:53:02 volumio initramfs: Checking for USB updates May 25 16:53:02 volumio initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) May 25 16:53:02 volumio initramfs: Re-sizing Volumio data partition May 25 16:53:02 volumio initramfs: Volumio data partition succesfully resized May 25 16:53:02 volumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) May 25 16:53:02 volumio initramfs: With Option: May 25 16:53:02 volumio initramfs: VOLUMIO_VERSION="2.777" May 25 16:53:02 volumio initramfs: Finish initramfs, continue booting Volumio May 25 16:53:02 volumio systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) May 25 16:53:02 volumio systemd[1]: Detected architecture 'arm'. May 25 16:53:02 volumio kernel: NET: Registered protocol family 10 May 25 16:53:02 volumio kernel: Segment Routing with IPv6 May 25 16:53:02 volumio systemd[1]: Inserted module 'ipv6' May 25 16:53:02 volumio systemd[1]: Set hostname to . May 25 16:53:02 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' May 25 16:53:02 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' May 25 16:53:02 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' May 25 16:53:02 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' May 25 16:53:02 volumio systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory. May 25 16:53:02 volumio systemd[1]: Starting Forward Password Requests to Wall Directory Watch. May 25 16:53:02 volumio kernel: i2c /dev entries driver May 25 16:53:02 volumio systemd-journal[200]: Journal started May 25 16:53:02 volumio systemd[1]: Starting Slices. May 25 16:53:04 volumio systemd[1]: Reached target Slices. May 25 16:53:04 volumio systemd[1]: Starting Remount Root and Kernel File Systems... May 25 16:53:04 volumio systemd[1]: Mounted Debug File System. May 25 16:53:04 volumio systemd[1]: Mounted POSIX Message Queue File System. May 25 16:53:04 volumio systemd[1]: Started Create list of required static device nodes for the current kernel. May 25 16:53:04 volumio systemd[1]: Started Restore / save the current clock. May 25 16:53:04 volumio systemd[1]: Time has been changed May 25 16:53:04 volumio systemd-modules-load[198]: Inserted module 'i2c_dev' May 25 16:53:04 volumio systemd[1]: Started Load Kernel Modules. May 25 16:53:02 volumio systemd[1]: Started udev Coldplug all Devices. May 25 16:53:02 volumio systemd[1]: Mounting FUSE Control File System... May 25 16:53:04 volumio systemd[1]: Started Remount Root and Kernel File Systems. May 25 16:53:04 volumio fake-hwclock[189]: Mon May 25 16:53:02 UTC 2020 May 25 16:53:04 volumio systemd[1]: Starting Various fixups to make systemd work better on Debian... May 25 16:53:04 volumio systemd[1]: Starting Load/Save Random Seed... May 25 16:53:04 volumio systemd[1]: Started Apply Kernel Variables. May 25 16:53:04 volumio systemd[1]: Started Create Static Device Nodes in /dev. May 25 16:53:04 volumio systemd[1]: Started Various fixups to make systemd work better on Debian. May 25 16:53:04 volumio systemd[1]: Started Load/Save Random Seed. May 25 16:53:05 volumio systemd[1]: Starting udev Kernel Device Manager... May 25 16:53:05 volumio systemd[1]: Starting Local File Systems (Pre). May 25 16:53:05 volumio systemd[1]: Reached target Local File Systems (Pre). May 25 16:53:05 volumio systemd[1]: Mounting /tmp... May 25 16:53:05 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. May 25 16:53:05 volumio systemd[1]: Mounting /var/spool/cups... May 25 16:53:05 volumio systemd[1]: Mounting /var/log... May 25 16:53:05 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 25 16:53:05 volumio systemd-udevd[233]: starting version 215 May 25 16:53:05 volumio systemd[1]: Mounted /tmp. May 25 16:53:05 volumio systemd[1]: Started udev Kernel Device Manager. May 25 16:53:05 volumio systemd[1]: Mounted /var/spool/cups. May 25 16:53:05 volumio systemd[1]: Mounted /var/log. May 25 16:53:05 volumio systemd[1]: Mounting /var/spool/cups/tmp... May 25 16:53:05 volumio systemd[1]: Starting Show Plymouth Boot Screen... May 25 16:53:05 volumio systemd[1]: Starting Copy rules generated while the root was ro... May 25 16:53:05 volumio systemd[1]: Mounted /var/spool/cups/tmp. May 25 16:53:05 volumio systemd[1]: Started Copy rules generated while the root was ro. May 25 16:53:05 volumio systemd-udevd[233]: invalid key/value pair in file /etc/udev/rules.d/92-gpio.rules on line 1,starting at character 226 (' ') May 25 16:53:05 volumio systemd[1]: Started Show Plymouth Boot Screen. May 25 16:53:05 volumio systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch. May 25 16:53:05 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. May 25 16:53:05 volumio systemd[1]: Started Dispatch Password Requests to Console Directory Watch. May 25 16:53:05 volumio systemd[1]: Starting Paths. May 25 16:53:05 volumio systemd[1]: Reached target Paths. May 25 16:53:05 volumio kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. May 25 16:53:05 volumio kernel: media: Linux media interface: v0.10 May 25 16:53:05 volumio kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver May 25 16:53:05 volumio kernel: [vc_sm_connected_init]: start May 25 16:53:05 volumio kernel: [vc_sm_connected_init]: installed successfully May 25 16:53:05 volumio kernel: videodev: Linux video capture interface: v2.00 May 25 16:53:05 volumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. May 25 16:53:05 volumio kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. May 25 16:53:05 volumio kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. May 25 16:53:05 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 May 25 16:53:05 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode May 25 16:53:05 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 May 25 16:53:05 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode May 25 16:53:05 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 May 25 16:53:05 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp May 25 16:53:05 volumio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. May 25 16:53:05 volumio kernel: snd_bcm2835: unknown parameter 'index' ignored May 25 16:53:05 volumio kernel: bcm2835_audio soc:audio: card created with 8 channels May 25 16:53:05 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database May 25 16:53:05 volumio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' May 25 16:53:05 volumio kernel: platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 May 25 16:53:05 volumio kernel: cfg80211: failed to load regulatory.db May 25 16:53:06 volumio systemd[1]: Found device /dev/mmcblk0p1. May 25 16:53:06 volumio systemd[1]: Starting File System Check on /dev/mmcblk0p1... May 25 16:53:06 volumio systemd[1]: Starting Sound Card. May 25 16:53:06 volumio systemd[1]: Reached target Sound Card. May 25 16:53:06 volumio kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 May 25 16:53:06 volumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 May 25 16:53:06 volumio kernel: usbcore: registered new interface driver brcmfmac May 25 16:53:06 volumio systemd[1]: Starting system-ifup.slice. May 25 16:53:06 volumio systemd[1]: Created slice system-ifup.slice. May 25 16:53:06 volumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 May 25 16:53:06 volumio kernel: brcmfmac: brcmf_c_process_clm_blob: no clm_blob available (err=-2), device may have limited channels available May 25 16:53:06 volumio kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Oct 22 2019 01:59:28 version 7.45.98.94 (r723000 CY) FWID 01-3b33decd May 25 16:53:06 volumio systemd[1]: Starting system-systemd\x2drfkill.slice. May 25 16:53:06 volumio systemd[1]: Created slice system-systemd\x2drfkill.slice. May 25 16:53:06 volumio systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill0... May 25 16:53:06 volumio systemd-fsck[389]: fsck.fat 3.0.27 (2014-11-12) May 25 16:53:06 volumio systemd-fsck[389]: Free cluster summary wrong (3865 vs. really 3863) May 25 16:53:06 volumio systemd-fsck[389]: Auto-correcting. May 25 16:53:06 volumio systemd-fsck[389]: Performing changes. May 25 16:53:06 volumio systemd-fsck[389]: /dev/mmcblk0p1: 229 files, 119181/123044 clusters May 25 16:53:06 volumio systemd[1]: Started File System Check on /dev/mmcblk0p1. May 25 16:53:06 volumio systemd[1]: Started Load/Save RF Kill Switch Status of rfkill0. May 25 16:53:06 volumio systemd[1]: Mounting /boot... May 25 16:53:06 volumio systemd[1]: Mounted /boot. May 25 16:53:06 volumio systemd[1]: Starting Local File Systems. May 25 16:53:06 volumio systemd[1]: Reached target Local File Systems. May 25 16:53:06 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 25 16:53:06 volumio systemd[1]: Starting Remote File Systems. May 25 16:53:06 volumio systemd[1]: Reached target Remote File Systems. May 25 16:53:06 volumio systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... May 25 16:53:06 volumio systemd[1]: Starting Create Volatile Files and Directories... May 25 16:53:06 volumio systemd[1]: Starting LSB: Raise network interfaces.... May 25 16:53:06 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 25 16:53:06 volumio systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. May 25 16:53:06 volumio systemd[1]: Started Create Volatile Files and Directories. May 25 16:53:06 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 25 16:53:06 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. May 25 16:53:07 volumio systemd[1]: Received SIGRTMIN+20 from PID 245 (plymouthd). May 25 16:53:07 volumio kernel: brcmfmac: power management disabled May 25 16:53:07 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 25 16:53:07 volumio kernel: brcmfmac: power management disabled May 25 16:53:07 volumio networking[425]: Configuring network interfaces...dhcpcd[522]: version 6.0.5 starting May 25 16:53:07 volumio dhcpcd[522]: version 6.0.5 starting May 25 16:53:08 volumio dhcpcd[522]: eth0: soliciting a DHCP lease May 25 16:53:08 volumio networking[425]: dhcpcd[522]: eth0: soliciting a DHCP lease May 25 16:53:08 volumio dhcpcd[522]: eth0: carrier lost May 25 16:53:08 volumio networking[425]: dhcpcd[522]: eth0: carrier lost May 25 16:53:09 volumio kernel: smsc95xx 1-1.1:1.0 eth0: link up, 100Mbps, full-duplex, lpa 0xC1E1 May 25 16:53:09 volumio networking[425]: dhcpcd[522]: eth0: carrier acquired May 25 16:53:09 volumio dhcpcd[522]: eth0: carrier acquired May 25 16:53:09 volumio dhcpcd[522]: eth0: soliciting a DHCP lease May 25 16:53:09 volumio networking[425]: dhcpcd[522]: eth0: soliciting a DHCP lease May 25 16:53:10 volumio dhcpcd[522]: eth0: offered 192.168.1.20 from 192.168.1.1 May 25 16:53:10 volumio networking[425]: dhcpcd[522]: eth0: offered 192.168.1.20 from 192.168.1.1 May 25 16:53:10 volumio networking[425]: dhcpcd[522]: eth0: leased 192.168.1.20 for 86400 seconds May 25 16:53:10 volumio dhcpcd[522]: eth0: leased 192.168.1.20 for 86400 seconds May 25 16:53:10 volumio dhcpcd[522]: eth0: adding host route to 192.168.1.20 via 127.0.0.1 May 25 16:53:10 volumio dhcpcd[522]: eth0: adding route to 192.168.1.0/24 May 25 16:53:10 volumio dhcpcd[522]: eth0: adding default route via 192.168.1.1 May 25 16:53:10 volumio networking[425]: dhcpcd[522]: eth0: adding host route to 192.168.1.20 via 127.0.0.1 May 25 16:53:10 volumio networking[425]: dhcpcd[522]: eth0: adding route to 192.168.1.0/24 May 25 16:53:10 volumio networking[425]: dhcpcd[522]: eth0: adding default route via 192.168.1.1 May 25 16:53:11 volumio networking[425]: dhcpcd[522]: forked to background, child pid 587 May 25 16:53:11 volumio dhcpcd[522]: forked to background, child pid 587 May 25 16:53:11 volumio networking[425]: done. May 25 16:53:11 volumio systemd[1]: Started LSB: Raise network interfaces.. May 25 16:53:11 volumio systemd[1]: Starting ifup for wlan0... May 25 16:53:11 volumio systemd[1]: Started ifup for wlan0. May 25 16:53:11 volumio systemd[1]: Starting ifup for eth0... May 25 16:53:11 volumio systemd[1]: Started ifup for eth0. May 25 16:53:11 volumio systemd[1]: Starting Network. May 25 16:53:11 volumio systemd[1]: Reached target Network. May 25 16:53:11 volumio systemd[1]: Starting Network is Online. May 25 16:53:11 volumio systemd[1]: Reached target Network is Online. May 25 16:53:11 volumio systemd[1]: Starting LSB: RPC portmapper replacement... May 25 16:53:11 volumio ifup[634]: /sbin/ifup: interface eth0 already configured May 25 16:53:11 volumio rpcbind[638]: Starting rpcbind daemon.... May 25 16:53:11 volumio systemd[1]: Started LSB: RPC portmapper replacement. May 25 16:53:11 volumio systemd[1]: Starting RPC Port Mapper. May 25 16:53:11 volumio systemd[1]: Reached target RPC Port Mapper. May 25 16:53:11 volumio systemd[1]: Starting LSB: NFS support files common to client and server... May 25 16:53:12 volumio rpc.statd[718]: Version 1.2.8 starting May 25 16:53:12 volumio sm-notify[719]: Version 1.2.8 starting May 25 16:53:12 volumio rpc.statd[718]: Failed to read /var/lib/nfs/state: Success May 25 16:53:12 volumio rpc.statd[718]: Initializing NSM state May 25 16:53:12 volumio kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). May 25 16:53:12 volumio nfs-common[683]: Starting NFS common utilities: statd idmapd. May 25 16:53:12 volumio systemd[1]: Started LSB: NFS support files common to client and server. May 25 16:53:12 volumio systemd[1]: Starting System Initialization. May 25 16:53:12 volumio systemd[1]: Reached target System Initialization. May 25 16:53:12 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. May 25 16:53:12 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 25 16:53:12 volumio systemd[1]: Starting mpd.socket. May 25 16:53:12 volumio systemd[1]: Listening on mpd.socket. May 25 16:53:12 volumio systemd[1]: Starting D-Bus System Message Bus Socket. May 25 16:53:12 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. May 25 16:53:12 volumio systemd[1]: Starting Sockets. May 25 16:53:12 volumio systemd[1]: Reached target Sockets. May 25 16:53:12 volumio systemd[1]: Starting Daily Cleanup of Temporary Directories. May 25 16:53:12 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. May 25 16:53:12 volumio systemd[1]: Starting Timers. May 25 16:53:12 volumio systemd[1]: Reached target Timers. May 25 16:53:12 volumio systemd[1]: Started Manage Sound Card State (restore and store). May 25 16:53:12 volumio systemd[1]: Starting Restore Sound Card State... May 25 16:53:12 volumio systemd[1]: Starting Basic System. May 25 16:53:12 volumio systemd[1]: Reached target Basic System. May 25 16:53:12 volumio systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... May 25 16:53:12 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. May 25 16:53:12 volumio systemd[1]: Starting cd.service... May 25 16:53:12 volumio systemd[1]: Started cd.service. May 25 16:53:12 volumio systemd[1]: Starting Configure Bluetooth Modems connected by UART... May 25 16:53:12 volumio systemd[1]: Starting Volumio Iptables Module... May 25 16:53:12 volumio systemd[1]: Started Volumio Iptables Module. May 25 16:53:12 volumio systemd[1]: Starting mount disks automatically with standby... May 25 16:53:12 volumio systemd[1]: Started mount disks automatically with standby. May 25 16:53:12 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... May 25 16:53:12 volumio systemd[1]: Started UPnP Renderer front-end to MPD. May 25 16:53:12 volumio systemd[1]: Starting volumio-remote-updater.service... May 25 16:53:12 volumio systemd[1]: Started volumio-remote-updater.service. May 25 16:53:12 volumio systemd[1]: Starting Volumio Log Rotation Service... May 25 16:53:12 volumio systemd[1]: Started Volumio Log Rotation Service. May 25 16:53:12 volumio systemd[1]: Starting Volumio SSH enabler... May 25 16:53:12 volumio systemd[1]: Started Volumio SSH enabler. May 25 16:53:12 volumio systemd[1]: Starting Wireless Services... May 25 16:53:12 volumio systemd[1]: Starting Xbindrc... May 25 16:53:12 volumio systemd[1]: Started Xbindrc. May 25 16:53:12 volumio systemd[1]: Starting /etc/rc.local Compatibility... May 25 16:53:12 volumio systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. May 25 16:53:12 volumio systemd[1]: Starting Login Service... May 25 16:53:12 volumio systemd[1]: Starting LSB: start Winbind daemon... May 25 16:53:12 volumio systemd[1]: Starting LSB: start Samba daemons for the AD DC... May 25 16:53:12 volumio systemd[1]: Starting LSB: Start NTP daemon... May 25 16:53:12 volumio systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... May 25 16:53:12 volumio kernel: uart-pl011 3f201000.serial: no DMA platform data May 25 16:53:12 volumio alsactl[735]: No state is present for card ALSA May 25 16:53:12 volumio alsactl[735]: Found hardware: "bcm2835_alsa" "Broadcom Mixer" "" "" "" May 25 16:53:12 volumio alsactl[735]: Hardware is initialized using a generic method May 25 16:53:12 volumio alsactl[735]: No state is present for card ALSA May 25 16:53:12 volumio volumio[737]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 25 16:53:12 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 25 16:53:12 volumio systemd[1]: Starting LSB: Brings up/down network automatically... May 25 16:53:12 volumio systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... May 25 16:53:12 volumio systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... May 25 16:53:12 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 25 16:53:12 volumio systemd[1]: Starting Music Player Daemon... May 25 16:53:12 volumio systemd[1]: Starting D-Bus System Message Bus... May 25 16:53:12 volumio systemd[1]: Started D-Bus System Message Bus. May 25 16:53:12 volumio dhcpcd[793]: Not running dhcpcd because there is aleady an interface specific instance ... failed! May 25 16:53:12 volumio dhcpcd[793]: /var/run/dhcpcd-eth0.pid ... failed! May 25 16:53:13 volumio ifplugd(eth0)[850]: ifplugd 0.28 initializing. May 25 16:53:13 volumio ifplugd(eth0)[850]: Using interface eth0/B8:27:EB:9E:20:38 with driver (version: 22-Aug-2005) May 25 16:53:13 volumio ifplugd(eth0)[850]: Using detection mode: SIOCETHTOOL May 25 16:53:13 volumio ifplugd(eth0)[850]: Initialization complete, link beat detected. May 25 16:53:13 volumio ifplugd(eth0)[850]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. May 25 16:53:13 volumio loadcpufreq[785]: Loading cpufreq kernel modules...done (none). May 25 16:53:13 volumio avahi-daemon[801]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). May 25 16:53:13 volumio avahi-daemon[801]: Successfully dropped root privileges. May 25 16:53:13 volumio avahi-daemon[801]: avahi-daemon 0.6.31 starting up. May 25 16:53:13 volumio ifplugd(eth0)[850]: client: /sbin/ifup: interface eth0 already configured May 25 16:53:13 volumio ntpd[842]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) May 25 16:53:13 volumio ntp[782]: Starting NTP server: ntpd. May 25 16:53:13 volumio ntpd[875]: proto: precision = 1.042 usec May 25 16:53:13 volumio ntpd[875]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 May 25 16:53:13 volumio sudo[769]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off May 25 16:53:13 volumio ntpd[875]: Listen and drop on 1 v6wildcard :: UDP 123 May 25 16:53:13 volumio ntpd[875]: Listen normally on 2 lo 127.0.0.1 UDP 123 May 25 16:53:13 volumio ntpd[875]: Listen normally on 3 eth0 192.168.1.20 UDP 123 May 25 16:53:13 volumio ntpd[875]: peers refreshed May 25 16:53:13 volumio ntpd[875]: Listening on routing socket on fd #20 for interface updates May 25 16:53:13 volumio ifplugd(eth0)[850]: Program executed successfully. May 25 16:53:13 volumio sudo[769]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 16:53:13 volumio ifplugd[786]: Network Interface Plugging Daemon...start eth0...done. May 25 16:53:13 volumio avahi-daemon[801]: Successfully called chroot(). May 25 16:53:13 volumio avahi-daemon[801]: Successfully dropped remaining capabilities. May 25 16:53:13 volumio sudo[769]: pam_unix(sudo:session): session closed for user root May 25 16:53:13 volumio avahi-daemon[801]: No service file found in /etc/avahi/services. May 25 16:53:13 volumio dbus[804]: [system] Successfully activated service 'org.freedesktop.systemd1' May 25 16:53:13 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 25 16:53:13 volumio systemd[1]: Starting Permit User Sessions... May 25 16:53:13 volumio avahi-daemon[801]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.20. May 25 16:53:13 volumio avahi-daemon[801]: New relevant interface eth0.IPv4 for mDNS. May 25 16:53:13 volumio avahi-daemon[801]: Network interface enumeration completed. May 25 16:53:13 volumio avahi-daemon[801]: Registering new address record for 192.168.1.20 on eth0.IPv4. May 25 16:53:13 volumio dbus[804]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' May 25 16:53:13 volumio avahi-daemon[801]: Registering HINFO record with values 'ARMV7L'/'LINUX'. May 25 16:53:13 volumio systemd[1]: Started Restore Sound Card State. May 25 16:53:13 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 25 16:53:13 volumio systemd[1]: Unit cd.service entered failed state. May 25 16:53:13 volumio systemd[1]: Started /etc/rc.local Compatibility. May 25 16:53:13 volumio systemd[1]: Started LSB: Start NTP daemon. May 25 16:53:13 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 25 16:53:13 volumio systemd[1]: Started LSB: Brings up/down network automatically. May 25 16:53:13 volumio systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. May 25 16:53:13 volumio systemd[1]: dhcpcd.service: control process exited, code=exited status=6 May 25 16:53:13 volumio systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. May 25 16:53:13 volumio systemd[1]: Unit dhcpcd.service entered failed state. May 25 16:53:13 volumio systemd[1]: Started Permit User Sessions. May 25 16:53:13 volumio systemd[1]: cd.service holdoff time over, scheduling restart. May 25 16:53:14 volumio systemd-logind[777]: New seat seat0. May 25 16:53:14 volumio systemd[1]: Started Login Service. May 25 16:53:14 volumio systemd[1]: Starting Disk Manager (legacy version)... May 25 16:53:14 volumio systemd[1]: Stopping cd.service... May 25 16:53:14 volumio systemd[1]: Starting cd.service... May 25 16:53:14 volumio systemd[1]: Started cd.service. May 25 16:53:14 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 25 16:53:14 volumio systemd[1]: Starting Hold until boot process finishes up... May 25 16:53:14 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... May 25 16:53:14 volumio volumio[892]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 25 16:53:14 volumio volumio-remote-updater[746]: Error: No active session May 25 16:53:14 volumio volumio-remote-updater[746]: [2020-05-25 16:53:14] [info] asio async_connect error: system:111 (Connection refused) May 25 16:53:14 volumio volumio-remote-updater[746]: [2020-05-25 16:53:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 25 16:53:14 volumio volumio-remote-updater[746]: [2020-05-25 16:53:14] [error] handle_connect error: Underlying Transport Error May 25 16:53:14 volumio systemd[1]: Received SIGRTMIN+21 from PID 245 (plymouthd). May 25 16:53:14 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 25 16:53:14 volumio systemd[1]: Unit cd.service entered failed state. May 25 16:53:14 volumio systemd[1]: Started Hold until boot process finishes up. May 25 16:53:14 volumio systemd[1]: Started Terminate Plymouth Boot Screen. May 25 16:53:14 volumio systemd[1]: cd.service holdoff time over, scheduling restart. May 25 16:53:14 volumio volumio[745]: Could not open config: /tmp/upmpdcli.conf May 25 16:53:14 volumio systemd[1]: Stopping cd.service... May 25 16:53:14 volumio systemd[1]: Starting cd.service... May 25 16:53:14 volumio systemd[1]: Started cd.service. May 25 16:53:14 volumio systemd[1]: Starting Getty on tty1... May 25 16:53:14 volumio systemd[1]: Started Getty on tty1. May 25 16:53:14 volumio systemd[1]: Starting Login Prompts. May 25 16:53:14 volumio systemd[1]: Reached target Login Prompts. May 25 16:53:14 volumio dbus[804]: [system] Successfully activated service 'org.freedesktop.UDisks' May 25 16:53:14 volumio systemd[1]: Started Disk Manager (legacy version). May 25 16:53:14 volumio volumio[910]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 25 16:53:14 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 25 16:53:14 volumio cpufrequtils[893]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. May 25 16:53:14 volumio systemd[1]: Unit cd.service entered failed state. May 25 16:53:14 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. May 25 16:53:14 volumio systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE May 25 16:53:14 volumio systemd[1]: Unit upmpdcli.service entered failed state. May 25 16:53:14 volumio dbus[804]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' May 25 16:53:14 volumio systemd[1]: cd.service holdoff time over, scheduling restart. May 25 16:53:14 volumio avahi-daemon[801]: Server startup complete. Host name is volumio.local. Local service cookie is 1438781728. May 25 16:53:14 volumio systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... May 25 16:53:14 volumio systemd[1]: Stopping cd.service... May 25 16:53:14 volumio systemd[1]: Starting cd.service... May 25 16:53:14 volumio systemd[1]: Started cd.service. May 25 16:53:14 volumio volumio[922]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 25 16:53:14 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 25 16:53:14 volumio systemd[1]: Unit cd.service entered failed state. May 25 16:53:14 volumio polkitd[921]: started daemon version 0.105 using authority implementation `local' version `0.105' May 25 16:53:14 volumio dbus[804]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 25 16:53:14 volumio systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. May 25 16:53:14 volumio udisks-glue[744]: Device file /dev/mmcblk0 inserted May 25 16:53:14 volumio udisks-glue[744]: Device file /dev/mmcblk0p3 inserted May 25 16:53:14 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 25 16:53:14 volumio systemd[1]: cd.service holdoff time over, scheduling restart. May 25 16:53:14 volumio systemd[1]: Stopping cd.service... May 25 16:53:14 volumio systemd[1]: Starting cd.service... May 25 16:53:14 volumio systemd[1]: Started cd.service. May 25 16:53:14 volumio systemd[1]: Stopping Xbindrc... May 25 16:53:14 volumio systemd[1]: Starting Xbindrc... May 25 16:53:14 volumio systemd[1]: Started Xbindrc. May 25 16:53:14 volumio systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 25 16:53:14 volumio systemd[1]: Unit cd.service entered failed state. May 25 16:53:14 volumio volumio[928]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 25 16:53:15 volumio systemd[1]: cd.service holdoff time over, scheduling restart. May 25 16:53:15 volumio systemd[1]: Stopping cd.service... May 25 16:53:15 volumio systemd[1]: Starting cd.service... May 25 16:53:15 volumio systemd[1]: cd.service start request repeated too quickly, refusing to start. May 25 16:53:15 volumio systemd[1]: Failed to start cd.service. May 25 16:53:15 volumio systemd[1]: Unit cd.service entered failed state. May 25 16:53:15 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 25 16:53:15 volumio systemd[1]: Stopping Xbindrc... May 25 16:53:15 volumio systemd[1]: Starting Xbindrc... May 25 16:53:15 volumio systemd[1]: Started Xbindrc. May 25 16:53:15 volumio haveged[736]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K May 25 16:53:15 volumio haveged[736]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244 May 25 16:53:15 volumio haveged[736]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00299 May 25 16:53:15 volumio haveged[736]: haveged: fills: 0, generated: 0 May 25 16:53:15 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 25 16:53:15 volumio systemd[1]: Stopping Xbindrc... May 25 16:53:15 volumio systemd[1]: Starting Xbindrc... May 25 16:53:15 volumio systemd[1]: Started Xbindrc. May 25 16:53:15 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 25 16:53:15 volumio systemd[1]: Stopping Xbindrc... May 25 16:53:15 volumio systemd[1]: Starting Xbindrc... May 25 16:53:15 volumio systemd[1]: Started Xbindrc. May 25 16:53:15 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 25 16:53:15 volumio systemd[1]: Stopping Xbindrc... May 25 16:53:15 volumio systemd[1]: Starting Xbindrc... May 25 16:53:15 volumio systemd[1]: xbindkeysrc.service start request repeated too quickly, refusing to start. May 25 16:53:15 volumio systemd[1]: Failed to start Xbindrc. May 25 16:53:15 volumio systemd[1]: Unit xbindkeysrc.service entered failed state. May 25 16:53:16 volumio wireless.js[887]: Cleaning previous... May 25 16:53:16 volumio mpd[802]: May 25 16:53 : exception: Database corrupted May 25 16:53:16 volumio systemd[1]: Stopped hotspot.service. May 25 16:53:16 volumio systemd[1]: Started Music Player Daemon. May 25 16:53:16 volumio sudo[965]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 25 16:53:16 volumio sudo[965]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 16:53:16 volumio sudo[965]: pam_unix(sudo:session): session closed for user root May 25 16:53:16 volumio sudo[973]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 25 16:53:16 volumio sudo[973]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 16:53:17 volumio systemd[1]: Started LSB: start Samba daemons for the AD DC. May 25 16:53:17 volumio winbind[778]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory May 25 16:53:17 volumio winbind[778]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 25 16:53:17 volumio winbind[778]: Unable to setup corepath for winbindd: No such file or directory May 25 16:53:17 volumio winbind[778]: mkdir failed on directory /var/log/samba/cores: No such file or directory May 25 16:53:17 volumio winbind[778]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 25 16:53:17 volumio winbind[778]: Unable to setup corepath for winbindd: No such file or directory May 25 16:53:17 volumio winbind[778]: [2020/05/25 16:53:17, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:17 volumio winbind[778]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 25 16:53:17 volumio winbind[778]: [2020/05/25 16:53:17, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:17 volumio winbind[778]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 25 16:53:17 volumio winbind[778]: [2020/05/25 16:53:17, 0] ../source3/winbindd/winbindd.c:1549(main) May 25 16:53:17 volumio winbind[778]: winbindd version 4.2.14-Debian started. May 25 16:53:17 volumio winbind[778]: Copyright Andrew Tridgell and the Samba Team 1992-2014 May 25 16:53:17 volumio winbind[778]: [2020/05/25 16:53:17, 0] ../lib/util/util.c:220(directory_create_or_exist) May 25 16:53:17 volumio winbind[778]: mkdir failed on directory /var/log/samba/cores: No such file or directory May 25 16:53:17 volumio winbind[778]: [2020/05/25 16:53:17, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) May 25 16:53:17 volumio winbind[778]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 25 16:53:17 volumio winbind[778]: [2020/05/25 16:53:17, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) May 25 16:53:17 volumio winbind[778]: Unable to setup corepath for winbindd: No such file or directory May 25 16:53:17 volumio kernel: Bluetooth: Core ver 2.22 May 25 16:53:17 volumio kernel: NET: Registered protocol family 31 May 25 16:53:17 volumio kernel: Bluetooth: HCI device and connection manager initialized May 25 16:53:17 volumio kernel: Bluetooth: HCI socket layer initialized May 25 16:53:17 volumio kernel: Bluetooth: L2CAP socket layer initialized May 25 16:53:17 volumio kernel: Bluetooth: SCO socket layer initialized May 25 16:53:17 volumio winbindd[986]: [2020/05/25 16:53:17.256232, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:17 volumio winbindd[986]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 25 16:53:17 volumio winbindd[986]: [2020/05/25 16:53:17.257408, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:17 volumio winbindd[986]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 25 16:53:17 volumio winbind[778]: [2020/05/25 16:53:17.256232, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:17 volumio winbind[778]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 25 16:53:17 volumio winbind[778]: [2020/05/25 16:53:17.257408, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:17 volumio winbind[778]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 25 16:53:17 volumio nmbd[783]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory May 25 16:53:17 volumio nmbd[783]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 25 16:53:17 volumio nmbd[783]: Unable to setup corepath for nmbd: No such file or directory May 25 16:53:17 volumio nmbd[989]: [2020/05/25 16:53:17.265927, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:17 volumio nmbd[989]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 25 16:53:17 volumio nmbd[989]: [2020/05/25 16:53:17.267378, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:17 volumio nmbd[989]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 25 16:53:17 volumio nmbd[783]: [2020/05/25 16:53:17, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:17 volumio nmbd[783]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 25 16:53:17 volumio nmbd[783]: [2020/05/25 16:53:17, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:17 volumio nmbd[783]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 25 16:53:17 volumio nmbd[783]: [2020/05/25 16:53:17, 0] ../source3/nmbd/nmbd.c:908(main) May 25 16:53:17 volumio nmbd[783]: nmbd version 4.2.14-Debian started. May 25 16:53:17 volumio nmbd[783]: Copyright Andrew Tridgell and the Samba Team 1992-2014 May 25 16:53:17 volumio nmbd[783]: [2020/05/25 16:53:17.265927, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:17 volumio nmbd[783]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 25 16:53:17 volumio nmbd[783]: [2020/05/25 16:53:17.267378, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:17 volumio nmbd[783]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 25 16:53:17 volumio nmbd[994]: [2020/05/25 16:53:17.270780, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) May 25 16:53:17 volumio nmbd[994]: started asyncdns process 995 May 25 16:53:17 volumio systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). May 25 16:53:17 volumio systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... May 25 16:53:17 volumio nmbd[783]: . May 25 16:53:17 volumio btuart[738]: bcm43xx_init May 25 16:53:17 volumio btuart[738]: Flash firmware /lib/firmware/BCM43430A1.hcd May 25 16:53:17 volumio btuart[738]: Set BDADDR UART: b8:27:eb:34:8a:92 May 25 16:53:17 volumio btuart[738]: Set Controller UART speed to 460800 bit/s May 25 16:53:17 volumio btuart[738]: Device setup complete May 25 16:53:17 volumio systemd[1]: Started Configure Bluetooth Modems connected by UART. May 25 16:53:17 volumio systemd-udevd[1002]: failed to execute '/usr/bin/hciconfig' '/usr/bin/hciconfig hci0 up': No such file or directory May 25 16:53:17 volumio kernel: Bluetooth: HCI UART driver ver 2.3 May 25 16:53:17 volumio kernel: Bluetooth: HCI UART protocol H4 registered May 25 16:53:17 volumio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered May 25 16:53:17 volumio kernel: Bluetooth: HCI UART protocol Broadcom registered May 25 16:53:17 volumio sudo[973]: pam_unix(sudo:session): session closed for user root May 25 16:53:17 volumio nmbd[994]: [2020/05/25 16:53:17.307504, 0] ../lib/util/become_daemon.c:124(daemon_ready) May 25 16:53:17 volumio nmbd[994]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 25 16:53:17 volumio winbindd[1009]: [2020/05/25 16:53:17.312315, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) May 25 16:53:17 volumio winbindd[1009]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 25 16:53:17 volumio systemd[1]: Started LSB: start Winbind daemon. May 25 16:53:17 volumio winbind[778]: . May 25 16:53:17 volumio nmbd[994]: [2020/05/25 16:53:17.322221, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 25 16:53:17 volumio nmbd[994]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.20 for name WORKGROUP<1d>. May 25 16:53:17 volumio nmbd[994]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. May 25 16:53:17 volumio systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill1... May 25 16:53:17 volumio systemd[1]: Starting Bluetooth service... May 25 16:53:17 volumio systemd[1]: Started Load/Save RF Kill Switch Status of rfkill1. May 25 16:53:17 volumio wireless.js[887]: Stopped aP May 25 16:53:17 volumio bluetoothd[1016]: Bluetooth daemon 5.23 May 25 16:53:17 volumio systemd[1]: Started Bluetooth service. May 25 16:53:17 volumio systemd[1]: Starting Bluetooth. May 25 16:53:17 volumio systemd[1]: Reached target Bluetooth. May 25 16:53:17 volumio bluetoothd[1016]: Starting SDP server May 25 16:53:17 volumio bluetoothd[1016]: Excluding (cli) sap May 25 16:53:17 volumio systemd[1]: Stopped hotspot.service. May 25 16:53:17 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 May 25 16:53:17 volumio kernel: Bluetooth: BNEP filters: protocol multicast May 25 16:53:17 volumio kernel: Bluetooth: BNEP socket layer initialized May 25 16:53:17 volumio dbus[804]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' May 25 16:53:17 volumio bluetoothd[1016]: Bluetooth management interface 1.14 initialized May 25 16:53:17 volumio systemd[1]: Starting Hostname Service... May 25 16:53:17 volumio sudo[1025]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 25 16:53:17 volumio sudo[1025]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 16:53:17 volumio sudo[1025]: pam_unix(sudo:session): session closed for user root May 25 16:53:17 volumio systemd-hostnamed[1024]: Warning: nss-myhostname is not installed. Changing the local hostname might make it unresolveable. Please install nss-myhostname! May 25 16:53:17 volumio dbus[804]: [system] Successfully activated service 'org.freedesktop.hostname1' May 25 16:53:17 volumio sudo[1033]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 25 16:53:17 volumio sudo[1033]: pam_unix(sudo:session): session opened for user root by (uid=0) May 25 16:53:17 volumio sudo[1033]: pam_unix(sudo:session): session closed for user root May 25 16:53:17 volumio avahi-daemon[801]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. May 25 16:53:17 volumio kernel: brcmfmac: power management disabled May 25 16:53:17 volumio avahi-daemon[801]: New relevant interface wlan0.IPv4 for mDNS. May 25 16:53:17 volumio avahi-daemon[801]: Registering new address record for 192.168.211.1 on wlan0.IPv4. May 25 16:53:17 volumio systemd[1]: Started Hostname Service. May 25 16:53:17 volumio systemd[1]: Starting hotspot.service... May 25 16:53:17 volumio systemd[1]: Started hotspot.service. May 25 16:53:17 volumio systemd[1]: Starting dhcpd.service... May 25 16:53:17 volumio systemd[1]: Starting dnsmasq.service... May 25 16:53:18 volumio systemd[1]: Started Wireless Services. May 25 16:53:18 volumio systemd[1]: Starting Volumio Backend Module... May 25 16:53:18 volumio systemd[1]: Started Volumio Backend Module. May 25 16:53:18 volumio systemd[1]: Starting First Start... May 25 16:53:18 volumio systemd[1]: Started First Start. May 25 16:53:18 volumio systemd[1]: Starting Volumio Streaming Daemon... May 25 16:53:18 volumio systemd[1]: Started Volumio Streaming Daemon. May 25 16:53:18 volumio dnsmasq[1062]: started, version 2.72 cachesize 150 May 25 16:53:18 volumio dnsmasq[1062]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect May 25 16:53:18 volumio dnsmasq[1062]: reading /etc/resolv.conf May 25 16:53:18 volumio dnsmasq[1062]: using nameserver 192.168.1.1#53 May 25 16:53:18 volumio dnsmasq[1062]: using nameserver 208.67.222.222#53 May 25 16:53:18 volumio dnsmasq[1062]: using nameserver 208.67.220.220#53 May 25 16:53:18 volumio dnsmasq[1062]: read /etc/hosts - 4 addresses May 25 16:53:18 volumio systemd[1]: Started dnsmasq.service. May 25 16:53:18 volumio systemd[1]: Starting Host and Network Name Lookups. May 25 16:53:18 volumio systemd[1]: Reached target Host and Network Name Lookups. May 25 16:53:18 volumio firststart.sh[1054]: Volumio first start configuration script May 25 16:53:18 volumio firststart.sh[1054]: configuring unconfigured packages May 25 16:53:18 volumio winbindd[1009]: [2020/05/25 16:53:18.888947, 0] ../lib/util/become_daemon.c:124(daemon_ready) May 25 16:53:18 volumio winbindd[1009]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 25 16:53:18 volumio winbindd[1064]: [2020/05/25 16:53:18.894471, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:18 volumio winbindd[1064]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO': No such file or directory May 25 16:53:18 volumio smbd[996]: Starting SMB/CIFS daemon: smbd[2020/05/25 16:53:18, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:18 volumio smbd[996]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 25 16:53:18 volumio smbd[996]: [2020/05/25 16:53:18, 0] ../lib/util/util.c:220(directory_create_or_exist) May 25 16:53:18 volumio smbd[996]: mkdir failed on directory /var/log/samba/cores: No such file or directory May 25 16:53:18 volumio smbd[996]: [2020/05/25 16:53:18, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) May 25 16:53:18 volumio smbd[996]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 25 16:53:18 volumio smbd[996]: [2020/05/25 16:53:18, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) May 25 16:53:18 volumio smbd[996]: Unable to setup corepath for smbd: No such file or directory May 25 16:53:18 volumio smbd[996]: [2020/05/25 16:53:18, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:18 volumio smbd[996]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 25 16:53:18 volumio smbd[996]: [2020/05/25 16:53:18, 0] ../source3/smbd/server.c:1241(main) May 25 16:53:18 volumio smbd[996]: smbd version 4.2.14-Debian started. May 25 16:53:18 volumio smbd[996]: Copyright Andrew Tridgell and the Samba Team 1992-2014 May 25 16:53:19 volumio smbd[1051]: [2020/05/25 16:53:19.008423, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:19 volumio smbd[1051]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 25 16:53:19 volumio smbd[1051]: [2020/05/25 16:53:19.010000, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:19 volumio smbd[1051]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 25 16:53:19 volumio smbd[1051]: [2020/05/25 16:53:19.010210, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:19 volumio smbd[1051]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 25 16:53:19 volumio smbd[996]: [2020/05/25 16:53:19.008423, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:19 volumio smbd[996]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 25 16:53:19 volumio smbd[996]: [2020/05/25 16:53:19.010000, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:19 volumio smbd[996]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 25 16:53:19 volumio smbd[996]: [2020/05/25 16:53:19.010210, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 25 16:53:19 volumio smbd[996]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 25 16:53:19 volumio smbd[996]: . May 25 16:53:19 volumio systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). May 25 16:53:19 volumio systemd[1]: Starting Multi-User System. May 25 16:53:19 volumio systemd[1]: Reached target Multi-User System. May 25 16:53:19 volumio systemd[1]: Starting Graphical Interface. May 25 16:53:19 volumio systemd[1]: Reached target Graphical Interface. May 25 16:53:19 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... May 25 16:53:19 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. May 25 16:53:19 volumio dhcpd[1046]: Internet Systems Consortium DHCP Server 4.3.1 May 25 16:53:19 volumio dhcpd[1046]: Copyright 2004-2014 Internet Systems Consortium. May 25 16:53:19 volumio dhcpd[1046]: All rights reserved. May 25 16:53:19 volumio dhcpd[1046]: For info, please visit https://www.isc.org/software/dhcp/ May 25 16:53:19 volumio dhcpd[1046]: Config file: /etc/dhcp/dhcpd.conf May 25 16:53:19 volumio dhcpd[1046]: Database file: /var/lib/dhcp/dhcpd.leases May 25 16:53:19 volumio dhcpd[1046]: PID file: /var/run/dhcpd.pid May 25 16:53:19 volumio hotspot[1046]: Internet Systems Consortium DHCP Server 4.3.1 May 25 16:53:19 volumio hotspot[1046]: Copyright 2004-2014 Internet Systems Consortium. May 25 16:53:19 volumio hotspot[1046]: All rights reserved. May 25 16:53:19 volumio hotspot[1046]: For info, please visit https://www.isc.org/software/dhcp/ May 25 16:53:19 volumio hotspot[1046]: Config file: /etc/dhcp/dhcpd.conf May 25 16:53:19 volumio hotspot[1046]: Database file: /var/lib/dhcp/dhcpd.leases May 25 16:53:19 volumio hotspot[1046]: PID file: /var/run/dhcpd.pid May 25 16:53:19 volumio volumio-remote-updater[746]: [2020-05-25 16:53:19] [info] asio async_connect error: system:111 (Connection refused) May 25 16:53:19 volumio volumio-remote-updater[746]: [2020-05-25 16:53:19] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 25 16:53:19 volumio volumio-remote-updater[746]: [2020-05-25 16:53:19] [error] handle_connect error: Underlying Transport Error May 25 16:53:19 volumio firststart.sh[1054]: Creating /var/log/samba folder May 25 16:53:19 volumio firststart.sh[1054]: Creating /boot/userconfig.txt May 25 16:53:19 volumio ntpd[875]: Listen normally on 4 wlan0 192.168.211.1 UDP 123 May 25 16:53:19 volumio ntpd[875]: peers refreshed May 25 16:53:19 volumio dhcpd[1046]: Internet Systems Consortium DHCP Server 4.3.1 May 25 16:53:19 volumio dhcpd[1046]: Copyright 2004-2014 Internet Systems Consortium. May 25 16:53:19 volumio dhcpd[1046]: All rights reserved. May 25 16:53:19 volumio dhcpd[1046]: For info, please visit https://www.isc.org/software/dhcp/ May 25 16:53:19 volumio dhcpd[1046]: Wrote 0 leases to leases file. May 25 16:53:19 volumio hotspot[1046]: Wrote 0 leases to leases file. May 25 16:53:19 volumio hotspot[1045]: Launching Hostapd 2.8 May 25 16:53:19 volumio dhcpd[1046]: May 25 16:53:19 volumio dhcpd[1046]: No subnet declaration for eth0 (192.168.1.20). May 25 16:53:19 volumio dhcpd[1046]: ** Ignoring requests on eth0. If this is not what May 25 16:53:19 volumio dhcpd[1046]: you want, please write a subnet declaration May 25 16:53:19 volumio dhcpd[1046]: in your dhcpd.conf file for the network segment May 25 16:53:19 volumio dhcpd[1046]: to which interface eth0 is attached. ** May 25 16:53:19 volumio dhcpd[1046]: May 25 16:53:19 volumio hotspot[1046]: No subnet declaration for eth0 (192.168.1.20). May 25 16:53:19 volumio hotspot[1046]: ** Ignoring requests on eth0. If this is not what May 25 16:53:19 volumio hotspot[1046]: you want, please write a subnet declaration May 25 16:53:19 volumio hotspot[1046]: in your dhcpd.conf file for the network segment May 25 16:53:19 volumio hotspot[1046]: to which interface eth0 is attached. ** May 25 16:53:19 volumio dhcpd[1046]: Listening on LPF/wlan0/b8:27:eb:cb:75:6d/192.168.211.0/24 May 25 16:53:19 volumio dhcpd[1046]: Sending on LPF/wlan0/b8:27:eb:cb:75:6d/192.168.211.0/24 May 25 16:53:19 volumio dhcpd[1046]: Sending on Socket/fallback/fallback-net May 25 16:53:19 volumio dhcpd[1089]: Server starting service. May 25 16:53:19 volumio systemd[1]: Started dhcpd.service. May 25 16:53:19 volumio systemd[1]: Startup finished in 1min 30.583s (kernel) + 19.562s (userspace) = 1min 50.145s. May 25 16:53:19 volumio hotspot[1046]: Listening on LPF/wlan0/b8:27:eb:cb:75:6d/192.168.211.0/24 May 25 16:53:19 volumio hotspot[1046]: Sending on LPF/wlan0/b8:27:eb:cb:75:6d/192.168.211.0/24 May 25 16:53:19 volumio hotspot[1046]: Sending on Socket/fallback/fallback-net May 25 16:53:19 volumio hotspot[1045]: Configuration file: /etc/hostapd/hostapd.conf May 25 16:53:19 volumio hotspot[1045]: wlan0: Could not connect to kernel driver May 25 16:53:19 volumio hotspot[1045]: Using interface wlan0 with hwaddr b8:27:eb:cb:75:6d and ssid "Volumio" May 25 16:53:19 volumio volumio-streaming-daemon[1055]: ############################ May 25 16:53:19 volumio volumio-streaming-daemon[1055]: # Volumio Streaming Daemon # May 25 16:53:19 volumio volumio-streaming-daemon[1055]: # Running on port 7777 # May 25 16:53:19 volumio volumio-streaming-daemon[1055]: ############################ May 25 16:53:19 volumio volumio-streaming-daemon[1055]: Environment: May 25 16:53:19 volumio hotspot[1045]: wlan0: interface state UNINITIALIZED->ENABLED May 25 16:53:19 volumio hotspot[1045]: wlan0: AP-ENABLED May 25 16:53:20 volumio smbd[1065]: [2020/05/25 16:53:20.054578, 0] ../lib/util/become_daemon.c:124(daemon_ready) May 25 16:53:20 volumio smbd[1065]: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 25 16:53:20 volumio firststart.sh[1054]: Removing default SSH host keys May 25 16:53:20 volumio firststart.sh[1054]: removed '/etc/ssh/ssh_host_dsa_key' May 25 16:53:20 volumio firststart.sh[1054]: removed '/etc/ssh/ssh_host_dsa_key.pub' May 25 16:53:20 volumio firststart.sh[1054]: removed '/etc/ssh/ssh_host_ecdsa_key' May 25 16:53:20 volumio firststart.sh[1054]: removed '/etc/ssh/ssh_host_ecdsa_key.pub' May 25 16:53:20 volumio firststart.sh[1054]: removed '/etc/ssh/ssh_host_ed25519_key' May 25 16:53:20 volumio firststart.sh[1054]: removed '/etc/ssh/ssh_host_ed25519_key.pub' May 25 16:53:20 volumio firststart.sh[1054]: removed '/etc/ssh/ssh_host_rsa_key' May 25 16:53:20 volumio firststart.sh[1054]: removed '/etc/ssh/ssh_host_rsa_key.pub' May 25 16:53:20 volumio firststart.sh[1054]: Generating SSH host keys Jun 11 20:06:57 volumio systemd[1]: Time has been changed Jun 11 20:06:59 volumio nmbd[994]: [2020/06/11 20:06:59.526024, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 11 20:06:59 volumio nmbd[994]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.20 for name WORKGROUP<1d>. Jun 11 20:06:59 volumio nmbd[994]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Jun 11 20:06:59 volumio nmbd[994]: [2020/06/11 20:06:59.528634, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 11 20:06:59 volumio nmbd[994]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.1.20 for name WORKGROUP<1d>. Jun 11 20:06:59 volumio nmbd[994]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Jun 11 20:06:59 volumio nmbd[994]: [2020/06/11 20:06:59.528911, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 11 20:06:59 volumio nmbd[994]: query_name_response: Multiple (4) responses received for a query on subnet 192.168.1.20 for name WORKGROUP<1d>. Jun 11 20:06:59 volumio nmbd[994]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Jun 11 20:06:59 volumio firststart.sh[1054]: debconf: unable to initialize frontend: Dialog Jun 11 20:06:59 volumio firststart.sh[1054]: debconf: (TERM is not set, so the dialog frontend is not usable.) Jun 11 20:06:59 volumio firststart.sh[1054]: debconf: falling back to frontend: Readline Jun 11 20:06:59 volumio firststart.sh[1054]: debconf: unable to initialize frontend: Readline Jun 11 20:06:59 volumio firststart.sh[1054]: debconf: (This frontend requires a controlling tty.) Jun 11 20:06:59 volumio firststart.sh[1054]: debconf: falling back to frontend: Teletype Jun 11 20:07:00 volumio volumio[1053]: info: ------------------------------------------- Jun 11 20:07:00 volumio volumio[1053]: info: ----- Volumio2 ---- Jun 11 20:07:00 volumio volumio[1053]: info: ------------------------------------------- Jun 11 20:07:00 volumio volumio[1053]: info: ----- System startup ---- Jun 11 20:07:00 volumio volumio[1053]: info: ------------------------------------------- Jun 11 20:07:00 volumio volumio[1053]: info: File /data/configuration/plugins.json does not exist. Copying from Volumio Jun 11 20:07:00 volumio volumio[1053]: info: MYVOLUMIO Environment detected Jun 11 20:07:01 volumio firststart.sh[1054]: Creating SSH2 RSA key; this may take some time ... Jun 11 20:07:01 volumio firststart.sh[1054]: 2048 b1:6f:e3:bf:ab:c0:84:42:56:c9:0b:4c:7a:18:c6:24 /etc/ssh/ssh_host_rsa_key.pub (RSA) Jun 11 20:07:01 volumio volumio[1053]: info: Plugin folders cleanup Jun 11 20:07:01 volumio volumio[1053]: info: Scanning into folder /volumio/app/plugins/ Jun 11 20:07:01 volumio volumio[1053]: info: Scanning category audio_interface Jun 11 20:07:01 volumio volumio[1053]: info: Scanning category miscellanea Jun 11 20:07:01 volumio volumio[1053]: info: Scanning category music_service Jun 11 20:07:01 volumio volumio[1053]: info: Scanning category plugins.json Jun 11 20:07:01 volumio volumio[1053]: info: Scanning category system_controller Jun 11 20:07:01 volumio volumio[1053]: info: Scanning category user_interface Jun 11 20:07:01 volumio volumio[1053]: info: Scanning into folder /data/plugins/ Jun 11 20:07:01 volumio volumio[1053]: info: Plugin folders cleanup completed Jun 11 20:07:01 volumio volumio[1053]: info: ------------------------------------------- Jun 11 20:07:01 volumio volumio[1053]: info: ----- Core plugins startup ---- Jun 11 20:07:01 volumio volumio[1053]: info: ------------------------------------------- Jun 11 20:07:01 volumio volumio[1053]: info: Loading plugins from folder /volumio/app/plugins/ Jun 11 20:07:01 volumio volumio[1053]: info: Adding plugin upnp to MyMusic Plugins Jun 11 20:07:01 volumio volumio[1053]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 11 20:07:01 volumio volumio[1053]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 11 20:07:01 volumio volumio[1053]: info: Loading plugins from folder /data/plugins/ Jun 11 20:07:01 volumio volumio[1053]: info: Loading plugin "system"... Jun 11 20:07:01 volumio volumio[1053]: No id defined. Creating one Jun 11 20:07:01 volumio volumio[1053]: info: Loading plugin "appearance"... Jun 11 20:07:03 volumio volumio[1053]: info: Loading plugin "network"... Jun 11 20:07:03 volumio volumio[1053]: info: Refreshing Cached IP Addresses Jun 11 20:07:03 volumio sudo[1148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 11 20:07:03 volumio volumio[1053]: info: Loading plugin "services"... Jun 11 20:07:03 volumio volumio[1053]: info: Loading plugin "alsa_controller"... Jun 11 20:07:03 volumio sudo[1148]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:03 volumio sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 11 20:07:03 volumio firststart.sh[1054]: Creating SSH2 DSA key; this may take some time ... Jun 11 20:07:03 volumio sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:03 volumio sudo[1148]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:03 volumio firststart.sh[1054]: 1024 9d:fe:ae:9b:f8:93:d9:f5:da:24:04:60:51:cf:e1:72 /etc/ssh/ssh_host_dsa_key.pub (DSA) Jun 11 20:07:03 volumio sudo[1150]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:03 volumio firststart.sh[1054]: Creating SSH2 ECDSA key; this may take some time ... Jun 11 20:07:03 volumio firststart.sh[1054]: 256 00:3f:93:62:5d:16:5a:6f:5b:8e:10:c5:3f:5f:dc:b1 /etc/ssh/ssh_host_ecdsa_key.pub (ECDSA) Jun 11 20:07:03 volumio volumio[1053]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 11 20:07:03 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 20:07:03 volumio volumio[1053]: info: Found match in Cards Database: setting mixer PCM for card Audio Jack Jun 11 20:07:03 volumio volumio[1053]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 11 20:07:03 volumio volumio[1053]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 11 20:07:03 volumio volumio[1053]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 11 20:07:03 volumio volumio[1053]: info: Loading plugin "wizard"... Jun 11 20:07:03 volumio volumio[1053]: info: Loading plugin "volumio_command_line_client"... Jun 11 20:07:03 volumio volumio[1053]: info: Loading plugin "upnp"... Jun 11 20:07:03 volumio volumio[1053]: info: [1591906023694] Starting Upmpd Daemon Jun 11 20:07:03 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 11 20:07:03 volumio volumio[1053]: info: Loading plugin "my_music"... Jun 11 20:07:03 volumio volumio[1053]: info: Loading plugin "mpd"... Jun 11 20:07:04 volumio volumio-remote-updater[746]: [2020-06-11 20:07:04] [connect] Successful connection Jun 11 20:07:04 volumio volumio[1053]: info: Loading plugin "upnp_browser"... Jun 11 20:07:04 volumio volumio[1053]: info: Loading plugin "networkfs"... Jun 11 20:07:05 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 11 20:07:05 volumio volumio[1053]: info: Loading plugin "alarm-clock"... Jun 11 20:07:05 volumio firststart.sh[1054]: insserv: warning: current start runlevel(s) (empty) of script `ssh' overrides LSB defaults (2 3 4 5). Jun 11 20:07:05 volumio firststart.sh[1054]: insserv: warning: current stop runlevel(s) (2 3 4 5) of script `ssh' overrides LSB defaults (empty). Jun 11 20:07:05 volumio volumio[1053]: info: Loading plugin "airplay_emulation"... Jun 11 20:07:05 volumio systemd[1]: Reloading. Jun 11 20:07:05 volumio volumio[1053]: info: Starting Shairport Sync Jun 11 20:07:05 volumio volumio[1053]: info: Loading plugin "last_100"... Jun 11 20:07:05 volumio volumio[1053]: info: Loading plugin "webradio"... Jun 11 20:07:05 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Jun 11 20:07:05 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Jun 11 20:07:05 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Jun 11 20:07:05 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Jun 11 20:07:05 volumio volumio[1053]: info: Loading plugin "i2s_dacs"... Jun 11 20:07:05 volumio volumio[1053]: info: I2S DAC not set, start Auto-detection Jun 11 20:07:05 volumio volumio[1053]: info: Loading plugin "volumiodiscovery"... Jun 11 20:07:05 volumio volumio[1053]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 11 20:07:05 volumio node[1053]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 11 20:07:05 volumio node[1053]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 11 20:07:05 volumio node[1053]: *** WARNING *** For more information see Jun 11 20:07:05 volumio node[1053]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 11 20:07:05 volumio node[1053]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 11 20:07:05 volumio node[1053]: *** WARNING *** For more information see Jun 11 20:07:05 volumio volumio[1053]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 11 20:07:05 volumio volumio[1053]: *** WARNING *** For more information see Jun 11 20:07:05 volumio volumio[1053]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 11 20:07:05 volumio volumio[1053]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 11 20:07:05 volumio volumio[1053]: *** WARNING *** For more information see Jun 11 20:07:05 volumio volumio[1053]: Discovery: StartAdv! undefined Jun 11 20:07:05 volumio volumio[1053]: Discovery: Started advertising... Volumio - undefined Jun 11 20:07:05 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 11 20:07:05 volumio volumio[1053]: info: Loading plugin "outputs"... Jun 11 20:07:05 volumio volumio[1053]: info: Loading plugin "albumart"... Jun 11 20:07:05 volumio volumio[1053]: info: Plugin example_plugin is not enabled Jun 11 20:07:05 volumio volumio[1053]: info: Loading plugin "inputs"... Jun 11 20:07:05 volumio volumio[1053]: info: Loading plugin "updater_comm"... Jun 11 20:07:05 volumio firststart.sh[1054]: Disabling firststart service Jun 11 20:07:05 volumio firststart.sh[1054]: Removed symlink /etc/systemd/system/multi-user.target.wants/firststart.service. Jun 11 20:07:05 volumio systemd[1]: Reloading. Jun 11 20:07:06 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Jun 11 20:07:06 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Jun 11 20:07:06 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Jun 11 20:07:06 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Jun 11 20:07:06 volumio volumio[1053]: info: Plugin mpdemulation is not enabled Jun 11 20:07:06 volumio volumio[1053]: info: Loading plugin "rest_api"... Jun 11 20:07:06 volumio firststart.sh[1054]: Finalizing Jun 11 20:07:06 volumio volumio[1053]: info: Loading plugin "websocket"... Jun 11 20:07:06 volumio volumio[1053]: info: ___________ START PLUGINS ___________ Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 11 20:07:06 volumio volumio[1053]: info: [1591906026197] CoreMusicLibrary::Adding element Media Servers Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 20:07:06 volumio volumio[1053]: Forking 3 albumart workers Jun 11 20:07:06 volumio volumio[1053]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 20:07:06 volumio volumio[1053]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 11 20:07:06 volumio volumio[1053]: info: [1591906026328] CoreMusicLibrary::Adding element Last_100 Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 11 20:07:06 volumio volumio[1053]: info: [1591906026335] CoreMusicLibrary::Adding element Webradio Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 11 20:07:06 volumio volumio[1053]: info: ------------------------------------------- Jun 11 20:07:06 volumio volumio[1053]: info: ----- MyVolumio plugins startup ---- Jun 11 20:07:06 volumio volumio[1053]: info: ------------------------------------------- Jun 11 20:07:06 volumio volumio[1053]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 11 20:07:06 volumio volumio[1053]: info: Loading i18n strings for locale en Jun 11 20:07:06 volumio volumio[1053]: Updating browse sources language Jun 11 20:07:06 volumio volumio[1053]: Cannot find translation for sourceMedia Servers Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::initPlayerControls Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:06 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:07 volumio volumio[1053]: Starting albumart workers Jun 11 20:07:07 volumio volumio[1053]: Starting albumart workers Jun 11 20:07:07 volumio volumio[1053]: Starting albumart workers Jun 11 20:07:09 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:07:09 volumio volumio[1053]: info: BOOT COMPLETED Jun 11 20:07:09 volumio volumio[1053]: [Metrics] CommandRouter: 8s 695.87ms Jun 11 20:07:09 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:07:14 volumio volumio[1053]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jun 11 20:07:14 volumio volumio[1053]: info: CoreCommandRouter::Close All Modals sent Jun 11 20:07:14 volumio volumio[1053]: info: CoreCommandRouter::Close All Modals sent Jun 11 20:07:14 volumio volumio[1053]: Express server listening on port 3000 Jun 11 20:07:14 volumio volumio[1053]: [Metrics] WebUI: 15s 797.45ms Jun 11 20:07:14 volumio volumio[1053]: Volumio Calling Home Jun 11 20:07:14 volumio volumio[1053]: info: CoreStateMachine::resetVolumioState Jun 11 20:07:14 volumio volumio[1053]: info: CoreStateMachine::getcurrentVolume Jun 11 20:07:14 volumio volumio[1053]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 20:07:14 volumio volumio[1053]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 11 20:07:14 volumio volumio[1053]: info: CoreStateMachine::getcurrentVolume Jun 11 20:07:14 volumio volumio[1053]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 20:07:14 volumio sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 11 20:07:14 volumio sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:14 volumio sudo[1331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 11 20:07:14 volumio volumio[1053]: info: Cannot read play queue from file Jun 11 20:07:14 volumio sudo[1331]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:14 volumio sudo[1329]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:14 volumio sudo[1331]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:14 volumio volumio[1053]: info: Setting Device type: Raspberry PI Jun 11 20:07:14 volumio volumio[1053]: info: MPD running with PID802 Jun 11 20:07:14 volumio volumio[1053]: ,establishing connection Jun 11 20:07:14 volumio sudo[1349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Jun 11 20:07:14 volumio sudo[1349]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:14 volumio sudo[1349]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:14 volumio volumio[1053]: info: VolumeController:: Volume=40 Mute =false Jun 11 20:07:14 volumio volumio[1053]: info: CoreStateMachine::pushState Jun 11 20:07:14 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:07:14 volumio volumio[1053]: info: CoreCommandRouter::volumioPushState Jun 11 20:07:14 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 20:07:14 volumio volumio[1053]: info: CoreStateMachine::updateTrackBlock Jun 11 20:07:14 volumio volumio[1053]: info: CorePlayQueue::getTrackBlock Jun 11 20:07:14 volumio volumio[1053]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 20:07:14 volumio volumio[1053]: info: VolumeController:: Volume=40 Mute =false Jun 11 20:07:14 volumio volumio[1053]: info: CoreStateMachine::pushState Jun 11 20:07:14 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:07:14 volumio volumio[1053]: info: CoreCommandRouter::volumioPushState Jun 11 20:07:14 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 20:07:14 volumio volumio[1053]: info: CoreStateMachine::updateTrackBlock Jun 11 20:07:14 volumio volumio[1053]: info: CorePlayQueue::getTrackBlock Jun 11 20:07:14 volumio volumio[1053]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 20:07:15 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:07:15 volumio volumio[1053]: info: I2S DAC DETECTION: Found Match with HiFiBerry DAC Plus at address 4d Jun 11 20:07:15 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 11 20:07:15 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 20:07:15 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 11 20:07:15 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:07:15 volumio volumio[1053]: info: Enabling I2S DAC: HiFiBerry DAC Plus Jun 11 20:07:15 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Jun 11 20:07:15 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:07:15 volumio sudo[1361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jun 11 20:07:15 volumio sudo[1361]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:15 volumio sudo[1361]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:15 volumio volumio[1053]: info: No Overlays Loaded Jun 11 20:07:15 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:07:15 volumio volumio[1053]: info: Output device has changed, restarting MPD Jun 11 20:07:15 volumio sudo[1370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dacplus Jun 11 20:07:15 volumio sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:15 volumio volumio[1053]: info: Output device has changed, restarting Shairport Sync Jun 11 20:07:15 volumio sudo[1373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 11 20:07:15 volumio sudo[1373]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:15 volumio sudo[1378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 11 20:07:15 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Jun 11 20:07:15 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Jun 11 20:07:15 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Jun 11 20:07:15 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Jun 11 20:07:15 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Jun 11 20:07:15 volumio sudo[1378]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:15 volumio systemd[1]: Stopping Music Player Daemon... Jun 11 20:07:15 volumio sudo[1370]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:16 volumio volumio[1053]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 20:07:16 volumio volumio[1053]: info: Output device has changed, restarting MPD Jun 11 20:07:16 volumio sudo[1373]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:16 volumio volumio[1053]: info: Output device has changed, restarting Shairport Sync Jun 11 20:07:16 volumio sudo[1410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 11 20:07:16 volumio sudo[1410]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:16 volumio sudo[1410]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:16 volumio sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 11 20:07:16 volumio sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:16 volumio volumio[1053]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 20:07:16 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 20:07:16 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Jun 11 20:07:16 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:07:16 volumio volumio[1053]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry DAC Plus Jun 11 20:07:16 volumio volumio[1053]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 11 20:07:16 volumio volumio[1053]: info: Updating Volume Controller Parameters: Device: 1 Name: Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Jun 11 20:07:16 volumio volumio[1053]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 20:07:16 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 11 20:07:16 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 11 20:07:16 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 11 20:07:16 volumio volumio[1053]: info: Version has changed, forcing UI Reload Jun 11 20:07:16 volumio volumio[1053]: info: CoreCommandRouter::Reload Ui Jun 11 20:07:16 volumio volumio[1053]: error: updateQueue error: null Jun 11 20:07:16 volumio volumio[1053]: error: Failed LSINFO: Error: [50@0] {} No such directory Jun 11 20:07:17 volumio volumio[1053]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Jun 11 20:07:17 volumio volumio[1053]: info: MPD Permissions set Jun 11 20:07:17 volumio volumio[1053]: info: MPD Permissions set Jun 11 20:07:17 volumio volumio[1053]: info: I2S Param [object Object] successfully enabled Jun 11 20:07:17 volumio volumio[1053]: info: VolumeController:: Volume=40 Mute =false Jun 11 20:07:17 volumio volumio[1053]: info: CoreStateMachine::pushState Jun 11 20:07:17 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::volumioPushState Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 20:07:17 volumio volumio[1053]: info: Volumio called home Jun 11 20:07:17 volumio volumio[1053]: info: VolumeController:: Volume=40 Mute =false Jun 11 20:07:17 volumio volumio[1053]: info: CoreStateMachine::pushState Jun 11 20:07:17 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::volumioPushState Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 20:07:17 volumio volumio[1053]: info: VolumeController:: Volume=undefined Mute =false Jun 11 20:07:17 volumio volumio[1053]: info: CoreStateMachine::pushState Jun 11 20:07:17 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::volumioPushState Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 20:07:17 volumio volumio[1053]: info: Starting Shairport Sync Jun 11 20:07:17 volumio volumio[1053]: info: Starting Shairport Sync Jun 11 20:07:17 volumio sudo[1442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 11 20:07:17 volumio sudo[1442]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:17 volumio sudo[1444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio sudo[1448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 11 20:07:17 volumio sudo[1444]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:17 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jun 11 20:07:17 volumio systemd[1]: Failed to reset devices.list on /system.slice: Invalid argument Jun 11 20:07:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 11 20:07:17 volumio sudo[1448]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 20:07:17 volumio volumio[1053]: Invalid card number. Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 11 20:07:17 volumio sudo[1442]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 11 20:07:17 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Jun 11 20:07:17 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 11 20:07:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 11 20:07:17 volumio sudo[1448]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:17 volumio volumio[1053]: Discovery: adding 62036db6-8722-46e7-ae2e-9a58d9808c11 Jun 11 20:07:17 volumio volumio[1053]: info: mDNS: Found device Volumio Jun 11 20:07:17 volumio volumio[1053]: info: CoreCommandRouter::volumioGetState Jun 11 20:07:17 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:07:17 volumio volumio[1053]: info: Upmpdcli Daemon Started Jun 11 20:07:17 volumio volumio[1053]: info: Shairport-Sync Started Jun 11 20:07:17 volumio volumio[1053]: Error adding Membership: Error: addMembership EINVAL Jun 11 20:07:18 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jun 11 20:07:18 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 11 20:07:18 volumio sudo[1444]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:18 volumio volumio[1053]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 11 20:07:18 volumio volumio[1053]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 11 20:07:18 volumio volumio[1053]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 11 20:07:18 volumio volumio[1053]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 11 20:07:18 volumio volumio[1053]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 11 20:07:18 volumio volumio[1053]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 11 20:07:18 volumio volumio[1053]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 11 20:07:18 volumio volumio[1053]: info: Adding plugin bluetooth to MyMusic Plugins Jun 11 20:07:18 volumio volumio[1053]: info: Adding plugin cd_controller to MyMusic Plugins Jun 11 20:07:18 volumio kernel: pcm512x 1-004d: Linked as a consumer to regulator.1 Jun 11 20:07:18 volumio kernel: snd-rpi-hifiberry-dacplus soc:sound: ASoC: CPU DAI (null) not registered - will retry Jun 11 20:07:18 volumio volumio[1053]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 11 20:07:18 volumio volumio[1053]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 11 20:07:18 volumio kernel: snd-rpi-hifiberry-dacplus soc:sound: pcm512x-hifi <-> 3f203000.i2s mapping ok Jun 11 20:07:18 volumio systemd[1]: Starting Music Player Daemon... Jun 11 20:07:19 volumio volumio-remote-updater[746]: [2020-06-11 20:07:19] [connect] Successful connection Jun 11 20:07:19 volumio systemd[1]: Started Music Player Daemon. Jun 11 20:07:19 volumio sudo[1416]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:19 volumio sudo[1378]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:21 volumio volumio[1053]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 11 20:07:23 volumio volumio[1053]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 11 20:07:23 volumio volumio[1053]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:07:23 volumio volumio[1053]: info: Starting MyVolumio Remote Streaming Endpoints Jun 11 20:07:23 volumio volumio[1053]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 11 20:07:23 volumio volumio[1053]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 11 20:07:23 volumio volumio[1053]: info: Streaming services startup Jun 11 20:07:23 volumio volumio[1053]: info: Starting Streaming Daemon Jun 11 20:07:23 volumio volumio[1053]: info: MyVolumio not started Jun 11 20:07:23 volumio volumio[1053]: info: Initializing device activation check Jun 11 20:07:23 volumio sudo[1509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 11 20:07:23 volumio sudo[1509]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:23 volumio systemd[1]: Stopping Volumio Streaming Daemon... Jun 11 20:07:23 volumio volumio[1053]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 11 20:07:23 volumio systemd[1]: Starting Volumio Streaming Daemon... Jun 11 20:07:23 volumio systemd[1]: Started Volumio Streaming Daemon. Jun 11 20:07:23 volumio sudo[1509]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:23 volumio volumio-streaming-daemon[1515]: ############################ Jun 11 20:07:23 volumio volumio-streaming-daemon[1515]: # Volumio Streaming Daemon # Jun 11 20:07:23 volumio volumio-streaming-daemon[1515]: # Running on port 7777 # Jun 11 20:07:23 volumio volumio-streaming-daemon[1515]: ############################ Jun 11 20:07:23 volumio volumio-streaming-daemon[1515]: Environment: production Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateVolumeSettings Jun 11 20:07:23 volumio sudo[1526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 11 20:07:23 volumio sudo[1526]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:23 volumio systemd[1]: Stopping Music Player Daemon... Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 11 20:07:23 volumio volumio[1053]: info: Updating Volume Controller Parameters: Device: 1 Name: HiFiBerry DAC Plus Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 11 20:07:23 volumio volumio[1053]: info: CoreStateMachine::getcurrentVolume Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 20:07:23 volumio volumio[1053]: Discovery: this is already registered, 62036db6-8722-46e7-ae2e-9a58d9808c11 Jun 11 20:07:23 volumio volumio[1053]: info: mDNS: Found device Volumio Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::volumioGetState Jun 11 20:07:23 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:07:23 volumio volumio[1053]: info: Shairport-Sync Started Jun 11 20:07:23 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:07:23 volumio systemd[1]: Starting Music Player Daemon... Jun 11 20:07:23 volumio volumio[1053]: info: Starting Shairport Sync Jun 11 20:07:23 volumio volumio[1053]: info: Starting Shairport Sync Jun 11 20:07:23 volumio sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 11 20:07:23 volumio sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:23 volumio volumio[1053]: error: Upnp client error: Error: This socket has been ended by the other party Jun 11 20:07:23 volumio sudo[1540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 11 20:07:23 volumio volumio-remote-updater[746]: [2020-06-11 20:07:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1591906039 101 Jun 11 20:07:23 volumio sudo[1540]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:07:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 11 20:07:23 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jun 11 20:07:23 volumio sudo[1538]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 11 20:07:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 11 20:07:23 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jun 11 20:07:23 volumio sudo[1540]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::volumioGetState Jun 11 20:07:23 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:07:23 volumio volumio[1053]: info: Shairport-Sync Started Jun 11 20:07:23 volumio volumio[1053]: info: Shairport-Sync Started Jun 11 20:07:23 volumio volumio[1053]: info: VolumeController:: Volume=100 Mute =false Jun 11 20:07:23 volumio volumio[1053]: info: CoreStateMachine::pushState Jun 11 20:07:23 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::volumioPushState Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 20:07:23 volumio volumio[1053]: info: VolumeController:: Volume=100 Mute =false Jun 11 20:07:23 volumio volumio[1053]: info: CoreStateMachine::pushState Jun 11 20:07:23 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::volumioPushState Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 20:07:23 volumio volumio[1053]: info: CoreStateMachine::updateTrackBlock Jun 11 20:07:23 volumio volumio[1053]: info: CorePlayQueue::getTrackBlock Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 20:07:23 volumio volumio[1053]: info: VolumeController:: Volume=100 Mute =false Jun 11 20:07:23 volumio volumio[1053]: info: CoreStateMachine::pushState Jun 11 20:07:23 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::volumioPushState Jun 11 20:07:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 20:07:23 volumio systemd[1]: Started Music Player Daemon. Jun 11 20:07:23 volumio sudo[1526]: pam_unix(sudo:session): session closed for user root Jun 11 20:07:23 volumio volumio[1053]: error: MPD error: false == true Jun 11 20:07:23 volumio volumio[1053]: error: updateQueue error: null Jun 11 20:07:23 volumio volumio[1053]: error: updateQueue error: null Jun 11 20:07:25 volumio volumio-streaming-daemon[1515]: Environment: production Jun 11 20:07:25 volumio volumio[1053]: info: Fetching Streaming Services browse cache Jun 11 20:07:26 volumio volumio[1053]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Jun 11 20:07:26 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 20:07:26 volumio volumio[1053]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Jun 11 20:07:26 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 20:07:28 volumio volumio[1053]: error: updateQueue error: null Jun 11 20:07:36 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 11 20:07:36 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:10:29 volumio sudo[1983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 11 20:10:29 volumio sudo[1983]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:29 volumio sudo[1985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 11 20:10:29 volumio sudo[1983]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:29 volumio sudo[1985]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:29 volumio sudo[1985]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:29 volumio sudo[2003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 11 20:10:29 volumio sudo[2003]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:29 volumio sudo[2005]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 11 20:10:29 volumio sudo[2003]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:29 volumio sudo[2005]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:29 volumio sudo[2005]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:29 volumio volumio[1053]: info: CoreCommandRouter::volumioGetState Jun 11 20:10:29 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:10:29 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 11 20:10:29 volumio volumio[1053]: info: CoreCommandRouter::volumioGetVisibleSources Jun 11 20:10:29 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 20:10:29 volumio volumio[1053]: info: Listing playlists Jun 11 20:10:30 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 11 20:10:30 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 11 20:10:30 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 11 20:10:30 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 11 20:10:30 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Jun 11 20:10:30 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Jun 11 20:10:30 volumio volumio[1053]: info: Could not detect Volumio Device: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 20:10:30 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Jun 11 20:10:32 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 11 20:10:33 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Jun 11 20:10:33 volumio volumio[1053]: info: Loading i18n strings for locale es Jun 11 20:10:33 volumio volumio[1053]: Updating browse sources language Jun 11 20:10:33 volumio volumio[1053]: Cannot find translation for sourceMedia Servers Jun 11 20:10:33 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 20:10:33 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 11 20:10:33 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 11 20:10:33 volumio sudo[2042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 11 20:10:33 volumio sudo[2042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:33 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 11 20:10:33 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 11 20:10:33 volumio volumio[1053]: info: Fetching Streaming Services browse cache Jun 11 20:10:34 volumio sudo[2042]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:34 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 11 20:10:34 volumio volumio[1053]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 11 20:10:34 volumio volumio[1053]: info: MYVOLUMIO: Adding device Jun 11 20:10:34 volumio volumio[1053]: info: MYVOLUMIO: Evaluating Server Jun 11 20:10:35 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings Jun 11 20:10:35 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices Jun 11 20:10:35 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 11 20:10:35 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:10:35 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 20:10:35 volumio volumio[1053]: info: MyVolumio status changed Jun 11 20:10:35 volumio volumio[1053]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Jun 11 20:10:35 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 20:10:35 volumio volumio[1053]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Jun 11 20:10:35 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 20:10:35 volumio volumio[1053]: info: Streaming services startup Jun 11 20:10:35 volumio volumio[1053]: info: Re-Starting Streaming Daemon Jun 11 20:10:35 volumio volumio[1053]: info: Removing browser output: myVolumio user plan is not superstar Jun 11 20:10:35 volumio volumio[1053]: info: Removing audio output: Jun 11 20:10:35 volumio volumio[1053]: info: Stoppping Tunnel 1 Jun 11 20:10:35 volumio volumio-streaming-daemon[1515]: Restarting daemon Jun 11 20:10:35 volumio sudo[2064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 11 20:10:35 volumio volumio-streaming-daemon[1515]: Environment: production Jun 11 20:10:35 volumio sudo[2064]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:35 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Jun 11 20:10:35 volumio sudo[2064]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:35 volumio volumio[1053]: info: Remote SSH Stopped Jun 11 20:10:36 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 11 20:10:36 volumio volumio[1053]: info: Setting Geolocation for MyVolumio to us1 Jun 11 20:10:36 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:10:36 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:10:36 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:10:36 volumio volumio-streaming-daemon[1515]: Environment: production Jun 11 20:10:36 volumio volumio[1053]: info: Fetching Streaming Services browse cache Jun 11 20:10:36 volumio volumio[1053]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Jun 11 20:10:36 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 20:10:36 volumio volumio[1053]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Jun 11 20:10:36 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 20:10:36 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 11 20:10:37 volumio volumio[1053]: info: Updating MyVolumio device info Jun 11 20:10:37 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:10:37 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:10:37 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: Output device has changed, restarting MPD Jun 11 20:10:39 volumio volumio[1053]: info: Output device has changed, restarting Shairport Sync Jun 11 20:10:39 volumio sudo[2081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 11 20:10:39 volumio sudo[2081]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:39 volumio sudo[2083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 11 20:10:39 volumio sudo[2081]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:39 volumio sudo[2083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:39 volumio systemd[1]: Stopping Music Player Daemon... Jun 11 20:10:39 volumio volumio[1053]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: Found match in i2s Card Database: setting mixer Digital for card HiFiBerry DAC Plus Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 11 20:10:39 volumio volumio[1053]: info: Updating Volume Controller Parameters: Device: 1 Name: HiFiBerry DAC Plus Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 20:10:39 volumio systemd[1]: Starting Music Player Daemon... Jun 11 20:10:39 volumio volumio[1053]: info: MPD Permissions set Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getExperienceAdvancedSettings Jun 11 20:10:39 volumio volumio[1053]: error: Upnp client error: Error: This socket has been ended by the other party Jun 11 20:10:39 volumio volumio[1053]: info: VolumeController:: Volume=100 Mute =false Jun 11 20:10:39 volumio volumio[1053]: info: CoreStateMachine::pushState Jun 11 20:10:39 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::volumioPushState Jun 11 20:10:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 20:10:39 volumio volumio[1053]: info: Starting Shairport Sync Jun 11 20:10:39 volumio sudo[2115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 11 20:10:39 volumio sudo[2115]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 11 20:10:39 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jun 11 20:10:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 11 20:10:39 volumio sudo[2115]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:39 volumio volumio[1053]: info: Shairport-Sync Started Jun 11 20:10:40 volumio systemd[1]: Started Music Player Daemon. Jun 11 20:10:40 volumio sudo[2083]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:40 volumio volumio[1053]: error: updateQueue error: null Jun 11 20:10:41 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , setExperienceAdvancedSettings Jun 11 20:10:41 volumio volumio[1053]: info: Saving Experience Advanced Settings Jun 11 20:10:41 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 11 20:10:41 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 11 20:10:41 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 11 20:10:41 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 11 20:10:41 volumio sudo[2142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 11 20:10:41 volumio sudo[2142]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:41 volumio sudo[2145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 11 20:10:41 volumio sudo[2145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:41 volumio sudo[2145]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:41 volumio volumio[1053]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Jun 11 20:10:41 volumio volumio[1053]: wlan0 Interface doesn't support scanning : Device or resource busy Jun 11 20:10:41 volumio volumio[1053]: info: Cannot use regular scanning, forcing with ap-force Jun 11 20:10:41 volumio sudo[2157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Jun 11 20:10:41 volumio sudo[2157]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:41 volumio sudo[2157]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:41 volumio volumio[1053]: command failed: Device or resource busy (-16) Jun 11 20:10:41 volumio volumio[1053]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Jun 11 20:10:41 volumio volumio[1053]: command failed: Device or resource busy (-16) Jun 11 20:10:42 volumio volumio[1053]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 11 20:10:42 volumio volumio[1053]: info: CoreStateMachine::getcurrentVolume Jun 11 20:10:42 volumio volumio[1053]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 20:10:42 volumio volumio[1053]: info: VolumeController:: Volume=100 Mute =false Jun 11 20:10:42 volumio volumio[1053]: info: CoreStateMachine::pushState Jun 11 20:10:42 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:10:42 volumio volumio[1053]: info: CoreCommandRouter::volumioPushState Jun 11 20:10:42 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 20:10:42 volumio volumio[1053]: info: CoreStateMachine::updateTrackBlock Jun 11 20:10:42 volumio volumio[1053]: info: CorePlayQueue::getTrackBlock Jun 11 20:10:42 volumio volumio[1053]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 20:10:42 volumio volumio[1053]: info: VolumeController:: Volume=100 Mute =false Jun 11 20:10:42 volumio volumio[1053]: info: CoreStateMachine::pushState Jun 11 20:10:42 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:10:42 volumio volumio[1053]: info: CoreCommandRouter::volumioPushState Jun 11 20:10:42 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 20:10:42 volumio sudo[2142]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:50 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork Jun 11 20:10:50 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo Jun 11 20:10:50 volumio sudo[2174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 11 20:10:50 volumio sudo[2174]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:50 volumio sudo[2174]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:50 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jun 11 20:10:50 volumio volumio[1053]: info: Saving new wireless network Jun 11 20:10:50 volumio sudo[2183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 11 20:10:50 volumio sudo[2183]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:50 volumio systemd[1]: Starting Wireless Services... Jun 11 20:10:50 volumio sudo[2189]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Jun 11 20:10:50 volumio sudo[2189]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:50 volumio sudo[2189]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:50 volumio wireless.js[2198]: Cleaning previous... Jun 11 20:10:50 volumio systemd[1]: Stopping dhcpd.service... Jun 11 20:10:50 volumio systemd[1]: Stopping Host and Network Name Lookups. Jun 11 20:10:50 volumio systemd[1]: Stopped target Host and Network Name Lookups. Jun 11 20:10:50 volumio systemd[1]: Stopping dnsmasq.service... Jun 11 20:10:50 volumio dnsmasq[1062]: exiting on receipt of SIGTERM Jun 11 20:10:50 volumio systemd[1]: Stopped dnsmasq.service. Jun 11 20:10:50 volumio systemd[1]: Stopped dhcpd.service. Jun 11 20:10:50 volumio systemd[1]: Stopping hotspot.service... Jun 11 20:10:55 volumio hotspot[2231]: Killing Hostapd Jun 11 20:10:55 volumio sudo[2239]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall hostapd Jun 11 20:10:55 volumio sudo[2239]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:55 volumio hotspot[2231]: hostapd: no process found Jun 11 20:10:55 volumio sudo[2239]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:55 volumio hotspot[2231]: Killing Dhcpd Jun 11 20:10:55 volumio sudo[2247]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall dhcpd Jun 11 20:10:55 volumio sudo[2247]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:55 volumio hotspot[2231]: dhcpd: no process found Jun 11 20:10:55 volumio sudo[2247]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:55 volumio systemd[1]: hotspot.service: control process exited, code=exited status=1 Jun 11 20:10:55 volumio systemd[1]: Stopped hotspot.service. Jun 11 20:10:55 volumio systemd[1]: Unit hotspot.service entered failed state. Jun 11 20:10:55 volumio sudo[2257]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 11 20:10:55 volumio sudo[2257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:55 volumio avahi-daemon[801]: Withdrawing address record for 192.168.211.1 on wlan0. Jun 11 20:10:55 volumio avahi-daemon[801]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Jun 11 20:10:55 volumio avahi-daemon[801]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 11 20:10:55 volumio sudo[2257]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:55 volumio volumio[1053]: info: mDNS: A device disapperared from network Jun 11 20:10:55 volumio volumio[1053]: info: mDNS: Device volumio disapperared from network Jun 11 20:10:56 volumio sudo[2267]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 11 20:10:56 volumio sudo[2267]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:56 volumio sudo[2267]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:57 volumio wireless.js[2198]: Stopped aP Jun 11 20:10:57 volumio wireless.js[2198]: Start wireless flow Jun 11 20:10:57 volumio wireless.js[2198]: Stopped hotspot (if there).. Jun 11 20:10:57 volumio sudo[2280]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 11 20:10:57 volumio sudo[2280]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:57 volumio sudo[2280]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:57 volumio sudo[2288]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 11 20:10:57 volumio sudo[2288]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:57 volumio sudo[2288]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:57 volumio wireless.js[2198]: DHCP IP Jun 11 20:10:57 volumio wireless.js[2198]: Start ap Jun 11 20:10:57 volumio sudo[2297]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 11 20:10:57 volumio sudo[2297]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:57 volumio dhcpcd[2302]: version 6.0.5 starting Jun 11 20:10:57 volumio wpa_supplicant[2296]: Successfully initialized wpa_supplicant Jun 11 20:10:57 volumio wpa_supplicant[2296]: nl80211: Could not configure driver mode Jun 11 20:10:57 volumio dhcpcd[2302]: eth0: soliciting a DHCP lease Jun 11 20:10:57 volumio dhcpcd[2302]: wlan0: waiting for carrier Jun 11 20:10:57 volumio wpa_supplicant[2337]: wlan0: Trying to associate with 10:da:43:88:b1:a7 (SSID='Living 2G' freq=2457 MHz) Jun 11 20:10:58 volumio wpa_supplicant[2337]: wlan0: Associated with 10:da:43:88:b1:a7 Jun 11 20:10:58 volumio dhcpcd[2302]: eth0: offered 192.168.1.5 from 192.168.1.1 Jun 11 20:10:58 volumio wireless.js[2198]: trying... Jun 11 20:10:58 volumio sudo[2341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 11 20:10:58 volumio sudo[2341]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:58 volumio sudo[2341]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:58 volumio dhcpcd[2302]: eth0: leased 192.168.1.5 for 86400 seconds Jun 11 20:10:58 volumio avahi-daemon[801]: Registering new address record for 192.168.1.5 on eth0.IPv4. Jun 11 20:10:58 volumio dhcpcd[2302]: eth0: adding host route to 192.168.1.5 via 127.0.0.1 Jun 11 20:10:58 volumio dhcpcd[2302]: eth0: adding route to 192.168.1.0/24 Jun 11 20:10:58 volumio dhcpcd[587]: eth0: removing route to 192.168.1.0/24 Jun 11 20:10:58 volumio dhcpcd[2302]: eth0: adding default route via 192.168.1.1 Jun 11 20:10:58 volumio dhcpcd[587]: eth0: removing default route via 192.168.1.1 Jun 11 20:10:58 volumio dhcpcd[2302]: forked to background, child pid 2371 Jun 11 20:10:58 volumio dhcpcd[2371]: eth0: removing route to 192.168.1.0/24 Jun 11 20:10:58 volumio dhcpcd[2371]: eth0: removing default route via 192.168.1.1 Jun 11 20:10:58 volumio sudo[2297]: pam_unix(sudo:session): session closed for user root Jun 11 20:10:59 volumio wpa_supplicant[2337]: wlan0: WPA: Key negotiation completed with 10:da:43:88:b1:a7 [PTK=CCMP GTK=CCMP] Jun 11 20:10:59 volumio wpa_supplicant[2337]: wlan0: CTRL-EVENT-CONNECTED - Connection to 10:da:43:88:b1:a7 completed [id=0 id_str=] Jun 11 20:10:59 volumio dhcpcd[2371]: wlan0: carrier acquired Jun 11 20:10:59 volumio wireless.js[2198]: trying... Jun 11 20:10:59 volumio dhcpcd[2371]: wlan0: soliciting a DHCP lease Jun 11 20:10:59 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 11 20:10:59 volumio sudo[2393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 11 20:10:59 volumio sudo[2393]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:10:59 volumio sudo[2393]: pam_unix(sudo:session): session closed for user root Jun 11 20:11:00 volumio dhcpcd[2371]: wlan0: offered 192.168.1.12 from 192.168.1.1 Jun 11 20:11:00 volumio wireless.js[2198]: trying... Jun 11 20:11:00 volumio sudo[2402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 11 20:11:00 volumio sudo[2402]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:11:00 volumio sudo[2402]: pam_unix(sudo:session): session closed for user root Jun 11 20:11:00 volumio dhcpcd[2371]: wlan0: leased 192.168.1.12 for 86400 seconds Jun 11 20:11:00 volumio avahi-daemon[801]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.12. Jun 11 20:11:00 volumio avahi-daemon[801]: New relevant interface wlan0.IPv4 for mDNS. Jun 11 20:11:00 volumio dhcpcd[2371]: eth0: adding route to 192.168.1.0/24 Jun 11 20:11:00 volumio dhcpcd[2371]: eth0: adding default route via 192.168.1.1 Jun 11 20:11:00 volumio avahi-daemon[801]: Registering new address record for 192.168.1.12 on wlan0.IPv4. Jun 11 20:11:00 volumio dhcpcd[2371]: wlan0: adding host route to 192.168.1.12 via 127.0.0.1 Jun 11 20:11:00 volumio dhcpcd[2371]: wlan0: adding route to 192.168.1.0/24 Jun 11 20:11:00 volumio dhcpcd[2371]: wlan0: adding default route via 192.168.1.1 Jun 11 20:11:00 volumio dhcpcd[2371]: eth0: removing route to 192.168.1.0/24 Jun 11 20:11:00 volumio dhcpcd[2371]: eth0: removing default route via 192.168.1.1 Jun 11 20:11:00 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jun 11 20:11:01 volumio wireless.js[2198]: trying... Jun 11 20:11:01 volumio sudo[2434]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 11 20:11:01 volumio sudo[2434]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:11:01 volumio sudo[2434]: pam_unix(sudo:session): session closed for user root Jun 11 20:11:01 volumio wireless.js[2198]: Connected to: ----Living 2G Jun 11 20:11:01 volumio wireless.js[2198]: ---- Jun 11 20:11:01 volumio sudo[2443]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 11 20:11:01 volumio sudo[2443]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:11:01 volumio sudo[2443]: pam_unix(sudo:session): session closed for user root Jun 11 20:11:01 volumio wireless.js[2198]: ... joined AP, wlan0 IPv4 is 192.168.1.12, ipV6 is undefined Jun 11 20:11:01 volumio wireless.js[2198]: It's done! AP Jun 11 20:11:01 volumio systemd[1]: Started Wireless Services. Jun 11 20:11:01 volumio sudo[2183]: pam_unix(sudo:session): session closed for user root Jun 11 20:11:01 volumio ntpd[875]: Listen normally on 5 eth0 192.168.1.5 UDP 123 Jun 11 20:11:01 volumio ntpd[875]: Listen normally on 6 wlan0 192.168.1.12 UDP 123 Jun 11 20:11:01 volumio ntpd[875]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=245 secs Jun 11 20:11:01 volumio ntpd[875]: peers refreshed Jun 11 20:11:10 volumio volumio[1053]: Discovery: adding 62036db6-8722-46e7-ae2e-9a58d9808c11 Jun 11 20:11:10 volumio volumio[1053]: info: mDNS: Found device Volumio Jun 11 20:11:10 volumio volumio[1053]: info: CoreCommandRouter::volumioGetState Jun 11 20:11:10 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:11:15 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Jun 11 20:11:15 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Jun 11 20:11:15 volumio sudo[2466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 11 20:11:15 volumio sudo[2466]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:11:15 volumio sudo[2466]: pam_unix(sudo:session): session closed for user root Jun 11 20:11:15 volumio sudo[2475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 11 20:11:15 volumio sudo[2475]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:11:15 volumio sudo[2475]: pam_unix(sudo:session): session closed for user root Jun 11 20:11:20 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Jun 11 20:11:20 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Jun 11 20:11:20 volumio volumio[1053]: info: Refreshing Cached IP Addresses Jun 11 20:11:20 volumio sudo[2497]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Jun 11 20:11:20 volumio sudo[2497]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:11:20 volumio sudo[2500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 11 20:11:20 volumio sudo[2505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 11 20:11:20 volumio sudo[2500]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:11:20 volumio sudo[2497]: pam_unix(sudo:session): session closed for user root Jun 11 20:11:20 volumio sudo[2505]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:11:20 volumio sudo[2500]: pam_unix(sudo:session): session closed for user root Jun 11 20:11:20 volumio sudo[2505]: pam_unix(sudo:session): session closed for user root Jun 11 20:11:26 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Jun 11 20:11:26 volumio volumio[1053]: info: Adding a new share Jun 11 20:11:26 volumio volumio[1053]: info: No correspondence found in configuration for share Quilombazo on IP 192.168.1.4 Jun 11 20:11:26 volumio sudo[2525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.4/media/Music /mnt/NAS/Quilombazo Jun 11 20:11:26 volumio sudo[2525]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:11:26 volumio kernel: FS-Cache: Netfs 'cifs' registered for caching Jun 11 20:11:26 volumio kernel: Key type cifs.spnego registered Jun 11 20:11:26 volumio kernel: Key type cifs.idmap registered Jun 11 20:11:26 volumio 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 20:11:30 volumio sudo[2556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 11 20:11:30 volumio sudo[2556]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:11:30 volumio sudo[2558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 11 20:11:30 volumio sudo[2556]: pam_unix(sudo:session): session closed for user root Jun 11 20:11:30 volumio sudo[2558]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:11:30 volumio sudo[2558]: pam_unix(sudo:session): session closed for user root Jun 11 20:11:30 volumio sudo[2565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 11 20:11:30 volumio sudo[2565]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:11:30 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Jun 11 20:11:30 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 11 20:11:30 volumio sudo[2565]: pam_unix(sudo:session): session closed for user root Jun 11 20:11:31 volumio volumio[1053]: info: Upmpdcli Daemon Started Jun 11 20:11:34 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Jun 11 20:11:34 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Jun 11 20:11:34 volumio volumio[1053]: info: Could not detect Volumio Device: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 20:11:34 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Jun 11 20:11:34 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Jun 11 20:11:34 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Jun 11 20:11:34 volumio volumio[1053]: info: Could not detect Volumio Device: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 20:11:35 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Jun 11 20:11:35 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Jun 11 20:11:35 volumio volumio[1053]: info: Wizard terminated Successfully Jun 11 20:11:39 volumio volumio[1053]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 20:11:39 volumio volumio[1053]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 20:11:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 11 20:11:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 20:11:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 20:11:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 20:11:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:11:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:11:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:11:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:11:39 volumio volumio[1053]: info: CoreCommandRouter::volumioGetBrowseSources Jun 11 20:11:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:11:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 11 20:11:43 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:11:47 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:11:51 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:11:55 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:11:59 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:12:01 volumio nmbd[994]: [2020/06/11 20:12:01.510992, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 11 20:12:01 volumio nmbd[994]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.12 for name WORKGROUP<1d>. Jun 11 20:12:01 volumio nmbd[994]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Jun 11 20:12:01 volumio nmbd[994]: [2020/06/11 20:12:01.511237, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 11 20:12:01 volumio nmbd[994]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.5 for name WORKGROUP<1d>. Jun 11 20:12:01 volumio nmbd[994]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Jun 11 20:12:01 volumio nmbd[994]: [2020/06/11 20:12:01.511391, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 11 20:12:01 volumio nmbd[994]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.20 for name WORKGROUP<1d>. Jun 11 20:12:01 volumio nmbd[994]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Jun 11 20:12:08 volumio volumio[1053]: info: Downloading plugin at http://plugins.volumio.org/plugins/volumio/armhf/music_service/volspotconnect2/volspotconnect2.zip Jun 11 20:12:11 volumio volumio[1053]: info: END DOWNLOAD: http://plugins.volumio.org/plugins/volumio/armhf/music_service/volspotconnect2/volspotconnect2.zip Jun 11 20:12:11 volumio volumio[1053]: info: Folder /tmp/plugins removed Jun 11 20:12:11 volumio volumio[1053]: info: Checking if plugin already exists Jun 11 20:12:11 volumio volumio[1053]: info: Rename folder Jun 11 20:12:11 volumio volumio[1053]: info: Folder /tmp/downloaded_plugin.zip removed Jun 11 20:12:11 volumio volumio[1053]: info: Move to category Jun 11 20:12:17 volumio volumio[1053]: info: Checking if install.sh is present Jun 11 20:12:17 volumio volumio[1053]: info: Executing install.sh Jun 11 20:12:17 volumio sudo[2740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/volspotconnect2/install.sh Jun 11 20:12:17 volumio sudo[2740]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:12:17 volumio volumio[1053]: info: Launching a bash shell Jun 11 20:12:17 volumio volumio[1053]: info: Installing volspotconnect2 dependencies Jun 11 20:12:17 volumio volumio[1053]: info: Detected cpu architecture as armv7l Jun 11 20:12:18 volumio volumio[1053]: info: Latest version: v0.2.1 Requested version: v0.2.1 Jun 11 20:12:18 volumio volumio[1053]: info: Supported device (arch = armv7l), downloading required packages for vollibrespot v0.2.1 Jun 11 20:12:18 volumio volumio[1053]: info: Downloading file Jun 11 20:12:24 volumio volumio[1053]: info: Extracting... Jun 11 20:12:24 volumio volumio[1053]: info: -rw-r--r-- 1 root root 4357998 Jun 11 20:12 vollibrespot-armv7l.tar.xz Jun 11 20:12:24 volumio volumio[1053]: info: vollibrespot v0.2.1 1438440 2020-05-15 (librespot 659de68 2020-05-10) -- Built On 2020-05-15 Jun 11 20:12:24 volumio sudo[2784]: root : TTY=unknown ; PWD=/data/plugins/music_service/volspotconnect2 ; USER=root ; COMMAND=/bin/tar -xvf volspotconnect2.service.tar -C / Jun 11 20:12:24 volumio sudo[2784]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:12:24 volumio sudo[2784]: pam_unix(sudo:session): session closed for user root Jun 11 20:12:24 volumio volumio[1053]: info: etc/systemd/system/volspotconnect2.service Jun 11 20:12:24 volumio sudo[2792]: root : TTY=unknown ; PWD=/data/plugins/music_service/volspotconnect2 ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/music_service/volspotconnect2/onstart1.sh Jun 11 20:12:24 volumio sudo[2792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:12:24 volumio sudo[2792]: pam_unix(sudo:session): session closed for user root Jun 11 20:12:24 volumio sudo[2740]: pam_unix(sudo:session): session closed for user root Jun 11 20:12:24 volumio volumio[1053]: info: volspotconnect2 installed Jun 11 20:12:24 volumio volumio[1053]: Plugin install end detected on script Jun 11 20:12:24 volumio volumio[1053]: info: Install script completed Jun 11 20:12:24 volumio volumio[1053]: info: Adding reference to registry Jun 11 20:12:24 volumio volumio[1053]: info: Done installing plugin. Jun 11 20:12:24 volumio volumio[1053]: info: Folder /tmp/plugins removed Jun 11 20:12:24 volumio volumio[1053]: info: Folder /tmp/downloaded_plugin.zip removed Jun 11 20:12:24 volumio volumio[1053]: info: Folder /data/temp removed Jun 11 20:12:26 volumio sudo[2525]: pam_unix(sudo:session): session closed for user root Jun 11 20:12:26 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -112 Jun 11 20:12:26 volumio volumio[1053]: info: Error mounting Quilombazo on IP 192.168.1.4 : (112): Host is down Jun 11 20:12:26 volumio volumio[1053]: Refer to the mount.cifs(8) manual page (e.g. man mount.cifs) Jun 11 20:12:27 volumio volumio[1053]: info: Enabling plugin volspotconnect2 Jun 11 20:12:27 volumio volumio[1053]: info: Loading plugin "volspotconnect2"... Jun 11 20:12:27 volumio volumio[1053]: info: PLUGIN START: volspotconnect2 Jun 11 20:12:27 volumio volumio[1053]: [SpotifyConnect] Creating VLS config file Jun 11 20:12:27 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:12:27 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 20:12:27 volumio volumio[1053]: [SpotifyConnect] Starting metadata listener Jun 11 20:12:27 volumio sudo[2811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Jun 11 20:12:27 volumio sudo[2811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:12:27 volumio systemd[1]: Starting Volspotconnect2 Daemon... Jun 11 20:12:27 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 11 20:12:27 volumio systemd[1]: Started Volspotconnect2 Daemon. Jun 11 20:12:27 volumio sudo[2811]: pam_unix(sudo:session): session closed for user root Jun 11 20:12:27 volumio volumio[2818]: vollibrespot v0.2.1 1438440 2020-05-15 (librespot 659de68 2020-05-10) -- Built On 2020-05-15 Jun 11 20:12:27 volumio volumio[2818]: Reading Config from "volspotify.toml" Jun 11 20:12:27 volumio volumio[2818]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Jun 11 20:12:27 volumio volumio[1053]: [SpotifyConnect] Vollibrespot Daemon service started! Jun 11 20:12:27 volumio volumio[1053]: [Metrics] SpotifyConnect: 0s 104.55ms Jun 11 20:12:27 volumio volumio[1053]: info: Done. Jun 11 20:12:27 volumio volumio[2818]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Jun 11 20:12:31 volumio volumio[1053]: info: Downloading plugin at http://plugins.volumio.org/plugins/volumio/armhf/music_service/spop/spop.zip Jun 11 20:12:34 volumio volumio[1053]: info: END DOWNLOAD: http://plugins.volumio.org/plugins/volumio/armhf/music_service/spop/spop.zip Jun 11 20:12:34 volumio volumio[1053]: info: Folder /tmp/plugins removed Jun 11 20:12:35 volumio volumio[1053]: info: Checking if plugin already exists Jun 11 20:12:35 volumio volumio[1053]: info: Rename folder Jun 11 20:12:35 volumio volumio[1053]: info: Folder /tmp/downloaded_plugin.zip removed Jun 11 20:12:35 volumio volumio[1053]: info: Move to category Jun 11 20:12:42 volumio volumio[1053]: info: Checking if install.sh is present Jun 11 20:12:42 volumio volumio[1053]: info: Executing install.sh Jun 11 20:12:42 volumio sudo[2852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Jun 11 20:12:42 volumio sudo[2852]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:12:42 volumio volumio[1053]: info: Installing Spop Dependencies Jun 11 20:12:42 volumio sudo[2861]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get update Jun 11 20:12:42 volumio sudo[2861]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:12:43 volumio volumio[1053]: info: Get:1 http://archive.volumio.org jessie InRelease [14.9 kB] Jun 11 20:12:43 volumio volumio[1053]: info: Get:2 http://archive.volumio.org jessie InRelease [22.9 kB] Jun 11 20:12:44 volumio volumio[1053]: info: Get:3 http://archive.volumio.org jessie/main Sources [7762 kB] Jun 11 20:12:51 volumio volumio[1053]: info: Get:4 http://archive.volumio.org jessie/contrib Sources [64.5 kB] Jun 11 20:12:52 volumio volumio[1053]: info: Get:5 http://archive.volumio.org jessie/non-free Sources [113 kB] Jun 11 20:12:52 volumio volumio[1053]: info: Get:6 http://archive.volumio.org jessie/rpi Sources [1132 B] Jun 11 20:12:53 volumio volumio[1053]: info: Get:7 http://archive.volumio.org jessie/main armhf Packages [9539 kB] Jun 11 20:13:01 volumio volumio[1053]: info: Get:8 http://archive.volumio.org jessie/contrib armhf Packages [43.3 kB] Jun 11 20:13:01 volumio volumio[1053]: info: Get:9 http://archive.volumio.org jessie/non-free armhf Packages [88.1 kB] Jun 11 20:13:01 volumio volumio[1053]: info: Get:10 http://archive.volumio.org jessie/rpi armhf Packages [1356 B] Jun 11 20:13:03 volumio volumio[1053]: info: Get:11 http://archive.volumio.org jessie/main Sources [57.1 kB] Jun 11 20:13:03 volumio volumio[1053]: info: Get:12 http://archive.volumio.org jessie/ui Sources [17.4 kB] Jun 11 20:13:04 volumio volumio[1053]: info: Get:13 http://archive.volumio.org jessie/main armhf Packages [171 kB] Jun 11 20:13:04 volumio volumio[1053]: info: Get:14 http://archive.volumio.org jessie/ui armhf Packages [58.9 kB] Jun 11 20:13:13 volumio volumio[1053]: info: Ign http://archive.volumio.org jessie/contrib Translation-en Jun 11 20:13:13 volumio volumio[1053]: info: Ign http://archive.volumio.org jessie/main Translation-en Jun 11 20:13:13 volumio volumio[1053]: info: Ign http://archive.volumio.org jessie/non-free Translation-en Jun 11 20:13:14 volumio volumio[1053]: info: Ign http://archive.volumio.org jessie/rpi Translation-en Jun 11 20:13:14 volumio volumio[1053]: info: Ign http://archive.volumio.org jessie/main Translation-en Jun 11 20:13:14 volumio volumio[1053]: info: Ign http://archive.volumio.org jessie/ui Translation-en Jun 11 20:13:17 volumio volumio[1053]: info: Fetched 18.0 MB in 31s (566 kB/s) Jun 11 20:13:44 volumio volumio[1053]: info: Reading package lists... Jun 11 20:13:44 volumio sudo[2861]: pam_unix(sudo:session): session closed for user root Jun 11 20:13:44 volumio sudo[3034]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt-get -y install libao-dev libglib2.0-dev libjson-glib-1.0-0 libjson-glib-dev libao-common libreadline-dev libsox-dev libsoup2.4-dev libsoup2.4-1 libdbus-glib-1-dev libnotify-dev --no-install-recommends Jun 11 20:13:44 volumio sudo[3034]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:13:44 volumio volumio[1053]: info: Reading package lists... Jun 11 20:13:45 volumio volumio[1053]: info: Building dependency tree... Jun 11 20:13:45 volumio volumio[1053]: info: Reading state information... Jun 11 20:13:46 volumio volumio[1053]: info: libao-common is already the newest version. Jun 11 20:13:46 volumio volumio[1053]: info: libdbus-glib-1-dev is already the newest version. Jun 11 20:13:46 volumio volumio[1053]: info: libglib2.0-dev is already the newest version. Jun 11 20:13:46 volumio volumio[1053]: info: libglib2.0-dev set to manually installed. Jun 11 20:13:46 volumio volumio[1053]: info: The following extra packages will be installed: Jun 11 20:13:46 volumio volumio[1053]: info: dconf-gsettings-backend dconf-service gir1.2-gdkpixbuf-2.0 gir1.2-json-1.0 Jun 11 20:13:46 volumio volumio[1053]: info: gir1.2-notify-0.7 gir1.2-soup-2.4 glib-networking glib-networking-common Jun 11 20:13:46 volumio volumio[1053]: info: glib-networking-services gsettings-desktop-schemas libdconf1 Jun 11 20:13:46 volumio volumio[1053]: info: libgdk-pixbuf2.0-dev libjson-glib-1.0-common libnotify4 libpng12-dev Jun 11 20:13:46 volumio volumio[1053]: info: libproxy1 libpthread-stubs0-dev libreadline6-dev libsoup-gnome2.4-1 Jun 11 20:13:46 volumio volumio[1053]: info: libsox-fmt-all libsox-fmt-ao libsox-fmt-mp3 libsox-fmt-oss libsox-fmt-pulse Jun 11 20:13:46 volumio volumio[1053]: info: libtinfo-dev libtwolame0 libx11-dev libxau-dev libxcb1-dev libxdmcp-dev Jun 11 20:13:46 volumio volumio[1053]: info: libxml2-dev x11proto-core-dev x11proto-input-dev x11proto-kb-dev Jun 11 20:13:46 volumio volumio[1053]: info: xorg-sgml-doctools xtrans-dev Jun 11 20:13:46 volumio volumio[1053]: info: Suggested packages: Jun 11 20:13:46 volumio volumio[1053]: info: libjson-glib-doc readline-doc libsoup2.4-doc libxcb-doc Jun 11 20:13:46 volumio volumio[1053]: info: Recommended packages: Jun 11 20:13:46 volumio volumio[1053]: info: notification-daemon libx11-doc Jun 11 20:13:46 volumio volumio[1053]: info: The following NEW packages will be installed: Jun 11 20:13:46 volumio volumio[1053]: info: dconf-gsettings-backend dconf-service gir1.2-gdkpixbuf-2.0 gir1.2-json-1.0 Jun 11 20:13:46 volumio volumio[1053]: info: gir1.2-notify-0.7 gir1.2-soup-2.4 glib-networking glib-networking-common Jun 11 20:13:46 volumio volumio[1053]: info: glib-networking-services gsettings-desktop-schemas libao-dev libdconf1 Jun 11 20:13:46 volumio volumio[1053]: info: libgdk-pixbuf2.0-dev libjson-glib-1.0-0 libjson-glib-1.0-common Jun 11 20:13:46 volumio volumio[1053]: info: libjson-glib-dev libnotify-dev libnotify4 libpng12-dev libproxy1 Jun 11 20:13:46 volumio volumio[1053]: info: libpthread-stubs0-dev libreadline-dev libreadline6-dev libsoup-gnome2.4-1 Jun 11 20:13:46 volumio volumio[1053]: info: libsoup2.4-1 libsoup2.4-dev libsox-dev libsox-fmt-all libsox-fmt-ao Jun 11 20:13:46 volumio volumio[1053]: info: libsox-fmt-mp3 libsox-fmt-oss libsox-fmt-pulse libtinfo-dev libtwolame0 Jun 11 20:13:46 volumio volumio[1053]: info: libx11-dev libxau-dev libxcb1-dev libxdmcp-dev libxml2-dev x11proto-core-dev Jun 11 20:13:46 volumio volumio[1053]: info: x11proto-input-dev x11proto-kb-dev xorg-sgml-doctools xtrans-dev Jun 11 20:13:46 volumio volumio[1053]: info: 0 upgraded, 44 newly installed, 0 to remove and 24 not upgraded. Jun 11 20:13:46 volumio volumio[1053]: info: Need to get 5840 kB of archives. Jun 11 20:13:46 volumio volumio[1053]: info: After this operation, 21.6 MB of additional disk space will be used. Jun 11 20:13:46 volumio volumio[1053]: info: Get:1 http://archive.volumio.org/raspbian/ jessie/main libdconf1 armhf 0.22.0-1 [31.1 kB] Jun 11 20:13:47 volumio volumio[1053]: info: Get:2 http://archive.volumio.org/raspbian/ jessie/main libjson-glib-1.0-common all 1.0.2-1 [147 kB] Jun 11 20:13:48 volumio volumio[1053]: info: Get:3 http://archive.volumio.org/raspbian/ jessie/main libjson-glib-1.0-0 armhf 1.0.2-1 [151 kB] Jun 11 20:13:49 volumio volumio[1053]: info: Get:4 http://archive.volumio.org/raspbian/ jessie/main libnotify4 armhf 0.7.6-2 [26.4 kB] Jun 11 20:13:52 volumio volumio[1053]: info: Get:5 http://archive.volumio.org/raspbian/ jessie/main libproxy1 armhf 0.4.11-4 [55.6 kB] Jun 11 20:13:52 volumio volumio[1053]: info: Get:6 http://archive.volumio.org/raspbian/ jessie/main glib-networking-common all 2.42.0-2 [52.1 kB] Jun 11 20:13:53 volumio volumio[1053]: info: Get:7 http://archive.volumio.org/raspbian/ jessie/main glib-networking-services armhf 2.42.0-2 [16.6 kB] Jun 11 20:13:54 volumio volumio[1053]: info: Get:8 http://archive.volumio.org/raspbian/ jessie/main dconf-service armhf 0.22.0-1 [38.0 kB] Jun 11 20:13:54 volumio volumio[1053]: info: Get:9 http://archive.volumio.org/raspbian/ jessie/main dconf-gsettings-backend armhf 0.22.0-1 [30.1 kB] Jun 11 20:13:54 volumio volumio[1053]: info: Get:10 http://archive.volumio.org/raspbian/ jessie/main gsettings-desktop-schemas all 3.14.1-1 [391 kB] Jun 11 20:13:55 volumio volumio[1053]: info: Get:11 http://archive.volumio.org/raspbian/ jessie/main glib-networking armhf 2.42.0-2 [43.9 kB] Jun 11 20:13:56 volumio volumio[1053]: info: Get:12 http://archive.volumio.org/raspbian/ jessie/main libsoup2.4-1 armhf 2.48.0-1+deb8u2 [216 kB] Jun 11 20:13:56 volumio volumio[1053]: info: Get:13 http://archive.volumio.org/raspbian/ jessie/main libsoup-gnome2.4-1 armhf 2.48.0-1+deb8u2 [57.1 kB] Jun 11 20:13:57 volumio volumio[1053]: info: Get:14 http://archive.volumio.org/raspbian/ jessie/main gir1.2-gdkpixbuf-2.0 armhf 2.31.1-2+deb8u7 [16.9 kB] Jun 11 20:13:57 volumio volumio[1053]: info: Get:15 http://archive.volumio.org/raspbian/ jessie/main gir1.2-json-1.0 armhf 1.0.2-1 [114 kB] Jun 11 20:13:57 volumio volumio[1053]: info: Get:16 http://archive.volumio.org/raspbian/ jessie/main gir1.2-notify-0.7 armhf 0.7.6-2 [19.8 kB] Jun 11 20:13:58 volumio volumio[1053]: info: Get:17 http://archive.volumio.org/raspbian/ jessie/main gir1.2-soup-2.4 armhf 2.48.0-1+deb8u2 [75.1 kB] Jun 11 20:13:58 volumio volumio[1053]: info: Get:18 http://archive.volumio.org/raspbian/ jessie/main libao-dev armhf 1.1.0-3 [49.5 kB] Jun 11 20:13:58 volumio volumio[1053]: info: Get:19 http://archive.volumio.org/raspbian/ jessie/main xorg-sgml-doctools all 1:1.11-1 [21.9 kB] Jun 11 20:13:59 volumio volumio[1053]: info: Get:20 http://archive.volumio.org/debian/ jessie/main x11proto-core-dev all 7.0.31-1 [728 kB] Jun 11 20:14:00 volumio volumio[1053]: info: Get:21 http://archive.volumio.org/raspbian/ jessie/main libxau-dev armhf 1:1.0.8-1 [23.0 kB] Jun 11 20:14:00 volumio volumio[1053]: info: Get:22 http://archive.volumio.org/raspbian/ jessie/main libxdmcp-dev armhf 1:1.1.1-1 [40.8 kB] Jun 11 20:14:01 volumio volumio[1053]: info: Get:23 http://archive.volumio.org/raspbian/ jessie/main x11proto-input-dev all 2.3.1-1 [157 kB] Jun 11 20:14:01 volumio volumio[1053]: info: Get:24 http://archive.volumio.org/raspbian/ jessie/main x11proto-kb-dev all 1.0.6-2 [269 kB] Jun 11 20:14:02 volumio volumio[1053]: info: Get:25 http://archive.volumio.org/debian/ jessie/main xtrans-dev all 1.3.5-1 [100 kB] Jun 11 20:14:02 volumio volumio[1053]: info: Get:26 http://archive.volumio.org/raspbian/ jessie/main libpthread-stubs0-dev armhf 0.3-4 [4042 B] Jun 11 20:14:03 volumio volumio[1053]: info: Get:27 http://archive.volumio.org/raspbian/ jessie/main libxcb1-dev armhf 1.10-3 [78.1 kB] Jun 11 20:14:03 volumio volumio[1053]: info: Get:28 http://archive.volumio.org/raspbian/ jessie/main libx11-dev armhf 2:1.6.2-3+deb8u2 [736 kB] Jun 11 20:14:04 volumio volumio[1053]: info: Get:29 http://archive.volumio.org/raspbian/ jessie/main libpng12-dev armhf 1.2.50-2+deb8u3 [237 kB] Jun 11 20:14:06 volumio volumio[1053]: info: Get:30 http://archive.volumio.org/raspbian/ jessie/main libgdk-pixbuf2.0-dev armhf 2.31.1-2+deb8u7 [52.5 kB] Jun 11 20:14:06 volumio volumio[1053]: info: Get:31 http://archive.volumio.org/raspbian/ jessie/main libjson-glib-dev armhf 1.0.2-1 [131 kB] Jun 11 20:14:07 volumio volumio[1053]: info: Get:32 http://archive.volumio.org/raspbian/ jessie/main libnotify-dev armhf 0.7.6-2 [31.3 kB] Jun 11 20:14:07 volumio volumio[1053]: info: Get:33 http://archive.volumio.org/raspbian/ jessie/main libtinfo-dev armhf 5.9+20140913-1+deb8u3 [66.2 kB] Jun 11 20:14:07 volumio volumio[1053]: info: Get:34 http://archive.volumio.org/raspbian/ jessie/main libreadline6-dev armhf 6.3-8 [105 kB] Jun 11 20:14:08 volumio volumio[1053]: info: Get:35 http://archive.volumio.org/raspbian/ jessie/main libreadline-dev armhf 6.3-8 [994 B] Jun 11 20:14:08 volumio volumio[1053]: info: Get:36 http://archive.volumio.org/raspbian/ jessie/main libxml2-dev armhf 2.9.1+dfsg1-5+deb8u7 [616 kB] Jun 11 20:14:09 volumio volumio[1053]: info: Get:37 http://archive.volumio.org/raspbian/ jessie/main libsoup2.4-dev armhf 2.48.0-1+deb8u2 [330 kB] Jun 11 20:14:10 volumio volumio[1053]: info: Get:38 http://archive.volumio.org/raspbian/ jessie/main libsox-fmt-ao armhf 14.4.1-5 [43.7 kB] Jun 11 20:14:11 volumio volumio[1053]: info: Get:39 http://archive.volumio.org/raspbian/ jessie/main libtwolame0 armhf 0.3.13-1.1 [47.3 kB] Jun 11 20:14:11 volumio volumio[1053]: info: Get:40 http://archive.volumio.org/raspbian/ jessie/main libsox-fmt-mp3 armhf 14.4.1-5 [52.1 kB] Jun 11 20:14:11 volumio volumio[1053]: info: Get:41 http://archive.volumio.org/raspbian/ jessie/main libsox-fmt-oss armhf 14.4.1-5 [44.0 kB] Jun 11 20:14:12 volumio volumio[1053]: info: Get:42 http://archive.volumio.org/raspbian/ jessie/main libsox-fmt-pulse armhf 14.4.1-5 [43.5 kB] Jun 11 20:14:12 volumio volumio[1053]: info: Get:43 http://archive.volumio.org/raspbian/ jessie/main libsox-fmt-all armhf 14.4.1-5 [41.2 kB] Jun 11 20:14:12 volumio volumio[1053]: info: Get:44 http://archive.volumio.org/raspbian/ jessie/main libsox-dev armhf 14.4.1-5 [308 kB] Jun 11 20:14:13 volumio volumio[1053]: info: Fetched 5840 kB in 26s (217 kB/s) Jun 11 20:14:14 volumio volumio[1053]: info: Selecting previously unselected package libdconf1:armhf. Jun 11 20:14:15 volumio volumio[1053]: [620B blob data] Jun 11 20:14:15 volumio volumio[1053]: info: Preparing to unpack .../libdconf1_0.22.0-1_armhf.deb ... Jun 11 20:14:15 volumio volumio[1053]: info: Unpacking libdconf1:armhf (0.22.0-1) ... Jun 11 20:14:16 volumio volumio[1053]: info: Selecting previously unselected package libjson-glib-1.0-common. Jun 11 20:14:16 volumio volumio[1053]: info: Preparing to unpack .../libjson-glib-1.0-common_1.0.2-1_all.deb ... Jun 11 20:14:16 volumio volumio[1053]: info: Unpacking libjson-glib-1.0-common (1.0.2-1) ... Jun 11 20:14:16 volumio volumio[1053]: info: Selecting previously unselected package libjson-glib-1.0-0:armhf. Jun 11 20:14:16 volumio volumio[1053]: info: Preparing to unpack .../libjson-glib-1.0-0_1.0.2-1_armhf.deb ... Jun 11 20:14:16 volumio volumio[1053]: info: Unpacking libjson-glib-1.0-0:armhf (1.0.2-1) ... Jun 11 20:14:18 volumio volumio[1053]: info: Selecting previously unselected package libnotify4:armhf. Jun 11 20:14:18 volumio volumio[1053]: info: Preparing to unpack .../libnotify4_0.7.6-2_armhf.deb ... Jun 11 20:14:18 volumio volumio[1053]: info: Unpacking libnotify4:armhf (0.7.6-2) ... Jun 11 20:14:18 volumio volumio[1053]: info: Selecting previously unselected package libproxy1:armhf. Jun 11 20:14:18 volumio volumio[1053]: info: Preparing to unpack .../libproxy1_0.4.11-4_armhf.deb ... Jun 11 20:14:18 volumio volumio[1053]: info: Unpacking libproxy1:armhf (0.4.11-4) ... Jun 11 20:14:19 volumio volumio[1053]: info: Selecting previously unselected package glib-networking-common. Jun 11 20:14:19 volumio volumio[1053]: info: Preparing to unpack .../glib-networking-common_2.42.0-2_all.deb ... Jun 11 20:14:19 volumio volumio[1053]: info: Unpacking glib-networking-common (2.42.0-2) ... Jun 11 20:14:19 volumio volumio[1053]: info: Selecting previously unselected package glib-networking-services. Jun 11 20:14:19 volumio volumio[1053]: info: Preparing to unpack .../glib-networking-services_2.42.0-2_armhf.deb ... Jun 11 20:14:19 volumio volumio[1053]: info: Unpacking glib-networking-services (2.42.0-2) ... Jun 11 20:14:19 volumio volumio[1053]: info: Selecting previously unselected package dconf-service. Jun 11 20:14:19 volumio volumio[1053]: info: Preparing to unpack .../dconf-service_0.22.0-1_armhf.deb ... Jun 11 20:14:19 volumio volumio[1053]: info: Unpacking dconf-service (0.22.0-1) ... Jun 11 20:14:29 volumio volumio[1053]: info: Selecting previously unselected package dconf-gsettings-backend:armhf. Jun 11 20:14:30 volumio volumio[1053]: info: Preparing to unpack .../dconf-gsettings-backend_0.22.0-1_armhf.deb ... Jun 11 20:14:30 volumio volumio[1053]: info: Unpacking dconf-gsettings-backend:armhf (0.22.0-1) ... Jun 11 20:14:30 volumio volumio[1053]: info: Selecting previously unselected package gsettings-desktop-schemas. Jun 11 20:14:30 volumio volumio[1053]: info: Preparing to unpack .../gsettings-desktop-schemas_3.14.1-1_all.deb ... Jun 11 20:14:30 volumio volumio[1053]: info: Unpacking gsettings-desktop-schemas (3.14.1-1) ... Jun 11 20:14:31 volumio volumio[1053]: info: Selecting previously unselected package glib-networking:armhf. Jun 11 20:14:31 volumio volumio[1053]: info: Preparing to unpack .../glib-networking_2.42.0-2_armhf.deb ... Jun 11 20:14:31 volumio volumio[1053]: info: Unpacking glib-networking:armhf (2.42.0-2) ... Jun 11 20:14:34 volumio volumio[1053]: info: Selecting previously unselected package libsoup2.4-1:armhf. Jun 11 20:14:34 volumio volumio[1053]: info: Preparing to unpack .../libsoup2.4-1_2.48.0-1+deb8u2_armhf.deb ... Jun 11 20:14:34 volumio volumio[1053]: info: Unpacking libsoup2.4-1:armhf (2.48.0-1+deb8u2) ... Jun 11 20:14:36 volumio volumio[1053]: info: Selecting previously unselected package libsoup-gnome2.4-1:armhf. Jun 11 20:14:36 volumio volumio[1053]: info: Preparing to unpack .../libsoup-gnome2.4-1_2.48.0-1+deb8u2_armhf.deb ... Jun 11 20:14:36 volumio volumio[1053]: info: Unpacking libsoup-gnome2.4-1:armhf (2.48.0-1+deb8u2) ... Jun 11 20:14:37 volumio volumio[1053]: info: Selecting previously unselected package gir1.2-gdkpixbuf-2.0. Jun 11 20:14:37 volumio volumio[1053]: info: Preparing to unpack .../gir1.2-gdkpixbuf-2.0_2.31.1-2+deb8u7_armhf.deb ... Jun 11 20:14:37 volumio volumio[1053]: info: Unpacking gir1.2-gdkpixbuf-2.0 (2.31.1-2+deb8u7) ... Jun 11 20:14:38 volumio volumio[1053]: info: Selecting previously unselected package gir1.2-json-1.0. Jun 11 20:14:38 volumio volumio[1053]: info: Preparing to unpack .../gir1.2-json-1.0_1.0.2-1_armhf.deb ... Jun 11 20:14:38 volumio volumio[1053]: info: Unpacking gir1.2-json-1.0 (1.0.2-1) ... Jun 11 20:14:38 volumio volumio[1053]: info: Selecting previously unselected package gir1.2-notify-0.7. Jun 11 20:14:38 volumio volumio[1053]: info: Preparing to unpack .../gir1.2-notify-0.7_0.7.6-2_armhf.deb ... Jun 11 20:14:38 volumio volumio[1053]: info: Unpacking gir1.2-notify-0.7 (0.7.6-2) ... Jun 11 20:14:40 volumio volumio[1053]: info: Selecting previously unselected package gir1.2-soup-2.4. Jun 11 20:14:40 volumio volumio[1053]: info: Preparing to unpack .../gir1.2-soup-2.4_2.48.0-1+deb8u2_armhf.deb ... Jun 11 20:14:40 volumio volumio[1053]: info: Unpacking gir1.2-soup-2.4 (2.48.0-1+deb8u2) ... Jun 11 20:14:42 volumio volumio[1053]: info: Selecting previously unselected package libao-dev. Jun 11 20:14:42 volumio volumio[1053]: info: Preparing to unpack .../libao-dev_1.1.0-3_armhf.deb ... Jun 11 20:14:42 volumio volumio[1053]: info: Unpacking libao-dev (1.1.0-3) ... Jun 11 20:14:42 volumio volumio[1053]: info: Selecting previously unselected package xorg-sgml-doctools. Jun 11 20:14:42 volumio volumio[1053]: info: Preparing to unpack .../xorg-sgml-doctools_1%3a1.11-1_all.deb ... Jun 11 20:14:42 volumio volumio[1053]: info: Unpacking xorg-sgml-doctools (1:1.11-1) ... Jun 11 20:14:44 volumio volumio[1053]: info: Selecting previously unselected package x11proto-core-dev. Jun 11 20:14:44 volumio volumio[1053]: info: Preparing to unpack .../x11proto-core-dev_7.0.31-1_all.deb ... Jun 11 20:14:44 volumio volumio[1053]: info: Unpacking x11proto-core-dev (7.0.31-1) ... Jun 11 20:14:45 volumio volumio[1053]: info: Selecting previously unselected package libxau-dev:armhf. Jun 11 20:14:45 volumio volumio[1053]: info: Preparing to unpack .../libxau-dev_1%3a1.0.8-1_armhf.deb ... Jun 11 20:14:45 volumio volumio[1053]: info: Unpacking libxau-dev:armhf (1:1.0.8-1) ... Jun 11 20:14:46 volumio volumio[1053]: info: Selecting previously unselected package libxdmcp-dev:armhf. Jun 11 20:14:46 volumio volumio[1053]: info: Preparing to unpack .../libxdmcp-dev_1%3a1.1.1-1_armhf.deb ... Jun 11 20:14:46 volumio volumio[1053]: info: Unpacking libxdmcp-dev:armhf (1:1.1.1-1) ... Jun 11 20:14:46 volumio volumio[1053]: info: Selecting previously unselected package x11proto-input-dev. Jun 11 20:14:46 volumio volumio[1053]: info: Preparing to unpack .../x11proto-input-dev_2.3.1-1_all.deb ... Jun 11 20:14:46 volumio volumio[1053]: info: Unpacking x11proto-input-dev (2.3.1-1) ... Jun 11 20:14:47 volumio volumio[1053]: info: Selecting previously unselected package x11proto-kb-dev. Jun 11 20:14:47 volumio volumio[1053]: info: Preparing to unpack .../x11proto-kb-dev_1.0.6-2_all.deb ... Jun 11 20:14:47 volumio volumio[1053]: info: Unpacking x11proto-kb-dev (1.0.6-2) ... Jun 11 20:14:48 volumio volumio[1053]: info: Selecting previously unselected package xtrans-dev. Jun 11 20:14:48 volumio volumio[1053]: info: Preparing to unpack .../xtrans-dev_1.3.5-1_all.deb ... Jun 11 20:14:48 volumio volumio[1053]: info: Unpacking xtrans-dev (1.3.5-1) ... Jun 11 20:14:49 volumio volumio[1053]: info: Selecting previously unselected package libpthread-stubs0-dev:armhf. Jun 11 20:14:49 volumio volumio[1053]: info: Preparing to unpack .../libpthread-stubs0-dev_0.3-4_armhf.deb ... Jun 11 20:14:49 volumio volumio[1053]: info: Unpacking libpthread-stubs0-dev:armhf (0.3-4) ... Jun 11 20:14:49 volumio volumio[1053]: info: Selecting previously unselected package libxcb1-dev:armhf. Jun 11 20:14:49 volumio volumio[1053]: info: Preparing to unpack .../libxcb1-dev_1.10-3_armhf.deb ... Jun 11 20:14:49 volumio volumio[1053]: info: Unpacking libxcb1-dev:armhf (1.10-3) ... Jun 11 20:14:49 volumio volumio[1053]: info: Selecting previously unselected package libx11-dev:armhf. Jun 11 20:14:49 volumio volumio[1053]: info: Preparing to unpack .../libx11-dev_2%3a1.6.2-3+deb8u2_armhf.deb ... Jun 11 20:14:49 volumio volumio[1053]: info: Unpacking libx11-dev:armhf (2:1.6.2-3+deb8u2) ... Jun 11 20:14:50 volumio volumio[1053]: info: Selecting previously unselected package libpng12-dev:armhf. Jun 11 20:14:50 volumio volumio[1053]: info: Preparing to unpack .../libpng12-dev_1.2.50-2+deb8u3_armhf.deb ... Jun 11 20:14:50 volumio volumio[1053]: info: Unpacking libpng12-dev:armhf (1.2.50-2+deb8u3) ... Jun 11 20:14:52 volumio volumio[1053]: info: Selecting previously unselected package libgdk-pixbuf2.0-dev. Jun 11 20:14:52 volumio volumio[1053]: info: Preparing to unpack .../libgdk-pixbuf2.0-dev_2.31.1-2+deb8u7_armhf.deb ... Jun 11 20:14:52 volumio volumio[1053]: info: Unpacking libgdk-pixbuf2.0-dev (2.31.1-2+deb8u7) ... Jun 11 20:14:53 volumio volumio[1053]: info: Selecting previously unselected package libjson-glib-dev. Jun 11 20:14:53 volumio volumio[1053]: info: Preparing to unpack .../libjson-glib-dev_1.0.2-1_armhf.deb ... Jun 11 20:14:53 volumio volumio[1053]: info: Unpacking libjson-glib-dev (1.0.2-1) ... Jun 11 20:14:57 volumio volumio[1053]: info: Selecting previously unselected package libnotify-dev. Jun 11 20:14:57 volumio volumio[1053]: info: Preparing to unpack .../libnotify-dev_0.7.6-2_armhf.deb ... Jun 11 20:14:57 volumio volumio[1053]: info: Unpacking libnotify-dev (0.7.6-2) ... Jun 11 20:15:06 volumio volumio[1053]: info: Selecting previously unselected package libtinfo-dev:armhf. Jun 11 20:15:06 volumio volumio[1053]: info: Preparing to unpack .../libtinfo-dev_5.9+20140913-1+deb8u3_armhf.deb ... Jun 11 20:15:07 volumio volumio[1053]: info: Unpacking libtinfo-dev:armhf (5.9+20140913-1+deb8u3) ... Jun 11 20:15:08 volumio volumio[1053]: info: Selecting previously unselected package libreadline6-dev:armhf. Jun 11 20:15:08 volumio volumio[1053]: info: Preparing to unpack .../libreadline6-dev_6.3-8_armhf.deb ... Jun 11 20:15:08 volumio volumio[1053]: info: Unpacking libreadline6-dev:armhf (6.3-8) ... Jun 11 20:15:10 volumio volumio[1053]: info: Selecting previously unselected package libreadline-dev:armhf. Jun 11 20:15:10 volumio volumio[1053]: info: Preparing to unpack .../libreadline-dev_6.3-8_armhf.deb ... Jun 11 20:15:11 volumio volumio[1053]: info: Unpacking libreadline-dev:armhf (6.3-8) ... Jun 11 20:15:12 volumio volumio[1053]: info: Selecting previously unselected package libxml2-dev:armhf. Jun 11 20:15:12 volumio volumio[1053]: info: Preparing to unpack .../libxml2-dev_2.9.1+dfsg1-5+deb8u7_armhf.deb ... Jun 11 20:15:12 volumio volumio[1053]: info: Unpacking libxml2-dev:armhf (2.9.1+dfsg1-5+deb8u7) ... Jun 11 20:15:15 volumio volumio[1053]: info: Selecting previously unselected package libsoup2.4-dev. Jun 11 20:15:15 volumio volumio[1053]: info: Preparing to unpack .../libsoup2.4-dev_2.48.0-1+deb8u2_armhf.deb ... Jun 11 20:15:15 volumio volumio[1053]: info: Unpacking libsoup2.4-dev (2.48.0-1+deb8u2) ... Jun 11 20:15:18 volumio volumio[1053]: info: Selecting previously unselected package libsox-fmt-ao:armhf. Jun 11 20:15:18 volumio volumio[1053]: info: Preparing to unpack .../libsox-fmt-ao_14.4.1-5_armhf.deb ... Jun 11 20:15:18 volumio volumio[1053]: info: Unpacking libsox-fmt-ao:armhf (14.4.1-5) ... Jun 11 20:15:18 volumio volumio[1053]: info: Selecting previously unselected package libtwolame0. Jun 11 20:15:18 volumio volumio[1053]: info: Preparing to unpack .../libtwolame0_0.3.13-1.1_armhf.deb ... Jun 11 20:15:18 volumio volumio[1053]: info: Unpacking libtwolame0 (0.3.13-1.1) ... Jun 11 20:15:19 volumio volumio[1053]: info: Selecting previously unselected package libsox-fmt-mp3:armhf. Jun 11 20:15:20 volumio volumio[1053]: info: Preparing to unpack .../libsox-fmt-mp3_14.4.1-5_armhf.deb ... Jun 11 20:15:20 volumio volumio[1053]: info: Unpacking libsox-fmt-mp3:armhf (14.4.1-5) ... Jun 11 20:15:20 volumio volumio[1053]: info: Selecting previously unselected package libsox-fmt-oss:armhf. Jun 11 20:15:20 volumio volumio[1053]: info: Preparing to unpack .../libsox-fmt-oss_14.4.1-5_armhf.deb ... Jun 11 20:15:20 volumio volumio[1053]: info: Unpacking libsox-fmt-oss:armhf (14.4.1-5) ... Jun 11 20:15:21 volumio volumio[1053]: info: Selecting previously unselected package libsox-fmt-pulse:armhf. Jun 11 20:15:21 volumio volumio[1053]: info: Preparing to unpack .../libsox-fmt-pulse_14.4.1-5_armhf.deb ... Jun 11 20:15:21 volumio volumio[1053]: info: Unpacking libsox-fmt-pulse:armhf (14.4.1-5) ... Jun 11 20:15:21 volumio volumio[1053]: info: Selecting previously unselected package libsox-fmt-all:armhf. Jun 11 20:15:21 volumio volumio[1053]: info: Preparing to unpack .../libsox-fmt-all_14.4.1-5_armhf.deb ... Jun 11 20:15:21 volumio volumio[1053]: info: Unpacking libsox-fmt-all:armhf (14.4.1-5) ... Jun 11 20:15:21 volumio volumio[1053]: info: Selecting previously unselected package libsox-dev:armhf. Jun 11 20:15:21 volumio volumio[1053]: info: Preparing to unpack .../libsox-dev_14.4.1-5_armhf.deb ... Jun 11 20:15:21 volumio volumio[1053]: info: Unpacking libsox-dev:armhf (14.4.1-5) ... Jun 11 20:15:22 volumio volumio[1053]: info: Processing triggers for libglib2.0-0:armhf (2.42.1-1) ... Jun 11 20:15:22 volumio volumio[1053]: info: Setting up libdconf1:armhf (0.22.0-1) ... Jun 11 20:15:22 volumio volumio[1053]: info: Setting up libjson-glib-1.0-common (1.0.2-1) ... Jun 11 20:15:22 volumio volumio[1053]: info: Setting up libjson-glib-1.0-0:armhf (1.0.2-1) ... Jun 11 20:15:22 volumio volumio[1053]: info: Setting up libnotify4:armhf (0.7.6-2) ... Jun 11 20:15:23 volumio volumio[1053]: info: Setting up libproxy1:armhf (0.4.11-4) ... Jun 11 20:15:23 volumio volumio[1053]: info: Setting up glib-networking-common (2.42.0-2) ... Jun 11 20:15:23 volumio volumio[1053]: info: Setting up glib-networking-services (2.42.0-2) ... Jun 11 20:15:23 volumio volumio[1053]: info: Setting up dconf-service (0.22.0-1) ... Jun 11 20:15:23 volumio volumio[1053]: info: Setting up dconf-gsettings-backend:armhf (0.22.0-1) ... Jun 11 20:15:23 volumio volumio[1053]: info: Setting up gsettings-desktop-schemas (3.14.1-1) ... Jun 11 20:15:23 volumio volumio[1053]: info: Setting up glib-networking:armhf (2.42.0-2) ... Jun 11 20:15:23 volumio volumio[1053]: info: Setting up libsoup2.4-1:armhf (2.48.0-1+deb8u2) ... Jun 11 20:15:23 volumio volumio[1053]: info: Setting up libsoup-gnome2.4-1:armhf (2.48.0-1+deb8u2) ... Jun 11 20:15:24 volumio volumio[1053]: info: Setting up gir1.2-gdkpixbuf-2.0 (2.31.1-2+deb8u7) ... Jun 11 20:15:25 volumio volumio[1053]: info: Setting up gir1.2-json-1.0 (1.0.2-1) ... Jun 11 20:15:25 volumio volumio[1053]: info: Setting up gir1.2-notify-0.7 (0.7.6-2) ... Jun 11 20:15:25 volumio volumio[1053]: info: Setting up gir1.2-soup-2.4 (2.48.0-1+deb8u2) ... Jun 11 20:15:25 volumio volumio[1053]: info: Setting up libao-dev (1.1.0-3) ... Jun 11 20:15:26 volumio volumio[1053]: info: Setting up xorg-sgml-doctools (1:1.11-1) ... Jun 11 20:15:26 volumio volumio[1053]: info: Setting up x11proto-core-dev (7.0.31-1) ... Jun 11 20:15:26 volumio volumio[1053]: info: Setting up libxau-dev:armhf (1:1.0.8-1) ... Jun 11 20:15:26 volumio volumio[1053]: info: Setting up libxdmcp-dev:armhf (1:1.1.1-1) ... Jun 11 20:15:26 volumio volumio[1053]: info: Setting up x11proto-input-dev (2.3.1-1) ... Jun 11 20:15:26 volumio volumio[1053]: info: Setting up x11proto-kb-dev (1.0.6-2) ... Jun 11 20:15:26 volumio volumio[1053]: info: Setting up xtrans-dev (1.3.5-1) ... Jun 11 20:15:26 volumio volumio[1053]: info: Setting up libpthread-stubs0-dev:armhf (0.3-4) ... Jun 11 20:15:26 volumio volumio[1053]: info: Setting up libxcb1-dev:armhf (1.10-3) ... Jun 11 20:15:26 volumio volumio[1053]: info: Setting up libx11-dev:armhf (2:1.6.2-3+deb8u2) ... Jun 11 20:15:26 volumio volumio[1053]: info: Setting up libpng12-dev:armhf (1.2.50-2+deb8u3) ... Jun 11 20:15:27 volumio volumio[1053]: info: Setting up libgdk-pixbuf2.0-dev (2.31.1-2+deb8u7) ... Jun 11 20:15:27 volumio volumio[1053]: info: Setting up libjson-glib-dev (1.0.2-1) ... Jun 11 20:15:27 volumio volumio[1053]: info: Setting up libnotify-dev (0.7.6-2) ... Jun 11 20:15:27 volumio volumio[1053]: info: Setting up libtinfo-dev:armhf (5.9+20140913-1+deb8u3) ... Jun 11 20:15:27 volumio volumio[1053]: info: Setting up libreadline6-dev:armhf (6.3-8) ... Jun 11 20:15:27 volumio volumio[1053]: info: Setting up libreadline-dev:armhf (6.3-8) ... Jun 11 20:15:27 volumio volumio[1053]: info: Setting up libxml2-dev:armhf (2.9.1+dfsg1-5+deb8u7) ... Jun 11 20:15:27 volumio volumio[1053]: info: Setting up libsoup2.4-dev (2.48.0-1+deb8u2) ... Jun 11 20:15:27 volumio volumio[1053]: info: Setting up libsox-fmt-ao:armhf (14.4.1-5) ... Jun 11 20:15:27 volumio volumio[1053]: info: Setting up libtwolame0 (0.3.13-1.1) ... Jun 11 20:15:27 volumio volumio[1053]: info: Setting up libsox-fmt-mp3:armhf (14.4.1-5) ... Jun 11 20:15:28 volumio volumio[1053]: info: Setting up libsox-fmt-oss:armhf (14.4.1-5) ... Jun 11 20:15:28 volumio volumio[1053]: info: Setting up libsox-fmt-pulse:armhf (14.4.1-5) ... Jun 11 20:15:28 volumio volumio[1053]: info: Setting up libsox-fmt-all:armhf (14.4.1-5) ... Jun 11 20:15:28 volumio volumio[1053]: info: Setting up libsox-dev:armhf (14.4.1-5) ... Jun 11 20:15:28 volumio volumio[1053]: info: Processing triggers for libc-bin (2.19-18+deb8u10) ... Jun 11 20:15:40 volumio sudo[3034]: pam_unix(sudo:session): session closed for user root Jun 11 20:15:40 volumio volumio[1053]: info: Installing Spop and libspotify Jun 11 20:15:40 volumio volumio[1053]: info: armhf Jun 11 20:15:43 volumio sudo[3784]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/tar xvf /tmp/spop-armhf.tar.gz -C / Jun 11 20:15:43 volumio sudo[3784]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:15:43 volumio volumio[1053]: info: etc/ Jun 11 20:15:43 volumio volumio[1053]: info: etc/spopd.conf Jun 11 20:15:43 volumio volumio[1053]: info: lib/ Jun 11 20:15:43 volumio volumio[1053]: info: lib/systemd/ Jun 11 20:15:43 volumio volumio[1053]: info: lib/systemd/system/ Jun 11 20:15:43 volumio volumio[1053]: info: lib/systemd/system/spop.service Jun 11 20:15:43 volumio volumio[1053]: info: usr/ Jun 11 20:15:43 volumio volumio[1053]: info: usr/local/ Jun 11 20:15:43 volumio volumio[1053]: info: usr/local/include/ Jun 11 20:15:43 volumio volumio[1053]: info: usr/local/include/libspotify/ Jun 11 20:15:43 volumio volumio[1053]: info: usr/local/include/libspotify/api.h Jun 11 20:15:43 volumio volumio[1053]: info: usr/local/lib/ Jun 11 20:15:43 volumio volumio[1053]: info: usr/local/lib/pkgconfig/ Jun 11 20:15:43 volumio volumio[1053]: info: usr/local/lib/pkgconfig/libspotify.pc Jun 11 20:15:43 volumio volumio[1053]: info: usr/local/lib/libspotify.so Jun 11 20:15:43 volumio volumio[1053]: info: usr/local/lib/libspotify.so.12.1.103 Jun 11 20:15:43 volumio sudo[3784]: pam_unix(sudo:session): session closed for user root Jun 11 20:15:43 volumio volumio[1053]: info: usr/local/lib/libspotify.so.12 Jun 11 20:15:43 volumio sudo[3794]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod 777 /etc/spopd.conf Jun 11 20:15:43 volumio volumio[1053]: info: usr/share/ Jun 11 20:15:43 volumio volumio[1053]: info: usr/share/spop/ Jun 11 20:15:43 volumio volumio[1053]: info: usr/share/spop/awesome/ Jun 11 20:15:43 volumio volumio[1053]: info: usr/share/spop/awesome/spop.lua Jun 11 20:15:43 volumio volumio[1053]: info: usr/lib/ Jun 11 20:15:43 volumio volumio[1053]: info: usr/lib/systemd/ Jun 11 20:15:43 volumio volumio[1053]: info: usr/lib/systemd/user/ Jun 11 20:15:43 volumio volumio[1053]: info: usr/lib/systemd/user/spopd.socket Jun 11 20:15:43 volumio sudo[3794]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:15:43 volumio volumio[1053]: info: usr/lib/systemd/user/spopd.service Jun 11 20:15:43 volumio volumio[1053]: info: usr/lib/libspop_plugin_savestate.so Jun 11 20:15:43 volumio sudo[3794]: pam_unix(sudo:session): session closed for user root Jun 11 20:15:43 volumio volumio[1053]: info: usr/lib/libspop_audio_ao.so Jun 11 20:15:43 volumio volumio[1053]: info: usr/lib/libspop_plugin_awesome.so Jun 11 20:15:43 volumio volumio[1053]: info: usr/lib/libspop_audio_sox.so Jun 11 20:15:43 volumio volumio[1053]: info: usr/lib/libspop_plugin_mpris2.so Jun 11 20:15:43 volumio volumio[1053]: info: usr/lib/libspop_plugin_scrobble.so Jun 11 20:15:43 volumio volumio[1053]: info: usr/lib/libspop_audio_oss.so Jun 11 20:15:43 volumio sudo[2852]: pam_unix(sudo:session): session closed for user root Jun 11 20:15:43 volumio volumio[1053]: info: usr/bin/ Jun 11 20:15:43 volumio volumio[1053]: info: usr/bin/dspop Jun 11 20:15:43 volumio volumio[1053]: info: usr/bin/spopd Jun 11 20:15:44 volumio volumio[1053]: info: Install script completed Jun 11 20:15:44 volumio volumio[1053]: info: Adding reference to registry Jun 11 20:15:44 volumio volumio[1053]: info: Done installing plugin. Jun 11 20:15:44 volumio volumio[1053]: info: Folder /tmp/plugins removed Jun 11 20:15:44 volumio volumio[1053]: info: Folder /tmp/downloaded_plugin.zip removed Jun 11 20:15:44 volumio volumio[1053]: Plugin install end detected on script Jun 11 20:15:44 volumio volumio[1053]: info: Folder /data/temp removed Jun 11 20:17:08 volumio nmbd[994]: [2020/06/11 20:17:08.128706, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 11 20:17:08 volumio nmbd[994]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.12 for name WORKGROUP<1d>. Jun 11 20:17:08 volumio nmbd[994]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Jun 11 20:17:08 volumio nmbd[994]: [2020/06/11 20:17:08.128941, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 11 20:17:08 volumio nmbd[994]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.5 for name WORKGROUP<1d>. Jun 11 20:17:08 volumio nmbd[994]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Jun 11 20:17:08 volumio nmbd[994]: [2020/06/11 20:17:08.129094, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 11 20:17:08 volumio nmbd[994]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.20 for name WORKGROUP<1d>. Jun 11 20:17:08 volumio nmbd[994]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Jun 11 20:17:13 volumio volumio[1053]: info: CoreCommandRouter::volumioGetState Jun 11 20:17:13 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:17:13 volumio volumio[1053]: info: CoreCommandRouter::volumioGetState Jun 11 20:17:13 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:17:13 volumio volumio[1053]: info: CoreCommandRouter::volumioGetState Jun 11 20:17:13 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:17:14 volumio sudo[4044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 11 20:17:14 volumio sudo[4044]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:17:14 volumio sudo[4047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 11 20:17:14 volumio sudo[4044]: pam_unix(sudo:session): session closed for user root Jun 11 20:17:14 volumio sudo[4047]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:17:14 volumio sudo[4047]: pam_unix(sudo:session): session closed for user root Jun 11 20:17:15 volumio sudo[4064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 11 20:17:15 volumio sudo[4064]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:17:15 volumio sudo[4064]: pam_unix(sudo:session): session closed for user root Jun 11 20:17:15 volumio sudo[4067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 11 20:17:15 volumio sudo[4067]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:17:15 volumio sudo[4067]: pam_unix(sudo:session): session closed for user root Jun 11 20:17:16 volumio sudo[4084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 11 20:17:16 volumio sudo[4084]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:17:16 volumio sudo[4086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 11 20:17:16 volumio sudo[4084]: pam_unix(sudo:session): session closed for user root Jun 11 20:17:16 volumio sudo[4086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:17:16 volumio sudo[4086]: pam_unix(sudo:session): session closed for user root Jun 11 20:17:16 volumio sudo[4102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 11 20:17:16 volumio sudo[4102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:17:16 volumio sudo[4104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 11 20:17:16 volumio sudo[4102]: pam_unix(sudo:session): session closed for user root Jun 11 20:17:16 volumio sudo[4104]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:17:16 volumio sudo[4104]: pam_unix(sudo:session): session closed for user root Jun 11 20:17:17 volumio volumio[1053]: info: CoreCommandRouter::volumioGetState Jun 11 20:17:17 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:17:18 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 11 20:17:18 volumio volumio[1053]: info: Listing playlists Jun 11 20:17:18 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 11 20:17:18 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 11 20:17:18 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 11 20:17:18 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 11 20:17:18 volumio volumio[1053]: info: CoreCommandRouter::volumioGetState Jun 11 20:17:18 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:17:20 volumio volumio[1053]: info: CoreCommandRouter::volumioGetVisibleSources Jun 11 20:17:20 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 20:17:20 volumio volumio[1053]: info: CoreCommandRouter::volumioGetQueue Jun 11 20:17:20 volumio volumio[1053]: info: CoreStateMachine::getQueue Jun 11 20:17:20 volumio volumio[1053]: info: CorePlayQueue::getQueue Jun 11 20:17:22 volumio volumio[1053]: info: CoreCommandRouter::volumioGetState Jun 11 20:17:22 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:17:22 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 11 20:17:22 volumio volumio[1053]: info: Listing playlists Jun 11 20:17:22 volumio volumio[1053]: info: CoreCommandRouter::volumioGetState Jun 11 20:17:22 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:17:22 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 11 20:17:22 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 11 20:17:22 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 11 20:17:22 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 11 20:17:23 volumio volumio[1053]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 20:17:23 volumio volumio[1053]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 20:17:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 11 20:17:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 20:17:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 20:17:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 20:17:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:17:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:17:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:17:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:17:23 volumio volumio[1053]: info: CoreCommandRouter::volumioGetBrowseSources Jun 11 20:17:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:17:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 11 20:17:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 11 20:17:27 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:17:31 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:17:35 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:17:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:17:43 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:17:47 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:17:49 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Jun 11 20:17:49 volumio volumio[1053]: info: Share Quilombazo successfully unmounted Jun 11 20:17:49 volumio sudo[4198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.4/media /mnt/NAS/Quilombazo Jun 11 20:17:49 volumio sudo[4198]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:17:49 volumio 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 20:17:51 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:17:55 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:17:59 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:03 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:07 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:11 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:15 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:19 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:23 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:27 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:30 volumio volumio[1053]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 20:18:30 volumio volumio[1053]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 20:18:30 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 11 20:18:30 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 20:18:30 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 20:18:30 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 20:18:30 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:18:30 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:18:30 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:18:30 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:18:30 volumio volumio[1053]: info: CoreCommandRouter::volumioGetBrowseSources Jun 11 20:18:31 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:31 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 11 20:18:35 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:43 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:47 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:48 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Jun 11 20:18:48 volumio volumio[1053]: info: Share Quilombazo successfully unmounted Jun 11 20:18:48 volumio sudo[4357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //192.168.1.4/media /mnt/NAS/Quilombazo Jun 11 20:18:48 volumio sudo[4357]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:18:48 volumio kernel: FS-Cache: Duplicate cookie detected Jun 11 20:18:48 volumio kernel: FS-Cache: O-cookie c=dc4f9d61 [p=992142fb fl=222 nc=0 na=1] Jun 11 20:18:48 volumio kernel: FS-Cache: O-cookie d=54c3f517 n=a36de6ad Jun 11 20:18:48 volumio kernel: FS-Cache: O-key=[8] '020001bdc0a80104' Jun 11 20:18:48 volumio kernel: FS-Cache: N-cookie c=9776663d [p=992142fb fl=2 nc=0 na=1] Jun 11 20:18:48 volumio kernel: FS-Cache: N-cookie d=54c3f517 n=7ba11820 Jun 11 20:18:48 volumio kernel: FS-Cache: N-key=[8] '020001bdc0a80104' Jun 11 20:18:50 volumio sudo[4198]: pam_unix(sudo:session): session closed for user root Jun 11 20:18:50 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -112 Jun 11 20:18:50 volumio volumio[1053]: info: An error occurred mounting the new share. Rolling back configuration Jun 11 20:18:51 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 11 20:18:51 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:55 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:57 volumio volumio[1053]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 20:18:57 volumio volumio[1053]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 20:18:57 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 11 20:18:57 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 20:18:57 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 20:18:57 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 11 20:18:57 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:18:57 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:18:57 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:18:57 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 20:18:57 volumio volumio[1053]: info: CoreCommandRouter::volumioGetBrowseSources Jun 11 20:18:57 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:18:57 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 11 20:19:01 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:19:05 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:19:09 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:19:13 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:19:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:19:21 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:19:25 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:19:29 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:19:33 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:19:38 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:19:49 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:20:01 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:20:07 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:20:07 volumio systemd[1]: Starting Cleanup of Temporary Directories... Jun 11 20:20:07 volumio systemd[1]: Started Cleanup of Temporary Directories. Jun 11 20:20:16 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:20:21 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:20:25 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:20:29 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:20:33 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:20:37 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:20:41 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:20:45 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:20:48 volumio sudo[4357]: pam_unix(sudo:session): session closed for user root Jun 11 20:20:48 volumio kernel: CIFS VFS: cifs_mount failed w/return code = -112 Jun 11 20:20:48 volumio volumio[1053]: info: An error occurred mounting the new share. Rolling back configuration Jun 11 20:20:49 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:20:49 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 11 20:20:53 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:20:57 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:21:01 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:21:05 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:21:09 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:21:13 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:21:17 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:21:21 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:21:26 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:21:30 volumio volumio[1053]: info: Enabling plugin spop Jun 11 20:21:30 volumio volumio[1053]: info: Loading plugin "spop"... Jun 11 20:21:31 volumio volumio[1053]: info: PLUGIN START: spop Jun 11 20:21:31 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:21:31 volumio sudo[4791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Jun 11 20:21:31 volumio sudo[4791]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:21:31 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Jun 11 20:21:31 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Jun 11 20:21:31 volumio systemd[1]: Starting Spotify Daemon... Jun 11 20:21:31 volumio systemd[1]: Started Spotify Daemon. Jun 11 20:21:31 volumio sudo[4791]: pam_unix(sudo:session): session closed for user root Jun 11 20:21:31 volumio volumio[1053]: info: SpopD Daemon Started Jun 11 20:21:31 volumio volumio[4798]: 2020-06-11 20:21:31 [SPTF] 20:21:31.486 I [offline_authorizer.cpp:297] Unable to login offline: no such user Jun 11 20:21:31 volumio volumio[4798]: 2020-06-11 20:21:31 [SPTF] 20:21:31.493 I [ap:1752] Connecting to AP ap.spotify.com:4070 Jun 11 20:21:31 volumio volumio[4798]: 2020-06-11 20:21:31 [SPTF] 20:21:31.715 I [ap:1226] Connected to AP: 104.154.127.187:4070 Jun 11 20:21:32 volumio volumio[4798]: 2020-06-11 20:21:32 [SPTF] 20:21:32.151 E [ap:3953] Connection error: 410 Jun 11 20:21:32 volumio volumio[4798]: 2020-06-11 20:21:32 [WARN] Login failed: Bad username and/or password Jun 11 20:21:32 volumio volumio[4798]: 2020-06-11 20:21:32 [ERR ] Could not get the playlist container. Jun 11 20:21:32 volumio systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP Jun 11 20:21:32 volumio systemd[1]: Unit spop.service entered failed state. Jun 11 20:21:34 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:21:36 volumio volumio[1053]: info: Connecting to daemon Jun 11 20:21:36 volumio volumio[1053]: error: SPOP command error: Error: connect ECONNREFUSED 127.0.0.1:6602 Jun 11 20:21:36 volumio volumio[1053]: info: Error: undefined Jun 11 20:21:36 volumio volumio[1053]: error: SPOP status error: Error: connect ECONNREFUSED 127.0.0.1:6602 Jun 11 20:21:36 volumio volumio[1053]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Jun 11 20:21:36 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 11 20:21:38 volumio volumio[1053]: info: CoreCommandRouter::volumioGetState Jun 11 20:21:38 volumio volumio[1053]: info: CorePlayQueue::getTrack 0 Jun 11 20:21:38 volumio volumio[1053]: info: CoreCommandRouter::volumioGetQueue Jun 11 20:21:38 volumio volumio[1053]: info: CoreStateMachine::getQueue Jun 11 20:21:38 volumio volumio[1053]: info: CorePlayQueue::getQueue Jun 11 20:21:38 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jun 11 20:21:38 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:21:42 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:21:46 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:21:50 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:21:56 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:21:59 volumio nmbd[994]: [2020/06/11 20:21:59.536979, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 11 20:21:59 volumio nmbd[994]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.12 for name WORKGROUP<1d>. Jun 11 20:21:59 volumio nmbd[994]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Jun 11 20:21:59 volumio nmbd[994]: [2020/06/11 20:21:59.537238, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 11 20:21:59 volumio nmbd[994]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.5 for name WORKGROUP<1d>. Jun 11 20:21:59 volumio nmbd[994]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Jun 11 20:21:59 volumio nmbd[994]: [2020/06/11 20:21:59.537440, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jun 11 20:21:59 volumio nmbd[994]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.20 for name WORKGROUP<1d>. Jun 11 20:21:59 volumio nmbd[994]: This response was from IP 192.168.1.4, reporting an IP address of 192.168.1.4. Jun 11 20:22:03 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:22:09 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:22:27 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:22:34 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:22:39 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:22:57 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 11 20:22:58 volumio volumio[1053]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Jun 11 20:22:58 volumio volumio[1053]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Jun 11 20:22:59 volumio sudo[5053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Jun 11 20:22:59 volumio sudo[5053]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 20:22:59 volumio sudo[5053]: pam_unix(sudo:session): session closed for user root Jun 11 20:22:59 volumio sudo[5071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Jun 11 20:22:59 volumio sudo[5071]: pam_unix(sudo:session): session opened for user root by (uid=0) # cat /var/log/mpd.log --------------- May 25 16:53 : zeroconf: No global port, disabling zeroconf Jun 11 20:07 : client: [0] opened from local Jun 11 20:07 : exception: No such directory Jun 11 20:07 : zeroconf: No global port, disabling zeroconf Jun 11 20:07 : client: [0] opened from 127.0.0.1:49932 Jun 11 20:07 : zeroconf: No global port, disabling zeroconf Jun 11 20:07 : client: [0] opened from local Jun 11 20:07 : client: [1] opened from local Jun 11 20:07 : client: [2] opened from local Jun 11 20:07 : client: [3] opened from local Jun 11 20:07 : client: [4] opened from 127.0.0.1:50028 Jun 11 20:07 : client: [5] opened from 127.0.0.1:50032 Jun 11 20:08 : client: [4] closed Jun 11 20:10 : zeroconf: No global port, disabling zeroconf Jun 11 20:10 : client: [0] opened from local Jun 11 20:11 : client: [1] opened from 127.0.0.1:50198 Jun 11 20:11 : client: [2] opened from 127.0.0.1:50206 Jun 11 20:12 : client: [1] closed