Sat May 18 16:07:23 UTC 2019 Description="'Failed to open ALSA device with new Volumio install on Pi Zero W with Digiboom DigiZero'" # cat /proc/version --------------- Linux version 4.14.92+ (dom@dom-XPS-13-9370) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1187 Wed Jan 9 19:46:46 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="fe83e03fe4a5ad6387f0fd162f915b7fc3065a36" VOLUMIO_FE_VERSION="3927cf879718a0297ddabc3805589c385dd4c7bd" VOLUMIO_BE_VERSION="d57008595633b428eec7784fea777242fe1c15ac" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Apr 23 20:16:23 CEST 2019" VOLUMIO_VERSION="2.575" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="5b62cf8f91d8d86f894459ee8b534ab5" # ifconfig --------------- 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:2473 errors:0 dropped:0 overruns:0 frame:0 TX packets:2473 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:1590710 (1.5 MiB) TX bytes:1590710 (1.5 MiB) wlan0 Link encap:Ethernet HWaddr b8:27:eb:b0:dc:03 inet addr:10.0.0.115 Bcast:10.0.0.255 Mask:255.255.255.0 UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:10687 errors:0 dropped:0 overruns:0 frame:0 TX packets:7546 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:11061518 (10.5 MiB) TX bytes:2357022 (2.2 MiB) # sudo iwconfig --------------- lo no wireless extensions. wlan0 IEEE 802.11 ESSID:"5thLevelWiFi" Mode:Managed Frequency:2.462 GHz Access Point: D4:AB:82:62:DD:65 Bit Rate=43.3 Mb/s Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off Link Quality=37/70 Signal level=-73 dBm Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0 Tx excessive retries:1 Invalid misc:0 Missed beacon:0 # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 0: ALSA [bcm2835 ALSA], device 0: bcm2835 ALSA [bcm2835 ALSA] Subdevices: 7/7 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 Subdevice #4: subdevice #4 Subdevice #5: subdevice #5 Subdevice #6: subdevice #6 card 0: ALSA [bcm2835 ALSA], device 1: bcm2835 ALSA [bcm2835 IEC958/HDMI] Subdevices: 1/1 Subdevice #0: subdevice #0 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 1 15:59 ? 00:00:07 /sbin/init root 2 0 0 15:59 ? 00:00:00 [kthreadd] root 3 2 0 15:59 ? 00:00:00 [kworker/0:0] root 4 2 0 15:59 ? 00:00:00 [kworker/0:0H] root 5 2 0 15:59 ? 00:00:00 [kworker/u2:0] root 6 2 0 15:59 ? 00:00:00 [mm_percpu_wq] root 7 2 0 15:59 ? 00:00:00 [ksoftirqd/0] root 8 2 0 15:59 ? 00:00:00 [kdevtmpfs] root 9 2 0 15:59 ? 00:00:00 [netns] root 11 2 0 15:59 ? 00:00:00 [khungtaskd] root 12 2 0 15:59 ? 00:00:00 [oom_reaper] root 13 2 0 15:59 ? 00:00:00 [writeback] root 14 2 0 15:59 ? 00:00:00 [kcompactd0] root 15 2 0 15:59 ? 00:00:00 [crypto] root 16 2 0 15:59 ? 00:00:00 [kblockd] root 17 2 0 15:59 ? 00:00:00 [watchdogd] root 18 2 0 15:59 ? 00:00:00 [rpciod] root 19 2 0 15:59 ? 00:00:00 [xprtiod] root 22 2 0 15:59 ? 00:00:00 [kswapd0] root 23 2 0 15:59 ? 00:00:00 [nfsiod] root 33 2 0 15:59 ? 00:00:00 [kthrotld] root 34 2 0 15:59 ? 00:00:00 [iscsi_eh] root 35 2 0 15:59 ? 00:00:00 [dwc_otg] root 36 2 0 15:59 ? 00:00:00 [DWC Notificatio] root 37 2 0 15:59 ? 00:00:00 [vchiq-slot/0] root 38 2 0 15:59 ? 00:00:00 [vchiq-recy/0] root 39 2 0 15:59 ? 00:00:00 [vchiq-sync/0] root 40 2 0 15:59 ? 00:00:00 [vchiq-keep/0] root 41 2 0 15:59 ? 00:00:00 [SMIO] root 42 2 0 15:59 ? 00:00:01 [irq/86-mmc1] root 52 2 0 15:59 ? 00:00:01 [mmcqd/0] root 75 2 0 15:59 ? 00:00:00 [jbd2/mmcblk0p2-] root 76 2 0 15:59 ? 00:00:00 [ext4-rsv-conver] root 83 2 0 15:59 ? 00:00:02 [loop0] root 86 2 0 15:59 ? 00:00:00 [kworker/0:1H] root 103 2 0 15:59 ? 00:00:00 [jbd2/mmcblk0p3-] root 104 2 0 15:59 ? 00:00:00 [ext4-rsv-conver] root 111 2 0 15:59 ? 00:00:00 [ipv6_addrconf] root 140 1 0 15:59 ? 00:00:03 /lib/systemd/systemd-journald root 168 1 0 15:59 ? 00:00:00 /lib/systemd/systemd-udevd root 229 2 0 15:59 ? 00:00:00 [cfg80211] root 234 2 0 15:59 ? 00:00:00 [brcmf_wq/mmc1:0] root 235 2 0 15:59 ? 00:00:00 [brcmf_wdog/mmc1] root 239 2 0 15:59 ? 00:00:00 [kworker/u2:2] root 281 2 0 15:59 ? 00:00:02 [kworker/u2:3] root 396 1 0 15:59 ? 00:00:00 /sbin/rpcbind -w root 420 2 0 15:59 ? 00:00:00 [kworker/0:4] statd 424 1 0 15:59 ? 00:00:00 /sbin/rpc.statd root 438 1 0 15:59 ? 00:00:00 /usr/sbin/rpc.idmapd root 440 1 0 15:59 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 --write=1024 volumio 444 1 0 15:59 ? 00:00:00 /usr/bin/udisks-glue --foreground volumio 447 1 0 15:59 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 457 1 0 15:59 ? 00:00:00 /lib/systemd/systemd-logind avahi 488 1 0 15:59 ? 00:00:00 avahi-daemon: running [zerodigital.local] mpd 493 1 1 15:59 ? 00:00:05 /usr/bin/mpd --no-daemon message+ 497 1 0 15:59 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation avahi 512 488 0 15:59 ? 00:00:00 avahi-daemon: chroot helper ntp 530 1 0 15:59 ? 00:00:01 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 100:104 root 552 1 0 16:00 ? 00:00:00 /usr/sbin/sshd -D root 563 1 0 16:00 ? 00:00:00 /usr/lib/udisks/udisks-daemon --no-debug root 574 563 0 16:00 ? 00:00:00 udisks-daemon: not polling any devices root 590 1 0 16:00 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 593 1 0 16:00 tty1 00:00:00 /sbin/agetty --noclear tty1 linux root 687 1 0 16:00 ? 00:00:00 /usr/sbin/winbindd root 696 687 0 16:00 ? 00:00:00 /usr/sbin/winbindd root 701 1 0 16:00 ? 00:00:00 /usr/sbin/nmbd -D root 703 701 0 16:00 ? 00:00:00 /usr/sbin/nmbd -D root 739 1 0 16:00 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 803 1 0 16:00 ? 00:00:00 /sbin/dhcpcd volumio 825 1 14 16:00 ? 00:01:00 /usr/local/bin/node /volumio/index.js root 837 1 0 16:00 ? 00:00:00 /usr/sbin/smbd -D root 843 687 0 16:00 ? 00:00:00 /usr/sbin/winbindd root 844 687 0 16:00 ? 00:00:00 /usr/sbin/winbindd root 845 837 0 16:00 ? 00:00:00 /usr/sbin/smbd -D root 870 2 0 16:00 ? 00:00:00 [cifsiod] root 871 2 0 16:00 ? 00:00:00 [cifsoplockd] root 874 2 0 16:00 ? 00:00:00 [cifsd] volumio 900 825 0 16:01 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 906 900 1 16:01 ? 00:00:04 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart root 1068 1 0 16:01 ? 00:00:00 /usr/sbin/lircd --driver=default --device=/dev/lirc0 --uinput root 1071 1 0 16:01 ? 00:00:00 /usr/bin/irexec -d /etc/lirc/lircrc volumio 1142 1 4 16:01 ? 00:00:14 /bin/streaming-daemon volumio 1231 1 0 16:01 ? 00:00:00 /bin/sh /tmp/sshtunnel.sh volumio 1232 1231 0 16:01 ? 00:00:00 /usr/lib/autossh/autossh -M 0 -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R 9hZSxdQeALPeSrkZ9Scrod1F1Fl1-ab445df7acd9a6713bc738f2eb179ba5.us1.myvolumio.org:8091:localhost:3000 us1.myvolumio.org volumio 1238 1232 0 16:01 ? 00:00:00 /usr/bin/ssh -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R 9hZSxdQeALPeSrkZ9Scrod1F1Fl1-ab445df7acd9a6713bc738f2eb179ba5.us1.myvolumio.org:8091:localhost:3000 us1.myvolumio.org root 1309 552 0 16:04 ? 00:00:00 sshd: volumio [priv] volumio 1315 1 0 16:05 ? 00:00:00 /lib/systemd/systemd --user volumio 1318 1315 0 16:05 ? 00:00:00 (sd-pam) volumio 1321 1309 0 16:05 ? 00:00:00 sshd: volumio@pts/0 volumio 1323 1321 0 16:05 pts/0 00:00:00 -bash root 1357 2 0 16:06 ? 00:00:00 [kworker/0:1] volumio 1364 825 0 16:07 ? 00:00:00 /bin/sh -c /usr/local/bin/node /volumio/logsubmit.js 'Failed to open ALSA device with new Volumio install on Pi Zero W with Digiboom DigiZero' volumio 1365 1364 65 16:07 ? 00:00:01 /usr/local/bin/node /volumio/logsubmit.js Failed to open ALSA device with new Volumio install on Pi Zero W with Digiboom DigiZero volumio 1390 1365 0 16:07 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1391 1390 0 16:07 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.2G 660M 1.4G 32% /imgpart /dev/loop0 292M 292M 0 100% /static overlay 27G 679M 25G 3% / devtmpfs 222M 0 222M 0% /dev tmpfs 233M 0 233M 0% /dev/shm tmpfs 233M 4.6M 228M 2% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 233M 0 233M 0% /sys/fs/cgroup tmpfs 233M 36K 233M 1% /tmp tmpfs 233M 0 233M 0% /var/spool/cups tmpfs 20M 48K 20M 1% /var/log tmpfs 233M 0 233M 0% /var/spool/cups/tmp /dev/mmcblk0p1 61M 37M 24M 61% /boot //10.0.0.235/Public\Volumio 1.8T 82G 1.8T 5% /mnt/NAS/SharedMusic tmpfs 47M 0 47M 0% /run/user/1000 # mount --------------- /dev/mmcblk0p2 on /imgpart type ext4 (rw,relatime,stripe=1024,data=ordered) /dev/loop0 on /static type squashfs (ro,relatime) overlay on / type overlay (rw,relatime,lowerdir=/mnt/static,upperdir=/mnt/ext/dyn,workdir=/mnt/ext/work) sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime) proc on /proc type proc (rw,relatime) devtmpfs on /dev type devtmpfs (rw,nosuid,size=226416k,nr_inodes=56604,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/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/net_cls type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=22,pgrp=1,timeout=300,minproto=5,maxproto=5,direct) mqueue on /dev/mqueue type mqueue (rw,relatime) debugfs on /sys/kernel/debug type debugfs (rw,relatime) fusectl on /sys/fs/fuse/connections type fusectl (rw,relatime) configfs on /sys/kernel/config type configfs (rw,relatime) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) tmpfs on /var/spool/cups/tmp type tmpfs (rw,noatime,mode=755) /dev/mmcblk0p1 on /boot type vfat (rw,nosuid,nodev,noexec,relatime,fmask=0111,dmask=0000,allow_utime=0022,codepage=437,iocharset=ascii,shortname=mixed,utf8,errors=remount-ro) rpc_pipefs on /run/rpc_pipefs type rpc_pipefs (rw,relatime) //10.0.0.235/Public\Volumio on /mnt/NAS/SharedMusic type cifs (rw,relatime,vers=default,cache=strict,username=chris@pensarc.com,domain=,uid=0,noforceuid,gid=0,noforcegid,addr=10.0.0.235,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=1048576,wsize=1048576,echo_interval=60,actimeo=1) tmpfs on /run/user/1000 type tmpfs (rw,nosuid,nodev,relatime,size=47632k,mode=700,uid=1000,gid=1000) # sudo journalctl -p 7 --------------- -- Logs begin at Sat 2019-05-18 15:59:22 UTC, end at Sat 2019-05-18 16:07:24 UTC. -- May 18 15:59:22 zerodigital systemd-journal[140]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 34.8M free of 228.4M available → current limit 30.0M). May 18 15:59:22 zerodigital kernel: Booting Linux on physical CPU 0x0 May 18 15:59:22 zerodigital kernel: Linux version 4.14.92+ (dom@dom-XPS-13-9370) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1187 Wed Jan 9 19:46:46 GMT 2019 May 18 15:59:22 zerodigital kernel: CPU: ARMv6-compatible processor [410fb767] revision 7 (ARMv7), cr=00c5387d May 18 15:59:22 zerodigital kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT nonaliasing instruction cache May 18 15:59:22 zerodigital kernel: OF: fdt: Machine model: Raspberry Pi Zero W Rev 1.1 May 18 15:59:22 zerodigital kernel: Memory policy: Data cache writeback May 18 15:59:22 zerodigital kernel: cma: Reserved 8 MiB at 0x1c800000 May 18 15:59:22 zerodigital kernel: On node 0 totalpages: 122880 May 18 15:59:22 zerodigital kernel: free_area_init_node: node 0, pgdat c09ca790, node_mem_map dc3c8000 May 18 15:59:22 zerodigital kernel: Normal zone: 1080 pages used for memmap May 18 15:59:22 zerodigital kernel: Normal zone: 0 pages reserved May 18 15:59:22 zerodigital kernel: Normal zone: 122880 pages, LIFO batch:31 May 18 15:59:22 zerodigital kernel: pcpu-alloc: s0 r0 d32768 u32768 alloc=1*32768 May 18 15:59:22 zerodigital kernel: pcpu-alloc: [0] 0 May 18 15:59:22 zerodigital kernel: Built 1 zonelists, mobility grouping on. Total pages: 121800 May 18 15:59:22 zerodigital kernel: Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=B8:27:EB:E5:89:56 vc_mem.mem_base=0x1ec00000 vc_mem.mem_size=0x20000000 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 18 15:59:22 zerodigital kernel: PID hash table entries: 2048 (order: 1, 8192 bytes) May 18 15:59:22 zerodigital kernel: Dentry cache hash table entries: 65536 (order: 6, 262144 bytes) May 18 15:59:22 zerodigital kernel: Inode-cache hash table entries: 32768 (order: 5, 131072 bytes) May 18 15:59:22 zerodigital kernel: Memory: 452836K/491520K available (6448K kernel code, 588K rwdata, 1992K rodata, 444K init, 673K bss, 30492K reserved, 8192K cma-reserved) May 18 15:59:22 zerodigital kernel: Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0xde800000 - 0xff800000 ( 528 MB) lowmem : 0xc0000000 - 0xde000000 ( 480 MB) modules : 0xbf000000 - 0xc0000000 ( 16 MB) .text : 0xc0008000 - 0xc0654350 (6449 kB) .init : 0xc08d3000 - 0xc0942000 ( 444 kB) .data : 0xc0942000 - 0xc09d5198 ( 589 kB) .bss : 0xc09daa90 - 0xc0a8302c ( 674 kB) May 18 15:59:22 zerodigital kernel: SLUB: HWalign=32, Order=0-3, MinObjects=0, CPUs=1, Nodes=1 May 18 15:59:22 zerodigital kernel: ftrace: allocating 24126 entries in 71 pages May 18 15:59:22 zerodigital kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 May 18 15:59:22 zerodigital kernel: sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns May 18 15:59:22 zerodigital kernel: clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns May 18 15:59:22 zerodigital kernel: bcm2835: system timer (irq = 27) May 18 15:59:22 zerodigital kernel: Console: colour dummy device 80x30 May 18 15:59:22 zerodigital kernel: console [tty1] enabled May 18 15:59:22 zerodigital kernel: Calibrating delay loop... 697.95 BogoMIPS (lpj=3489792) May 18 15:59:22 zerodigital kernel: pid_max: default: 32768 minimum: 301 May 18 15:59:22 zerodigital kernel: Mount-cache hash table entries: 1024 (order: 0, 4096 bytes) May 18 15:59:22 zerodigital kernel: Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes) May 18 15:59:22 zerodigital kernel: Disabling memory control group subsystem May 18 15:59:22 zerodigital kernel: CPU: Testing write buffer coherency: ok May 18 15:59:22 zerodigital kernel: Setting up static identity map for 0x8200 - 0x8238 May 18 15:59:22 zerodigital kernel: devtmpfs: initialized May 18 15:59:22 zerodigital kernel: random: get_random_u32 called from bucket_table_alloc+0x88/0x1c4 with crng_init=0 May 18 15:59:22 zerodigital kernel: VFP support v0.3: implementor 41 architecture 1 part 20 variant b rev 5 May 18 15:59:22 zerodigital kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns May 18 15:59:22 zerodigital kernel: futex hash table entries: 256 (order: -1, 3072 bytes) May 18 15:59:22 zerodigital kernel: pinctrl core: initialized pinctrl subsystem May 18 15:59:22 zerodigital kernel: NET: Registered protocol family 16 May 18 15:59:22 zerodigital kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations May 18 15:59:22 zerodigital kernel: hw-breakpoint: found 6 breakpoint and 1 watchpoint registers. May 18 15:59:22 zerodigital kernel: hw-breakpoint: maximum watchpoint size is 4 bytes. May 18 15:59:22 zerodigital kernel: Serial: AMBA PL011 UART driver May 18 15:59:22 zerodigital kernel: bcm2835-mbox 2000b880.mailbox: mailbox enabled May 18 15:59:22 zerodigital kernel: uart-pl011 20201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe May 18 15:59:22 zerodigital kernel: bcm2835-dma 20007000.dma: DMA legacy API manager at de80d000, dmachans=0x1 May 18 15:59:22 zerodigital kernel: SCSI subsystem initialized May 18 15:59:22 zerodigital kernel: usbcore: registered new interface driver usbfs May 18 15:59:22 zerodigital kernel: usbcore: registered new interface driver hub May 18 15:59:22 zerodigital kernel: usbcore: registered new device driver usb May 18 15:59:22 zerodigital kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2019-01-09 20:04 May 18 15:59:22 zerodigital kernel: clocksource: Switched to clocksource timer May 18 15:59:22 zerodigital kernel: VFS: Disk quotas dquot_6.6.0 May 18 15:59:22 zerodigital kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) May 18 15:59:22 zerodigital kernel: FS-Cache: Loaded May 18 15:59:22 zerodigital kernel: CacheFiles: Loaded May 18 15:59:22 zerodigital kernel: NET: Registered protocol family 2 May 18 15:59:22 zerodigital kernel: TCP established hash table entries: 4096 (order: 2, 16384 bytes) May 18 15:59:22 zerodigital kernel: TCP bind hash table entries: 4096 (order: 2, 16384 bytes) May 18 15:59:22 zerodigital kernel: TCP: Hash tables configured (established 4096 bind 4096) May 18 15:59:22 zerodigital kernel: UDP hash table entries: 256 (order: 0, 4096 bytes) May 18 15:59:22 zerodigital kernel: UDP-Lite hash table entries: 256 (order: 0, 4096 bytes) May 18 15:59:22 zerodigital kernel: NET: Registered protocol family 1 May 18 15:59:22 zerodigital kernel: RPC: Registered named UNIX socket transport module. May 18 15:59:22 zerodigital kernel: RPC: Registered udp transport module. May 18 15:59:22 zerodigital kernel: RPC: Registered tcp transport module. May 18 15:59:22 zerodigital kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. May 18 15:59:22 zerodigital kernel: Trying to unpack rootfs image as initramfs... May 18 15:59:22 zerodigital kernel: Freeing initrd memory: 14848K May 18 15:59:22 zerodigital kernel: hw perfevents: no irqs for PMU, sampling events not supported May 18 15:59:22 zerodigital kernel: hw perfevents: enabled with armv6_1176 PMU driver, 3 counters available May 18 15:59:22 zerodigital kernel: workingset: timestamp_bits=14 max_order=17 bucket_order=3 May 18 15:59:22 zerodigital kernel: FS-Cache: Netfs 'nfs' registered for caching May 18 15:59:22 zerodigital kernel: NFS: Registering the id_resolver key type May 18 15:59:22 zerodigital kernel: Key type id_resolver registered May 18 15:59:22 zerodigital kernel: Key type id_legacy registered May 18 15:59:22 zerodigital kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... May 18 15:59:22 zerodigital kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) May 18 15:59:22 zerodigital kernel: io scheduler noop registered (default) May 18 15:59:22 zerodigital kernel: io scheduler deadline registered May 18 15:59:22 zerodigital kernel: io scheduler cfq registered May 18 15:59:22 zerodigital kernel: io scheduler mq-deadline registered May 18 15:59:22 zerodigital kernel: io scheduler kyber registered May 18 15:59:22 zerodigital kernel: BCM2708FB: allocated DMA memory 5c900000 May 18 15:59:22 zerodigital kernel: BCM2708FB: allocated DMA channel 0 @ de80d000 May 18 15:59:22 zerodigital kernel: Console: switching to colour frame buffer device 80x30 May 18 15:59:22 zerodigital kernel: bcm2835-rng 20104000.rng: hwrng registered May 18 15:59:22 zerodigital kernel: vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB) May 18 15:59:22 zerodigital kernel: vc-sm: Videocore shared memory driver May 18 15:59:22 zerodigital kernel: gpiomem-bcm2835 20200000.gpiomem: Initialised: Registers at 0x20200000 May 18 15:59:22 zerodigital kernel: brd: module loaded May 18 15:59:22 zerodigital kernel: loop: module loaded May 18 15:59:22 zerodigital kernel: Loading iSCSI transport class v2.0-870. May 18 15:59:22 zerodigital kernel: usbcore: registered new interface driver smsc95xx May 18 15:59:22 zerodigital kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) May 18 15:59:22 zerodigital kernel: dwc_otg 20980000.usb: base=0xf0980000 May 18 15:59:22 zerodigital kernel: random: fast init done May 18 15:59:22 zerodigital kernel: Core Release: 2.80a May 18 15:59:22 zerodigital kernel: Setting default values for core params May 18 15:59:22 zerodigital kernel: Finished setting default values for core params May 18 15:59:22 zerodigital kernel: Using Buffer DMA mode May 18 15:59:22 zerodigital kernel: Periodic Transfer Interrupt Enhancement - disabled May 18 15:59:22 zerodigital kernel: Multiprocessor Interrupt Enhancement - disabled May 18 15:59:22 zerodigital kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 May 18 15:59:22 zerodigital kernel: Dedicated Tx FIFOs mode May 18 15:59:22 zerodigital kernel: WARN::dwc_otg_hcd_init:1046: FIQ DMA bounce buffers: virt = 0xdc914000 dma = 0x5c914000 len=9024 May 18 15:59:22 zerodigital kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled May 18 15:59:22 zerodigital kernel: dwc_otg: Microframe scheduler enabled May 18 15:59:22 zerodigital kernel: WARN::hcd_init_fiq:459: FIQ on core 0 at 0xc049f7c0 May 18 15:59:22 zerodigital kernel: WARN::hcd_init_fiq:460: FIQ ASM at 0xc049fa9c length 36 May 18 15:59:22 zerodigital kernel: WARN::hcd_init_fiq:486: MPHI regs_base at 0xf0006000 May 18 15:59:22 zerodigital kernel: dwc_otg 20980000.usb: DWC OTG Controller May 18 15:59:22 zerodigital kernel: dwc_otg 20980000.usb: new USB bus registered, assigned bus number 1 May 18 15:59:22 zerodigital kernel: dwc_otg 20980000.usb: irq 56, io mem 0x00000000 May 18 15:59:22 zerodigital kernel: Init: Port Power? op_state=1 May 18 15:59:22 zerodigital kernel: Init: Power Port (0) May 18 15:59:22 zerodigital kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 May 18 15:59:22 zerodigital kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 May 18 15:59:22 zerodigital kernel: usb usb1: Product: DWC OTG Controller May 18 15:59:22 zerodigital kernel: usb usb1: Manufacturer: Linux 4.14.92+ dwc_otg_hcd May 18 15:59:22 zerodigital kernel: usb usb1: SerialNumber: 20980000.usb May 18 15:59:22 zerodigital kernel: hub 1-0:1.0: USB hub found May 18 15:59:22 zerodigital kernel: hub 1-0:1.0: 1 port detected May 18 15:59:22 zerodigital kernel: dwc_otg: FIQ enabled May 18 15:59:22 zerodigital kernel: dwc_otg: NAK holdoff enabled May 18 15:59:23 zerodigital kernel: dwc_otg: FIQ split-transaction FSM enabled May 18 15:59:23 zerodigital kernel: Module dwc_common_port init May 18 15:59:23 zerodigital kernel: usbcore: registered new interface driver usb-storage May 18 15:59:23 zerodigital kernel: mousedev: PS/2 mouse device common for all mice May 18 15:59:23 zerodigital kernel: IR NEC protocol handler initialized May 18 15:59:23 zerodigital kernel: IR RC5(x/sz) protocol handler initialized May 18 15:59:23 zerodigital kernel: IR RC6 protocol handler initialized May 18 15:59:23 zerodigital kernel: IR JVC protocol handler initialized May 18 15:59:23 zerodigital kernel: IR Sony protocol handler initialized May 18 15:59:23 zerodigital kernel: IR SANYO protocol handler initialized May 18 15:59:23 zerodigital kernel: IR Sharp protocol handler initialized May 18 15:59:23 zerodigital kernel: IR MCE Keyboard/mouse protocol handler initialized May 18 15:59:23 zerodigital kernel: IR XMP protocol handler initialized May 18 15:59:23 zerodigital kernel: bcm2835-wdt 20100000.watchdog: Broadcom BCM2835 watchdog timer May 18 15:59:23 zerodigital kernel: bcm2835-cpufreq: min=700000 max=1000000 May 18 15:59:23 zerodigital kernel: sdhci: Secure Digital Host Controller Interface driver May 18 15:59:23 zerodigital kernel: sdhci: Copyright(c) Pierre Ossman May 18 15:59:23 zerodigital kernel: mmc-bcm2835 20300000.mmc: could not get clk, deferring probe May 18 15:59:23 zerodigital kernel: sdhost-bcm2835 20202000.mmc: could not get clk, deferring probe May 18 15:59:23 zerodigital kernel: sdhci-pltfm: SDHCI platform and OF driver helper May 18 15:59:23 zerodigital kernel: ledtrig-cpu: registered to indicate activity on CPUs May 18 15:59:23 zerodigital kernel: hidraw: raw HID events driver (C) Jiri Kosina May 18 15:59:23 zerodigital kernel: usbcore: registered new interface driver usbhid May 18 15:59:23 zerodigital kernel: usbhid: USB HID core driver May 18 15:59:23 zerodigital kernel: vchiq: vchiq_init_state: slot_zero = dc980000, is_master = 0 May 18 15:59:23 zerodigital kernel: [vc_sm_connected_init]: start May 18 15:59:23 zerodigital kernel: [vc_sm_connected_init]: end - returning 0 May 18 15:59:23 zerodigital kernel: Initializing XFRM netlink socket May 18 15:59:23 zerodigital kernel: NET: Registered protocol family 17 May 18 15:59:23 zerodigital kernel: Key type dns_resolver registered May 18 15:59:23 zerodigital kernel: registered taskstats version 1 May 18 15:59:23 zerodigital kernel: uart-pl011 20201000.serial: cts_event_workaround enabled May 18 15:59:23 zerodigital kernel: 20201000.serial: ttyAMA0 at MMIO 0x20201000 (irq = 81, base_baud = 0) is a PL011 rev2 May 18 15:59:23 zerodigital kernel: mmc-bcm2835 20300000.mmc: mmc_debug:0 mmc_debug2:0 May 18 15:59:23 zerodigital kernel: mmc-bcm2835 20300000.mmc: DMA channel allocated May 18 15:59:23 zerodigital kernel: sdhost: log_buf @ dc913000 (5c913000) May 18 15:59:23 zerodigital kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) May 18 15:59:23 zerodigital kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) May 18 15:59:23 zerodigital kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) May 18 15:59:23 zerodigital kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) May 18 15:59:23 zerodigital kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) May 18 15:59:23 zerodigital kernel: of_cfs_init May 18 15:59:23 zerodigital kernel: of_cfs_init: OK May 18 15:59:23 zerodigital kernel: Freeing unused kernel memory: 444K May 18 15:59:23 zerodigital kernel: This architecture does not have kernel memory protection. May 18 15:59:23 zerodigital kernel: mmc0: host does not support reading read-only switch, assuming write-enable May 18 15:59:23 zerodigital kernel: mmc0: new high speed SDHC card at address 0001 May 18 15:59:23 zerodigital kernel: mmcblk0: mmc0:0001 EB1QT 29.8 GiB May 18 15:59:23 zerodigital kernel: mmcblk0: p1 p2 p3 May 18 15:59:23 zerodigital kernel: mmc1: new high speed SDIO card at address 0001 May 18 15:59:23 zerodigital initramfs: Booting Volumio for BCM2835 May 18 15:59:23 zerodigital initramfs: This script mounts rootfs RO with an overlay RW layer. May 18 15:59:23 zerodigital kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 18 15:59:23 zerodigital initramfs: IMGPART=/dev/mmcblk0p2 May 18 15:59:23 zerodigital initramfs: IMGFILE=/volumio_current.sqsh May 18 15:59:23 zerodigital initramfs: Boot delay (except first time) will be 5 seconds May 18 15:59:23 zerodigital initramfs: /dev/mmcblk0p2: May 18 15:59:23 zerodigital kernel: EXT4-fs (mmcblk0p2): recovery complete May 18 15:59:23 zerodigital kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) May 18 15:59:23 zerodigital initramfs: Doing a 5 second delay here to give kernel load a headstart May 18 15:59:23 zerodigital initramfs: Checking for USB updates May 18 15:59:23 zerodigital initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) May 18 15:59:23 zerodigital kernel: EXT4-fs (mmcblk0p3): recovery complete May 18 15:59:23 zerodigital kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) May 18 15:59:23 zerodigital initramfs: With Option: May 18 15:59:23 zerodigital initramfs: VOLUMIO_VERSION="2.575" May 18 15:59:23 zerodigital initramfs: Finish initramfs, continue booting Volumio May 18 15:59:23 zerodigital kernel: random: systemd: uninitialized urandom read (16 bytes read) May 18 15:59:23 zerodigital systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) May 18 15:59:23 zerodigital systemd[1]: Detected architecture 'arm'. May 18 15:59:23 zerodigital kernel: NET: Registered protocol family 10 May 18 15:59:23 zerodigital kernel: Segment Routing with IPv6 May 18 15:59:23 zerodigital systemd[1]: Inserted module 'ipv6' May 18 15:59:23 zerodigital systemd[1]: Set hostname to . May 18 15:59:23 zerodigital kernel: random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) May 18 15:59:23 zerodigital kernel: random: systemd: uninitialized urandom read (16 bytes read) May 18 15:59:23 zerodigital systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' May 18 15:59:23 zerodigital systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' May 18 15:59:23 zerodigital systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' May 18 15:59:23 zerodigital systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' May 18 15:59:23 zerodigital 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 18 15:59:23 zerodigital systemd[1]: Starting Forward Password Requests to Wall Directory Watch. May 18 15:59:23 zerodigital kernel: fuse init (API version 7.26) May 18 15:59:23 zerodigital kernel: i2c /dev entries driver May 18 15:59:23 zerodigital systemd-journal[140]: Journal started May 18 15:59:23 zerodigital systemd[1]: Starting Slices. May 18 15:59:23 zerodigital systemd[1]: Reached target Slices. May 18 15:59:23 zerodigital systemd[1]: Starting Remount Root and Kernel File Systems... May 18 15:59:23 zerodigital systemd[1]: Started Restore / save the current clock. May 18 15:59:23 zerodigital systemd[1]: Started Create list of required static device nodes for the current kernel. May 18 15:59:23 zerodigital systemd[1]: Time has been changed May 18 15:59:23 zerodigital systemd-modules-load[137]: Inserted module 'fuse' May 18 15:59:23 zerodigital systemd-modules-load[137]: Inserted module 'i2c_dev' May 18 15:59:22 zerodigital systemd[1]: Started Load Kernel Modules. May 18 15:59:22 zerodigital systemd[1]: Mounted POSIX Message Queue File System. May 18 15:59:22 zerodigital systemd[1]: Mounted Debug File System. May 18 15:59:23 zerodigital systemd[1]: Started udev Coldplug all Devices. May 18 15:59:23 zerodigital kernel: Adding 524284k swap on /data/swapfile. Priority:-2 extents:3 across:540668k SSFS May 18 15:59:23 zerodigital volumio[123]: 512 MB or less RAM Detected, need to enable swap May 18 15:59:23 zerodigital volumio[123]: Enabling Swap May 18 15:59:23 zerodigital volumio[123]: Setting swappiness to 40 May 18 15:59:23 zerodigital volumio[123]: vm.swappiness = 40 May 18 15:59:23 zerodigital fake-hwclock[128]: Sat May 18 15:59:22 UTC 2019 May 18 15:59:23 zerodigital systemd[1]: Started Various fixups to make systemd work better on Debian. May 18 15:59:23 zerodigital systemd[1]: Starting Load/Save Random Seed... May 18 15:59:23 zerodigital systemd[1]: Mounting FUSE Control File System... May 18 15:59:23 zerodigital systemd[1]: Mounting Configuration File System... May 18 15:59:23 zerodigital systemd[1]: Starting Apply Kernel Variables... May 18 15:59:23 zerodigital systemd[1]: Starting Create Static Device Nodes in /dev... May 18 15:59:23 zerodigital systemd[1]: Mounted FUSE Control File System. May 18 15:59:23 zerodigital systemd[1]: Mounted Configuration File System. May 18 15:59:23 zerodigital systemd[1]: Started Load/Save Random Seed. May 18 15:59:23 zerodigital systemd[1]: Started Apply Kernel Variables. May 18 15:59:23 zerodigital systemd[1]: Started Create Static Device Nodes in /dev. May 18 15:59:23 zerodigital systemd[1]: Starting udev Kernel Device Manager... May 18 15:59:23 zerodigital systemd[1]: Starting Local File Systems (Pre). May 18 15:59:23 zerodigital systemd[1]: Reached target Local File Systems (Pre). May 18 15:59:23 zerodigital systemd[1]: Mounting /tmp... May 18 15:59:23 zerodigital systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. May 18 15:59:23 zerodigital systemd[1]: Mounting /var/spool/cups... May 18 15:59:23 zerodigital systemd[1]: Mounting /var/log... May 18 15:59:23 zerodigital systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. May 18 15:59:24 zerodigital systemd-udevd[168]: starting version 215 May 18 15:59:24 zerodigital systemd[1]: Started udev Kernel Device Manager. May 18 15:59:24 zerodigital systemd[1]: Mounted /var/spool/cups. May 18 15:59:24 zerodigital systemd[1]: Mounted /tmp. May 18 15:59:24 zerodigital systemd[1]: Mounted /var/log. May 18 15:59:24 zerodigital systemd[1]: Mounting /var/spool/cups/tmp... May 18 15:59:24 zerodigital systemd[1]: Starting Show Plymouth Boot Screen... May 18 15:59:24 zerodigital systemd[1]: Starting Copy rules generated while the root was ro... May 18 15:59:24 zerodigital systemd[1]: Mounted /var/spool/cups/tmp. May 18 15:59:24 zerodigital systemd[1]: Started Copy rules generated while the root was ro. May 18 15:59:24 zerodigital systemd[1]: Started Show Plymouth Boot Screen. May 18 15:59:24 zerodigital systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch. May 18 15:59:24 zerodigital systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. May 18 15:59:24 zerodigital systemd[1]: Started Dispatch Password Requests to Console Directory Watch. May 18 15:59:24 zerodigital systemd[1]: Starting Paths. May 18 15:59:24 zerodigital systemd[1]: Reached target Paths. May 18 15:59:26 zerodigital kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. May 18 15:59:26 zerodigital kernel: snd_bcm2835: unknown parameter 'index' ignored May 18 15:59:26 zerodigital kernel: bcm2835_alsa bcm2835_alsa: card created with 8 channels May 18 15:59:27 zerodigital kernel: snd-rpi-justboom-digi soc:sound: ASoC: CPU DAI (null) not registered - will retry May 18 15:59:27 zerodigital kernel: snd-rpi-justboom-digi soc:sound: ASoC: CODEC DAI wm8804-spdif not registered - will retry May 18 15:59:27 zerodigital systemd[1]: Found device /dev/mmcblk0p1. May 18 15:59:27 zerodigital systemd[1]: Starting File System Check on /dev/mmcblk0p1... May 18 15:59:27 zerodigital kernel: snd-rpi-justboom-digi soc:sound: ASoC: CODEC DAI wm8804-spdif not registered - will retry May 18 15:59:27 zerodigital kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 May 18 15:59:27 zerodigital kernel: brcmfmac: brcmf_fw_map_chip_to_name: using brcm/brcmfmac43430-sdio.bin for chip 0x00a9a6(43430) rev 0x000001 May 18 15:59:27 zerodigital kernel: usbcore: registered new interface driver brcmfmac May 18 15:59:28 zerodigital kernel: snd-rpi-justboom-digi soc:sound: ASoC: CODEC DAI wm8804-spdif not registered - will retry May 18 15:59:28 zerodigital kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Oct 23 2017 03:55:53 version 7.45.98.38 (r674442 CY) FWID 01-e58d219f May 18 15:59:28 zerodigital kernel: brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 7.11.15 Compiler: 1.24.2 ClmImport: 1.24.1 Creation: 2014-05-26 10:53:55 Inc Data: 9.10.39 Inc Compiler: 1.29.4 Inc ClmImport: 1.36.3 Creation: 2017-10-23 03:47:14 May 18 15:59:28 zerodigital systemd-fsck[230]: fsck.fat 3.0.27 (2014-11-12) May 18 15:59:28 zerodigital systemd-fsck[230]: /dev/mmcblk0p1: 182 files, 74371/123044 clusters May 18 15:59:28 zerodigital systemd[1]: Started File System Check on /dev/mmcblk0p1. May 18 15:59:28 zerodigital systemd[1]: Mounting /boot... May 18 15:59:28 zerodigital systemd[1]: Mounted /boot. May 18 15:59:28 zerodigital systemd[1]: Starting Local File Systems. May 18 15:59:28 zerodigital systemd[1]: Reached target Local File Systems. May 18 15:59:28 zerodigital systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... May 18 15:59:28 zerodigital systemd[1]: Starting Remote File Systems. May 18 15:59:28 zerodigital systemd[1]: Reached target Remote File Systems. May 18 15:59:28 zerodigital systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... May 18 15:59:28 zerodigital systemd[1]: Starting Create Volatile Files and Directories... May 18 15:59:29 zerodigital systemd[1]: Starting LSB: Raise network interfaces.... May 18 15:59:29 zerodigital systemd[1]: Started Tell Plymouth To Write Out Runtime Data. May 18 15:59:29 zerodigital systemd[1]: Started Create Volatile Files and Directories. May 18 15:59:29 zerodigital systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. May 18 15:59:29 zerodigital systemd[1]: Starting Update UTMP about System Boot/Shutdown... May 18 15:59:29 zerodigital systemd[1]: Starting Sound Card. May 18 15:59:29 zerodigital systemd[1]: Reached target Sound Card. May 18 15:59:29 zerodigital systemd[1]: Started Update UTMP about System Boot/Shutdown. May 18 15:59:30 zerodigital kernel: wm8804 1-003b: Failed to read device ID: -121 May 18 15:59:30 zerodigital kernel: wm8804: probe of 1-003b failed with error -121 May 18 15:59:30 zerodigital systemd[1]: Starting system-systemd\x2drfkill.slice. May 18 15:59:30 zerodigital systemd[1]: Created slice system-systemd\x2drfkill.slice. May 18 15:59:30 zerodigital systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill0... May 18 15:59:30 zerodigital systemd[1]: Started Load/Save RF Kill Switch Status of rfkill0. May 18 15:59:30 zerodigital systemd[1]: Starting system-ifup.slice. May 18 15:59:30 zerodigital systemd[1]: Created slice system-ifup.slice. May 18 15:59:31 zerodigital kernel: brcmfmac: power management disabled May 18 15:59:32 zerodigital systemd[1]: Received SIGRTMIN+20 from PID 187 (plymouthd). May 18 15:59:32 zerodigital kernel: brcmfmac: power management disabled May 18 15:59:33 zerodigital networking[249]: Configuring network interfaces...done. May 18 15:59:33 zerodigital systemd[1]: Started LSB: Raise network interfaces.. May 18 15:59:33 zerodigital systemd[1]: Starting ifup for wlan0... May 18 15:59:33 zerodigital systemd[1]: Started ifup for wlan0. May 18 15:59:33 zerodigital systemd[1]: Starting Network. May 18 15:59:33 zerodigital systemd[1]: Reached target Network. May 18 15:59:33 zerodigital systemd[1]: Starting Network is Online. May 18 15:59:33 zerodigital systemd[1]: Reached target Network is Online. May 18 15:59:33 zerodigital systemd[1]: Starting LSB: RPC portmapper replacement... May 18 15:59:34 zerodigital rpcbind[350]: Starting rpcbind daemon.... May 18 15:59:34 zerodigital systemd[1]: Started LSB: RPC portmapper replacement. May 18 15:59:34 zerodigital systemd[1]: Starting RPC Port Mapper. May 18 15:59:34 zerodigital systemd[1]: Reached target RPC Port Mapper. May 18 15:59:34 zerodigital systemd[1]: Starting LSB: NFS support files common to client and server... May 18 15:59:34 zerodigital rpc.statd[424]: Version 1.2.8 starting May 18 15:59:35 zerodigital sm-notify[425]: Version 1.2.8 starting May 18 15:59:35 zerodigital kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). May 18 15:59:35 zerodigital nfs-common[404]: Starting NFS common utilities: statd idmapd. May 18 15:59:35 zerodigital systemd[1]: Started LSB: NFS support files common to client and server. May 18 15:59:35 zerodigital systemd[1]: Starting System Initialization. May 18 15:59:35 zerodigital systemd[1]: Reached target System Initialization. May 18 15:59:35 zerodigital systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. May 18 15:59:35 zerodigital systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. May 18 15:59:35 zerodigital systemd[1]: Starting mpd.socket. May 18 15:59:35 zerodigital systemd[1]: Listening on mpd.socket. May 18 15:59:35 zerodigital systemd[1]: Starting D-Bus System Message Bus Socket. May 18 15:59:35 zerodigital systemd[1]: Listening on D-Bus System Message Bus Socket. May 18 15:59:35 zerodigital systemd[1]: Starting Sockets. May 18 15:59:35 zerodigital systemd[1]: Reached target Sockets. May 18 15:59:35 zerodigital systemd[1]: Starting Daily Cleanup of Temporary Directories. May 18 15:59:35 zerodigital systemd[1]: Started Daily Cleanup of Temporary Directories. May 18 15:59:35 zerodigital systemd[1]: Starting Timers. May 18 15:59:35 zerodigital systemd[1]: Reached target Timers. May 18 15:59:35 zerodigital systemd[1]: Started Manage Sound Card State (restore and store). May 18 15:59:35 zerodigital systemd[1]: Starting Restore Sound Card State... May 18 15:59:35 zerodigital systemd[1]: Starting Basic System. May 18 15:59:35 zerodigital systemd[1]: Reached target Basic System. May 18 15:59:35 zerodigital systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... May 18 15:59:35 zerodigital systemd[1]: Started Entropy daemon using the HAVEGE algorithm. May 18 15:59:35 zerodigital systemd[1]: Starting cd.service... May 18 15:59:35 zerodigital systemd[1]: Started cd.service. May 18 15:59:35 zerodigital systemd[1]: Starting Volumio Iptables Module... May 18 15:59:35 zerodigital systemd[1]: Started Volumio Iptables Module. May 18 15:59:35 zerodigital systemd[1]: Starting mount disks automatically with standby... May 18 15:59:35 zerodigital systemd[1]: Started mount disks automatically with standby. May 18 15:59:35 zerodigital systemd[1]: Starting UPnP Renderer front-end to MPD... May 18 15:59:35 zerodigital systemd[1]: Started UPnP Renderer front-end to MPD. May 18 15:59:35 zerodigital volumio[441]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 18 15:59:35 zerodigital systemd[1]: Starting volumio-remote-updater.service... May 18 15:59:36 zerodigital systemd[1]: Started volumio-remote-updater.service. May 18 15:59:36 zerodigital systemd[1]: Starting Volumio SSH enabler... May 18 15:59:36 zerodigital systemd[1]: Started Volumio SSH enabler. May 18 15:59:36 zerodigital systemd[1]: Starting Wireless Services... May 18 15:59:36 zerodigital systemd[1]: Starting Xbindrc... May 18 15:59:36 zerodigital systemd[1]: Started Xbindrc. May 18 15:59:36 zerodigital systemd[1]: Starting OpenBSD Secure Shell server... May 18 15:59:36 zerodigital systemd[1]: Starting /etc/rc.local Compatibility... May 18 15:59:36 zerodigital kernel: ip_tables: (C) 2000-2006 Netfilter Core Team May 18 15:59:36 zerodigital systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. May 18 15:59:36 zerodigital systemd[1]: Starting Login Service... May 18 15:59:36 zerodigital systemd[1]: Starting LSB: start Winbind daemon... May 18 15:59:36 zerodigital systemd[1]: Starting LSB: start Samba daemons for the AD DC... May 18 15:59:36 zerodigital kernel: nf_conntrack version 0.5.0 (8192 buckets, 32768 max) May 18 15:59:36 zerodigital systemd[1]: Starting LSB: Start NTP daemon... May 18 15:59:37 zerodigital systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... May 18 15:59:37 zerodigital systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... May 18 15:59:37 zerodigital systemd[1]: Starting LSB: Brings up/down network automatically... May 18 15:59:37 zerodigital systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... May 18 15:59:37 zerodigital systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... May 18 15:59:37 zerodigital systemd[1]: Starting Avahi mDNS/DNS-SD Stack... May 18 15:59:37 zerodigital systemd[1]: Starting Music Player Daemon... May 18 15:59:38 zerodigital systemd[1]: Starting D-Bus System Message Bus... May 18 15:59:38 zerodigital systemd[1]: Started D-Bus System Message Bus. May 18 15:59:38 zerodigital ifplugd[473]: Network Interface Plugging Daemon...skip eth0...done. May 18 15:59:38 zerodigital avahi-daemon[488]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). May 18 15:59:39 zerodigital avahi-daemon[488]: Successfully dropped root privileges. May 18 15:59:39 zerodigital avahi-daemon[488]: avahi-daemon 0.6.31 starting up. May 18 15:59:40 zerodigital sudo[453]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off May 18 15:59:40 zerodigital ntpd[509]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) May 18 15:59:40 zerodigital ntp[461]: Starting NTP server: ntpd. May 18 15:59:40 zerodigital dhcpcd[481]: Not running dhcpcd because /etc/network/interfaces ... failed! May 18 15:59:40 zerodigital ntpd[530]: proto: precision = 1.000 usec May 18 15:59:40 zerodigital sudo[453]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 15:59:40 zerodigital avahi-daemon[488]: Successfully called chroot(). May 18 15:59:40 zerodigital avahi-daemon[488]: Successfully dropped remaining capabilities. May 18 15:59:40 zerodigital avahi-daemon[488]: Loading service file /services/volumio.service. May 18 15:59:40 zerodigital dbus[497]: [system] Successfully activated service 'org.freedesktop.systemd1' May 18 15:59:40 zerodigital ntpd[530]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 May 18 15:59:40 zerodigital avahi-daemon[488]: Network interface enumeration completed. May 18 15:59:40 zerodigital avahi-daemon[488]: Registering HINFO record with values 'ARMV6L'/'LINUX'. May 18 15:59:40 zerodigital dbus[497]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' May 18 15:59:40 zerodigital avahi-daemon[488]: Server startup complete. Host name is zerodigital.local. Local service cookie is 1418402806. May 18 15:59:40 zerodigital avahi-daemon[488]: Service "ZeroDigital" (/services/volumio.service) successfully established. May 18 15:59:40 zerodigital ntpd[530]: Listen and drop on 1 v6wildcard :: UDP 123 May 18 15:59:40 zerodigital ntpd[530]: Listen normally on 2 lo 127.0.0.1 UDP 123 May 18 15:59:40 zerodigital ntpd[530]: peers refreshed May 18 15:59:40 zerodigital ntpd[530]: Listening on routing socket on fd #19 for interface updates May 18 15:59:40 zerodigital sudo[453]: pam_unix(sudo:session): session closed for user root May 18 15:59:40 zerodigital systemd[1]: Started Avahi mDNS/DNS-SD Stack. May 18 15:59:40 zerodigital systemd[1]: Starting Permit User Sessions... May 18 15:59:41 zerodigital systemd[1]: Started Restore Sound Card State. May 18 15:59:41 zerodigital systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 18 15:59:41 zerodigital systemd[1]: Unit cd.service entered failed state. May 18 15:59:41 zerodigital dhcpcd[481]: defines some interfaces that will use a DHCP client ... failed! May 18 15:59:41 zerodigital systemd[1]: Started OpenBSD Secure Shell server. May 18 15:59:41 zerodigital loadcpufreq[469]: Loading cpufreq kernel modules...done (none). May 18 15:59:41 zerodigital systemd[1]: Started /etc/rc.local Compatibility. May 18 15:59:41 zerodigital systemd[1]: Started LSB: Start NTP daemon. May 18 15:59:41 zerodigital systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. May 18 15:59:41 zerodigital systemd[1]: Started LSB: Brings up/down network automatically. May 18 15:59:41 zerodigital systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. May 18 15:59:41 zerodigital systemd[1]: dhcpcd.service: control process exited, code=exited status=6 May 18 15:59:41 zerodigital systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. May 18 15:59:41 zerodigital systemd[1]: Unit dhcpcd.service entered failed state. May 18 15:59:42 zerodigital systemd[1]: Started Permit User Sessions. May 18 15:59:42 zerodigital systemd[1]: cd.service holdoff time over, scheduling restart. May 18 15:59:42 zerodigital sshd[552]: Server listening on 0.0.0.0 port 22. May 18 15:59:42 zerodigital sshd[552]: Server listening on :: port 22. May 18 15:59:43 zerodigital volumio-remote-updater[447]: Error: No active session May 18 15:59:43 zerodigital volumio-remote-updater[447]: [2019-05-18 15:59:43] [info] asio async_connect error: system:111 (Connection refused) May 18 15:59:43 zerodigital volumio-remote-updater[447]: [2019-05-18 15:59:43] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 18 15:59:43 zerodigital volumio-remote-updater[447]: [2019-05-18 15:59:43] [error] handle_connect error: Underlying Transport Error May 18 15:59:44 zerodigital volumio[446]: Could not open config: /tmp/upmpdcli.conf May 18 15:59:45 zerodigital systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE May 18 15:59:45 zerodigital systemd[1]: Unit upmpdcli.service entered failed state. May 18 15:59:46 zerodigital systemd-logind[457]: New seat seat0. May 18 15:59:46 zerodigital systemd[1]: Started Login Service. May 18 15:59:46 zerodigital systemd[1]: Starting Disk Manager (legacy version)... May 18 15:59:46 zerodigital systemd[1]: Stopping cd.service... May 18 15:59:46 zerodigital systemd[1]: Starting cd.service... May 18 15:59:46 zerodigital systemd[1]: Started cd.service. May 18 15:59:46 zerodigital systemd[1]: Starting LSB: set CPUFreq kernel parameters... May 18 15:59:46 zerodigital volumio[565]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 18 15:59:46 zerodigital systemd[1]: Starting Hold until boot process finishes up... May 18 15:59:46 zerodigital systemd[1]: Starting Terminate Plymouth Boot Screen... May 18 15:59:47 zerodigital systemd[1]: Received SIGRTMIN+21 from PID 187 (plymouthd). May 18 15:59:47 zerodigital systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 18 15:59:47 zerodigital systemd[1]: Unit cd.service entered failed state. May 18 15:59:47 zerodigital systemd[1]: Started Hold until boot process finishes up. May 18 15:59:47 zerodigital systemd[1]: Started Terminate Plymouth Boot Screen. May 18 15:59:47 zerodigital dbus[497]: [system] Successfully activated service 'org.freedesktop.UDisks' May 18 15:59:47 zerodigital systemd[1]: cd.service holdoff time over, scheduling restart. May 18 15:59:48 zerodigital systemd[1]: Started Disk Manager (legacy version). May 18 15:59:48 zerodigital cpufrequtils[566]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...done. May 18 15:59:48 zerodigital systemd[1]: Started LSB: set CPUFreq kernel parameters. May 18 15:59:48 zerodigital dbus[497]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' May 18 15:59:48 zerodigital volumio-remote-updater[447]: [2019-05-18 15:59:48] [info] asio async_connect error: system:111 (Connection refused) May 18 15:59:48 zerodigital volumio-remote-updater[447]: [2019-05-18 15:59:48] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 18 15:59:48 zerodigital volumio-remote-updater[447]: [2019-05-18 15:59:48] [error] handle_connect error: Underlying Transport Error May 18 15:59:48 zerodigital systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 18 15:59:49 zerodigital systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... May 18 15:59:49 zerodigital systemd[1]: Stopping Xbindrc... May 18 15:59:49 zerodigital systemd[1]: Starting Xbindrc... May 18 15:59:49 zerodigital systemd[1]: Started Xbindrc. May 18 15:59:49 zerodigital systemd[1]: Stopping cd.service... May 18 15:59:49 zerodigital systemd[1]: Starting cd.service... May 18 15:59:49 zerodigital systemd[1]: Started cd.service. May 18 15:59:49 zerodigital systemd[1]: Starting Getty on tty1... May 18 15:59:49 zerodigital systemd[1]: Started Getty on tty1. May 18 15:59:49 zerodigital systemd[1]: Starting Login Prompts. May 18 15:59:49 zerodigital systemd[1]: Reached target Login Prompts. May 18 15:59:49 zerodigital volumio[592]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 18 15:59:49 zerodigital systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 18 15:59:49 zerodigital systemd[1]: Unit cd.service entered failed state. May 18 15:59:49 zerodigital systemd[1]: cd.service holdoff time over, scheduling restart. May 18 15:59:49 zerodigital systemd[1]: Stopping cd.service... May 18 15:59:49 zerodigital systemd[1]: Starting cd.service... May 18 15:59:49 zerodigital systemd[1]: Started cd.service. May 18 15:59:50 zerodigital volumio[597]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 18 15:59:50 zerodigital systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 18 15:59:50 zerodigital systemd[1]: Unit cd.service entered failed state. May 18 15:59:50 zerodigital systemd[1]: cd.service holdoff time over, scheduling restart. May 18 15:59:50 zerodigital polkitd[590]: started daemon version 0.105 using authority implementation `local' version `0.105' May 18 15:59:50 zerodigital systemd[1]: Stopping cd.service... May 18 15:59:50 zerodigital dbus[497]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 18 15:59:50 zerodigital systemd[1]: Starting cd.service... May 18 15:59:50 zerodigital systemd[1]: Started cd.service. May 18 15:59:50 zerodigital systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. May 18 15:59:50 zerodigital volumio[600]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 18 15:59:50 zerodigital systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 18 15:59:50 zerodigital systemd[1]: Unit cd.service entered failed state. May 18 15:59:50 zerodigital systemd[1]: cd.service holdoff time over, scheduling restart. May 18 15:59:50 zerodigital systemd[1]: Stopping cd.service... May 18 15:59:50 zerodigital systemd[1]: Starting cd.service... May 18 15:59:51 zerodigital systemd[1]: Started cd.service. May 18 15:59:51 zerodigital volumio[602]: /bin/bash: /volumio/app/plugins/music_service/cd_controller/cd.sh: No such file or directory May 18 15:59:51 zerodigital systemd[1]: cd.service: main process exited, code=exited, status=127/n/a May 18 15:59:51 zerodigital systemd[1]: Unit cd.service entered failed state. May 18 15:59:51 zerodigital systemd[1]: cd.service holdoff time over, scheduling restart. May 18 15:59:51 zerodigital systemd[1]: Stopping cd.service... May 18 15:59:51 zerodigital systemd[1]: Starting cd.service... May 18 15:59:51 zerodigital systemd[1]: cd.service start request repeated too quickly, refusing to start. May 18 15:59:51 zerodigital systemd[1]: Failed to start cd.service. May 18 15:59:51 zerodigital systemd[1]: Unit cd.service entered failed state. May 18 15:59:51 zerodigital udisks-glue[444]: Device file /dev/mmcblk0p3 inserted May 18 15:59:51 zerodigital udisks-glue[444]: Device file /dev/mmcblk0 inserted May 18 15:59:52 zerodigital ntpd[530]: Deferring DNS for 0.debian.pool.ntp.org 1 May 18 15:59:52 zerodigital ntpd[530]: Deferring DNS for 1.debian.pool.ntp.org 1 May 18 15:59:52 zerodigital ntpd[530]: Deferring DNS for 2.debian.pool.ntp.org 1 May 18 15:59:52 zerodigital ntpd[530]: Deferring DNS for 3.debian.pool.ntp.org 1 May 18 15:59:52 zerodigital ntpd[604]: signal_no_reset: signal 17 had flags 4000000 May 18 15:59:52 zerodigital systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 18 15:59:52 zerodigital systemd[1]: Stopping Xbindrc... May 18 15:59:52 zerodigital systemd[1]: Starting Xbindrc... May 18 15:59:52 zerodigital systemd[1]: Started Xbindrc. May 18 15:59:54 zerodigital ntpd_intres[604]: host name not found: 0.debian.pool.ntp.org May 18 15:59:54 zerodigital ntpd_intres[604]: host name not found: 1.debian.pool.ntp.org May 18 15:59:54 zerodigital ntpd_intres[604]: host name not found: 2.debian.pool.ntp.org May 18 15:59:54 zerodigital ntpd_intres[604]: host name not found: 3.debian.pool.ntp.org May 18 15:59:54 zerodigital systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 18 15:59:54 zerodigital systemd[1]: Stopping Xbindrc... May 18 15:59:54 zerodigital systemd[1]: Starting Xbindrc... May 18 15:59:54 zerodigital systemd[1]: Started Xbindrc. May 18 15:59:56 zerodigital volumio-remote-updater[447]: [2019-05-18 15:59:56] [info] asio async_connect error: system:111 (Connection refused) May 18 15:59:56 zerodigital volumio-remote-updater[447]: [2019-05-18 15:59:56] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 18 15:59:56 zerodigital volumio-remote-updater[447]: [2019-05-18 15:59:56] [error] handle_connect error: Underlying Transport Error May 18 15:59:57 zerodigital systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 18 15:59:57 zerodigital systemd[1]: Stopping Xbindrc... May 18 15:59:57 zerodigital systemd[1]: Starting Xbindrc... May 18 15:59:57 zerodigital systemd[1]: Started Xbindrc. May 18 15:59:59 zerodigital systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 18 15:59:59 zerodigital systemd[1]: Stopping Xbindrc... May 18 15:59:59 zerodigital systemd[1]: Starting Xbindrc... May 18 15:59:59 zerodigital systemd[1]: Started Xbindrc. May 18 16:00:01 zerodigital haveged[440]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K May 18 16:00:01 zerodigital haveged[440]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244 May 18 16:00:01 zerodigital haveged[440]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00101 May 18 16:00:01 zerodigital haveged[440]: haveged: fills: 0, generated: 0 May 18 16:00:01 zerodigital kernel: random: crng init done May 18 16:00:01 zerodigital kernel: random: 7 urandom warning(s) missed due to ratelimiting May 18 16:00:01 zerodigital systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 18 16:00:01 zerodigital systemd[1]: Stopping Xbindrc... May 18 16:00:01 zerodigital systemd[1]: Starting Xbindrc... May 18 16:00:02 zerodigital systemd[1]: Started Xbindrc. May 18 16:00:04 zerodigital systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 18 16:00:04 zerodigital systemd[1]: Stopping Xbindrc... May 18 16:00:04 zerodigital systemd[1]: Starting Xbindrc... May 18 16:00:04 zerodigital systemd[1]: Started Xbindrc. May 18 16:00:06 zerodigital volumio-remote-updater[447]: [2019-05-18 16:00:06] [info] asio async_connect error: system:111 (Connection refused) May 18 16:00:06 zerodigital volumio-remote-updater[447]: [2019-05-18 16:00:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 18 16:00:06 zerodigital volumio-remote-updater[447]: [2019-05-18 16:00:06] [error] handle_connect error: Underlying Transport Error May 18 16:00:06 zerodigital systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 18 16:00:06 zerodigital systemd[1]: Stopping Xbindrc... May 18 16:00:06 zerodigital systemd[1]: Starting Xbindrc... May 18 16:00:06 zerodigital systemd[1]: Started Xbindrc. May 18 16:00:07 zerodigital wireless.js[555]: WIRELESS DAEMON: start May 18 16:00:07 zerodigital wireless.js[555]: WIRELESS: Loaded configuration May 18 16:00:07 zerodigital wireless.js[555]: Cleaning previous... May 18 16:00:07 zerodigital systemd[1]: Started LSB: start Samba daemons for the AD DC. May 18 16:00:08 zerodigital systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 18 16:00:08 zerodigital systemd[1]: Stopping Xbindrc... May 18 16:00:08 zerodigital systemd[1]: Starting Xbindrc... May 18 16:00:08 zerodigital systemd[1]: Started Xbindrc. May 18 16:00:08 zerodigital systemd[1]: Stopped hotspot.service. May 18 16:00:08 zerodigital sudo[654]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 18 16:00:09 zerodigital sudo[654]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:00:09 zerodigital sudo[654]: pam_unix(sudo:session): session closed for user root May 18 16:00:09 zerodigital sudo[662]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 18 16:00:09 zerodigital sudo[662]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:00:10 zerodigital sudo[662]: pam_unix(sudo:session): session closed for user root May 18 16:00:10 zerodigital systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 18 16:00:10 zerodigital systemd[1]: Stopping Xbindrc... May 18 16:00:10 zerodigital systemd[1]: Starting Xbindrc... May 18 16:00:10 zerodigital systemd[1]: Started Xbindrc. May 18 16:00:10 zerodigital systemd[1]: Started Music Player Daemon. May 18 16:00:10 zerodigital wireless.js[555]: Stopped aP May 18 16:00:10 zerodigital wireless.js[555]: Start wireless flow May 18 16:00:10 zerodigital wireless.js[555]: Stopped hotspot (if there).. May 18 16:00:10 zerodigital winbind[459]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory May 18 16:00:10 zerodigital winbind[459]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 18 16:00:10 zerodigital winbind[459]: Unable to setup corepath for winbindd: No such file or directory May 18 16:00:10 zerodigital winbind[459]: mkdir failed on directory /var/log/samba/cores: No such file or directory May 18 16:00:10 zerodigital winbind[459]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 18 16:00:10 zerodigital winbind[459]: Unable to setup corepath for winbindd: No such file or directory May 18 16:00:10 zerodigital winbind[459]: [2019/05/18 16:00:10, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:10 zerodigital winbind[459]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 18 16:00:10 zerodigital winbind[459]: [2019/05/18 16:00:10, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:10 zerodigital winbind[459]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 18 16:00:10 zerodigital winbind[459]: [2019/05/18 16:00:10, 0] ../source3/winbindd/winbindd.c:1549(main) May 18 16:00:11 zerodigital winbind[459]: winbindd version 4.2.14-Debian started. May 18 16:00:11 zerodigital winbind[459]: Copyright Andrew Tridgell and the Samba Team 1992-2014 May 18 16:00:11 zerodigital winbind[459]: [2019/05/18 16:00:10, 0] ../lib/util/util.c:220(directory_create_or_exist) May 18 16:00:11 zerodigital winbind[459]: mkdir failed on directory /var/log/samba/cores: No such file or directory May 18 16:00:11 zerodigital winbind[459]: [2019/05/18 16:00:10, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) May 18 16:00:11 zerodigital winbind[459]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 18 16:00:11 zerodigital winbind[459]: [2019/05/18 16:00:10, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) May 18 16:00:11 zerodigital winbind[459]: Unable to setup corepath for winbindd: No such file or directory May 18 16:00:10 zerodigital winbindd[651]: [2019/05/18 16:00:10.951514, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:10 zerodigital winbindd[651]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 18 16:00:10 zerodigital winbindd[651]: [2019/05/18 16:00:10.994389, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:10 zerodigital winbindd[651]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 18 16:00:11 zerodigital sudo[686]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 18 16:00:11 zerodigital winbindd[687]: [2019/05/18 16:00:11.102110, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) May 18 16:00:11 zerodigital winbindd[687]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 May 18 16:00:11 zerodigital sudo[686]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:00:11 zerodigital sudo[686]: pam_unix(sudo:session): session closed for user root May 18 16:00:11 zerodigital systemd[1]: Started LSB: start Winbind daemon. May 18 16:00:11 zerodigital winbind[459]: [2019/05/18 16:00:10.951514, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:11 zerodigital winbind[459]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 18 16:00:11 zerodigital winbind[459]: [2019/05/18 16:00:10.994389, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:11 zerodigital winbind[459]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory May 18 16:00:11 zerodigital winbind[459]: . May 18 16:00:11 zerodigital winbindd[687]: [2019/05/18 16:00:11.293667, 0] ../lib/util/become_daemon.c:124(daemon_ready) May 18 16:00:11 zerodigital winbindd[687]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections May 18 16:00:11 zerodigital winbindd[696]: [2019/05/18 16:00:11.308396, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:11 zerodigital winbindd[696]: Unable to open new log file '/var/log/samba/log.wb-ZERODIGITAL': No such file or directory May 18 16:00:11 zerodigital sudo[695]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 18 16:00:11 zerodigital nmbd[464]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory May 18 16:00:11 zerodigital nmbd[672]: [2019/05/18 16:00:11.429705, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:11 zerodigital nmbd[672]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 18 16:00:11 zerodigital nmbd[464]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 18 16:00:11 zerodigital nmbd[464]: Unable to setup corepath for nmbd: No such file or directory May 18 16:00:11 zerodigital nmbd[464]: [2019/05/18 16:00:11, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:11 zerodigital nmbd[464]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 18 16:00:11 zerodigital nmbd[464]: [2019/05/18 16:00:11, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:11 zerodigital nmbd[464]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 18 16:00:11 zerodigital nmbd[464]: [2019/05/18 16:00:11, 0] ../source3/nmbd/nmbd.c:908(main) May 18 16:00:11 zerodigital nmbd[464]: nmbd version 4.2.14-Debian started. May 18 16:00:11 zerodigital nmbd[464]: Copyright Andrew Tridgell and the Samba Team 1992-2014 May 18 16:00:11 zerodigital nmbd[464]: [2019/05/18 16:00:11.429705, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:11 zerodigital nmbd[464]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 18 16:00:11 zerodigital sudo[695]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:00:11 zerodigital nmbd[672]: [2019/05/18 16:00:11.483805, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:11 zerodigital nmbd[672]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 18 16:00:11 zerodigital nmbd[701]: [2019/05/18 16:00:11.530969, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) May 18 16:00:11 zerodigital nmbd[701]: started asyncdns process 703 May 18 16:00:11 zerodigital sudo[695]: pam_unix(sudo:session): session closed for user root May 18 16:00:11 zerodigital nmbd[701]: [2019/05/18 16:00:11.590913, 0] ../lib/util/become_daemon.c:135(daemon_status) May 18 16:00:11 zerodigital nmbd[701]: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). May 18 16:00:11 zerodigital systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). May 18 16:00:11 zerodigital systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... May 18 16:00:11 zerodigital nmbd[464]: [2019/05/18 16:00:11.483805, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:11 zerodigital nmbd[464]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory May 18 16:00:11 zerodigital systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 18 16:00:11 zerodigital systemd[1]: Stopping Xbindrc... May 18 16:00:11 zerodigital systemd[1]: Starting Xbindrc... May 18 16:00:11 zerodigital nmbd[464]: . May 18 16:00:11 zerodigital systemd[1]: Started Xbindrc. May 18 16:00:11 zerodigital wireless.js[555]: DHCP IP May 18 16:00:11 zerodigital wireless.js[555]: Start ap May 18 16:00:12 zerodigital sudo[716]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 18 16:00:12 zerodigital sudo[716]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:00:12 zerodigital dhcpcd[720]: version 6.0.5 starting May 18 16:00:12 zerodigital kernel: brcmfmac: power management disabled May 18 16:00:12 zerodigital wpa_supplicant[710]: Successfully initialized wpa_supplicant May 18 16:00:12 zerodigital wireless.js[555]: trying... May 18 16:00:13 zerodigital systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 18 16:00:13 zerodigital systemd[1]: Stopping Xbindrc... May 18 16:00:13 zerodigital systemd[1]: Starting Xbindrc... May 18 16:00:13 zerodigital systemd[1]: Started Xbindrc. May 18 16:00:13 zerodigital dhcpcd[720]: wlan0: waiting for carrier May 18 16:00:13 zerodigital sudo[738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 16:00:13 zerodigital sudo[738]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:00:13 zerodigital sudo[738]: pam_unix(sudo:session): session closed for user root May 18 16:00:13 zerodigital wpa_supplicant[739]: wlan0: Trying to associate with d4:ab:82:62:dd:65 (SSID='5thLevelWiFi' freq=2462 MHz) May 18 16:00:14 zerodigital systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 18 16:00:14 zerodigital systemd[1]: Stopping Xbindrc... May 18 16:00:14 zerodigital systemd[1]: Starting Xbindrc... May 18 16:00:14 zerodigital systemd[1]: Started Xbindrc. May 18 16:00:14 zerodigital wpa_supplicant[739]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 May 18 16:00:14 zerodigital wireless.js[555]: trying... May 18 16:00:14 zerodigital sudo[756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 16:00:14 zerodigital sudo[756]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:00:14 zerodigital sudo[756]: pam_unix(sudo:session): session closed for user root May 18 16:00:14 zerodigital wpa_supplicant[739]: wlan0: Trying to associate with d4:ab:82:62:dd:65 (SSID='5thLevelWiFi' freq=2462 MHz) May 18 16:00:15 zerodigital systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 18 16:00:15 zerodigital systemd[1]: Stopping Xbindrc... May 18 16:00:15 zerodigital systemd[1]: Starting Xbindrc... May 18 16:00:15 zerodigital wpa_supplicant[739]: wlan0: Associated with d4:ab:82:62:dd:65 May 18 16:00:15 zerodigital wpa_supplicant[739]: wlan0: WPA: Key negotiation completed with d4:ab:82:62:dd:65 [PTK=CCMP GTK=CCMP] May 18 16:00:15 zerodigital wpa_supplicant[739]: wlan0: CTRL-EVENT-CONNECTED - Connection to d4:ab:82:62:dd:65 completed [id=0 id_str=] May 18 16:00:15 zerodigital dhcpcd[720]: wlan0: carrier acquired May 18 16:00:15 zerodigital systemd[1]: Started Xbindrc. May 18 16:00:15 zerodigital wpa_supplicant[739]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US May 18 16:00:15 zerodigital dhcpcd[720]: wlan0: rebinding lease of 10.0.0.115 May 18 16:00:15 zerodigital dhcpcd[720]: wlan0: leased 10.0.0.115 for 604800 seconds May 18 16:00:15 zerodigital avahi-daemon[488]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.0.115. May 18 16:00:15 zerodigital avahi-daemon[488]: New relevant interface wlan0.IPv4 for mDNS. May 18 16:00:15 zerodigital avahi-daemon[488]: Registering new address record for 10.0.0.115 on wlan0.IPv4. May 18 16:00:15 zerodigital dhcpcd[720]: wlan0: adding host route to 10.0.0.115 via 127.0.0.1 May 18 16:00:15 zerodigital dhcpcd[720]: wlan0: adding route to 10.0.0.0/24 May 18 16:00:15 zerodigital dhcpcd[720]: wlan0: adding default route via 10.0.0.1 May 18 16:00:15 zerodigital wireless.js[555]: trying... May 18 16:00:16 zerodigital volumio-remote-updater[447]: [2019-05-18 16:00:16] [info] asio async_connect error: system:111 (Connection refused) May 18 16:00:16 zerodigital volumio-remote-updater[447]: [2019-05-18 16:00:16] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 18 16:00:16 zerodigital volumio-remote-updater[447]: [2019-05-18 16:00:16] [error] handle_connect error: Underlying Transport Error May 18 16:00:16 zerodigital sudo[799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 16:00:16 zerodigital dhcpcd[720]: forked to background, child pid 803 May 18 16:00:16 zerodigital sudo[716]: pam_unix(sudo:session): session closed for user root May 18 16:00:16 zerodigital sudo[799]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:00:16 zerodigital sudo[799]: pam_unix(sudo:session): session closed for user root May 18 16:00:16 zerodigital systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. May 18 16:00:16 zerodigital systemd[1]: Stopping Xbindrc... May 18 16:00:16 zerodigital systemd[1]: Starting Xbindrc... May 18 16:00:16 zerodigital systemd[1]: xbindkeysrc.service start request repeated too quickly, refusing to start. May 18 16:00:16 zerodigital systemd[1]: Failed to start Xbindrc. May 18 16:00:16 zerodigital systemd[1]: Unit xbindkeysrc.service entered failed state. May 18 16:00:16 zerodigital wireless.js[555]: Connected to: ----5thLevelWiFi May 18 16:00:16 zerodigital wireless.js[555]: ---- May 18 16:00:16 zerodigital sudo[815]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:00:16 zerodigital sudo[815]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:00:16 zerodigital sudo[815]: pam_unix(sudo:session): session closed for user root May 18 16:00:16 zerodigital nmbd[701]: [2019/05/18 16:00:16.616929, 0] ../lib/util/become_daemon.c:124(daemon_ready) May 18 16:00:16 zerodigital nmbd[701]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections May 18 16:00:16 zerodigital wireless.js[555]: ... joined AP, wlan0 IPv4 is 10.0.0.115, ipV6 is undefined May 18 16:00:16 zerodigital wireless.js[555]: It's done! AP May 18 16:00:16 zerodigital systemd[1]: Started Wireless Services. May 18 16:00:16 zerodigital systemd[1]: Starting Volumio Backend Module... May 18 16:00:16 zerodigital systemd[1]: Started Volumio Backend Module. May 18 16:00:16 zerodigital systemd[1]: Starting Volumio Streaming Daemon... May 18 16:00:16 zerodigital systemd[1]: Started Volumio Streaming Daemon. May 18 16:00:17 zerodigital ntpd[530]: Listen normally on 3 wlan0 10.0.0.115 UDP 123 May 18 16:00:17 zerodigital ntpd[530]: peers refreshed May 18 16:00:17 zerodigital volumio-streaming-daemon[826]: ############################ May 18 16:00:17 zerodigital volumio-streaming-daemon[826]: # Volumio Streaming Daemon # May 18 16:00:17 zerodigital volumio-streaming-daemon[826]: # Running on port 7777 # May 18 16:00:17 zerodigital volumio-streaming-daemon[826]: ############################ May 18 16:00:19 zerodigital ntpd_intres[604]: DNS 0.debian.pool.ntp.org -> 104.236.167.15 May 18 16:00:19 zerodigital ntpd_intres[604]: DNS 1.debian.pool.ntp.org -> 44.190.6.254 May 18 16:00:19 zerodigital smbd[706]: Starting SMB/CIFS daemon: smbd[2019/05/18 16:00:19, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:19 zerodigital smbd[706]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 18 16:00:19 zerodigital smbd[706]: [2019/05/18 16:00:19, 0] ../lib/util/util.c:220(directory_create_or_exist) May 18 16:00:19 zerodigital smbd[706]: mkdir failed on directory /var/log/samba/cores: No such file or directory May 18 16:00:19 zerodigital smbd[706]: [2019/05/18 16:00:19, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) May 18 16:00:19 zerodigital smbd[706]: Failed to create /var/log/samba/cores for user 0 with mode 0700 May 18 16:00:19 zerodigital smbd[706]: [2019/05/18 16:00:19, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) May 18 16:00:19 zerodigital smbd[706]: Unable to setup corepath for smbd: No such file or directory May 18 16:00:19 zerodigital smbd[706]: [2019/05/18 16:00:19, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:19 zerodigital smbd[706]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 18 16:00:19 zerodigital smbd[706]: [2019/05/18 16:00:19, 0] ../source3/smbd/server.c:1241(main) May 18 16:00:19 zerodigital smbd[706]: smbd version 4.2.14-Debian started. May 18 16:00:19 zerodigital smbd[706]: Copyright Andrew Tridgell and the Samba Team 1992-2014 May 18 16:00:19 zerodigital ntpd_intres[604]: DNS 2.debian.pool.ntp.org -> 72.46.53.234 May 18 16:00:19 zerodigital ntpd_intres[604]: DNS 3.debian.pool.ntp.org -> 12.167.151.2 May 18 16:00:19 zerodigital smbd[836]: [2019/05/18 16:00:19.711476, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:19 zerodigital smbd[836]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 18 16:00:19 zerodigital smbd[706]: [2019/05/18 16:00:19.711476, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:19 zerodigital smbd[706]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 18 16:00:19 zerodigital smbd[836]: [2019/05/18 16:00:19.724297, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:19 zerodigital smbd[836]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 18 16:00:19 zerodigital smbd[836]: [2019/05/18 16:00:19.735648, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:19 zerodigital smbd[836]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 18 16:00:19 zerodigital smbd[706]: [2019/05/18 16:00:19.724297, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:19 zerodigital smbd[706]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 18 16:00:19 zerodigital smbd[706]: [2019/05/18 16:00:19.735648, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:19 zerodigital smbd[706]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 18 16:00:19 zerodigital smbd[706]: . May 18 16:00:19 zerodigital systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). May 18 16:00:19 zerodigital systemd[1]: Starting Multi-User System. May 18 16:00:19 zerodigital systemd[1]: Reached target Multi-User System. May 18 16:00:19 zerodigital systemd[1]: Starting Graphical Interface. May 18 16:00:19 zerodigital systemd[1]: Reached target Graphical Interface. May 18 16:00:19 zerodigital systemd[1]: Starting Update UTMP about System Runlevel Changes... May 18 16:00:19 zerodigital systemd[1]: Started Update UTMP about System Runlevel Changes. May 18 16:00:19 zerodigital systemd[1]: Startup finished in 9.798s (kernel) + 59.493s (userspace) = 1min 9.291s. May 18 16:00:20 zerodigital winbindd[843]: [2019/05/18 16:00:20.073614, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:20 zerodigital winbindd[843]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory May 18 16:00:20 zerodigital winbindd[844]: [2019/05/18 16:00:20.189394, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:20 zerodigital winbindd[844]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory May 18 16:00:20 zerodigital smbd[837]: [2019/05/18 16:00:20.379975, 0] ../lib/util/become_daemon.c:124(daemon_ready) May 18 16:00:20 zerodigital smbd[837]: STATUS=daemon 'smbd' finished starting up and ready to serve connections May 18 16:00:20 zerodigital smbd[845]: [2019/05/18 16:00:20.406349, 0] ../lib/util/debug.c:603(reopen_logs_internal) May 18 16:00:20 zerodigital smbd[845]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory May 18 16:00:26 zerodigital volumio-remote-updater[447]: [2019-05-18 16:00:26] [connect] Successful connection May 18 16:00:46 zerodigital systemd[1]: Time has been changed May 18 16:00:46 zerodigital volumio[825]: info: ------------------------------------------- May 18 16:00:46 zerodigital volumio[825]: info: ----- Volumio2 ---- May 18 16:00:46 zerodigital volumio[825]: info: ------------------------------------------- May 18 16:00:46 zerodigital volumio[825]: info: ----- System startup ---- May 18 16:00:46 zerodigital volumio[825]: info: ------------------------------------------- May 18 16:00:46 zerodigital volumio[825]: info: MYVOLUMIO Environment detected May 18 16:00:47 zerodigital volumio[825]: info: Plugin folders cleanup May 18 16:00:47 zerodigital volumio[825]: info: Scanning into folder /volumio/app/plugins/ May 18 16:00:47 zerodigital volumio[825]: info: Scanning category audio_interface May 18 16:00:47 zerodigital volumio[825]: info: Scanning category miscellanea May 18 16:00:47 zerodigital volumio[825]: info: Scanning category music_service May 18 16:00:47 zerodigital volumio[825]: info: Scanning category plugins.json May 18 16:00:47 zerodigital volumio[825]: info: Scanning category system_controller May 18 16:00:47 zerodigital volumio[825]: info: Scanning category user_interface May 18 16:00:47 zerodigital volumio[825]: info: Scanning into folder /data/plugins/ May 18 16:00:47 zerodigital volumio[825]: info: Scanning category accessory May 18 16:00:47 zerodigital volumio[825]: info: Scanning category miscellanea May 18 16:00:47 zerodigital volumio[825]: info: Scanning category music_service May 18 16:00:47 zerodigital volumio[825]: info: Scanning category system_controller May 18 16:00:47 zerodigital volumio[825]: info: Scanning category user_interface May 18 16:00:47 zerodigital volumio[825]: info: Plugin folders cleanup completed May 18 16:00:47 zerodigital volumio[825]: info: ------------------------------------------- May 18 16:00:47 zerodigital volumio[825]: info: ----- Core plugins startup ---- May 18 16:00:47 zerodigital volumio[825]: info: ------------------------------------------- May 18 16:00:47 zerodigital volumio[825]: info: Loading plugins from folder /volumio/app/plugins/ May 18 16:00:47 zerodigital volumio[825]: info: Adding plugin upnp to MyMusic Plugins May 18 16:00:47 zerodigital volumio[825]: info: Adding plugin airplay_emulation to MyMusic Plugins May 18 16:00:47 zerodigital volumio[825]: info: Adding plugin upnp_browser to MyMusic Plugins May 18 16:00:47 zerodigital volumio[825]: info: Loading plugins from folder /data/plugins/ May 18 16:00:47 zerodigital volumio[825]: info: Loading plugin "system"... May 18 16:00:47 zerodigital volumio[825]: { Error: ENOENT: no such file or directory, open '/sys/class/net/eth0/address' May 18 16:00:47 zerodigital volumio[825]: at Object.fs.openSync (fs.js:646:18) May 18 16:00:47 zerodigital volumio[825]: at Object.fs.readFileSync (fs.js:551:33) May 18 16:00:47 zerodigital volumio[825]: at ControllerSystem.callHome (/volumio/app/plugins/system_controller/system/index.js:577:20) May 18 16:00:47 zerodigital volumio[825]: at ControllerSystem.onVolumioStart (/volumio/app/plugins/system_controller/system/index.js:51:7) May 18 16:00:47 zerodigital volumio[825]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:169:35) May 18 16:00:47 zerodigital volumio[825]: at /volumio/app/pluginmanager.js:261:30 May 18 16:00:47 zerodigital volumio[825]: at Array.forEach () May 18 16:00:47 zerodigital volumio[825]: at HashMap. (/volumio/app/pluginmanager.js:260:17) May 18 16:00:47 zerodigital volumio[825]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:172:10) May 18 16:00:47 zerodigital volumio[825]: at HashMap.proto.(anonymous function) [as forEach] (/volumio/node_modules/hashmap/hashmap.js:184:7) May 18 16:00:47 zerodigital volumio[825]: errno: -2, May 18 16:00:47 zerodigital volumio[825]: code: 'ENOENT', May 18 16:00:47 zerodigital volumio[825]: syscall: 'open', May 18 16:00:47 zerodigital volumio[825]: path: '/sys/class/net/eth0/address' } May 18 16:00:47 zerodigital volumio[825]: info: Loading plugin "ampswitch"... May 18 16:00:48 zerodigital volumio[825]: info: Applying required configuration parameters for plugin ampswitch May 18 16:00:49 zerodigital volumio[825]: info: Loading plugin "appearance"... May 18 16:00:53 zerodigital volumio[825]: info: Loading plugin "network"... May 18 16:00:55 zerodigital volumio[825]: info: Loading plugin "services"... May 18 16:00:55 zerodigital volumio[825]: info: Loading plugin "alsa_controller"... May 18 16:00:55 zerodigital volumio[825]: info: Loading plugin "wizard"... May 18 16:00:55 zerodigital volumio[825]: info: Loading plugin "volumio_command_line_client"... May 18 16:00:55 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 16:00:55 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 16:00:55 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 16:00:55 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 16:00:55 zerodigital volumio[825]: info: Plugin upnp is not enabled May 18 16:00:55 zerodigital volumio[825]: info: Loading plugin "my_music"... May 18 16:00:56 zerodigital volumio[825]: info: Loading plugin "mpd"... May 18 16:00:57 zerodigital volumio[825]: info: Plugin upnp_browser is not enabled May 18 16:00:57 zerodigital volumio[825]: info: Loading plugin "networkfs"... May 18 16:00:58 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 18 16:00:58 zerodigital volumio[825]: info: Loading plugin "alarm-clock"... May 18 16:00:58 zerodigital sudo[861]: 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,rw //10.0.0.235/Public\Volumio /mnt/NAS/SharedMusic May 18 16:00:58 zerodigital sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:00:58 zerodigital kernel: FS-Cache: Netfs 'cifs' registered for caching May 18 16:00:58 zerodigital kernel: Key type cifs.spnego registered May 18 16:00:58 zerodigital kernel: Key type cifs.idmap registered May 18 16:00:58 zerodigital 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. May 18 16:00:59 zerodigital kernel: CIFS VFS: ioctl error in smb2_get_dfs_refer rc=-2 May 18 16:00:59 zerodigital sudo[861]: pam_unix(sudo:session): session closed for user root May 18 16:00:59 zerodigital volumio[825]: info: Plugin airplay_emulation is not enabled May 18 16:00:59 zerodigital volumio[825]: info: Loading plugin "last_100"... May 18 16:00:59 zerodigital volumio[825]: info: Loading plugin "webradio"... May 18 16:01:00 zerodigital volumio-remote-updater[447]: [2019-05-18 16:01:00] [connect] Successful connection May 18 16:01:02 zerodigital volumio[825]: info: Loading plugin "i2s_dacs"... May 18 16:01:02 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:02 zerodigital volumio[825]: info: Loading plugin "volumiodiscovery"... May 18 16:01:02 zerodigital volumio[825]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 18 16:01:02 zerodigital node[825]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 18 16:01:02 zerodigital node[825]: *** WARNING *** Please fix your application to use the native API of Avahi! May 18 16:01:02 zerodigital node[825]: *** WARNING *** For more information see May 18 16:01:02 zerodigital node[825]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 18 16:01:02 zerodigital node[825]: *** WARNING *** Please fix your application to use the native API of Avahi! May 18 16:01:02 zerodigital node[825]: *** WARNING *** For more information see May 18 16:01:02 zerodigital volumio[825]: *** WARNING *** Please fix your application to use the native API of Avahi! May 18 16:01:02 zerodigital volumio[825]: *** WARNING *** For more information see May 18 16:01:02 zerodigital volumio[825]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 18 16:01:02 zerodigital volumio[825]: *** WARNING *** Please fix your application to use the native API of Avahi! May 18 16:01:02 zerodigital volumio[825]: *** WARNING *** For more information see May 18 16:01:02 zerodigital volumio[825]: info: Applying required configuration parameters for plugin volumiodiscovery May 18 16:01:02 zerodigital volumio[825]: Discovery: StartAdv! undefined May 18 16:01:02 zerodigital volumio[825]: Discovery: Started advertising... zerodigital - undefined May 18 16:01:02 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 18 16:01:02 zerodigital volumio[825]: info: Loading plugin "autostart"... May 18 16:01:03 zerodigital volumio[825]: info: Applying required configuration parameters for plugin autostart May 18 16:01:03 zerodigital volumio[825]: info: Loading plugin "outputs"... May 18 16:01:03 zerodigital volumio[825]: info: Loading plugin "albumart"... May 18 16:01:03 zerodigital volumio[825]: info: Plugin example_plugin is not enabled May 18 16:01:03 zerodigital volumio[825]: info: Loading plugin "inputs"... May 18 16:01:03 zerodigital volumio[825]: info: Loading plugin "updater_comm"... May 18 16:01:04 zerodigital volumio[825]: info: Plugin mpdemulation is not enabled May 18 16:01:04 zerodigital volumio[825]: info: Loading plugin "rest_api"... May 18 16:01:05 zerodigital volumio[825]: info: Loading plugin "websocket"... May 18 16:01:05 zerodigital volumio[825]: info: Loading plugin "ir_controller"... May 18 16:01:06 zerodigital volumio[825]: Forking 1 albumart workers May 18 16:01:06 zerodigital volumio[825]: info: Applying required configuration parameters for plugin ir_controller May 18 16:01:07 zerodigital volumio[825]: info: Loading plugin "pandora"... May 18 16:01:11 zerodigital volumio[825]: Starting albumart workers May 18 16:01:14 zerodigital volumio[825]: info: Applying required configuration parameters for plugin pandora May 18 16:01:14 zerodigital volumio[825]: info: Loading plugin "backup_restore"... May 18 16:01:15 zerodigital volumio-remote-updater[447]: [2019-05-18 16:01:15] [connect] Successful connection May 18 16:01:15 zerodigital volumio[825]: info: Plugin rotaryencoder is not enabled May 18 16:01:16 zerodigital volumio[825]: info: ___________ START PLUGINS ___________ May 18 16:01:16 zerodigital volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 18 16:01:16 zerodigital volumio[825]: info: [1558195276167] CoreMusicLibrary::Adding element Last_100 May 18 16:01:16 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 16:01:16 zerodigital volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 18 16:01:16 zerodigital volumio[825]: info: [1558195276209] CoreMusicLibrary::Adding element Webradio May 18 16:01:16 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 16:01:16 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 18 16:01:16 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:16 zerodigital volumio[825]: info: HAT did not load /proc/device-tree/lirc_rpi! May 18 16:01:16 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:16 zerodigital sudo[920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay lirc-rpi gpio_in_pin=25 May 18 16:01:16 zerodigital sudo[920]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:16 zerodigital sudo[922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/lirc/hardware.conf May 18 16:01:16 zerodigital sudo[922]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:16 zerodigital sudo[922]: pam_unix(sudo:session): session closed for user root May 18 16:01:16 zerodigital sudo[927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /etc/lirc/hardware.conf /etc/lirc/lircd.conf /etc/lirc/lircmd.conf /etc/lirc/lircrc May 18 16:01:16 zerodigital sudo[927]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:16 zerodigital kernel: lirc_dev: IR Remote Control driver registered, major 243 May 18 16:01:16 zerodigital kernel: lirc_rpi: module is from the staging directory, the quality is unknown, you have been warned. May 18 16:01:16 zerodigital kernel: snd-rpi-justboom-digi soc:sound: ASoC: CODEC DAI wm8804-spdif not registered - will retry May 18 16:01:16 zerodigital sudo[920]: pam_unix(sudo:session): session closed for user root May 18 16:01:16 zerodigital sudo[927]: pam_unix(sudo:session): session closed for user root May 18 16:01:17 zerodigital volumio[825]: info: ------------------------------------------- May 18 16:01:17 zerodigital volumio[825]: info: ----- MyVolumio plugins startup ---- May 18 16:01:17 zerodigital volumio[825]: info: ------------------------------------------- May 18 16:01:17 zerodigital volumio[825]: info: Loading plugins from folder /myvolumio/plugins May 18 16:01:17 zerodigital volumio[825]: info: Loading plugins from folder /data/myvolumio/plugins May 18 16:01:17 zerodigital volumio[825]: info: Loading plugin "cd_controller"... May 18 16:01:17 zerodigital volumio[825]: info: Loading plugin "streaming_services"... May 18 16:01:17 zerodigital kernel: lirc_rpi: auto-detected active high receiver on GPIO pin 25 May 18 16:01:17 zerodigital kernel: lirc_rpi lirc_rpi: lirc_dev: driver lirc_rpi registered at minor = 0 May 18 16:01:17 zerodigital kernel: lirc_rpi: driver registered! May 18 16:01:18 zerodigital volumio[825]: info: Loading plugin "my_volumio"... May 18 16:01:18 zerodigital systemd[1]: Starting LSB: Starts LIRC daemon.... May 18 16:01:18 zerodigital lirc[960]: Loading LIRC modules:. May 18 16:01:18 zerodigital kernel: input: lircd as /devices/virtual/input/input0 May 18 16:01:18 zerodigital lircd-0.9.0-pre1[971]: lircd(default) ready, using /var/run/lirc/lircd May 18 16:01:18 zerodigital lirc[960]: Starting remote control daemon(s) : LIRC :. May 18 16:01:18 zerodigital lircd-0.9.0-pre1[971]: accepted new client on /var/run/lirc/lircd May 18 16:01:18 zerodigital lirc[960]: Starting execution daemon: irexec:. May 18 16:01:18 zerodigital systemd[1]: Started LSB: Starts LIRC daemon.. May 18 16:01:20 zerodigital volumio[825]: info: Adding CD REST API Endpoints May 18 16:01:20 zerodigital volumio[825]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller May 18 16:01:20 zerodigital volumio[825]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller May 18 16:01:20 zerodigital volumio[825]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller May 18 16:01:20 zerodigital volumio[825]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 18 16:01:20 zerodigital volumio[825]: info: Detecting CD presence May 18 16:01:20 zerodigital volumio[825]: info: Streaming services startup May 18 16:01:20 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:20 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:20 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:20 zerodigital volumio[825]: info: Starting MyVolumio Remote Streaming Endpoints May 18 16:01:20 zerodigital sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 18 16:01:20 zerodigital sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:21 zerodigital systemd[1]: Stopping Volumio Streaming Daemon... May 18 16:01:21 zerodigital systemd[1]: Starting Volumio Streaming Daemon... May 18 16:01:21 zerodigital systemd[1]: Started Volumio Streaming Daemon. May 18 16:01:21 zerodigital sudo[976]: pam_unix(sudo:session): session closed for user root May 18 16:01:21 zerodigital volumio[825]: info: Loading i18n strings for locale en May 18 16:01:21 zerodigital volumio-streaming-daemon[982]: ############################ May 18 16:01:21 zerodigital volumio-streaming-daemon[982]: # Volumio Streaming Daemon # May 18 16:01:21 zerodigital volumio-streaming-daemon[982]: # Running on port 7777 # May 18 16:01:21 zerodigital volumio-streaming-daemon[982]: ############################ May 18 16:01:21 zerodigital volumio[825]: Updating browse sources language May 18 16:01:21 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 16:01:21 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 16:01:21 zerodigital volumio[825]: info: CoreCommandRouter::initPlayerControls May 18 16:01:21 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 16:01:21 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards May 18 16:01:21 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 16:01:21 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 16:01:21 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 16:01:21 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 16:01:21 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 16:01:21 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 16:01:21 zerodigital volumio[825]: info: BOOT COMPLETED May 18 16:01:21 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:21 zerodigital volumio[825]: ALSA lib pcm_hw.c:1713:(_snd_pcm_hw_open) Invalid value for card May 18 16:01:21 zerodigital volumio[825]: aplay: main:722: audio open error: No such file or directory May 18 16:01:21 zerodigital volumio[825]: Cannot play startup sound May 18 16:01:21 zerodigital volumio[825]: info: CoreCommandRouter::Close All Modals sent May 18 16:01:21 zerodigital volumio[825]: info: CoreCommandRouter::Close All Modals sent May 18 16:01:21 zerodigital volumio[825]: Express server listening on port 3000 May 18 16:01:21 zerodigital volumio[825]: Volumio Calling Home May 18 16:01:22 zerodigital volumio[825]: info: CoreStateMachine::resetVolumioState May 18 16:01:22 zerodigital volumio[825]: info: CoreStateMachine::getcurrentVolume May 18 16:01:22 zerodigital volumio[825]: info: CoreCommandRouter::volumioRetrievevolume May 18 16:01:22 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 18 16:01:22 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 18 16:01:24 zerodigital volumio[825]: info: Volumio called home May 18 16:01:24 zerodigital volumio[825]: info: Setting Device type: Raspberry PI May 18 16:01:24 zerodigital volumio[825]: info: lirc permissions set May 18 16:01:24 zerodigital volumio[825]: info: Hardware permissions set May 18 16:01:24 zerodigital volumio[825]: info: lirc-rpi overlay enabled May 18 16:01:24 zerodigital volumio[825]: info: MPD running with PID493 ,establishing connection May 18 16:01:24 zerodigital volumio[825]: info: AutoStart - getting queue May 18 16:01:24 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetQueue May 18 16:01:24 zerodigital volumio[825]: info: CoreStateMachine::getQueue May 18 16:01:24 zerodigital volumio[825]: info: CorePlayQueue::getQueue May 18 16:01:25 zerodigital volumio[825]: info: lirc correctly updated May 18 16:01:25 zerodigital volumio[825]: info: VolumeController:: Volume=undefined Mute =false May 18 16:01:25 zerodigital volumio[825]: info: CoreStateMachine::pushState May 18 16:01:25 zerodigital volumio[825]: info: CoreStateMachine::getState May 18 16:01:25 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:01:25 zerodigital volumio[825]: info: CoreCommandRouter::volumioPushState May 18 16:01:25 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 16:01:25 zerodigital volumio[825]: info: May 18 16:01:25 zerodigital volumio[825]: ---------------------------- Client requests Volume 30 May 18 16:01:25 zerodigital volumio[825]: info: VolumeController::SetAlsaVolume30 May 18 16:01:25 zerodigital volumio[825]: info: CoreStateMachine::pushState May 18 16:01:25 zerodigital volumio[825]: info: CoreStateMachine::getState May 18 16:01:25 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:01:25 zerodigital volumio[825]: info: CoreCommandRouter::volumioPushState May 18 16:01:25 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 16:01:26 zerodigital volumio[825]: info: Reloading queue from file May 18 16:01:26 zerodigital volumio[825]: info: May 18 16:01:26 zerodigital volumio[825]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory May 18 16:01:26 zerodigital volumio[825]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) May 18 16:01:26 zerodigital volumio[825]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) May 18 16:01:26 zerodigital volumio[825]: at emitOne (events.js:116:13) May 18 16:01:26 zerodigital volumio[825]: at Socket.emit (events.js:211:7) May 18 16:01:26 zerodigital volumio[825]: at addChunk (_stream_readable.js:263:12) May 18 16:01:26 zerodigital volumio[825]: at readableAddChunk (_stream_readable.js:246:13) May 18 16:01:26 zerodigital volumio[825]: at Socket.Readable.push (_stream_readable.js:208:10) May 18 16:01:26 zerodigital volumio[825]: at Pipe.onread (net.js:607:20) May 18 16:01:27 zerodigital volumio[825]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. May 18 16:01:27 zerodigital volumio[825]: info: Setting volume on startup at 30 May 18 16:01:27 zerodigital volumio[825]: info: CoreStateMachine::setRepeat null single undefined May 18 16:01:27 zerodigital volumio[825]: info: CoreStateMachine::pushState May 18 16:01:27 zerodigital volumio[825]: info: CoreStateMachine::getState May 18 16:01:27 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:01:27 zerodigital volumio[825]: info: CoreCommandRouter::volumioPushState May 18 16:01:27 zerodigital sudo[1011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop lirc.service May 18 16:01:27 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 16:01:27 zerodigital volumio[825]: info: CoreStateMachine::setRandom null May 18 16:01:27 zerodigital volumio[825]: info: CoreStateMachine::pushState May 18 16:01:27 zerodigital volumio[825]: info: CoreStateMachine::getState May 18 16:01:27 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:01:27 zerodigital volumio[825]: info: CoreCommandRouter::volumioPushState May 18 16:01:27 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 16:01:27 zerodigital sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:27 zerodigital systemd[1]: Stopping LSB: Starts LIRC daemon.... May 18 16:01:27 zerodigital lircd-0.9.0-pre1[971]: removed client May 18 16:01:27 zerodigital lirc[1017]: Stopping execution daemon: irexec:. May 18 16:01:27 zerodigital lircd-0.9.0-pre1[971]: caught signal May 18 16:01:27 zerodigital lirc[1017]: Stopping remote control daemon(s): LIRC:. May 18 16:01:27 zerodigital systemd[1]: Stopped LSB: Starts LIRC daemon.. May 18 16:01:27 zerodigital sudo[1011]: pam_unix(sudo:session): session closed for user root May 18 16:01:29 zerodigital volumio[825]: error: MyVolumio Custom Token format not valid, refreshing it May 18 16:01:30 zerodigital volumio[825]: Discovery: adding 9dded842-6e4b-4d91-a218-1f830e9f375f May 18 16:01:30 zerodigital volumio[825]: info: mDNS: Found device zerodigital May 18 16:01:30 zerodigital volumio[825]: Discovery: adding 7180e17a-2431-4fc1-bf06-b68404c444f3 May 18 16:01:30 zerodigital volumio[825]: info: mDNS: Found device Volumio May 18 16:01:30 zerodigital sudo[1035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:01:30 zerodigital sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:30 zerodigital sudo[1035]: pam_unix(sudo:session): session closed for user root May 18 16:01:30 zerodigital sudo[1042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:01:30 zerodigital sudo[1042]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:30 zerodigital sudo[1042]: pam_unix(sudo:session): session closed for user root May 18 16:01:30 zerodigital volumio-remote-updater[447]: [2019-05-18 16:01:30] [connect] Successful connection May 18 16:01:30 zerodigital sudo[1053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start lirc.service May 18 16:01:31 zerodigital sudo[1053]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:31 zerodigital systemd[1]: Starting LSB: Starts LIRC daemon.... May 18 16:01:31 zerodigital volumio[825]: info: Fetching Streaming Services browse cache May 18 16:01:31 zerodigital lirc[1058]: Loading LIRC modules:. May 18 16:01:31 zerodigital kernel: input: lircd as /devices/virtual/input/input1 May 18 16:01:31 zerodigital lircd-0.9.0-pre1[1068]: lircd(default) ready, using /var/run/lirc/lircd May 18 16:01:31 zerodigital lirc[1058]: Starting remote control daemon(s) : LIRC :. May 18 16:01:31 zerodigital lircd-0.9.0-pre1[1068]: accepted new client on /var/run/lirc/lircd May 18 16:01:31 zerodigital lirc[1058]: Starting execution daemon: irexec:. May 18 16:01:31 zerodigital systemd[1]: Started LSB: Starts LIRC daemon.. May 18 16:01:31 zerodigital sudo[1053]: pam_unix(sudo:session): session closed for user root May 18 16:01:31 zerodigital volumio[825]: info: [ASDebug] CurState: stop PrevState: na May 18 16:01:31 zerodigital volumio[825]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 18 16:01:31 zerodigital volumio[825]: info: [ASDebug] CurState: stop PrevState: na May 18 16:01:31 zerodigital volumio[825]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 18 16:01:31 zerodigital volumio[825]: info: [ASDebug] CurState: stop PrevState: na May 18 16:01:31 zerodigital volumio[825]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 18 16:01:31 zerodigital volumio[825]: Discovery: adding 6fa8341a-050f-4d20-a210-f5beddeed716 May 18 16:01:31 zerodigital volumio[825]: info: mDNS: Found device Volumio2 May 18 16:01:31 zerodigital sudo[1076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:01:31 zerodigital sudo[1076]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:31 zerodigital sudo[1076]: pam_unix(sudo:session): session closed for user root May 18 16:01:32 zerodigital volumio[825]: info: lirc correctly started May 18 16:01:32 zerodigital volumio[825]: Discovery: adding 0d8143b8-1594-4788-8c84-ea25dd4c3bd1 May 18 16:01:32 zerodigital volumio[825]: info: mDNS: Found device copperwood May 18 16:01:32 zerodigital volumio[825]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL May 18 16:01:32 zerodigital sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:01:32 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 16:01:32 zerodigital sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:32 zerodigital sudo[1085]: pam_unix(sudo:session): session closed for user root May 18 16:01:32 zerodigital volumio[825]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ May 18 16:01:32 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 16:01:32 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetState May 18 16:01:32 zerodigital volumio[825]: info: CoreStateMachine::getState May 18 16:01:32 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:01:32 zerodigital volumio-remote-updater[447]: [2019-05-18 16:01:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1558195290 101 May 18 16:01:32 zerodigital sudo[1094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:01:32 zerodigital volumio[825]: info: [ASDebug] Togle GPIO: OFF May 18 16:01:32 zerodigital sudo[1094]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:33 zerodigital sudo[1094]: pam_unix(sudo:session): session closed for user root May 18 16:01:33 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetState May 18 16:01:33 zerodigital volumio[825]: info: CoreStateMachine::getState May 18 16:01:33 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:01:33 zerodigital sudo[1103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:01:33 zerodigital sudo[1103]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:33 zerodigital sudo[1103]: pam_unix(sudo:session): session closed for user root May 18 16:01:33 zerodigital volumio[825]: info: [Panodora] Logged in May 18 16:01:34 zerodigital volumio[825]: info: [ASDebug] CurState: stop PrevState: stop May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 18 16:01:35 zerodigital volumio[825]: info: [1558195295516] CoreMusicLibrary::Adding element Pandora Radio May 18 16:01:35 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:35 zerodigital volumio-streaming-daemon[982]: Service tidal is not allowed May 18 16:01:37 zerodigital volumio[825]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 18 16:01:37 zerodigital volumio[825]: info: MYVOLUMIO: Adding device May 18 16:01:37 zerodigital volumio[825]: info: MYVOLUMIO: Evaluating Server May 18 16:01:39 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 16:01:39 zerodigital volumio[825]: info: MyVolumio status changed May 18 16:01:39 zerodigital volumio[825]: info: Streaming services startup May 18 16:01:39 zerodigital sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 18 16:01:39 zerodigital sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:39 zerodigital systemd[1]: Stopping Volumio Streaming Daemon... May 18 16:01:39 zerodigital systemd[1]: Starting Volumio Streaming Daemon... May 18 16:01:39 zerodigital systemd[1]: Started Volumio Streaming Daemon. May 18 16:01:39 zerodigital volumio[825]: info: Adding audio output: browser May 18 16:01:39 zerodigital sudo[1120]: pam_unix(sudo:session): session closed for user root May 18 16:01:40 zerodigital volumio-streaming-daemon[1127]: ############################ May 18 16:01:40 zerodigital volumio-streaming-daemon[1127]: # Volumio Streaming Daemon # May 18 16:01:40 zerodigital volumio-streaming-daemon[1127]: # Running on port 7777 # May 18 16:01:40 zerodigital volumio-streaming-daemon[1127]: ############################ May 18 16:01:40 zerodigital volumio[825]: info: Setting Geolocation for MyVolumio to us1 May 18 16:01:40 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:40 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:40 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:40 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:40 zerodigital volumio[825]: info: Remote config written successfully May 18 16:01:40 zerodigital volumio[825]: info: Starting Tunnel 1 May 18 16:01:40 zerodigital volumio[825]: info: Starting Tunnel Connection Checker May 18 16:01:40 zerodigital volumio[825]: info: Detecting CD presence May 18 16:01:40 zerodigital volumio[825]: info: MyVolumio status changed May 18 16:01:40 zerodigital volumio[825]: info: Streaming services startup May 18 16:01:40 zerodigital sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 18 16:01:40 zerodigital sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:40 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 16:01:40 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 16:01:40 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetVisibleSources May 18 16:01:40 zerodigital systemd[1]: Stopping Volumio Streaming Daemon... May 18 16:01:40 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 16:01:40 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetState May 18 16:01:40 zerodigital volumio[825]: info: CoreStateMachine::getState May 18 16:01:40 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:01:40 zerodigital systemd[1]: Starting Volumio Streaming Daemon... May 18 16:01:40 zerodigital systemd[1]: Started Volumio Streaming Daemon. May 18 16:01:40 zerodigital sudo[1135]: pam_unix(sudo:session): session closed for user root May 18 16:01:40 zerodigital volumio-streaming-daemon[1142]: ############################ May 18 16:01:40 zerodigital volumio-streaming-daemon[1142]: # Volumio Streaming Daemon # May 18 16:01:40 zerodigital volumio-streaming-daemon[1142]: # Running on port 7777 # May 18 16:01:40 zerodigital volumio-streaming-daemon[1142]: ############################ May 18 16:01:40 zerodigital sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:01:40 zerodigital sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:40 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 18 16:01:40 zerodigital volumio[825]: info: Listing playlists May 18 16:01:40 zerodigital sudo[1149]: pam_unix(sudo:session): session closed for user root May 18 16:01:41 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetVisibleSources May 18 16:01:41 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 16:01:41 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetState May 18 16:01:41 zerodigital volumio[825]: info: CoreStateMachine::getState May 18 16:01:41 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:01:41 zerodigital sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:01:41 zerodigital sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:41 zerodigital sudo[1151]: pam_unix(sudo:session): session closed for user root May 18 16:01:41 zerodigital sudo[1165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:01:41 zerodigital sudo[1165]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:41 zerodigital sudo[1165]: pam_unix(sudo:session): session closed for user root May 18 16:01:41 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 18 16:01:41 zerodigital volumio[825]: info: Listing playlists May 18 16:01:41 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetVisibleSources May 18 16:01:41 zerodigital volumio-streaming-daemon[1142]: Enabled services: May 18 16:01:41 zerodigital volumio-streaming-daemon[1142]: 1) tidal May 18 16:01:41 zerodigital volumio-streaming-daemon[1142]: 2) qobuz May 18 16:01:41 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 16:01:41 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetState May 18 16:01:41 zerodigital volumio[825]: info: CoreStateMachine::getState May 18 16:01:41 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:01:41 zerodigital sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:01:41 zerodigital sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:41 zerodigital sudo[1175]: pam_unix(sudo:session): session closed for user root May 18 16:01:41 zerodigital sudo[1185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:01:41 zerodigital sudo[1185]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:41 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 18 16:01:41 zerodigital volumio[825]: info: Listing playlists May 18 16:01:41 zerodigital sudo[1185]: pam_unix(sudo:session): session closed for user root May 18 16:01:41 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetVisibleSources May 18 16:01:41 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 16:01:41 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetState May 18 16:01:41 zerodigital volumio[825]: info: CoreStateMachine::getState May 18 16:01:41 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:01:41 zerodigital sudo[1194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:01:41 zerodigital sudo[1194]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:42 zerodigital sudo[1194]: pam_unix(sudo:session): session closed for user root May 18 16:01:42 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 18 16:01:42 zerodigital sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:01:42 zerodigital volumio[825]: info: Listing playlists May 18 16:01:42 zerodigital sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:42 zerodigital sudo[1203]: pam_unix(sudo:session): session closed for user root May 18 16:01:42 zerodigital sudo[1208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:01:42 zerodigital volumio[825]: info: Updating MyVolumio device info May 18 16:01:42 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:42 zerodigital sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:42 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:42 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:42 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:42 zerodigital sudo[1208]: pam_unix(sudo:session): session closed for user root May 18 16:01:42 zerodigital sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 18 16:01:42 zerodigital sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:42 zerodigital systemd[1]: Starting MyVolumio SSH Tunnel... May 18 16:01:42 zerodigital systemd[1]: Started MyVolumio SSH Tunnel. May 18 16:01:42 zerodigital sudo[1226]: pam_unix(sudo:session): session closed for user root May 18 16:01:43 zerodigital autossh[1232]: port set to 0, monitoring disabled May 18 16:01:43 zerodigital autossh[1232]: starting ssh (count 1) May 18 16:01:43 zerodigital autossh[1232]: ssh child pid is 1238 May 18 16:01:43 zerodigital volumio[825]: info: Remote SSH Started May 18 16:01:43 zerodigital volumiossh-tunnel[1231]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. May 18 16:01:44 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetState May 18 16:01:44 zerodigital volumio[825]: info: CoreStateMachine::getState May 18 16:01:44 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:01:44 zerodigital sudo[1241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:01:44 zerodigital sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:01:44 zerodigital sudo[1241]: pam_unix(sudo:session): session closed for user root May 18 16:01:45 zerodigital volumio[825]: info: Fetching Streaming Services browse cache May 18 16:01:45 zerodigital volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 18 16:01:45 zerodigital volumio[825]: info: [1558195305062] CoreMusicLibrary::Adding element TIDAL May 18 16:01:45 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 16:01:45 zerodigital volumio[825]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ May 18 16:01:45 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 16:01:46 zerodigital volumio[825]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory May 18 16:01:46 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:46 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 18 16:01:46 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 16:01:47 zerodigital volumio[825]: info: Fetching Streaming Services browse cache May 18 16:01:47 zerodigital volumio[825]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 18 16:01:47 zerodigital volumio[825]: info: [1558195307656] CoreMusicLibrary::Adding element TIDAL May 18 16:01:47 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 16:01:48 zerodigital volumio[825]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ May 18 16:01:48 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 16:02:32 zerodigital sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 18 16:02:32 zerodigital sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:02:32 zerodigital sudo[1256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:02:32 zerodigital sudo[1254]: pam_unix(sudo:session): session closed for user root May 18 16:02:32 zerodigital sudo[1256]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:02:33 zerodigital sudo[1256]: pam_unix(sudo:session): session closed for user root May 18 16:02:33 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetVisibleSources May 18 16:02:33 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 16:02:33 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetState May 18 16:02:33 zerodigital volumio[825]: info: CoreStateMachine::getState May 18 16:02:33 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:02:33 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 18 16:02:33 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 18 16:02:33 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 18 16:02:33 zerodigital sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:02:33 zerodigital sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:02:33 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 18 16:02:33 zerodigital volumio[825]: info: Listing playlists May 18 16:02:33 zerodigital sudo[1274]: pam_unix(sudo:session): session closed for user root May 18 16:02:33 zerodigital sudo[1280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:02:34 zerodigital sudo[1280]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:02:34 zerodigital sudo[1280]: pam_unix(sudo:session): session closed for user root May 18 16:02:37 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetState May 18 16:02:37 zerodigital volumio[825]: info: CoreStateMachine::getState May 18 16:02:37 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:02:37 zerodigital volumio[825]: info: CoreCommandRouter::volumioGetQueue May 18 16:02:37 zerodigital volumio[825]: info: CoreStateMachine::getQueue May 18 16:02:37 zerodigital volumio[825]: info: CorePlayQueue::getQueue May 18 16:02:37 zerodigital sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:02:37 zerodigital sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:02:37 zerodigital sudo[1292]: pam_unix(sudo:session): session closed for user root May 18 16:05:06 zerodigital sshd[1309]: Accepted password for volumio from 10.0.0.176 port 63766 ssh2 May 18 16:05:06 zerodigital sshd[1309]: pam_unix(sshd:session): session opened for user volumio by (uid=0) May 18 16:05:06 zerodigital systemd-logind[457]: New session c1 of user volumio. May 18 16:05:06 zerodigital systemd[1]: Starting user-1000.slice. May 18 16:05:06 zerodigital systemd[1]: Created slice user-1000.slice. May 18 16:05:06 zerodigital systemd[1]: Starting Session c1 of user volumio. May 18 16:05:06 zerodigital systemd[1]: Started Session c1 of user volumio. May 18 16:05:06 zerodigital systemd[1]: Starting User Manager for UID 1000... May 18 16:05:06 zerodigital systemd[1315]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) May 18 16:05:06 zerodigital systemd[1315]: Starting Paths. May 18 16:05:06 zerodigital systemd[1315]: Reached target Paths. May 18 16:05:06 zerodigital systemd[1315]: Starting Timers. May 18 16:05:06 zerodigital systemd[1315]: Reached target Timers. May 18 16:05:06 zerodigital systemd[1315]: Starting Sockets. May 18 16:05:06 zerodigital systemd[1315]: Reached target Sockets. May 18 16:05:06 zerodigital systemd[1315]: Starting Basic System. May 18 16:05:06 zerodigital systemd[1315]: Reached target Basic System. May 18 16:05:06 zerodigital systemd[1315]: Starting Default. May 18 16:05:06 zerodigital systemd[1315]: Reached target Default. May 18 16:05:06 zerodigital systemd[1315]: Startup finished in 81ms. May 18 16:05:06 zerodigital systemd[1]: Started User Manager for UID 1000. May 18 16:05:06 zerodigital sshd[1309]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory May 18 16:05:06 zerodigital sshd[1309]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory May 18 16:06:03 zerodigital sudo[1331]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f May 18 16:06:03 zerodigital sudo[1331]: pam_unix(sudo:session): session opened for user root by volumio(uid=0) May 18 16:06:15 zerodigital volumio[825]: info: CoreCommandRouter::volumioPlay May 18 16:06:15 zerodigital volumio[825]: UNSET VOLATILE May 18 16:06:15 zerodigital volumio[825]: info: CoreStateMachine::play index undefined May 18 16:06:15 zerodigital volumio[825]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 16:06:15 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:06:15 zerodigital volumio[825]: info: CoreStateMachine::startPlaybackTimer May 18 16:06:15 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:06:15 zerodigital volumio[825]: info: [1558195575861] streamingServices::clearAddPlayTrack May 18 16:06:15 zerodigital volumio-streaming-daemon[1142]: Loading tidal configuration May 18 16:06:15 zerodigital volumio-streaming-daemon[1142]: http://api.tidal.com/v1/tracks/73091793/streamurl?countryCode=US&soundQuality=LOSSLESS May 18 16:06:16 zerodigital volumio-streaming-daemon[1142]: {"url":"http://ab-pr-cf.audio.tidal.com/4da05ed4bfcf78410d4c870cbb120f2f_39.flac?Expires=1558199176&Signature=I7C0QjerKE17HNbViTS0a0qw6n~y8ywS~8zmxPr5seDnbzQftPY9wie-E7bsDt56WVCB9JRqUsgR1PV47oMeH5C6oARrdwUf8f0uOBb~tQH5jkdvudt4RPgiu65i8~gixd2gVVYC03~zweZeuwC4HeTFmBKtxoWjzaPTvSrKjwCs5Pgbo823yRj64ckcl1rtphS-tv036zsiAuQSP7Aie-dOBFhAMgdqgHjbCP4ahpyDYBXeEF1n-kU7a~7gkTzbuGDNYxM~SbbqAHGVdbUxk1XR7bU5ArOAbE3~TYwgCqxPSjZeLGhkcIc2rXsNCyn9lTbv4hm~4NgiQHW~jIGUuA__&Key-Pair-Id=APKAIZ3WPBE4R6SP555A","trackId":73091793,"playTimeLeftInMinutes":-1,"soundQuality":"LOSSLESS","encryptionKey":"","codec":"MQA"} May 18 16:06:16 zerodigital volumio[825]: info: ControllerMpd::sendMpdCommand stop May 18 16:06:16 zerodigital volumio[825]: info: sending command... May 18 16:06:16 zerodigital volumio[825]: info: parsing response... May 18 16:06:16 zerodigital volumio[825]: info: ControllerMpd::sendMpdCommand clear May 18 16:06:16 zerodigital volumio[825]: info: sending command... May 18 16:06:16 zerodigital volumio[825]: info: May 18 16:06:16 zerodigital volumio[825]: ---------------------------- MPD announces system playlist update May 18 16:06:16 zerodigital volumio[825]: info: Ignoring MPD Status Update May 18 16:06:16 zerodigital volumio[825]: info: parsing response... May 18 16:06:16 zerodigital volumio[825]: info: ControllerMpd::sendMpdCommand load "http://ab-pr-cf.audio.tidal.com/4da05ed4bfcf78410d4c870cbb120f2f_39.flac?Expires=1558199176&Signature=I7C0QjerKE17HNbViTS0a0qw6n~y8ywS~8zmxPr5seDnbzQftPY9wie-E7bsDt56WVCB9JRqUsgR1PV47oMeH5C6oARrdwUf8f0uOBb~tQH5jkdvudt4RPgiu65i8~gixd2gVVYC03~zweZeuwC4HeTFmBKtxoWjzaPTvSrKjwCs5Pgbo823yRj64ckcl1rtphS-tv036zsiAuQSP7Aie-dOBFhAMgdqgHjbCP4ahpyDYBXeEF1n-kU7a~7gkTzbuGDNYxM~SbbqAHGVdbUxk1XR7bU5ArOAbE3~TYwgCqxPSjZeLGhkcIc2rXsNCyn9lTbv4hm~4NgiQHW~jIGUuA__&Key-Pair-Id=APKAIZ3WPBE4R6SP555A" May 18 16:06:16 zerodigital volumio[825]: info: sending command... May 18 16:06:16 zerodigital volumio[825]: info: May 18 16:06:16 zerodigital volumio[825]: info: ------------------------------ 71ms May 18 16:06:16 zerodigital volumio[825]: info: ControllerMpd::sendMpdCommand add "http://ab-pr-cf.audio.tidal.com/4da05ed4bfcf78410d4c870cbb120f2f_39.flac?Expires=1558199176&Signature=I7C0QjerKE17HNbViTS0a0qw6n~y8ywS~8zmxPr5seDnbzQftPY9wie-E7bsDt56WVCB9JRqUsgR1PV47oMeH5C6oARrdwUf8f0uOBb~tQH5jkdvudt4RPgiu65i8~gixd2gVVYC03~zweZeuwC4HeTFmBKtxoWjzaPTvSrKjwCs5Pgbo823yRj64ckcl1rtphS-tv036zsiAuQSP7Aie-dOBFhAMgdqgHjbCP4ahpyDYBXeEF1n-kU7a~7gkTzbuGDNYxM~SbbqAHGVdbUxk1XR7bU5ArOAbE3~TYwgCqxPSjZeLGhkcIc2rXsNCyn9lTbv4hm~4NgiQHW~jIGUuA__&Key-Pair-Id=APKAIZ3WPBE4R6SP555A" May 18 16:06:16 zerodigital volumio[825]: info: sending command... May 18 16:06:16 zerodigital volumio[825]: info: May 18 16:06:16 zerodigital volumio[825]: ---------------------------- MPD announces system playlist update May 18 16:06:16 zerodigital volumio[825]: info: Ignoring MPD Status Update May 18 16:06:16 zerodigital volumio[825]: info: parsing response... May 18 16:06:16 zerodigital volumio[825]: info: CoreStateMachine::setConsumeUpdateService mpd May 18 16:06:16 zerodigital volumio[825]: info: ControllerMpd::sendMpdCommand play May 18 16:06:16 zerodigital volumio[825]: info: sending command... May 18 16:06:16 zerodigital volumio[825]: info: ------------------------------ 60ms May 18 16:06:16 zerodigital volumio[825]: info: parsing response... May 18 16:06:17 zerodigital volumio[825]: info: May 18 16:06:17 zerodigital volumio[825]: ---------------------------- MPD announces state update: player May 18 16:06:17 zerodigital volumio[825]: info: ControllerMpd::getState May 18 16:06:17 zerodigital volumio[825]: info: ControllerMpd::sendMpdCommand status May 18 16:06:17 zerodigital volumio[825]: info: sending command... May 18 16:06:17 zerodigital volumio[825]: info: May 18 16:06:17 zerodigital volumio[825]: ---------------------------- MPD announces state update: player May 18 16:06:17 zerodigital volumio[825]: info: ControllerMpd::getState May 18 16:06:17 zerodigital volumio[825]: info: ControllerMpd::sendMpdCommand status May 18 16:06:17 zerodigital volumio[825]: info: sending command... May 18 16:06:17 zerodigital volumio[825]: info: parsing response... May 18 16:06:18 zerodigital volumio[825]: info: ControllerMpd::sendMpdCommand clearerror May 18 16:06:18 zerodigital volumio[825]: info: sending command... May 18 16:06:18 zerodigital volumio[825]: info: ControllerMpd::parseState May 18 16:06:18 zerodigital volumio[825]: info: ControllerMpd::sendMpdCommand playlistinfo May 18 16:06:18 zerodigital volumio[825]: info: sending command... May 18 16:06:18 zerodigital volumio[825]: info: parsing response... May 18 16:06:18 zerodigital volumio[825]: info: ControllerMpd::sendMpdCommand clearerror May 18 16:06:18 zerodigital volumio[825]: info: parsing response... May 18 16:06:18 zerodigital volumio[825]: info: parsing response... May 18 16:06:18 zerodigital volumio[825]: info: sending command... May 18 16:06:18 zerodigital volumio[825]: info: ControllerMpd::parseState May 18 16:06:18 zerodigital volumio[825]: info: ControllerMpd::sendMpdCommand playlistinfo May 18 16:06:18 zerodigital volumio[825]: info: ControllerMpd::parseTrackInfo May 18 16:06:18 zerodigital volumio[825]: info: sending command... May 18 16:06:18 zerodigital volumio[825]: info: ControllerMpd::pushState May 18 16:06:18 zerodigital volumio[825]: info: CoreCommandRouter::servicePushState May 18 16:06:18 zerodigital volumio[825]: info: CoreStateMachine::syncState May 18 16:06:18 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:06:18 zerodigital volumio[825]: info: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":334,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"4da05ed4bfcf78410d4c870cbb120f2f_39.flac?Expires=1558199176&Signature=I7C0QjerKE17HNbViTS0a0qw6n~y8ywS~8zmxPr5seDnbzQftPY9wie-E7bsDt56WVCB9JRqUsgR1PV47oMeH5C6oARrdwUf8f0uOBb~tQH5jkdvudt4RPgiu65i8~gixd2gVVYC03~zweZeuwC4HeTFmBKtxoWjzaPTvSrKjwCs5Pgbo823yRj64ckcl1rtphS-tv036zsiAuQSP7Aie-dOBFhAMgdqgHjbCP4ahpyDYBXeEF1n-kU7a~7gkTzbuGDNYxM~SbbqAHGVdbUxk1XR7bU5ArOAbE3~TYwgCqxPSjZeLGhkcIc2rXsNCyn9lTbv4hm~4NgiQHW~jIGUuA__&Key-Pair-Id=APKAIZ3WPBE4R6SP555A","artist":null,"album":null,"uri":"http://ab-pr-cf.audio.tidal.com/4da05ed4bfcf78410d4c870cbb120f2f_39.flac?Expires=1558199176&Signature=I7C0QjerKE17HNbViTS0a0qw6n~y8ywS~8zmxPr5seDnbzQftPY9wie-E7bsDt56WVCB9JRqUsgR1PV47oMeH5C6oARrdwUf8f0uOBb~tQH5jkdvudt4RPgiu65i8~gixd2gVVYC03~zweZeuwC4HeTFmBKtxoWjzaPTvSrKjwCs5Pgbo823yRj64ckcl1rtphS-tv036zsiAuQSP7Aie-dOBFhAMgdqgHjbCP4ahpyDYBXeEF1n-kU7a~7gkTzbuGDNYxM~SbbqAHGVdbUxk1XR7bU5ArOAbE3~TYwgCqxPSjZeLGhkcIc2rXsNCyn9lTbv4hm~4NgiQHW~jIGUuA__&Key-Pair-Id=APKAIZ3WPBE4R6SP555A","trackType":"tidal"} May 18 16:06:18 zerodigital volumio[825]: info: CURRENT POSITION 0 May 18 16:06:18 zerodigital volumio[825]: info: CoreStateMachine::syncState stateService pause May 18 16:06:18 zerodigital volumio[825]: info: CoreStateMachine::syncState currentStatus stop May 18 16:06:18 zerodigital volumio[825]: info: CoreStateMachine::pushState May 18 16:06:18 zerodigital volumio[825]: info: CoreStateMachine::getState May 18 16:06:18 zerodigital volumio[825]: info: CoreCommandRouter::volumioPushState May 18 16:06:18 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 16:06:19 zerodigital sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:06:19 zerodigital sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:06:19 zerodigital volumio[825]: info: ------------------------------ 1583ms May 18 16:06:19 zerodigital sudo[1339]: pam_unix(sudo:session): session closed for user root May 18 16:06:19 zerodigital volumio[825]: info: [ASDebug] CurState: PrevState: stop May 18 16:06:19 zerodigital volumio[825]: info: parsing response... May 18 16:06:19 zerodigital volumio[825]: info: parsing response... May 18 16:06:19 zerodigital volumio[825]: info: ControllerMpd::parseTrackInfo May 18 16:06:19 zerodigital volumio[825]: info: ControllerMpd::pushState May 18 16:06:19 zerodigital volumio[825]: info: CoreCommandRouter::servicePushState May 18 16:06:19 zerodigital volumio[825]: info: CoreStateMachine::syncState May 18 16:06:19 zerodigital volumio[825]: info: CorePlayQueue::getTrack 0 May 18 16:06:19 zerodigital volumio[825]: info: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":334,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"4da05ed4bfcf78410d4c870cbb120f2f_39.flac?Expires=1558199176&Signature=I7C0QjerKE17HNbViTS0a0qw6n~y8ywS~8zmxPr5seDnbzQftPY9wie-E7bsDt56WVCB9JRqUsgR1PV47oMeH5C6oARrdwUf8f0uOBb~tQH5jkdvudt4RPgiu65i8~gixd2gVVYC03~zweZeuwC4HeTFmBKtxoWjzaPTvSrKjwCs5Pgbo823yRj64ckcl1rtphS-tv036zsiAuQSP7Aie-dOBFhAMgdqgHjbCP4ahpyDYBXeEF1n-kU7a~7gkTzbuGDNYxM~SbbqAHGVdbUxk1XR7bU5ArOAbE3~TYwgCqxPSjZeLGhkcIc2rXsNCyn9lTbv4hm~4NgiQHW~jIGUuA__&Key-Pair-Id=APKAIZ3WPBE4R6SP555A","artist":null,"album":null,"uri":"http://ab-pr-cf.audio.tidal.com/4da05ed4bfcf78410d4c870cbb120f2f_39.flac?Expires=1558199176&Signature=I7C0QjerKE17HNbViTS0a0qw6n~y8ywS~8zmxPr5seDnbzQftPY9wie-E7bsDt56WVCB9JRqUsgR1PV47oMeH5C6oARrdwUf8f0uOBb~tQH5jkdvudt4RPgiu65i8~gixd2gVVYC03~zweZeuwC4HeTFmBKtxoWjzaPTvSrKjwCs5Pgbo823yRj64ckcl1rtphS-tv036zsiAuQSP7Aie-dOBFhAMgdqgHjbCP4ahpyDYBXeEF1n-kU7a~7gkTzbuGDNYxM~SbbqAHGVdbUxk1XR7bU5ArOAbE3~TYwgCqxPSjZeLGhkcIc2rXsNCyn9lTbv4hm~4NgiQHW~jIGUuA__&Key-Pair-Id=APKAIZ3WPBE4R6SP555A","trackType":"tidal"} May 18 16:06:19 zerodigital volumio[825]: info: CURRENT POSITION 0 May 18 16:06:19 zerodigital volumio[825]: info: CoreStateMachine::syncState stateService pause May 18 16:06:19 zerodigital volumio[825]: info: CoreStateMachine::syncState currentStatus stop May 18 16:06:19 zerodigital volumio[825]: info: CoreStateMachine::pushState May 18 16:06:19 zerodigital volumio[825]: info: CoreStateMachine::getState May 18 16:06:19 zerodigital volumio[825]: info: CoreCommandRouter::volumioPushState May 18 16:06:19 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 16:06:20 zerodigital sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 16:06:20 zerodigital sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:06:20 zerodigital volumio[825]: info: ------------------------------ 2658ms May 18 16:06:20 zerodigital sudo[1348]: pam_unix(sudo:session): session closed for user root May 18 16:06:20 zerodigital volumio[825]: info: [ASDebug] CurState: PrevState: stop May 18 16:06:44 zerodigital sudo[1331]: pam_unix(sudo:session): session closed for user root May 18 16:07:22 zerodigital volumio[825]: info: CALLMETHOD: system_controller system sendBugReport [object Object] May 18 16:07:22 zerodigital volumio[825]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport May 18 16:07:23 zerodigital sudo[1380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig May 18 16:07:23 zerodigital sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 16:07:24 zerodigital sudo[1380]: pam_unix(sudo:session): session closed for user root May 18 16:07:24 zerodigital sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 May 18 16:07:24 zerodigital sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) # cat /var/log/mpd.log --------------- May 18 16:00 : zeroconf: No global port, disabling zeroconf May 18 16:01 : client: [0] opened from local May 18 16:01 : exception: No such directory May 18 16:06 : exception: No such playlist ALSA lib pcm_hw.c:1713:(_snd_pcm_hw_open) Invalid value for card May 18 16:06 : exception: Failed to open "alsa" [alsa] May 18 16:06 : exception: nested: Failed to open ALSA device "hw:1,0": No such file or directory May 18 16:06 : exception: Failed to open "alsa" [alsa] May 18 16:06 : exception: nested: Failed to open ALSA device "hw:1,0": No such file or directory May 18 16:06 : player: problems opening audio device while playing "http://ab-pr-cf.audio.tidal.com/4da05ed4bfcf78410d4c870cbb120f2f_39.flac?Expires=1558199176&Signature=I7C0QjerKE17HNbViTS0a0qw6n~y8ywS~8zmxPr5seDnbzQftPY9wie-E7bsDt56WVCB9JRqUsgR1PV47oMeH5C6oARrdwUf8f0uOBb~tQH5jkdvudt4RPgiu65i8~gixd2gVVYC03~zweZeuwC4HeTFmBKtxoWjzaPTvSrKjwCs5Pgbo823yRj64ckcl1rtphS-tv036zsiAuQSP7Aie-dOBFhAMgdqgHjbCP4ahpyDYBXeEF1n-kU7a~7gkTzbuGDNYxM~SbbqAHGVdbUxk1XR7bU5ArOAbE3~TYwgCqxPSjZeLGhkcIc2rXsNCyn9lTbv4hm~4NgiQHW~jIGUuA__&Key-Pair-Id=APKAIZ3WPBE4R6SP555A"