Mon Mar 11 22:40:49 UTC 2019 Description="'Sending logs as requested by ALLOsupport for problem with No Sound. I'\''ll post aplay -l results in thread...'" # cat /proc/version --------------- Linux version 4.14.92-v7+ (dom@dom-XPS-13-9370) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1187 SMP Wed Jan 9 20:01:54 GMT 2019 # cat /etc/os-release --------------- PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="44b2fa85b4b3fa34b9dce1402ab445658a4a845a" VOLUMIO_FE_VERSION="a229f8b133df0eed141775431f02f040e5a51d20" VOLUMIO_BE_VERSION="adf0384995b68dee9fc3a8eedb9a69a90d236430" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Feb 18 19:31:59 CET 2019" VOLUMIO_VERSION="2.555" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="804eca9a7e1d9c2d73b735206b85b15d" # ifconfig --------------- eth0 Link encap:Ethernet HWaddr b8:27:eb:5a:33:26 inet addr:192.168.1.60 Bcast:192.168.1.255 Mask:255.255.255.0 UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:16631 errors:0 dropped:1 overruns:0 frame:0 TX packets:9190 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:12319337 (11.7 MiB) TX bytes:6335204 (6.0 MiB) lo Link encap:Local Loopback inet addr:127.0.0.1 Mask:255.0.0.0 UP LOOPBACK RUNNING MTU:65536 Metric:1 RX packets:24591 errors:0 dropped:0 overruns:0 frame:0 TX packets:24591 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:2487381 (2.3 MiB) TX bytes:2487381 (2.3 MiB) wlan0 Link encap:Ethernet HWaddr b8:27:eb:0f:66:73 inet addr:192.168.1.61 Bcast:192.168.1.255 Mask:255.255.255.0 UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1 RX packets:6731 errors:0 dropped:0 overruns:0 frame:0 TX packets:109 errors:0 dropped:0 overruns:0 carrier:0 collisions:0 txqueuelen:1000 RX bytes:1583591 (1.5 MiB) TX bytes:13384 (13.0 KiB) # sudo iwconfig --------------- eth0 no wireless extensions. lo no wireless extensions. wlan0 IEEE 802.11 ESSID:"terabeans" Mode:Managed Frequency:2.417 GHz Access Point: 20:AA:4B:60:90:97 Bit Rate=65 Mb/s Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off Link Quality=70/70 Signal level=-39 dBm Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0 Tx excessive retries:0 Invalid misc:0 Missed beacon:0 # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 0: ALSA [bcm2835 ALSA], device 0: bcm2835 ALSA [bcm2835 ALSA] Subdevices: 7/7 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 Subdevice #4: subdevice #4 Subdevice #5: subdevice #5 Subdevice #6: subdevice #6 card 0: ALSA [bcm2835 ALSA], device 1: bcm2835 ALSA [bcm2835 IEC958/HDMI] Subdevices: 1/1 Subdevice #0: subdevice #0 card 1: BossDAC [BossDAC], device 0: Boss DAC HiFi [Master] pcm512x-hifi-0 [] Subdevices: 1/1 Subdevice #0: subdevice #0 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 0 21:40 ? 00:00:05 /sbin/init root 2 0 0 21:40 ? 00:00:00 [kthreadd] root 4 2 0 21:40 ? 00:00:00 [kworker/0:0H] root 6 2 0 21:40 ? 00:00:00 [mm_percpu_wq] root 7 2 0 21:40 ? 00:00:00 [ksoftirqd/0] root 8 2 0 21:40 ? 00:00:00 [rcu_sched] root 9 2 0 21:40 ? 00:00:00 [rcu_bh] root 10 2 0 21:40 ? 00:00:00 [migration/0] root 11 2 0 21:40 ? 00:00:00 [cpuhp/0] root 12 2 0 21:40 ? 00:00:00 [cpuhp/1] root 13 2 0 21:40 ? 00:00:00 [migration/1] root 14 2 0 21:40 ? 00:00:00 [ksoftirqd/1] root 16 2 0 21:40 ? 00:00:00 [kworker/1:0H] root 17 2 0 21:40 ? 00:00:00 [cpuhp/2] root 18 2 0 21:40 ? 00:00:00 [migration/2] root 19 2 0 21:40 ? 00:00:00 [ksoftirqd/2] root 21 2 0 21:40 ? 00:00:00 [kworker/2:0H] root 22 2 0 21:40 ? 00:00:00 [cpuhp/3] root 23 2 0 21:40 ? 00:00:00 [migration/3] root 24 2 0 21:40 ? 00:00:00 [ksoftirqd/3] root 25 2 0 21:40 ? 00:00:00 [kworker/3:0] root 26 2 0 21:40 ? 00:00:00 [kworker/3:0H] root 27 2 0 21:40 ? 00:00:00 [kdevtmpfs] root 28 2 0 21:40 ? 00:00:00 [netns] root 30 2 0 21:40 ? 00:00:00 [kworker/1:1] root 33 2 0 21:40 ? 00:00:00 [khungtaskd] root 34 2 0 21:40 ? 00:00:00 [oom_reaper] root 35 2 0 21:40 ? 00:00:00 [writeback] root 36 2 0 21:40 ? 00:00:00 [kcompactd0] root 37 2 0 21:40 ? 00:00:00 [crypto] root 38 2 0 21:40 ? 00:00:00 [kblockd] root 39 2 0 21:40 ? 00:00:00 [watchdogd] root 40 2 0 21:40 ? 00:00:00 [rpciod] root 41 2 0 21:40 ? 00:00:00 [xprtiod] root 44 2 0 21:40 ? 00:00:00 [kswapd0] root 45 2 0 21:40 ? 00:00:00 [nfsiod] root 55 2 0 21:40 ? 00:00:00 [kthrotld] root 56 2 0 21:40 ? 00:00:00 [iscsi_eh] root 57 2 0 21:40 ? 00:00:00 [dwc_otg] root 58 2 0 21:40 ? 00:00:00 [DWC Notificatio] root 59 2 0 21:40 ? 00:00:00 [vchiq-slot/0] root 60 2 0 21:40 ? 00:00:00 [vchiq-recy/0] root 61 2 0 21:40 ? 00:00:00 [vchiq-sync/0] root 62 2 0 21:40 ? 00:00:00 [vchiq-keep/0] root 63 2 0 21:40 ? 00:00:00 [SMIO] root 65 2 0 21:40 ? 00:00:00 [irq/92-mmc1] root 89 2 0 21:40 ? 00:00:00 [mmcqd/0] root 99 2 0 21:40 ? 00:00:00 [jbd2/mmcblk0p2-] root 100 2 0 21:40 ? 00:00:00 [ext4-rsv-conver] root 104 2 0 21:40 ? 00:00:00 [irq/169-usb-001] root 110 2 0 21:40 ? 00:00:00 [loop0] root 113 2 0 21:40 ? 00:00:00 [kworker/0:1H] root 117 2 0 21:40 ? 00:00:00 [kworker/2:1H] root 131 2 0 21:40 ? 00:00:00 [jbd2/mmcblk0p3-] root 132 2 0 21:40 ? 00:00:00 [ext4-rsv-conver] root 139 2 0 21:40 ? 00:00:00 [kworker/3:1H] root 140 2 0 21:40 ? 00:00:00 [ipv6_addrconf] root 154 2 0 21:40 ? 00:00:00 [kworker/1:1H] root 168 1 0 21:40 ? 00:00:01 /lib/systemd/systemd-journald root 178 1 0 21:40 ? 00:00:00 /lib/systemd/systemd-udevd root 283 2 0 21:40 ? 00:00:00 [cfg80211] root 299 2 0 21:40 ? 00:00:00 [brcmf_wq/mmc1:0] root 300 2 0 21:40 ? 00:00:00 [brcmf_wdog/mmc1] root 508 1 0 21:40 ? 00:00:00 /sbin/rpcbind -w root 539 1 0 21:40 ? 00:00:00 dhcpcd eth0 statd 562 1 0 21:40 ? 00:00:00 /sbin/rpc.statd root 577 1 0 21:40 ? 00:00:00 /usr/sbin/rpc.idmapd root 579 1 0 21:40 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 --write=1024 volumio 581 1 0 21:40 ? 00:00:00 /usr/bin/udisks-glue --foreground volumio 583 1 0 21:40 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 590 1 0 21:40 ? 00:00:00 /lib/systemd/systemd-logind avahi 605 1 0 21:40 ? 00:00:00 avahi-daemon: running [volumio.local] mpd 606 1 0 21:40 ? 00:00:03 /usr/bin/mpd --no-daemon message+ 612 1 0 21:40 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation root 665 1 0 21:40 ? 00:00:01 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I ntp 700 1 0 21:40 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 100:104 avahi 705 605 0 21:40 ? 00:00:00 avahi-daemon: chroot helper root 733 1 0 21:40 ? 00:00:00 /usr/lib/udisks/udisks-daemon --no-debug root 745 1 0 21:40 tty1 00:00:00 /sbin/agetty --noclear tty1 linux root 753 733 0 21:40 ? 00:00:00 udisks-daemon: not polling any devices root 755 1 0 21:40 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 824 1 0 21:40 ? 00:00:00 /usr/sbin/nmbd -D root 825 824 0 21:40 ? 00:00:00 /usr/sbin/nmbd -D root 836 1 0 21:40 ? 00:00:00 /usr/sbin/winbindd root 840 836 0 21:40 ? 00:00:00 /usr/sbin/winbindd root 910 1 0 21:40 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 921 1 0 21:40 ? 00:00:00 /sbin/dhcpcd root 927 1 0 21:40 ? 00:00:00 /usr/sbin/smbd -D root 930 836 0 21:40 ? 00:00:00 /usr/sbin/winbindd root 931 836 0 21:40 ? 00:00:00 /usr/sbin/winbindd root 932 927 0 21:40 ? 00:00:00 /usr/sbin/smbd -D volumio 1020 1 0 21:40 ? 00:00:18 /usr/local/bin/node /volumio/index.js root 1059 2 0 21:40 ? 00:00:00 [cifsiod] root 1060 2 0 21:40 ? 00:00:00 [cifsoplockd] root 1063 2 0 21:40 ? 00:00:00 [cifsd] volumio 1102 1020 0 21:40 ? 00:00:00 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1108 1102 0 21:40 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1109 1102 0 21:40 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1114 1102 0 21:40 ? 00:00:01 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1133 1 0 21:40 ? 00:00:00 /bin/streaming-daemon volumio 1173 1 0 21:40 ? 00:00:00 /usr/local/bin/shairport-sync volumio 1242 1 0 21:40 ? 00:00:06 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf root 2411 1 0 21:43 ? 00:00:00 /usr/sbin/sshd -D root 5425 2 0 21:50 ? 00:00:00 [kworker/u8:2] root 8441 2 0 21:57 ? 00:00:00 [kworker/3:1] root 9382 2 0 21:59 ? 00:00:00 [kworker/0:0] root 11090 2 0 22:03 ? 00:00:00 [kworker/u8:0] root 13358 2 0 22:08 ? 00:00:00 [kworker/2:0] root 16052 2 0 22:15 ? 00:00:00 [kworker/2:2] root 17263 2 0 22:17 ? 00:00:00 [kworker/1:2] root 21483 2 0 22:28 ? 00:00:00 [kworker/0:2] root 24794 2 0 22:36 ? 00:00:00 [kworker/1:0] root 25233 2 0 22:37 ? 00:00:00 [kworker/0:1] root 25461 2 0 22:37 ? 00:00:00 [kworker/2:1] root 26721 2 0 22:40 ? 00:00:00 [kworker/u8:1] volumio 26814 1020 0 22:40 ? 00:00:00 /bin/sh -c /usr/local/bin/node /volumio/logsubmit.js 'Sending logs as requested by ALLOsupport for problem with No Sound. I'\''ll post aplay -l results in thread...' volumio 26815 26814 0 22:40 ? 00:00:00 /usr/local/bin/node /volumio/logsubmit.js Sending logs as requested by ALLOsupport for problem with No Sound. I'll post aplay -l results in thread... volumio 26840 26815 0 22:40 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 26841 26840 0 22:40 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.2G 644M 1.5G 31% /imgpart /dev/loop0 284M 284M 0 100% /static overlay 27G 13M 26G 1% / devtmpfs 474M 0 474M 0% /dev tmpfs 486M 0 486M 0% /dev/shm tmpfs 486M 4.7M 481M 1% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 486M 0 486M 0% /sys/fs/cgroup tmpfs 486M 36K 486M 1% /tmp tmpfs 486M 0 486M 0% /var/spool/cups tmpfs 20M 60K 20M 1% /var/log tmpfs 486M 0 486M 0% /var/spool/cups/tmp /dev/mmcblk0p1 61M 37M 24M 61% /boot //NAS/Music 1.9T 1.3T 594G 69% /mnt/NAS/Music # 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=485032k,nr_inodes=121258,mode=755) tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev,noexec) devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000) tmpfs on /run type tmpfs (rw,nosuid,nodev,mode=755) tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k) tmpfs on /sys/fs/cgroup type tmpfs (ro,nosuid,nodev,noexec,mode=755) cgroup on /sys/fs/cgroup/systemd type cgroup (rw,nosuid,nodev,noexec,relatime,xattr,release_agent=/lib/systemd/systemd-cgroups-agent,name=systemd) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/net_cls type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=22,pgrp=1,timeout=300,minproto=5,maxproto=5,direct) debugfs on /sys/kernel/debug type debugfs (rw,relatime) mqueue on /dev/mqueue type mqueue (rw,relatime) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) fusectl on /sys/fs/fuse/connections type fusectl (rw,relatime) tmpfs on /var/spool/cups/tmp type tmpfs (rw,noatime,mode=755) configfs on /sys/kernel/config type configfs (rw,relatime) /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) //NAS/Music on /mnt/NAS/Music type cifs (ro,relatime,vers=default,cache=strict,username=admin,domain=,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.1.56,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=1048576,wsize=1048576,echo_interval=60,actimeo=1) # sudo journalctl -p 7 --------------- -- Logs begin at Mon 2019-03-11 21:39:45 UTC, end at Mon 2019-03-11 22:40:49 UTC. -- Mar 11 21:39:45 volumio systemd-journal[168]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 72.8M free of 481.2M available → current limit 30.0M). Mar 11 21:39:45 volumio kernel: Booting Linux on physical CPU 0x0 Mar 11 21:39:45 volumio kernel: Linux version 4.14.92-v7+ (dom@dom-XPS-13-9370) (gcc version 4.9.3 (crosstool-NG crosstool-ng-1.22.0-88-g8460611)) #1187 SMP Wed Jan 9 20:01:54 GMT 2019 Mar 11 21:39:45 volumio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Mar 11 21:39:45 volumio kernel: CPU: div instructions available: patching division code Mar 11 21:39:45 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Mar 11 21:39:45 volumio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Plus Rev 1.3 Mar 11 21:39:45 volumio kernel: Memory policy: Data cache writealloc Mar 11 21:39:45 volumio kernel: cma: Reserved 8 MiB at 0x3d800000 Mar 11 21:39:45 volumio kernel: On node 0 totalpages: 253952 Mar 11 21:39:45 volumio kernel: free_area_init_node: node 0, pgdat 80c85400, node_mem_map bcf3c000 Mar 11 21:39:45 volumio kernel: Normal zone: 2232 pages used for memmap Mar 11 21:39:45 volumio kernel: Normal zone: 0 pages reserved Mar 11 21:39:45 volumio kernel: Normal zone: 253952 pages, LIFO batch:31 Mar 11 21:39:45 volumio kernel: percpu: Embedded 17 pages/cpu @bcee4000 s38720 r8192 d22720 u69632 Mar 11 21:39:45 volumio kernel: pcpu-alloc: s38720 r8192 d22720 u69632 alloc=17*4096 Mar 11 21:39:45 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Mar 11 21:39:45 volumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 251720 Mar 11 21:39:45 volumio kernel: Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash quiet plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 console=ttyS0,115200 kgdboc=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh elevator=noop rootwait bootdelay=5 logo.nologo vt.global_cursor_default=0 loglevel=0 Mar 11 21:39:45 volumio kernel: PID hash table entries: 4096 (order: 2, 16384 bytes) Mar 11 21:39:45 volumio kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes) Mar 11 21:39:45 volumio kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes) Mar 11 21:39:45 volumio kernel: Memory: 970064K/1015808K available (7168K kernel code, 576K rwdata, 2080K rodata, 1024K init, 698K bss, 37552K reserved, 8192K cma-reserved) Mar 11 21:39:45 volumio kernel: Virtual kernel memory layout: vector : 0xffff0000 - 0xffff1000 ( 4 kB) fixmap : 0xffc00000 - 0xfff00000 (3072 kB) vmalloc : 0xbe800000 - 0xff800000 (1040 MB) lowmem : 0x80000000 - 0xbe000000 ( 992 MB) modules : 0x7f000000 - 0x80000000 ( 16 MB) .text : 0x80008000 - 0x80800000 (8160 kB) .init : 0x80b00000 - 0x80c00000 (1024 kB) .data : 0x80c00000 - 0x80c90334 ( 577 kB) .bss : 0x80c97ef8 - 0x80d468f0 ( 699 kB) Mar 11 21:39:45 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Mar 11 21:39:45 volumio kernel: ftrace: allocating 25296 entries in 75 pages Mar 11 21:39:45 volumio kernel: Hierarchical RCU implementation. Mar 11 21:39:45 volumio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Mar 11 21:39:45 volumio kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Mar 11 21:39:45 volumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Mar 11 21:39:45 volumio kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Mar 11 21:39:45 volumio kernel: Switching to timer-based delay loop, resolution 52ns Mar 11 21:39:45 volumio kernel: Console: colour dummy device 80x30 Mar 11 21:39:45 volumio kernel: console [tty1] enabled Mar 11 21:39:45 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Mar 11 21:39:45 volumio kernel: pid_max: default: 32768 minimum: 301 Mar 11 21:39:45 volumio kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes) Mar 11 21:39:45 volumio kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes) Mar 11 21:39:45 volumio kernel: Disabling memory control group subsystem Mar 11 21:39:45 volumio kernel: CPU: Testing write buffer coherency: ok Mar 11 21:39:45 volumio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Mar 11 21:39:45 volumio kernel: Setting up static identity map for 0x100000 - 0x10003c Mar 11 21:39:45 volumio kernel: Hierarchical SRCU implementation. Mar 11 21:39:45 volumio kernel: smp: Bringing up secondary CPUs ... Mar 11 21:39:45 volumio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Mar 11 21:39:45 volumio kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Mar 11 21:39:45 volumio kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Mar 11 21:39:45 volumio kernel: smp: Brought up 1 node, 4 CPUs Mar 11 21:39:45 volumio kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Mar 11 21:39:45 volumio kernel: CPU: All CPU(s) started in HYP mode. Mar 11 21:39:45 volumio kernel: CPU: Virtualization extensions available. Mar 11 21:39:45 volumio kernel: devtmpfs: initialized Mar 11 21:39:45 volumio kernel: random: get_random_u32 called from bucket_table_alloc+0xfc/0x24c with crng_init=0 Mar 11 21:39:45 volumio kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Mar 11 21:39:45 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Mar 11 21:39:45 volumio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) Mar 11 21:39:45 volumio kernel: pinctrl core: initialized pinctrl subsystem Mar 11 21:39:45 volumio kernel: NET: Registered protocol family 16 Mar 11 21:39:45 volumio kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Mar 11 21:39:45 volumio kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Mar 11 21:39:45 volumio kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Mar 11 21:39:45 volumio kernel: Serial: AMBA PL011 UART driver Mar 11 21:39:45 volumio kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Mar 11 21:39:45 volumio kernel: uart-pl011 3f201000.serial: could not find pctldev for node /soc/gpio@7e200000/uart0_pins, deferring probe Mar 11 21:39:45 volumio kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager at be813000, dmachans=0x1 Mar 11 21:39:45 volumio kernel: SCSI subsystem initialized Mar 11 21:39:45 volumio kernel: usbcore: registered new interface driver usbfs Mar 11 21:39:45 volumio kernel: usbcore: registered new interface driver hub Mar 11 21:39:45 volumio kernel: usbcore: registered new device driver usb Mar 11 21:39:45 volumio kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2019-01-09 20:04 Mar 11 21:39:45 volumio kernel: clocksource: Switched to clocksource arch_sys_counter Mar 11 21:39:45 volumio kernel: VFS: Disk quotas dquot_6.6.0 Mar 11 21:39:45 volumio kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Mar 11 21:39:45 volumio kernel: FS-Cache: Loaded Mar 11 21:39:45 volumio kernel: CacheFiles: Loaded Mar 11 21:39:45 volumio kernel: NET: Registered protocol family 2 Mar 11 21:39:45 volumio kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes) Mar 11 21:39:45 volumio kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes) Mar 11 21:39:45 volumio kernel: TCP: Hash tables configured (established 8192 bind 8192) Mar 11 21:39:45 volumio kernel: UDP hash table entries: 512 (order: 2, 16384 bytes) Mar 11 21:39:45 volumio kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes) Mar 11 21:39:45 volumio kernel: NET: Registered protocol family 1 Mar 11 21:39:45 volumio kernel: RPC: Registered named UNIX socket transport module. Mar 11 21:39:45 volumio kernel: RPC: Registered udp transport module. Mar 11 21:39:45 volumio kernel: RPC: Registered tcp transport module. Mar 11 21:39:45 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Mar 11 21:39:45 volumio kernel: Trying to unpack rootfs image as initramfs... Mar 11 21:39:45 volumio kernel: Freeing initrd memory: 14824K Mar 11 21:39:45 volumio kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Mar 11 21:39:45 volumio kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Mar 11 21:39:45 volumio kernel: FS-Cache: Netfs 'nfs' registered for caching Mar 11 21:39:45 volumio kernel: NFS: Registering the id_resolver key type Mar 11 21:39:45 volumio kernel: Key type id_resolver registered Mar 11 21:39:45 volumio kernel: Key type id_legacy registered Mar 11 21:39:45 volumio kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Mar 11 21:39:45 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Mar 11 21:39:45 volumio kernel: io scheduler noop registered (default) Mar 11 21:39:45 volumio kernel: io scheduler deadline registered Mar 11 21:39:45 volumio kernel: io scheduler cfq registered Mar 11 21:39:45 volumio kernel: io scheduler mq-deadline registered Mar 11 21:39:45 volumio kernel: io scheduler kyber registered Mar 11 21:39:45 volumio kernel: BCM2708FB: allocated DMA memory fd900000 Mar 11 21:39:45 volumio kernel: BCM2708FB: allocated DMA channel 0 @ be813000 Mar 11 21:39:45 volumio kernel: Console: switching to colour frame buffer device 80x30 Mar 11 21:39:45 volumio kernel: bcm2835-rng 3f104000.rng: hwrng registered Mar 11 21:39:45 volumio kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Mar 11 21:39:45 volumio kernel: vc-sm: Videocore shared memory driver Mar 11 21:39:45 volumio kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000 Mar 11 21:39:45 volumio kernel: brd: module loaded Mar 11 21:39:45 volumio kernel: loop: module loaded Mar 11 21:39:45 volumio kernel: Loading iSCSI transport class v2.0-870. Mar 11 21:39:45 volumio kernel: libphy: Fixed MDIO Bus: probed Mar 11 21:39:45 volumio kernel: usbcore: registered new interface driver lan78xx Mar 11 21:39:45 volumio kernel: usbcore: registered new interface driver smsc95xx Mar 11 21:39:45 volumio kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Mar 11 21:39:45 volumio kernel: dwc_otg 3f980000.usb: base=0xf0980000 Mar 11 21:39:45 volumio kernel: Core Release: 2.80a Mar 11 21:39:45 volumio kernel: Setting default values for core params Mar 11 21:39:45 volumio kernel: Finished setting default values for core params Mar 11 21:39:45 volumio kernel: Using Buffer DMA mode Mar 11 21:39:45 volumio kernel: Periodic Transfer Interrupt Enhancement - disabled Mar 11 21:39:45 volumio kernel: Multiprocessor Interrupt Enhancement - disabled Mar 11 21:39:45 volumio kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Mar 11 21:39:45 volumio kernel: Dedicated Tx FIFOs mode Mar 11 21:39:45 volumio kernel: WARN::dwc_otg_hcd_init:1046: FIQ DMA bounce buffers: virt = 0xbd914000 dma = 0xfd914000 len=9024 Mar 11 21:39:45 volumio kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Mar 11 21:39:45 volumio kernel: dwc_otg: Microframe scheduler enabled Mar 11 21:39:45 volumio kernel: WARN::hcd_init_fiq:459: FIQ on core 1 at 0x805ed07c Mar 11 21:39:45 volumio kernel: WARN::hcd_init_fiq:460: FIQ ASM at 0x805ed3e4 length 36 Mar 11 21:39:45 volumio kernel: WARN::hcd_init_fiq:486: MPHI regs_base at 0xf0006000 Mar 11 21:39:45 volumio kernel: dwc_otg 3f980000.usb: DWC OTG Controller Mar 11 21:39:45 volumio kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Mar 11 21:39:45 volumio kernel: dwc_otg 3f980000.usb: irq 62, io mem 0x00000000 Mar 11 21:39:45 volumio kernel: Init: Port Power? op_state=1 Mar 11 21:39:45 volumio kernel: Init: Power Port (0) Mar 11 21:39:45 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 Mar 11 21:39:45 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Mar 11 21:39:45 volumio kernel: usb usb1: Product: DWC OTG Controller Mar 11 21:39:45 volumio kernel: usb usb1: Manufacturer: Linux 4.14.92-v7+ dwc_otg_hcd Mar 11 21:39:45 volumio kernel: usb usb1: SerialNumber: 3f980000.usb Mar 11 21:39:45 volumio kernel: hub 1-0:1.0: USB hub found Mar 11 21:39:45 volumio kernel: hub 1-0:1.0: 1 port detected Mar 11 21:39:45 volumio kernel: dwc_otg: FIQ enabled Mar 11 21:39:45 volumio kernel: dwc_otg: NAK holdoff enabled Mar 11 21:39:45 volumio kernel: dwc_otg: FIQ split-transaction FSM enabled Mar 11 21:39:45 volumio kernel: Module dwc_common_port init Mar 11 21:39:45 volumio kernel: usbcore: registered new interface driver usb-storage Mar 11 21:39:45 volumio kernel: mousedev: PS/2 mouse device common for all mice Mar 11 21:39:45 volumio kernel: IR NEC protocol handler initialized Mar 11 21:39:45 volumio kernel: IR RC5(x/sz) protocol handler initialized Mar 11 21:39:45 volumio kernel: IR RC6 protocol handler initialized Mar 11 21:39:45 volumio kernel: IR JVC protocol handler initialized Mar 11 21:39:45 volumio kernel: IR Sony protocol handler initialized Mar 11 21:39:45 volumio kernel: IR SANYO protocol handler initialized Mar 11 21:39:45 volumio kernel: IR Sharp protocol handler initialized Mar 11 21:39:45 volumio kernel: IR MCE Keyboard/mouse protocol handler initialized Mar 11 21:39:45 volumio kernel: IR XMP protocol handler initialized Mar 11 21:39:45 volumio kernel: bcm2835-wdt 3f100000.watchdog: Broadcom BCM2835 watchdog timer Mar 11 21:39:45 volumio kernel: bcm2835-cpufreq: min=600000 max=1400000 Mar 11 21:39:45 volumio kernel: sdhci: Secure Digital Host Controller Interface driver Mar 11 21:39:45 volumio kernel: sdhci: Copyright(c) Pierre Ossman Mar 11 21:39:45 volumio kernel: mmc-bcm2835 3f300000.mmc: could not get clk, deferring probe Mar 11 21:39:45 volumio kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe Mar 11 21:39:45 volumio kernel: sdhci-pltfm: SDHCI platform and OF driver helper Mar 11 21:39:45 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Mar 11 21:39:45 volumio kernel: hidraw: raw HID events driver (C) Jiri Kosina Mar 11 21:39:45 volumio kernel: usbcore: registered new interface driver usbhid Mar 11 21:39:45 volumio kernel: usbhid: USB HID core driver Mar 11 21:39:45 volumio kernel: vchiq: vchiq_init_state: slot_zero = bd980000, is_master = 0 Mar 11 21:39:45 volumio kernel: [vc_sm_connected_init]: start Mar 11 21:39:45 volumio kernel: [vc_sm_connected_init]: end - returning 0 Mar 11 21:39:45 volumio kernel: Initializing XFRM netlink socket Mar 11 21:39:45 volumio kernel: NET: Registered protocol family 17 Mar 11 21:39:45 volumio kernel: Key type dns_resolver registered Mar 11 21:39:45 volumio kernel: Registering SWP/SWPB emulation handler Mar 11 21:39:45 volumio kernel: registered taskstats version 1 Mar 11 21:39:45 volumio kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Mar 11 21:39:45 volumio kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 87, base_baud = 0) is a PL011 rev2 Mar 11 21:39:45 volumio kernel: mmc-bcm2835 3f300000.mmc: mmc_debug:0 mmc_debug2:0 Mar 11 21:39:45 volumio kernel: mmc-bcm2835 3f300000.mmc: DMA channel allocated Mar 11 21:39:45 volumio kernel: sdhost: log_buf @ bd913000 (fd913000) Mar 11 21:39:45 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Mar 11 21:39:45 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Mar 11 21:39:45 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Mar 11 21:39:45 volumio kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Mar 11 21:39:45 volumio kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Mar 11 21:39:45 volumio kernel: of_cfs_init Mar 11 21:39:45 volumio kernel: of_cfs_init: OK Mar 11 21:39:45 volumio kernel: Freeing unused kernel memory: 1024K Mar 11 21:39:45 volumio kernel: random: fast init done Mar 11 21:39:45 volumio kernel: Indeed it is in host mode hprt0 = 00021501 Mar 11 21:39:45 volumio kernel: mmc1: new high speed SDIO card at address 0001 Mar 11 21:39:45 volumio kernel: mmc0: host does not support reading read-only switch, assuming write-enable Mar 11 21:39:45 volumio kernel: mmc0: new high speed SDHC card at address aaaa Mar 11 21:39:45 volumio kernel: mmcblk0: mmc0:aaaa SC32G 29.7 GiB Mar 11 21:39:45 volumio kernel: mmcblk0: p1 p2 p3 Mar 11 21:39:45 volumio initramfs: Booting Volumio for BCM2835 Mar 11 21:39:45 volumio initramfs: This script mounts rootfs RO with an overlay RW layer. Mar 11 21:39:45 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 11 21:39:45 volumio initramfs: IMGPART=/dev/mmcblk0p2 Mar 11 21:39:45 volumio initramfs: IMGFILE=/volumio_current.sqsh Mar 11 21:39:45 volumio initramfs: Boot delay (except first time) will be 5 seconds Mar 11 21:39:45 volumio initramfs: /dev/mmcblk0p2 not detected,retrying mdev in 5 seconds Mar 11 21:39:45 volumio kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Mar 11 21:39:45 volumio kernel: Indeed it is in host mode hprt0 = 00001101 Mar 11 21:39:45 volumio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=2514 Mar 11 21:39:45 volumio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Mar 11 21:39:45 volumio kernel: hub 1-1:1.0: USB hub found Mar 11 21:39:45 volumio kernel: hub 1-1:1.0: 4 ports detected Mar 11 21:39:45 volumio kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Mar 11 21:39:45 volumio initramfs: /dev/mmcblk0p2: Mar 11 21:39:45 volumio kernel: EXT4-fs (mmcblk0p2): recovery complete Mar 11 21:39:45 volumio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Mar 11 21:39:45 volumio initramfs: Doing a 5 second delay here to give kernel load a headstart Mar 11 21:39:45 volumio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=2514 Mar 11 21:39:45 volumio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Mar 11 21:39:45 volumio kernel: hub 1-1.1:1.0: USB hub found Mar 11 21:39:45 volumio kernel: hub 1-1.1:1.0: 3 ports detected Mar 11 21:39:45 volumio kernel: usb 1-1.1.2: new low-speed USB device number 4 using dwc_otg Mar 11 21:39:45 volumio kernel: usb 1-1.1.2: New USB device found, idVendor=046d, idProduct=c077 Mar 11 21:39:45 volumio kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Mar 11 21:39:45 volumio kernel: usb 1-1.1.2: Product: USB Optical Mouse Mar 11 21:39:45 volumio kernel: usb 1-1.1.2: Manufacturer: Logitech Mar 11 21:39:45 volumio kernel: input: Logitech USB Optical Mouse as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.0/0003:046D:C077.0001/input/input0 Mar 11 21:39:45 volumio kernel: hid-generic 0003:046D:C077.0001: input,hidraw0: USB HID v1.11 Mouse [Logitech USB Optical Mouse] on usb-3f980000.usb-1.1.2/input0 Mar 11 21:39:45 volumio kernel: usb 1-1.3: new low-speed USB device number 5 using dwc_otg Mar 11 21:39:45 volumio kernel: usb 1-1.3: New USB device found, idVendor=1a2c, idProduct=2d23 Mar 11 21:39:45 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Mar 11 21:39:45 volumio kernel: usb 1-1.3: Product: USB Keyboard Mar 11 21:39:45 volumio kernel: usb 1-1.3: Manufacturer: USB Mar 11 21:39:45 volumio kernel: input: USB USB Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/0003:1A2C:2D23.0002/input/input1 Mar 11 21:39:45 volumio kernel: hid-generic 0003:1A2C:2D23.0002: input,hidraw1: USB HID v1.10 Keyboard [USB USB Keyboard] on usb-3f980000.usb-1.3/input0 Mar 11 21:39:45 volumio kernel: input: USB USB Keyboard as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.1/0003:1A2C:2D23.0003/input/input2 Mar 11 21:39:45 volumio kernel: usb 1-1.1.1: new high-speed USB device number 6 using dwc_otg Mar 11 21:39:45 volumio kernel: hid-generic 0003:1A2C:2D23.0003: input,hidraw2: USB HID v1.10 Device [USB USB Keyboard] on usb-3f980000.usb-1.3/input1 Mar 11 21:39:45 volumio kernel: usb 1-1.1.1: New USB device found, idVendor=0424, idProduct=7800 Mar 11 21:39:45 volumio kernel: usb 1-1.1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Mar 11 21:39:45 volumio kernel: libphy: lan78xx-mdiobus: probed Mar 11 21:39:45 volumio kernel: lan78xx 1-1.1.1:1.0 (unnamed net_device) (uninitialized): int urb period 64 Mar 11 21:39:45 volumio initramfs: Checking for USB updates Mar 11 21:39:45 volumio initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Mar 11 21:39:45 volumio kernel: EXT4-fs (mmcblk0p3): recovery complete Mar 11 21:39:45 volumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Mar 11 21:39:45 volumio initramfs: With Option: Mar 11 21:39:45 volumio initramfs: VOLUMIO_VERSION="2.555" Mar 11 21:39:45 volumio initramfs: Finish initramfs, continue booting Volumio Mar 11 21:39:45 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 11 21:39:45 volumio systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR) Mar 11 21:39:45 volumio systemd[1]: Detected architecture 'arm'. Mar 11 21:39:45 volumio kernel: NET: Registered protocol family 10 Mar 11 21:39:45 volumio kernel: Segment Routing with IPv6 Mar 11 21:39:45 volumio systemd[1]: Inserted module 'ipv6' Mar 11 21:39:45 volumio systemd[1]: Set hostname to . Mar 11 21:39:45 volumio kernel: random: systemd-sysv-ge: uninitialized urandom read (16 bytes read) Mar 11 21:39:45 volumio kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 11 21:39:45 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Mar 11 21:39:45 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Mar 11 21:39:45 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Mar 11 21:39:45 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Mar 11 21:39:45 volumio systemd[1]: Cannot add dependency job for unit display-manager.service, ignoring: Unit display-manager.service failed to load: No such file or directory. Mar 11 21:39:45 volumio systemd[1]: Starting Forward Password Requests to Wall Directory Watch. Mar 11 21:39:45 volumio kernel: fuse init (API version 7.26) Mar 11 21:39:45 volumio systemd-udevd[178]: starting version 215 Mar 11 21:39:45 volumio systemd-journal[168]: Journal started Mar 11 21:39:45 volumio systemd[1]: Starting Slices. Mar 11 21:39:45 volumio systemd[1]: Reached target Slices. Mar 11 21:39:45 volumio systemd[1]: Starting Remount Root and Kernel File Systems... Mar 11 21:39:45 volumio systemd[1]: Started Create list of required static device nodes for the current kernel. Mar 11 21:39:45 volumio systemd[1]: Starting Create Static Device Nodes in /dev... Mar 11 21:39:45 volumio systemd[1]: Time has been changed Mar 11 21:39:45 volumio systemd[1]: Started Restore / save the current clock. Mar 11 21:39:45 volumio systemd[1]: Started Create Static Device Nodes in /dev. Mar 11 21:39:45 volumio systemd[1]: Starting udev Kernel Device Manager... Mar 11 21:39:45 volumio systemd[1]: Started udev Coldplug all Devices. Mar 11 21:39:45 volumio systemd[1]: Mounted Debug File System. Mar 11 21:39:45 volumio systemd-modules-load[166]: Inserted module 'fuse' Mar 11 21:39:45 volumio fake-hwclock[157]: Mon Mar 11 21:39:45 UTC 2019 Mar 11 21:39:45 volumio systemd[1]: Starting Copy rules generated while the root was ro... Mar 11 21:39:45 volumio systemd-modules-load[166]: Inserted module 'i2c_dev' Mar 11 21:39:45 volumio kernel: i2c /dev entries driver Mar 11 21:39:45 volumio systemd[1]: Started Various fixups to make systemd work better on Debian. Mar 11 21:39:45 volumio systemd[1]: Starting Load/Save Random Seed... Mar 11 21:39:45 volumio systemd[1]: Starting Local File Systems (Pre). Mar 11 21:39:45 volumio systemd[1]: Reached target Local File Systems (Pre). Mar 11 21:39:45 volumio systemd[1]: Mounting /tmp... Mar 11 21:39:45 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Mar 11 21:39:45 volumio systemd[1]: Mounting /var/spool/cups... Mar 11 21:39:45 volumio systemd[1]: Mounting /var/log... Mar 11 21:39:45 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Mar 11 21:39:45 volumio systemd[1]: Starting Show Plymouth Boot Screen... Mar 11 21:39:45 volumio systemd[1]: Mounted /var/spool/cups. Mar 11 21:39:45 volumio systemd[1]: Mounted /tmp. Mar 11 21:39:45 volumio systemd[1]: Started Load Kernel Modules. Mar 11 21:39:45 volumio systemd[1]: Started Copy rules generated while the root was ro. Mar 11 21:39:45 volumio systemd[1]: Mounted /var/log. Mar 11 21:39:45 volumio systemd[1]: Started Load/Save Random Seed. Mar 11 21:39:45 volumio systemd[1]: Started Show Plymouth Boot Screen. Mar 11 21:39:45 volumio systemd[1]: Starting Forward Password Requests to Plymouth Directory Watch. Mar 11 21:39:45 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Mar 11 21:39:45 volumio systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Mar 11 21:39:45 volumio systemd[1]: Starting Paths. Mar 11 21:39:45 volumio systemd[1]: Reached target Paths. Mar 11 21:39:45 volumio systemd[1]: Mounting FUSE Control File System... Mar 11 21:39:45 volumio systemd[1]: Mounting Configuration File System... Mar 11 21:39:45 volumio systemd[1]: Starting Apply Kernel Variables... Mar 11 21:39:45 volumio systemd[1]: Mounting /var/spool/cups/tmp... Mar 11 21:39:45 volumio systemd[1]: Mounted FUSE Control File System. Mar 11 21:39:45 volumio systemd[1]: Mounted Configuration File System. Mar 11 21:39:45 volumio systemd[1]: Mounted /var/spool/cups/tmp. Mar 11 21:39:45 volumio systemd[1]: Started Apply Kernel Variables. Mar 11 21:39:45 volumio kernel: random: crng init done Mar 11 21:39:45 volumio kernel: random: 7 urandom warning(s) missed due to ratelimiting Mar 11 21:39:45 volumio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Mar 11 21:39:45 volumio kernel: snd_bcm2835: unknown parameter 'index' ignored Mar 11 21:39:45 volumio kernel: bcm2835_alsa bcm2835_alsa: card created with 8 channels Mar 11 21:39:45 volumio kernel: snd-allo-boss-dac soc:sound: ASoC: CODEC DAI pcm512x-hifi not registered - will retry Mar 11 21:39:45 volumio kernel: snd-allo-boss-dac soc:sound: snd_soc_register_card() failed: -517 Mar 11 21:39:46 volumio systemd[1]: Starting Sound Card. Mar 11 21:39:46 volumio systemd[1]: Reached target Sound Card. Mar 11 21:39:46 volumio systemd[1]: Found device /dev/mmcblk0p1. Mar 11 21:39:46 volumio systemd[1]: Starting File System Check on /dev/mmcblk0p1... Mar 11 21:39:46 volumio kernel: pcm512x 1-004d: 1-004d supply AVDD not found, using dummy regulator Mar 11 21:39:46 volumio kernel: pcm512x 1-004d: 1-004d supply DVDD not found, using dummy regulator Mar 11 21:39:46 volumio kernel: pcm512x 1-004d: 1-004d supply CPVDD not found, using dummy regulator Mar 11 21:39:46 volumio kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Mar 11 21:39:46 volumio kernel: brcmfmac: brcmf_fw_map_chip_to_name: using brcm/brcmfmac43455-sdio.bin for chip 0x004345(17221) rev 0x000006 Mar 11 21:39:46 volumio kernel: usbcore: registered new interface driver brcmfmac Mar 11 21:39:46 volumio kernel: snd-allo-boss-dac soc:sound: pcm512x-hifi <-> 3f203000.i2s mapping ok Mar 11 21:39:46 volumio systemd-fsck[287]: fsck.fat 3.0.27 (2014-11-12) Mar 11 21:39:46 volumio systemd-fsck[287]: /dev/mmcblk0p1: 181 files, 74313/123044 clusters Mar 11 21:39:46 volumio systemd[1]: Started File System Check on /dev/mmcblk0p1. Mar 11 21:39:46 volumio systemd[1]: Mounting /boot... Mar 11 21:39:46 volumio systemd[1]: Starting system-ifup.slice. Mar 11 21:39:46 volumio systemd[1]: Created slice system-ifup.slice. Mar 11 21:39:46 volumio systemd[1]: Mounted /boot. Mar 11 21:39:46 volumio systemd[1]: Starting Local File Systems. Mar 11 21:39:46 volumio systemd[1]: Reached target Local File Systems. Mar 11 21:39:46 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Mar 11 21:39:46 volumio systemd[1]: Starting Remote File Systems. Mar 11 21:39:46 volumio systemd[1]: Reached target Remote File Systems. Mar 11 21:39:46 volumio systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage... Mar 11 21:39:46 volumio systemd[1]: Starting Create Volatile Files and Directories... Mar 11 21:39:46 volumio systemd[1]: Starting LSB: Raise network interfaces.... Mar 11 21:39:46 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Mar 11 21:39:46 volumio systemd[1]: Started Create Volatile Files and Directories. Mar 11 21:39:46 volumio kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware version = wl0: Feb 27 2018 03:15:32 version 7.45.154 (r684107 CY) FWID 01-4fbe0b04 Mar 11 21:39:46 volumio kernel: brcmfmac: brcmf_c_preinit_dcmds: CLM version = API: 12.2 Data: 9.10.105 Compiler: 1.29.4 ClmImport: 1.36.3 Creation: 2018-03-09 18:56:28 Mar 11 21:39:46 volumio systemd[1]: Started Trigger Flushing of Journal to Persistent Storage. Mar 11 21:39:46 volumio systemd[1]: Starting system-systemd\x2drfkill.slice. Mar 11 21:39:46 volumio systemd[1]: Created slice system-systemd\x2drfkill.slice. Mar 11 21:39:46 volumio systemd[1]: Starting Load/Save RF Kill Switch Status of rfkill0... Mar 11 21:39:46 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Mar 11 21:39:46 volumio systemd[1]: Started Load/Save RF Kill Switch Status of rfkill0. Mar 11 21:39:46 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Mar 11 21:39:47 volumio kernel: brcmfmac: power management disabled Mar 11 21:39:48 volumio systemd[1]: Received SIGRTMIN+20 from PID 212 (plymouthd). Mar 11 21:39:48 volumio kernel: brcmfmac: power management disabled Mar 11 21:39:48 volumio networking[315]: Configuring network interfaces...done. Mar 11 21:39:48 volumio systemd[1]: Started LSB: Raise network interfaces.. Mar 11 21:39:48 volumio systemd[1]: Starting ifup for wlan0... Mar 11 21:39:48 volumio systemd[1]: Started ifup for wlan0. Mar 11 21:39:48 volumio systemd[1]: Starting ifup for eth0... Mar 11 21:39:48 volumio systemd[1]: Started ifup for eth0. Mar 11 21:39:48 volumio systemd[1]: Starting Network. Mar 11 21:39:48 volumio systemd[1]: Reached target Network. Mar 11 21:39:48 volumio systemd[1]: Starting Network is Online. Mar 11 21:39:48 volumio systemd[1]: Reached target Network is Online. Mar 11 21:39:48 volumio systemd[1]: Starting LSB: RPC portmapper replacement... Mar 11 21:39:48 volumio ifup[413]: dhcpcd[447]: version 6.0.5 starting Mar 11 21:39:48 volumio dhcpcd[447]: version 6.0.5 starting Mar 11 21:39:48 volumio dhcpcd[447]: eth0: soliciting a DHCP lease Mar 11 21:39:48 volumio ifup[413]: dhcpcd[447]: eth0: soliciting a DHCP lease Mar 11 21:39:48 volumio dhcpcd[447]: eth0: offered 192.168.1.60 from 192.168.1.1 `ecosystem.home.cisco.com' Mar 11 21:39:48 volumio ifup[413]: dhcpcd[447]: eth0: offered 192.168.1.60 from 192.168.1.1 `ecosystem.home.cisco.com' Mar 11 21:39:48 volumio dhcpcd[447]: eth0: leased 192.168.1.60 for 86400 seconds Mar 11 21:39:48 volumio dhcpcd[447]: eth0: adding host route to 192.168.1.60 via 127.0.0.1 Mar 11 21:39:48 volumio dhcpcd[447]: eth0: adding route to 192.168.1.0/24 Mar 11 21:39:48 volumio dhcpcd[447]: eth0: adding default route via 192.168.1.1 Mar 11 21:39:48 volumio ifup[413]: dhcpcd[447]: eth0: leased 192.168.1.60 for 86400 seconds Mar 11 21:39:48 volumio ifup[413]: dhcpcd[447]: eth0: adding host route to 192.168.1.60 via 127.0.0.1 Mar 11 21:39:48 volumio ifup[413]: dhcpcd[447]: eth0: adding route to 192.168.1.0/24 Mar 11 21:39:48 volumio ifup[413]: dhcpcd[447]: eth0: adding default route via 192.168.1.1 Mar 11 21:39:48 volumio rpcbind[414]: Starting rpcbind daemon.... Mar 11 21:39:48 volumio systemd[1]: Started LSB: RPC portmapper replacement. Mar 11 21:39:48 volumio systemd[1]: Starting RPC Port Mapper. Mar 11 21:39:48 volumio systemd[1]: Reached target RPC Port Mapper. Mar 11 21:39:48 volumio systemd[1]: Starting LSB: NFS support files common to client and server... Mar 11 21:39:49 volumio dhcpcd[447]: forked to background, child pid 539 Mar 11 21:39:49 volumio ifup[413]: dhcpcd[447]: forked to background, child pid 539 Mar 11 21:39:49 volumio rpc.statd[562]: Version 1.2.8 starting Mar 11 21:39:49 volumio sm-notify[563]: Version 1.2.8 starting Mar 11 21:39:49 volumio kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de). Mar 11 21:39:49 volumio nfs-common[523]: Starting NFS common utilities: statd idmapd. Mar 11 21:39:49 volumio systemd[1]: Started LSB: NFS support files common to client and server. Mar 11 21:39:49 volumio systemd[1]: Starting System Initialization. Mar 11 21:39:49 volumio systemd[1]: Reached target System Initialization. Mar 11 21:39:49 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket. Mar 11 21:39:49 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Mar 11 21:39:49 volumio systemd[1]: Starting mpd.socket. Mar 11 21:39:49 volumio systemd[1]: Listening on mpd.socket. Mar 11 21:39:49 volumio systemd[1]: Starting D-Bus System Message Bus Socket. Mar 11 21:39:49 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Mar 11 21:39:49 volumio systemd[1]: Starting Sockets. Mar 11 21:39:49 volumio systemd[1]: Reached target Sockets. Mar 11 21:39:49 volumio systemd[1]: Starting Daily Cleanup of Temporary Directories. Mar 11 21:39:49 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Mar 11 21:39:49 volumio systemd[1]: Starting Timers. Mar 11 21:39:49 volumio systemd[1]: Reached target Timers. Mar 11 21:39:49 volumio systemd[1]: Started Manage Sound Card State (restore and store). Mar 11 21:39:49 volumio systemd[1]: Starting Restore Sound Card State... Mar 11 21:39:49 volumio systemd[1]: Starting Basic System. Mar 11 21:39:49 volumio systemd[1]: Reached target Basic System. Mar 11 21:39:49 volumio systemd[1]: Starting Entropy daemon using the HAVEGE algorithm... Mar 11 21:39:49 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Mar 11 21:39:49 volumio systemd[1]: Starting Volumio Iptables Module... Mar 11 21:39:49 volumio systemd[1]: Started Volumio Iptables Module. Mar 11 21:39:49 volumio systemd[1]: Starting mount disks automatically with standby... Mar 11 21:39:49 volumio systemd[1]: Started mount disks automatically with standby. Mar 11 21:39:49 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Mar 11 21:39:49 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 11 21:39:49 volumio systemd[1]: Starting volumio-remote-updater.service... Mar 11 21:39:49 volumio systemd[1]: Started volumio-remote-updater.service. Mar 11 21:39:49 volumio systemd[1]: Starting Volumio SSH enabler... Mar 11 21:39:49 volumio systemd[1]: Started Volumio SSH enabler. Mar 11 21:39:49 volumio systemd[1]: Starting Wireless Services... Mar 11 21:39:49 volumio systemd[1]: Starting Xbindrc... Mar 11 21:39:49 volumio systemd[1]: Started Xbindrc. Mar 11 21:39:49 volumio systemd[1]: Starting /etc/rc.local Compatibility... Mar 11 21:39:49 volumio systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available. Mar 11 21:39:49 volumio systemd[1]: Starting Login Service... Mar 11 21:39:49 volumio systemd[1]: Starting LSB: start Winbind daemon... Mar 11 21:39:49 volumio systemd[1]: Starting LSB: start Samba daemons for the AD DC... Mar 11 21:39:49 volumio systemd[1]: Starting LSB: Start NTP daemon... Mar 11 21:39:49 volumio systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)... Mar 11 21:39:49 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Mar 11 21:39:49 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Mar 11 21:39:49 volumio systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon... Mar 11 21:39:49 volumio systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support... Mar 11 21:39:49 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Mar 11 21:39:49 volumio systemd[1]: Starting Music Player Daemon... Mar 11 21:39:49 volumio systemd[1]: Starting D-Bus System Message Bus... Mar 11 21:39:49 volumio systemd[1]: Started D-Bus System Message Bus. Mar 11 21:39:49 volumio dhcpcd[604]: Not running dhcpcd because there is aleady an interface specific instance ... failed! Mar 11 21:39:49 volumio dhcpcd[604]: /var/run/dhcpcd-eth0.pid ... failed! Mar 11 21:39:49 volumio ifplugd(eth0)[665]: ifplugd 0.28 initializing. Mar 11 21:39:49 volumio ifplugd(eth0)[665]: Using interface eth0/B8:27:EB:5A:33:26 with driver (version: 1.0.6) Mar 11 21:39:49 volumio ifplugd(eth0)[665]: Using detection mode: SIOCETHTOOL Mar 11 21:39:49 volumio ifplugd(eth0)[665]: Initialization complete, link beat detected. Mar 11 21:39:49 volumio kernel: ip_tables: (C) 2000-2006 Netfilter Core Team Mar 11 21:39:49 volumio loadcpufreq[597]: Loading cpufreq kernel modules...done (none). Mar 11 21:39:49 volumio kernel: nf_conntrack version 0.5.0 (16384 buckets, 65536 max) Mar 11 21:39:49 volumio ifplugd(eth0)[665]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Mar 11 21:39:50 volumio ifplugd(eth0)[665]: client: /sbin/ifup: interface eth0 already configured Mar 11 21:39:50 volumio ifplugd(eth0)[665]: Program executed successfully. Mar 11 21:39:50 volumio ifplugd[598]: Network Interface Plugging Daemon...start eth0...done. Mar 11 21:39:50 volumio ntpd[672]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1) Mar 11 21:39:50 volumio ntp[593]: Starting NTP server: ntpd. Mar 11 21:39:50 volumio ntpd[700]: proto: precision = 1.041 usec Mar 11 21:39:50 volumio ntpd[700]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Mar 11 21:39:50 volumio ntpd[700]: Listen and drop on 1 v6wildcard :: UDP 123 Mar 11 21:39:50 volumio ntpd[700]: Listen normally on 2 lo 127.0.0.1 UDP 123 Mar 11 21:39:50 volumio ntpd[700]: Listen normally on 3 eth0 192.168.1.60 UDP 123 Mar 11 21:39:50 volumio ntpd[700]: peers refreshed Mar 11 21:39:50 volumio ntpd[700]: Listening on routing socket on fd #20 for interface updates Mar 11 21:39:50 volumio systemd[1]: Starting Permit User Sessions... Mar 11 21:39:50 volumio systemd[1]: Started Restore Sound Card State. Mar 11 21:39:50 volumio avahi-daemon[605]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106). Mar 11 21:39:50 volumio avahi-daemon[605]: Successfully dropped root privileges. Mar 11 21:39:50 volumio avahi-daemon[605]: avahi-daemon 0.6.31 starting up. Mar 11 21:39:50 volumio systemd[1]: Started /etc/rc.local Compatibility. Mar 11 21:39:50 volumio systemd[1]: Started LSB: Start NTP daemon. Mar 11 21:39:50 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Mar 11 21:39:50 volumio systemd[1]: Started LSB: Brings up/down network automatically. Mar 11 21:39:50 volumio systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon. Mar 11 21:39:50 volumio systemd[1]: dhcpcd.service: control process exited, code=exited status=6 Mar 11 21:39:50 volumio systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support. Mar 11 21:39:50 volumio systemd[1]: Unit dhcpcd.service entered failed state. Mar 11 21:39:50 volumio avahi-daemon[605]: Successfully called chroot(). Mar 11 21:39:50 volumio avahi-daemon[605]: Successfully dropped remaining capabilities. Mar 11 21:39:50 volumio avahi-daemon[605]: No service file found in /etc/avahi/services. Mar 11 21:39:50 volumio avahi-daemon[605]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.60. Mar 11 21:39:50 volumio systemd[1]: Started Permit User Sessions. Mar 11 21:39:50 volumio avahi-daemon[605]: New relevant interface eth0.IPv4 for mDNS. Mar 11 21:39:50 volumio avahi-daemon[605]: Network interface enumeration completed. Mar 11 21:39:50 volumio avahi-daemon[605]: Registering new address record for 192.168.1.60 on eth0.IPv4. Mar 11 21:39:50 volumio avahi-daemon[605]: Registering HINFO record with values 'ARMV7L'/'LINUX'. Mar 11 21:39:50 volumio sudo[589]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off Mar 11 21:39:50 volumio sudo[589]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:39:50 volumio dbus[612]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service' Mar 11 21:39:50 volumio sudo[589]: pam_unix(sudo:session): session closed for user root Mar 11 21:39:50 volumio systemd-logind[590]: New seat seat0. Mar 11 21:39:50 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Mar 11 21:39:50 volumio systemd[1]: Started Login Service. Mar 11 21:39:50 volumio systemd[1]: Starting Disk Manager (legacy version)... Mar 11 21:39:50 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Mar 11 21:39:50 volumio systemd[1]: Starting Hold until boot process finishes up... Mar 11 21:39:50 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Mar 11 21:39:51 volumio systemd[1]: Received SIGRTMIN+21 from PID 212 (plymouthd). Mar 11 21:39:51 volumio systemd[1]: Started Hold until boot process finishes up. Mar 11 21:39:51 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Mar 11 21:39:51 volumio systemd[1]: Starting Getty on tty1... Mar 11 21:39:51 volumio systemd[1]: Started Getty on tty1. Mar 11 21:39:51 volumio systemd[1]: Starting Login Prompts. Mar 11 21:39:51 volumio systemd[1]: Reached target Login Prompts. Mar 11 21:39:51 volumio cpufrequtils[734]: CPUFreq Utilities: Setting performance CPUFreq governor...error: unexpectedly disconnected from boot status daemon Mar 11 21:39:51 volumio cpufrequtils[734]: CPU0...CPU1...CPU2...CPU3...done. Mar 11 21:39:51 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Mar 11 21:39:51 volumio dbus[612]: [system] Successfully activated service 'org.freedesktop.UDisks' Mar 11 21:39:51 volumio systemd[1]: Started Disk Manager (legacy version). Mar 11 21:39:51 volumio dbus[612]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service' Mar 11 21:39:51 volumio systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks... Mar 11 21:39:51 volumio polkitd[755]: started daemon version 0.105 using authority implementation `local' version `0.105' Mar 11 21:39:51 volumio dbus[612]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Mar 11 21:39:51 volumio systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks. Mar 11 21:39:51 volumio udisks-glue[581]: Device file /dev/mmcblk0 inserted Mar 11 21:39:51 volumio udisks-glue[581]: Device file /dev/mmcblk0p3 inserted Mar 11 21:39:51 volumio avahi-daemon[605]: Server startup complete. Host name is volumio.local. Local service cookie is 4280244621. Mar 11 21:39:51 volumio volumio-remote-updater[583]: Error: No active session Mar 11 21:39:51 volumio volumio-remote-updater[583]: [2019-03-11 21:39:51] [info] asio async_connect error: system:111 (Connection refused) Mar 11 21:39:51 volumio volumio-remote-updater[583]: [2019-03-11 21:39:51] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 11 21:39:51 volumio volumio-remote-updater[583]: [2019-03-11 21:39:51] [error] handle_connect error: Underlying Transport Error Mar 11 21:39:51 volumio haveged[579]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K Mar 11 21:39:51 volumio haveged[579]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244 Mar 11 21:39:51 volumio haveged[579]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.0012 Mar 11 21:39:51 volumio haveged[579]: haveged: fills: 0, generated: 0 Mar 11 21:39:51 volumio volumio[582]: Could not open config: /tmp/upmpdcli.conf Mar 11 21:39:51 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Mar 11 21:39:51 volumio systemd[1]: Stopping Xbindrc... Mar 11 21:39:51 volumio systemd[1]: Starting Xbindrc... Mar 11 21:39:51 volumio systemd[1]: Started Xbindrc. Mar 11 21:39:51 volumio systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE Mar 11 21:39:51 volumio systemd[1]: Unit upmpdcli.service entered failed state. Mar 11 21:39:52 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Mar 11 21:39:52 volumio systemd[1]: Stopping Xbindrc... Mar 11 21:39:52 volumio systemd[1]: Starting Xbindrc... Mar 11 21:39:52 volumio systemd[1]: Started Xbindrc. Mar 11 21:39:52 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Mar 11 21:39:52 volumio systemd[1]: Stopping Xbindrc... Mar 11 21:39:52 volumio systemd[1]: Starting Xbindrc... Mar 11 21:39:52 volumio systemd[1]: Started Xbindrc. Mar 11 21:39:53 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Mar 11 21:39:53 volumio systemd[1]: Stopping Xbindrc... Mar 11 21:39:53 volumio systemd[1]: Starting Xbindrc... Mar 11 21:39:53 volumio systemd[1]: Started Xbindrc. Mar 11 21:39:53 volumio systemd[1]: xbindkeysrc.service holdoff time over, scheduling restart. Mar 11 21:39:53 volumio systemd[1]: Stopping Xbindrc... Mar 11 21:39:53 volumio systemd[1]: Starting Xbindrc... Mar 11 21:39:53 volumio systemd[1]: xbindkeysrc.service start request repeated too quickly, refusing to start. Mar 11 21:39:53 volumio systemd[1]: Failed to start Xbindrc. Mar 11 21:39:53 volumio systemd[1]: Unit xbindkeysrc.service entered failed state. Mar 11 21:39:53 volumio wireless.js[723]: WIRELESS DAEMON: start Mar 11 21:39:53 volumio wireless.js[723]: WIRELESS: Loaded configuration Mar 11 21:39:53 volumio wireless.js[723]: Cleaning previous... Mar 11 21:39:53 volumio systemd[1]: Stopped hotspot.service. Mar 11 21:39:53 volumio systemd[1]: Started Music Player Daemon. Mar 11 21:39:53 volumio sudo[799]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 11 21:39:53 volumio sudo[799]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:39:53 volumio sudo[799]: pam_unix(sudo:session): session closed for user root Mar 11 21:39:54 volumio sudo[807]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 11 21:39:54 volumio sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:39:54 volumio systemd[1]: Started LSB: start Samba daemons for the AD DC. Mar 11 21:39:54 volumio winbind[591]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Mar 11 21:39:54 volumio winbind[591]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Mar 11 21:39:54 volumio winbind[591]: Unable to setup corepath for winbindd: No such file or directory Mar 11 21:39:54 volumio winbind[591]: mkdir failed on directory /var/log/samba/cores: No such file or directory Mar 11 21:39:54 volumio winbind[591]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Mar 11 21:39:54 volumio winbind[591]: Unable to setup corepath for winbindd: No such file or directory Mar 11 21:39:54 volumio winbind[591]: [2019/03/11 21:39:54, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:54 volumio winbind[591]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Mar 11 21:39:54 volumio winbind[591]: [2019/03/11 21:39:54, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:54 volumio winbind[591]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Mar 11 21:39:54 volumio winbind[591]: [2019/03/11 21:39:54, 0] ../source3/winbindd/winbindd.c:1549(main) Mar 11 21:39:54 volumio winbind[591]: winbindd version 4.2.14-Debian started. Mar 11 21:39:54 volumio winbind[591]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Mar 11 21:39:54 volumio winbind[591]: [2019/03/11 21:39:54, 0] ../lib/util/util.c:220(directory_create_or_exist) Mar 11 21:39:54 volumio winbind[591]: mkdir failed on directory /var/log/samba/cores: No such file or directory Mar 11 21:39:54 volumio winbind[591]: [2019/03/11 21:39:54, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Mar 11 21:39:54 volumio winbind[591]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Mar 11 21:39:54 volumio winbind[591]: [2019/03/11 21:39:54, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Mar 11 21:39:54 volumio winbind[591]: Unable to setup corepath for winbindd: No such file or directory Mar 11 21:39:54 volumio winbindd[820]: [2019/03/11 21:39:54.413653, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:54 volumio winbindd[820]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Mar 11 21:39:54 volumio winbindd[820]: [2019/03/11 21:39:54.414556, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:54 volumio winbindd[820]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Mar 11 21:39:54 volumio winbind[591]: [2019/03/11 21:39:54.413653, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:54 volumio winbind[591]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Mar 11 21:39:54 volumio winbind[591]: [2019/03/11 21:39:54.414556, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:54 volumio winbind[591]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Mar 11 21:39:54 volumio nmbd[595]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Mar 11 21:39:54 volumio nmbd[595]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Mar 11 21:39:54 volumio nmbd[595]: Unable to setup corepath for nmbd: No such file or directory Mar 11 21:39:54 volumio nmbd[823]: [2019/03/11 21:39:54.434133, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:54 volumio nmbd[823]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Mar 11 21:39:54 volumio nmbd[823]: [2019/03/11 21:39:54.435372, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:54 volumio nmbd[823]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Mar 11 21:39:54 volumio nmbd[824]: [2019/03/11 21:39:54.438351, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) Mar 11 21:39:54 volumio nmbd[824]: started asyncdns process 825 Mar 11 21:39:54 volumio systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Mar 11 21:39:54 volumio systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Mar 11 21:39:54 volumio nmbd[595]: [2019/03/11 21:39:54, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:54 volumio nmbd[595]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Mar 11 21:39:54 volumio nmbd[595]: [2019/03/11 21:39:54, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:54 volumio nmbd[595]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Mar 11 21:39:54 volumio nmbd[595]: [2019/03/11 21:39:54, 0] ../source3/nmbd/nmbd.c:908(main) Mar 11 21:39:54 volumio nmbd[595]: nmbd version 4.2.14-Debian started. Mar 11 21:39:54 volumio nmbd[595]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Mar 11 21:39:54 volumio nmbd[595]: [2019/03/11 21:39:54.434133, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:54 volumio nmbd[595]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Mar 11 21:39:54 volumio nmbd[595]: [2019/03/11 21:39:54.435372, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:54 volumio nmbd[595]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Mar 11 21:39:54 volumio nmbd[595]: . Mar 11 21:39:54 volumio sudo[807]: pam_unix(sudo:session): session closed for user root Mar 11 21:39:54 volumio nmbd[824]: [2019/03/11 21:39:54.563573, 0] ../lib/util/become_daemon.c:124(daemon_ready) Mar 11 21:39:54 volumio nmbd[824]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 11 21:39:54 volumio winbindd[836]: [2019/03/11 21:39:54.575062, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Mar 11 21:39:54 volumio winbindd[836]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 11 21:39:54 volumio systemd[1]: Started LSB: start Winbind daemon. Mar 11 21:39:54 volumio winbind[591]: . Mar 11 21:39:54 volumio winbindd[836]: [2019/03/11 21:39:54.609629, 0] ../lib/util/become_daemon.c:124(daemon_ready) Mar 11 21:39:54 volumio winbindd[836]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 11 21:39:54 volumio winbindd[840]: [2019/03/11 21:39:54.613990, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:54 volumio winbindd[840]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO': No such file or directory Mar 11 21:39:54 volumio wireless.js[723]: Stopped aP Mar 11 21:39:54 volumio wireless.js[723]: Start wireless flow Mar 11 21:39:54 volumio wireless.js[723]: Stopped hotspot (if there).. Mar 11 21:39:54 volumio sudo[843]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 11 21:39:54 volumio sudo[843]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:39:54 volumio sudo[843]: pam_unix(sudo:session): session closed for user root Mar 11 21:39:54 volumio sudo[851]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 11 21:39:54 volumio sudo[851]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:39:54 volumio sudo[851]: pam_unix(sudo:session): session closed for user root Mar 11 21:39:54 volumio wireless.js[723]: DHCP IP Mar 11 21:39:54 volumio wireless.js[723]: Start ap Mar 11 21:39:54 volumio sudo[862]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 11 21:39:54 volumio sudo[862]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:39:54 volumio dhcpcd[866]: version 6.0.5 starting Mar 11 21:39:54 volumio kernel: brcmfmac: power management disabled Mar 11 21:39:54 volumio wpa_supplicant[859]: Successfully initialized wpa_supplicant Mar 11 21:39:54 volumio dhcpcd[866]: eth0: rebinding lease of 192.168.1.60 Mar 11 21:39:54 volumio dhcpcd[866]: wlan0: waiting for carrier Mar 11 21:39:54 volumio dhcpcd[866]: eth0: leased 192.168.1.60 for 86400 seconds Mar 11 21:39:54 volumio dhcpcd[866]: eth0: adding host route to 192.168.1.60 via 127.0.0.1 Mar 11 21:39:54 volumio dhcpcd[539]: eth0: removing route to 192.168.1.0/24 Mar 11 21:39:54 volumio dhcpcd[866]: eth0: adding route to 192.168.1.0/24 Mar 11 21:39:54 volumio dhcpcd[866]: eth0: adding default route via 192.168.1.1 Mar 11 21:39:54 volumio dhcpcd[539]: eth0: removing default route via 192.168.1.1 Mar 11 21:39:54 volumio dhcpcd[866]: forked to background, child pid 921 Mar 11 21:39:54 volumio dhcpcd[921]: eth0: removing route to 192.168.1.0/24 Mar 11 21:39:54 volumio dhcpcd[921]: eth0: removing default route via 192.168.1.1 Mar 11 21:39:54 volumio sudo[862]: pam_unix(sudo:session): session closed for user root Mar 11 21:39:55 volumio smbd[826]: Starting SMB/CIFS daemon: smbd[2019/03/11 21:39:55, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:55 volumio smbd[826]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Mar 11 21:39:55 volumio smbd[826]: [2019/03/11 21:39:55, 0] ../lib/util/util.c:220(directory_create_or_exist) Mar 11 21:39:55 volumio smbd[826]: mkdir failed on directory /var/log/samba/cores: No such file or directory Mar 11 21:39:55 volumio smbd[826]: [2019/03/11 21:39:55, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Mar 11 21:39:55 volumio smbd[826]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Mar 11 21:39:55 volumio smbd[826]: [2019/03/11 21:39:55, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Mar 11 21:39:55 volumio smbd[826]: Unable to setup corepath for smbd: No such file or directory Mar 11 21:39:55 volumio smbd[826]: [2019/03/11 21:39:55, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:55 volumio smbd[826]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Mar 11 21:39:55 volumio smbd[826]: [2019/03/11 21:39:55, 0] ../source3/smbd/server.c:1241(main) Mar 11 21:39:55 volumio smbd[826]: smbd version 4.2.14-Debian started. Mar 11 21:39:55 volumio smbd[826]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Mar 11 21:39:55 volumio smbd[926]: [2019/03/11 21:39:55.259167, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:55 volumio smbd[926]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Mar 11 21:39:55 volumio smbd[926]: [2019/03/11 21:39:55.260492, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:55 volumio smbd[926]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Mar 11 21:39:55 volumio smbd[926]: [2019/03/11 21:39:55.260643, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:55 volumio smbd[926]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Mar 11 21:39:55 volumio smbd[826]: [2019/03/11 21:39:55.259167, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:55 volumio smbd[826]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Mar 11 21:39:55 volumio smbd[826]: [2019/03/11 21:39:55.260492, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:55 volumio smbd[826]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Mar 11 21:39:55 volumio smbd[826]: [2019/03/11 21:39:55.260643, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:55 volumio smbd[826]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Mar 11 21:39:55 volumio smbd[826]: . Mar 11 21:39:55 volumio systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Mar 11 21:39:55 volumio winbindd[930]: [2019/03/11 21:39:55.305286, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:55 volumio winbindd[930]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Mar 11 21:39:55 volumio winbindd[931]: [2019/03/11 21:39:55.335917, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:55 volumio winbindd[931]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory Mar 11 21:39:55 volumio smbd[927]: [2019/03/11 21:39:55.365069, 0] ../lib/util/become_daemon.c:124(daemon_ready) Mar 11 21:39:55 volumio smbd[927]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 11 21:39:55 volumio smbd[932]: [2019/03/11 21:39:55.371458, 0] ../lib/util/debug.c:603(reopen_logs_internal) Mar 11 21:39:55 volumio smbd[932]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Mar 11 21:39:55 volumio wireless.js[723]: trying... Mar 11 21:39:55 volumio sudo[935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 11 21:39:55 volumio sudo[935]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:39:55 volumio sudo[935]: pam_unix(sudo:session): session closed for user root Mar 11 21:39:56 volumio volumio-remote-updater[583]: [2019-03-11 21:39:56] [info] asio async_connect error: system:111 (Connection refused) Mar 11 21:39:56 volumio volumio-remote-updater[583]: [2019-03-11 21:39:56] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 11 21:39:56 volumio volumio-remote-updater[583]: [2019-03-11 21:39:56] [error] handle_connect error: Underlying Transport Error Mar 11 21:39:56 volumio wireless.js[723]: trying... Mar 11 21:39:56 volumio sudo[945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 11 21:39:56 volumio sudo[945]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:39:56 volumio sudo[945]: pam_unix(sudo:session): session closed for user root Mar 11 21:40:34 volumio systemd[1]: Time has been changed Mar 11 21:40:35 volumio wireless.js[723]: trying... Mar 11 21:40:35 volumio wpa_supplicant[910]: wlan0: Trying to associate with 20:aa:4b:60:90:97 (SSID='terabeans' freq=2417 MHz) Mar 11 21:40:35 volumio sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 11 21:40:35 volumio sudo[954]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:40:35 volumio sudo[954]: pam_unix(sudo:session): session closed for user root Mar 11 21:40:35 volumio wpa_supplicant[910]: wlan0: Associated with 20:aa:4b:60:90:97 Mar 11 21:40:35 volumio wpa_supplicant[910]: wlan0: WPA: Key negotiation completed with 20:aa:4b:60:90:97 [PTK=CCMP GTK=TKIP] Mar 11 21:40:35 volumio wpa_supplicant[910]: wlan0: CTRL-EVENT-CONNECTED - Connection to 20:aa:4b:60:90:97 completed [id=0 id_str=] Mar 11 21:40:35 volumio dhcpcd[921]: wlan0: carrier acquired Mar 11 21:40:35 volumio dhcpcd[921]: wlan0: rebinding lease of 192.168.1.61 Mar 11 21:40:35 volumio dhcpcd[921]: wlan0: leased 192.168.1.61 for 86400 seconds Mar 11 21:40:35 volumio avahi-daemon[605]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.61. Mar 11 21:40:35 volumio avahi-daemon[605]: New relevant interface wlan0.IPv4 for mDNS. Mar 11 21:40:35 volumio dhcpcd[921]: eth0: adding route to 192.168.1.0/24 Mar 11 21:40:35 volumio avahi-daemon[605]: Registering new address record for 192.168.1.61 on wlan0.IPv4. Mar 11 21:40:35 volumio dhcpcd[921]: eth0: adding default route via 192.168.1.1 Mar 11 21:40:35 volumio dhcpcd[921]: wlan0: adding host route to 192.168.1.61 via 127.0.0.1 Mar 11 21:40:35 volumio dhcpcd[921]: wlan0: adding route to 192.168.1.0/24 Mar 11 21:40:35 volumio dhcpcd[921]: wlan0: adding default route via 192.168.1.1 Mar 11 21:40:35 volumio dhcpcd[921]: eth0: removing route to 192.168.1.0/24 Mar 11 21:40:35 volumio dhcpcd[921]: eth0: removing default route via 192.168.1.1 Mar 11 21:40:36 volumio wireless.js[723]: trying... Mar 11 21:40:36 volumio sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 11 21:40:36 volumio sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:40:36 volumio sudo[1002]: pam_unix(sudo:session): session closed for user root Mar 11 21:40:36 volumio wireless.js[723]: Connected to: ----terabeans Mar 11 21:40:36 volumio wireless.js[723]: ---- Mar 11 21:40:36 volumio sudo[1011]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:40:36 volumio sudo[1011]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:40:36 volumio sudo[1011]: pam_unix(sudo:session): session closed for user root Mar 11 21:40:36 volumio wireless.js[723]: ... joined AP, wlan0 IPv4 is 192.168.1.61, ipV6 is undefined Mar 11 21:40:36 volumio wireless.js[723]: It's done! AP Mar 11 21:40:36 volumio systemd[1]: Started Wireless Services. Mar 11 21:40:36 volumio systemd[1]: Starting Volumio Backend Module... Mar 11 21:40:36 volumio systemd[1]: Started Volumio Backend Module. Mar 11 21:40:36 volumio systemd[1]: Starting Volumio Streaming Daemon... Mar 11 21:40:36 volumio systemd[1]: Started Volumio Streaming Daemon. Mar 11 21:40:36 volumio systemd[1]: Starting Multi-User System. Mar 11 21:40:36 volumio systemd[1]: Reached target Multi-User System. Mar 11 21:40:36 volumio systemd[1]: Starting Graphical Interface. Mar 11 21:40:36 volumio systemd[1]: Reached target Graphical Interface. Mar 11 21:40:36 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 11 21:40:36 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 11 21:40:36 volumio systemd[1]: Startup finished in 9.130s (kernel) + 14.741s (userspace) = 23.871s. Mar 11 21:40:36 volumio volumio-streaming-daemon[1021]: ############################ Mar 11 21:40:36 volumio volumio-streaming-daemon[1021]: # Volumio Streaming Daemon # Mar 11 21:40:36 volumio volumio-streaming-daemon[1021]: # Running on port 7777 # Mar 11 21:40:36 volumio volumio-streaming-daemon[1021]: ############################ Mar 11 21:40:36 volumio volumio-streaming-daemon[1021]: Checking variant in static cache Mar 11 21:40:36 volumio volumio-streaming-daemon[1021]: Enabled services: Mar 11 21:40:36 volumio volumio-streaming-daemon[1021]: 1) tidal Mar 11 21:40:36 volumio volumio-streaming-daemon[1021]: 2) qobuz Mar 11 21:40:36 volumio ntpd[700]: Listen normally on 4 wlan0 192.168.1.61 UDP 123 Mar 11 21:40:36 volumio ntpd[700]: peers refreshed Mar 11 21:40:38 volumio volumio[1020]: info: ------------------------------------------- Mar 11 21:40:38 volumio volumio[1020]: info: ----- Volumio2 ---- Mar 11 21:40:38 volumio volumio[1020]: info: ------------------------------------------- Mar 11 21:40:38 volumio volumio[1020]: info: ----- System startup ---- Mar 11 21:40:38 volumio volumio[1020]: info: ------------------------------------------- Mar 11 21:40:38 volumio volumio[1020]: info: MYVOLUMIO Environment detected Mar 11 21:40:39 volumio volumio[1020]: info: Plugin folders cleanup Mar 11 21:40:39 volumio volumio[1020]: info: Scanning into folder /volumio/app/plugins/ Mar 11 21:40:39 volumio volumio[1020]: info: Scanning category audio_interface Mar 11 21:40:39 volumio volumio[1020]: info: Scanning category miscellanea Mar 11 21:40:39 volumio volumio[1020]: info: Scanning category music_service Mar 11 21:40:39 volumio volumio[1020]: info: Scanning category plugins.json Mar 11 21:40:39 volumio volumio[1020]: info: Scanning category system_controller Mar 11 21:40:39 volumio volumio[1020]: info: Scanning category user_interface Mar 11 21:40:39 volumio volumio[1020]: info: Scanning into folder /data/plugins/ Mar 11 21:40:39 volumio volumio[1020]: info: Plugin folders cleanup completed Mar 11 21:40:39 volumio volumio[1020]: info: ------------------------------------------- Mar 11 21:40:39 volumio volumio[1020]: info: ----- Core plugins startup ---- Mar 11 21:40:39 volumio volumio[1020]: info: ------------------------------------------- Mar 11 21:40:39 volumio volumio[1020]: info: Loading plugins from folder /volumio/app/plugins/ Mar 11 21:40:39 volumio volumio[1020]: info: Adding plugin upnp to MyMusic Plugins Mar 11 21:40:39 volumio volumio[1020]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 11 21:40:39 volumio volumio[1020]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 11 21:40:39 volumio volumio[1020]: info: Loading plugins from folder /data/plugins/ Mar 11 21:40:39 volumio volumio[1020]: info: Loading plugin "system"... Mar 11 21:40:39 volumio volumio[1020]: info: Loading plugin "appearance"... Mar 11 21:40:40 volumio volumio[1020]: info: Loading plugin "network"... Mar 11 21:40:40 volumio volumio[1020]: info: Loading plugin "services"... Mar 11 21:40:40 volumio volumio[1020]: info: Loading plugin "alsa_controller"... Mar 11 21:40:40 volumio volumio[1020]: info: Loading plugin "wizard"... Mar 11 21:40:40 volumio volumio[1020]: info: Loading plugin "volumio_command_line_client"... Mar 11 21:40:40 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 21:40:40 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 21:40:40 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 21:40:40 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 21:40:40 volumio volumio[1020]: info: Loading plugin "upnp"... Mar 11 21:40:40 volumio volumio[1020]: info: [1552340440890] Starting Upmpd Daemon Mar 11 21:40:40 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 11 21:40:40 volumio volumio[1020]: info: Loading plugin "my_music"... Mar 11 21:40:40 volumio volumio[1020]: info: Loading plugin "mpd"... Mar 11 21:40:41 volumio volumio[1020]: info: Loading plugin "upnp_browser"... Mar 11 21:40:41 volumio volumio-remote-updater[583]: [2019-03-11 21:40:41] [connect] Successful connection Mar 11 21:40:42 volumio volumio[1020]: info: Loading plugin "networkfs"... Mar 11 21:40:42 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 11 21:40:42 volumio volumio[1020]: info: Loading plugin "alarm-clock"... Mar 11 21:40:42 volumio sudo[1050]: 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 //NAS/Music /mnt/NAS/Music Mar 11 21:40:42 volumio sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:40:42 volumio kernel: FS-Cache: Netfs 'cifs' registered for caching Mar 11 21:40:42 volumio kernel: Key type cifs.spnego registered Mar 11 21:40:42 volumio kernel: Key type cifs.idmap registered Mar 11 21:40:42 volumio kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Mar 11 21:40:42 volumio volumio[1020]: info: Loading plugin "airplay_emulation"... Mar 11 21:40:42 volumio kernel: CIFS VFS: ioctl error in smb2_get_dfs_refer rc=-2 Mar 11 21:40:42 volumio sudo[1050]: pam_unix(sudo:session): session closed for user root Mar 11 21:40:42 volumio volumio[1020]: info: Starting Shairport Sync Mar 11 21:40:42 volumio volumio[1020]: info: Loading plugin "last_100"... Mar 11 21:40:42 volumio volumio[1020]: info: Loading plugin "webradio"... Mar 11 21:40:42 volumio volumio[1020]: info: Loading plugin "i2s_dacs"... Mar 11 21:40:42 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 21:40:42 volumio volumio[1020]: info: Loading plugin "volumiodiscovery"... Mar 11 21:40:42 volumio volumio[1020]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 11 21:40:42 volumio node[1020]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 11 21:40:42 volumio node[1020]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 11 21:40:42 volumio node[1020]: *** WARNING *** For more information see Mar 11 21:40:42 volumio node[1020]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 11 21:40:42 volumio node[1020]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 11 21:40:42 volumio node[1020]: *** WARNING *** For more information see Mar 11 21:40:42 volumio volumio[1020]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 11 21:40:42 volumio volumio[1020]: *** WARNING *** For more information see Mar 11 21:40:42 volumio volumio[1020]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 11 21:40:42 volumio volumio[1020]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 11 21:40:42 volumio volumio[1020]: *** WARNING *** For more information see Mar 11 21:40:42 volumio volumio[1020]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 11 21:40:42 volumio volumio[1020]: Discovery: StartAdv! undefined Mar 11 21:40:42 volumio volumio[1020]: Discovery: Started advertising... Volumio - undefined Mar 11 21:40:42 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 11 21:40:42 volumio volumio[1020]: info: Loading plugin "outputs"... Mar 11 21:40:42 volumio volumio[1020]: info: Loading plugin "albumart"... Mar 11 21:40:43 volumio volumio[1020]: info: Plugin example_plugin is not enabled Mar 11 21:40:43 volumio volumio[1020]: info: Loading plugin "updater_comm"... Mar 11 21:40:43 volumio volumio[1020]: info: Plugin mpdemulation is not enabled Mar 11 21:40:43 volumio volumio[1020]: info: Loading plugin "rest_api"... Mar 11 21:40:43 volumio volumio[1020]: info: Loading plugin "websocket"... Mar 11 21:40:43 volumio volumio[1020]: info: ___________ START PLUGINS ___________ Mar 11 21:40:43 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 21:40:43 volumio volumio[1020]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 11 21:40:43 volumio volumio[1020]: info: [1552340443213] CoreMusicLibrary::Adding element Media Servers Mar 11 21:40:43 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 21:40:43 volumio volumio[1020]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 11 21:40:43 volumio volumio[1020]: info: [1552340443245] CoreMusicLibrary::Adding element Last_100 Mar 11 21:40:43 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 21:40:43 volumio volumio[1020]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 11 21:40:43 volumio volumio[1020]: info: [1552340443252] CoreMusicLibrary::Adding element Webradio Mar 11 21:40:43 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 21:40:43 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 11 21:40:43 volumio volumio[1020]: info: ------------------------------------------- Mar 11 21:40:43 volumio volumio[1020]: info: ----- MyVolumio plugins startup ---- Mar 11 21:40:43 volumio volumio[1020]: info: ------------------------------------------- Mar 11 21:40:43 volumio volumio[1020]: info: Loading plugins from folder /myvolumio/plugins Mar 11 21:40:43 volumio volumio[1020]: info: Loading plugins from folder /data/myvolumio/plugins Mar 11 21:40:43 volumio volumio[1020]: info: Loading plugin "streaming_services"... Mar 11 21:40:43 volumio volumio[1020]: Forking 3 albumart workers Mar 11 21:40:43 volumio volumio[1020]: info: Loading plugin "my_volumio"... Mar 11 21:40:44 volumio volumio[1020]: Starting albumart workers Mar 11 21:40:44 volumio volumio[1020]: Starting albumart workers Mar 11 21:40:44 volumio volumio[1020]: Starting albumart workers Mar 11 21:40:44 volumio volumio[1020]: info: Streaming services startup Mar 11 21:40:44 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 21:40:44 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 21:40:44 volumio volumio[1020]: info: Starting MyVolumio Remote Streaming Endpoints Mar 11 21:40:44 volumio volumio[1020]: info: Loading i18n strings for locale en Mar 11 21:40:44 volumio sudo[1127]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 11 21:40:44 volumio volumio[1020]: Updating browse sources language Mar 11 21:40:44 volumio volumio[1020]: Cannot find translation for sourceMedia Servers Mar 11 21:40:44 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 21:40:44 volumio sudo[1127]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:40:44 volumio systemd[1]: Stopping Volumio Streaming Daemon... Mar 11 21:40:44 volumio systemd[1]: Starting Volumio Streaming Daemon... Mar 11 21:40:44 volumio systemd[1]: Started Volumio Streaming Daemon. Mar 11 21:40:44 volumio sudo[1127]: pam_unix(sudo:session): session closed for user root Mar 11 21:40:44 volumio volumio-streaming-daemon[1133]: ############################ Mar 11 21:40:44 volumio volumio-streaming-daemon[1133]: # Volumio Streaming Daemon # Mar 11 21:40:44 volumio volumio-streaming-daemon[1133]: # Running on port 7777 # Mar 11 21:40:44 volumio volumio-streaming-daemon[1133]: ############################ Mar 11 21:40:44 volumio volumio-streaming-daemon[1133]: Checking variant in static cache Mar 11 21:40:44 volumio volumio-streaming-daemon[1133]: Enabled services: Mar 11 21:40:44 volumio volumio-streaming-daemon[1133]: 1) tidal Mar 11 21:40:44 volumio volumio-streaming-daemon[1133]: 2) qobuz Mar 11 21:40:44 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 21:40:44 volumio volumio[1020]: info: CoreCommandRouter::initPlayerControls Mar 11 21:40:44 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 21:40:44 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Mar 11 21:40:44 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 21:40:44 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 21:40:44 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 21:40:44 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 21:40:44 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 21:40:44 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 21:40:44 volumio volumio[1020]: info: BOOT COMPLETED Mar 11 21:40:44 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 21:40:49 volumio volumio[1020]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Mar 11 21:40:49 volumio volumio[1020]: info: CoreCommandRouter::Close All Modals sent Mar 11 21:40:49 volumio volumio[1020]: info: CoreCommandRouter::Close All Modals sent Mar 11 21:40:49 volumio volumio[1020]: Express server listening on port 3000 Mar 11 21:40:49 volumio volumio[1020]: Volumio Calling Home Mar 11 21:40:49 volumio volumio[1020]: info: MyVolumio not started Mar 11 21:40:49 volumio volumio[1020]: info: CoreStateMachine::resetVolumioState Mar 11 21:40:49 volumio volumio[1020]: info: CoreStateMachine::getcurrentVolume Mar 11 21:40:49 volumio volumio[1020]: info: CoreCommandRouter::volumioRetrievevolume Mar 11 21:40:50 volumio volumio[1020]: info: Setting Device type: Raspberry PI Mar 11 21:40:50 volumio volumio[1020]: info: MPD running with PID606 ,establishing connection Mar 11 21:40:50 volumio volumio[1020]: info: VolumeController:: Volume=100 Mute =false Mar 11 21:40:50 volumio volumio[1020]: info: CoreStateMachine::pushState Mar 11 21:40:50 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 21:40:50 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 21:40:50 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Mar 11 21:40:50 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 21:40:50 volumio volumio[1020]: info: Volumio called home Mar 11 21:40:50 volumio volumio[1020]: info: Reloading queue from file Mar 11 21:40:50 volumio volumio[1020]: info: Mar 11 21:40:50 volumio volumio[1020]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Mar 11 21:40:50 volumio volumio[1020]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Mar 11 21:40:50 volumio volumio[1020]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Mar 11 21:40:50 volumio volumio[1020]: at emitOne (events.js:116:13) Mar 11 21:40:50 volumio volumio[1020]: at Socket.emit (events.js:211:7) Mar 11 21:40:50 volumio volumio[1020]: at addChunk (_stream_readable.js:263:12) Mar 11 21:40:50 volumio volumio[1020]: at readableAddChunk (_stream_readable.js:246:13) Mar 11 21:40:50 volumio volumio[1020]: at Socket.Readable.push (_stream_readable.js:208:10) Mar 11 21:40:50 volumio volumio[1020]: at Pipe.onread (net.js:607:20) Mar 11 21:40:50 volumio volumio[1020]: info: CoreStateMachine::setRepeat null single undefined Mar 11 21:40:50 volumio volumio[1020]: info: CoreStateMachine::pushState Mar 11 21:40:50 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 21:40:50 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 21:40:50 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Mar 11 21:40:50 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 21:40:50 volumio volumio[1020]: info: CoreStateMachine::setRandom null Mar 11 21:40:50 volumio volumio[1020]: info: CoreStateMachine::pushState Mar 11 21:40:50 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 21:40:50 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 21:40:50 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Mar 11 21:40:50 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 21:40:50 volumio sudo[1158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Mar 11 21:40:50 volumio sudo[1158]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:40:50 volumio sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Mar 11 21:40:50 volumio sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:40:50 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Mar 11 21:40:50 volumio systemd[1]: Started ShairportSync AirTunes receiver. Mar 11 21:40:50 volumio sudo[1158]: pam_unix(sudo:session): session closed for user root Mar 11 21:40:50 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Mar 11 21:40:50 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 11 21:40:50 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Mar 11 21:40:50 volumio systemd[1]: Started ShairportSync AirTunes receiver. Mar 11 21:40:50 volumio sudo[1160]: pam_unix(sudo:session): session closed for user root Mar 11 21:40:50 volumio shairport-sync[1173]: Successful Startup Mar 11 21:40:50 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 11 21:40:50 volumio volumio[1020]: Discovery: adding 9188a5f8-b226-4b22-9a4e-56efa5875410 Mar 11 21:40:50 volumio volumio[1020]: info: mDNS: Found device Volumio Mar 11 21:40:50 volumio sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:40:50 volumio sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:40:50 volumio sudo[1183]: pam_unix(sudo:session): session closed for user root Mar 11 21:40:50 volumio volumio[1020]: info: Shairport-Sync Started Mar 11 21:40:50 volumio volumio[1020]: Error adding Membership: Error: addMembership EINVAL Mar 11 21:40:50 volumio volumio[1020]: info: Shairport-Sync Started Mar 11 21:40:50 volumio volumio[1020]: Discovery: this is already registered, 9188a5f8-b226-4b22-9a4e-56efa5875410 Mar 11 21:40:50 volumio sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:40:50 volumio volumio[1020]: info: mDNS: Found device Volumio Mar 11 21:40:50 volumio sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:40:50 volumio sudo[1192]: pam_unix(sudo:session): session closed for user root Mar 11 21:40:50 volumio sudo[1198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:40:50 volumio sudo[1198]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:40:50 volumio sudo[1198]: pam_unix(sudo:session): session closed for user root Mar 11 21:40:51 volumio volumio[1020]: info: CoreCommandRouter::volumioGetVisibleSources Mar 11 21:40:51 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 21:40:51 volumio volumio[1020]: info: CoreCommandRouter::volumioGetState Mar 11 21:40:51 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 21:40:51 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 21:40:51 volumio sudo[1210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:40:51 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 11 21:40:51 volumio volumio[1020]: info: Listing playlists Mar 11 21:40:51 volumio sudo[1210]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:40:51 volumio sudo[1210]: pam_unix(sudo:session): session closed for user root Mar 11 21:40:51 volumio sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:40:51 volumio sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:40:51 volumio sudo[1213]: pam_unix(sudo:session): session closed for user root Mar 11 21:40:51 volumio volumio[1020]: info: CoreCommandRouter::volumioGetState Mar 11 21:40:51 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 21:40:51 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 21:40:51 volumio sudo[1228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:40:51 volumio sudo[1228]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:40:51 volumio sudo[1228]: pam_unix(sudo:session): session closed for user root Mar 11 21:40:51 volumio sudo[1237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 11 21:40:51 volumio sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:40:51 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Mar 11 21:40:51 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Mar 11 21:40:51 volumio sudo[1237]: pam_unix(sudo:session): session closed for user root Mar 11 21:40:51 volumio volumio[1020]: info: Upmpdcli Daemon Started Mar 11 21:40:52 volumio volumio-streaming-daemon[1133]: Checking variant in static cache Mar 11 21:40:52 volumio volumio[1020]: info: Fetching Streaming Services browse cache Mar 11 21:40:52 volumio volumio[1020]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Mar 11 21:40:52 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 21:40:52 volumio volumio[1020]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Mar 11 21:40:52 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 21:40:54 volumio volumio[1020]: error: error Mar 11 21:40:54 volumio volumio[1020]: error: error Mar 11 21:40:56 volumio volumio-remote-updater[583]: [2019-03-11 21:40:56] [connect] Successful connection Mar 11 21:40:56 volumio volumio-remote-updater[583]: [2019-03-11 21:40:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1552340456 101 Mar 11 21:41:13 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 11 21:41:13 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 21:41:36 volumio volumio[1020]: error: error Mar 11 21:41:38 volumio volumio[1020]: error: error Mar 11 21:41:53 volumio sudo[1696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 11 21:41:53 volumio sudo[1696]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:41:53 volumio sudo[1698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:41:53 volumio sudo[1696]: pam_unix(sudo:session): session closed for user root Mar 11 21:41:53 volumio sudo[1698]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:41:53 volumio sudo[1698]: pam_unix(sudo:session): session closed for user root Mar 11 21:41:54 volumio volumio[1020]: info: CoreCommandRouter::volumioGetVisibleSources Mar 11 21:41:54 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 21:41:54 volumio volumio[1020]: info: CoreCommandRouter::volumioGetState Mar 11 21:41:54 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 21:41:54 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 21:41:54 volumio sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:41:54 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 11 21:41:54 volumio sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:41:54 volumio sudo[1721]: pam_unix(sudo:session): session closed for user root Mar 11 21:41:54 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 11 21:41:54 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 11 21:41:54 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 11 21:41:54 volumio sudo[1730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:41:54 volumio sudo[1730]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:41:54 volumio volumio[1020]: info: Listing playlists Mar 11 21:41:54 volumio sudo[1730]: pam_unix(sudo:session): session closed for user root Mar 11 21:42:26 volumio volumio[1020]: error: error Mar 11 21:42:26 volumio volumio[1020]: error: error Mar 11 21:42:41 volumio sudo[2062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 11 21:42:41 volumio sudo[2062]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:42:41 volumio sudo[2064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:42:41 volumio sudo[2062]: pam_unix(sudo:session): session closed for user root Mar 11 21:42:41 volumio sudo[2064]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:42:41 volumio sudo[2064]: pam_unix(sudo:session): session closed for user root Mar 11 21:42:42 volumio volumio[1020]: info: CoreCommandRouter::volumioGetVisibleSources Mar 11 21:42:42 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 21:42:42 volumio volumio[1020]: info: CoreCommandRouter::volumioGetState Mar 11 21:42:42 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 21:42:42 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 21:42:42 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 11 21:42:42 volumio sudo[2087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:42:42 volumio sudo[2087]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:42:42 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 11 21:42:42 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 11 21:42:42 volumio sudo[2087]: pam_unix(sudo:session): session closed for user root Mar 11 21:42:42 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 11 21:42:42 volumio sudo[2096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:42:42 volumio volumio[1020]: info: Listing playlists Mar 11 21:42:42 volumio sudo[2096]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:42:42 volumio sudo[2096]: pam_unix(sudo:session): session closed for user root Mar 11 21:43:16 volumio volumio[1020]: error: error Mar 11 21:43:18 volumio volumio[1020]: error: error Mar 11 21:43:21 volumio volumio[1020]: info: CoreCommandRouter::volumioGetState Mar 11 21:43:21 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 21:43:21 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 21:43:21 volumio sudo[2380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:43:21 volumio sudo[2380]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:43:21 volumio sudo[2380]: pam_unix(sudo:session): session closed for user root Mar 11 21:43:21 volumio volumio[1020]: info: CoreCommandRouter::volumioGetQueue Mar 11 21:43:21 volumio volumio[1020]: info: CoreStateMachine::getQueue Mar 11 21:43:21 volumio volumio[1020]: info: CorePlayQueue::getQueue Mar 11 21:43:23 volumio volumio[1020]: info: CALLMETHOD: system_controller system enableSSH true Mar 11 21:43:23 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , enableSSH Mar 11 21:43:23 volumio sudo[2403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ssh.service Mar 11 21:43:23 volumio sudo[2403]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:43:23 volumio systemd[1]: Starting OpenBSD Secure Shell server... Mar 11 21:43:23 volumio systemd[1]: Started OpenBSD Secure Shell server. Mar 11 21:43:23 volumio sudo[2403]: pam_unix(sudo:session): session closed for user root Mar 11 21:43:23 volumio sshd[2411]: Server listening on 0.0.0.0 port 22. Mar 11 21:43:23 volumio sshd[2411]: Server listening on :: port 22. Mar 11 21:43:23 volumio sudo[2415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable ssh.service Mar 11 21:43:23 volumio sudo[2415]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:43:24 volumio systemd[1]: Reloading. Mar 11 21:43:24 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Mar 11 21:43:24 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Mar 11 21:43:24 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Mar 11 21:43:24 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Mar 11 21:43:24 volumio systemd[1]: Reloading. Mar 11 21:43:24 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Mar 11 21:43:24 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Mar 11 21:43:24 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Mar 11 21:43:24 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Mar 11 21:43:24 volumio systemd[1]: Reloading. Mar 11 21:43:24 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Mar 11 21:43:24 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Mar 11 21:43:24 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Mar 11 21:43:24 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Mar 11 21:43:24 volumio sudo[2415]: pam_unix(sudo:session): session closed for user root Mar 11 21:43:24 volumio volumio[1020]: info: enable SSH service success Mar 11 21:43:31 volumio volumio[1020]: info: CALLMETHOD: system_controller system enableSSH true Mar 11 21:43:31 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , enableSSH Mar 11 21:43:31 volumio sudo[2512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start ssh.service Mar 11 21:43:31 volumio sudo[2512]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:43:31 volumio systemd[1]: Started OpenBSD Secure Shell server. Mar 11 21:43:31 volumio sudo[2512]: pam_unix(sudo:session): session closed for user root Mar 11 21:43:31 volumio sudo[2520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable ssh.service Mar 11 21:43:31 volumio sudo[2520]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:43:31 volumio systemd[1]: Reloading. Mar 11 21:43:31 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Mar 11 21:43:31 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Mar 11 21:43:31 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Mar 11 21:43:31 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Mar 11 21:43:31 volumio systemd[1]: Reloading. Mar 11 21:43:31 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Mar 11 21:43:31 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Mar 11 21:43:31 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Mar 11 21:43:31 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Mar 11 21:43:31 volumio systemd[1]: Reloading. Mar 11 21:43:31 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Mar 11 21:43:31 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Mar 11 21:43:31 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Mar 11 21:43:31 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Mar 11 21:43:31 volumio sudo[2520]: pam_unix(sudo:session): session closed for user root Mar 11 21:43:31 volumio volumio[1020]: info: enable SSH service success Mar 11 21:43:56 volumio sshd[2632]: pam_unix(sshd:auth): authentication failure; logname= uid=0 euid=0 tty=ssh ruser= rhost=peaberry.eau.wi.charter.com user=root Mar 11 21:43:57 volumio sshd[2632]: Failed password for root from 192.168.1.40 port 33701 ssh2 Mar 11 21:44:03 volumio sshd[2632]: Failed password for root from 192.168.1.40 port 33701 ssh2 Mar 11 21:44:06 volumio volumio[1020]: error: error Mar 11 21:44:06 volumio volumio[1020]: error: error Mar 11 21:44:11 volumio sshd[2632]: Failed password for root from 192.168.1.40 port 33701 ssh2 Mar 11 21:44:16 volumio sshd[2632]: Connection closed by 192.168.1.40 [preauth] Mar 11 21:44:16 volumio sshd[2632]: PAM 2 more authentication failures; logname= uid=0 euid=0 tty=ssh ruser= rhost=peaberry.eau.wi.charter.com user=root Mar 11 21:44:57 volumio volumio[1020]: error: error Mar 11 21:44:57 volumio volumio[1020]: error: error Mar 11 21:45:11 volumio sshd[2985]: Accepted password for volumio from 192.168.1.40 port 33707 ssh2 Mar 11 21:45:11 volumio sshd[2985]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Mar 11 21:45:11 volumio systemd[1]: Starting user-1000.slice. Mar 11 21:45:11 volumio systemd[1]: Created slice user-1000.slice. Mar 11 21:45:11 volumio systemd[1]: Starting User Manager for UID 1000... Mar 11 21:45:11 volumio systemd-logind[590]: New session c1 of user volumio. Mar 11 21:45:11 volumio systemd[1]: Starting Session c1 of user volumio. Mar 11 21:45:11 volumio systemd[1]: Started Session c1 of user volumio. Mar 11 21:45:11 volumio systemd[3281]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Mar 11 21:45:11 volumio systemd[3281]: Starting Paths. Mar 11 21:45:11 volumio systemd[3281]: Reached target Paths. Mar 11 21:45:11 volumio systemd[3281]: Starting Timers. Mar 11 21:45:11 volumio systemd[3281]: Reached target Timers. Mar 11 21:45:11 volumio systemd[3281]: Starting Sockets. Mar 11 21:45:11 volumio systemd[3281]: Reached target Sockets. Mar 11 21:45:11 volumio systemd[3281]: Starting Basic System. Mar 11 21:45:11 volumio systemd[3281]: Reached target Basic System. Mar 11 21:45:11 volumio systemd[3281]: Starting Default. Mar 11 21:45:11 volumio systemd[3281]: Reached target Default. Mar 11 21:45:11 volumio systemd[3281]: Startup finished in 27ms. Mar 11 21:45:11 volumio systemd[1]: Started User Manager for UID 1000. Mar 11 21:45:11 volumio sshd[2985]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory Mar 11 21:45:11 volumio sshd[2985]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory Mar 11 21:45:46 volumio volumio[1020]: error: error Mar 11 21:45:47 volumio volumio[1020]: error: error Mar 11 21:46:37 volumio volumio[1020]: error: error Mar 11 21:46:38 volumio volumio[1020]: error: error Mar 11 21:47:27 volumio volumio[1020]: error: error Mar 11 21:47:27 volumio volumio[1020]: error: error Mar 11 21:48:17 volumio volumio[1020]: error: error Mar 11 21:48:17 volumio volumio[1020]: error: error Mar 11 21:49:06 volumio volumio[1020]: error: error Mar 11 21:49:06 volumio volumio[1020]: error: error Mar 11 21:49:56 volumio volumio[1020]: error: error Mar 11 21:49:57 volumio volumio[1020]: error: error Mar 11 21:50:46 volumio volumio[1020]: error: error Mar 11 21:50:46 volumio volumio[1020]: error: error Mar 11 21:51:38 volumio volumio[1020]: error: error Mar 11 21:51:38 volumio volumio[1020]: error: error Mar 11 21:52:27 volumio volumio[1020]: error: error Mar 11 21:52:27 volumio volumio[1020]: error: error Mar 11 21:53:17 volumio volumio[1020]: error: error Mar 11 21:53:18 volumio volumio[1020]: error: error Mar 11 21:54:07 volumio volumio[1020]: error: error Mar 11 21:54:08 volumio volumio[1020]: error: error Mar 11 21:54:56 volumio sudo[7405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 11 21:54:56 volumio sudo[7405]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:54:56 volumio sudo[7407]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:54:56 volumio sudo[7405]: pam_unix(sudo:session): session closed for user root Mar 11 21:54:56 volumio sudo[7407]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:54:56 volumio sudo[7407]: pam_unix(sudo:session): session closed for user root Mar 11 21:54:57 volumio volumio[1020]: info: CoreCommandRouter::volumioGetVisibleSources Mar 11 21:54:57 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 21:54:57 volumio volumio[1020]: info: CoreCommandRouter::volumioGetState Mar 11 21:54:57 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 21:54:57 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 21:54:57 volumio sudo[7423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:54:57 volumio sudo[7423]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:54:57 volumio sudo[7423]: pam_unix(sudo:session): session closed for user root Mar 11 21:54:57 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 11 21:54:57 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 11 21:54:57 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 11 21:54:57 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 11 21:54:57 volumio sudo[7432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:54:57 volumio sudo[7432]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:54:57 volumio volumio[1020]: info: Listing playlists Mar 11 21:54:57 volumio sudo[7432]: pam_unix(sudo:session): session closed for user root Mar 11 21:54:58 volumio volumio[1020]: error: error Mar 11 21:54:58 volumio volumio[1020]: error: error Mar 11 21:55:07 volumio volumio[1020]: info: CoreCommandRouter::volumioGetState Mar 11 21:55:07 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 21:55:07 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 21:55:07 volumio sudo[7518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 21:55:07 volumio volumio[1020]: info: CoreCommandRouter::volumioGetQueue Mar 11 21:55:07 volumio volumio[1020]: info: CoreStateMachine::getQueue Mar 11 21:55:08 volumio volumio[1020]: info: CorePlayQueue::getQueue Mar 11 21:55:08 volumio sudo[7518]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 21:55:08 volumio sudo[7518]: pam_unix(sudo:session): session closed for user root Mar 11 21:55:45 volumio systemd[1]: Starting Cleanup of Temporary Directories... Mar 11 21:55:45 volumio systemd[1]: Started Cleanup of Temporary Directories. Mar 11 21:55:46 volumio volumio[1020]: error: error Mar 11 21:55:48 volumio volumio[1020]: error: error Mar 11 21:56:37 volumio volumio[1020]: error: error Mar 11 21:56:38 volumio volumio[1020]: error: error Mar 11 21:57:17 volumio sshd[2985]: pam_unix(sshd:session): session closed for user volumio Mar 11 21:57:17 volumio systemd-logind[590]: Removed session c1. Mar 11 21:57:17 volumio systemd[1]: Stopping User Manager for UID 1000... Mar 11 21:57:17 volumio systemd[3281]: Stopping Default. Mar 11 21:57:17 volumio systemd[3281]: Stopped target Default. Mar 11 21:57:17 volumio systemd[3281]: Stopping Basic System. Mar 11 21:57:17 volumio systemd[3281]: Stopped target Basic System. Mar 11 21:57:17 volumio systemd[3281]: Stopping Paths. Mar 11 21:57:17 volumio systemd[3281]: Stopped target Paths. Mar 11 21:57:17 volumio systemd[3281]: Stopping Timers. Mar 11 21:57:17 volumio systemd[3281]: Stopped target Timers. Mar 11 21:57:17 volumio systemd[3281]: Stopping Sockets. Mar 11 21:57:17 volumio systemd[3281]: Stopped target Sockets. Mar 11 21:57:17 volumio systemd[3281]: Starting Shutdown. Mar 11 21:57:17 volumio systemd[3281]: Reached target Shutdown. Mar 11 21:57:17 volumio systemd[3281]: Starting Exit the Session... Mar 11 21:57:17 volumio systemd[3281]: Received SIGRTMIN+24 from PID 8433 (kill). Mar 11 21:57:17 volumio systemd[3284]: pam_unix(systemd-user:session): session closed for user volumio Mar 11 21:57:17 volumio systemd[1]: Stopped User Manager for UID 1000. Mar 11 21:57:17 volumio systemd[1]: Stopping user-1000.slice. Mar 11 21:57:17 volumio systemd[1]: Removed slice user-1000.slice. Mar 11 21:57:26 volumio volumio[1020]: error: error Mar 11 21:57:28 volumio volumio[1020]: error: error Mar 11 21:58:18 volumio volumio[1020]: error: error Mar 11 21:58:18 volumio volumio[1020]: error: error Mar 11 21:59:07 volumio volumio[1020]: error: error Mar 11 21:59:08 volumio volumio[1020]: error: error Mar 11 21:59:57 volumio volumio[1020]: error: error Mar 11 21:59:58 volumio volumio[1020]: error: error Mar 11 22:00:46 volumio volumio[1020]: error: error Mar 11 22:00:47 volumio volumio[1020]: error: error Mar 11 22:01:36 volumio volumio[1020]: error: error Mar 11 22:01:37 volumio volumio[1020]: error: error Mar 11 22:02:26 volumio volumio[1020]: error: error Mar 11 22:02:26 volumio volumio[1020]: error: error Mar 11 22:03:17 volumio volumio[1020]: error: error Mar 11 22:03:17 volumio volumio[1020]: error: error Mar 11 22:03:36 volumio volumio[1020]: info: CoreCommandRouter::volumioGetState Mar 11 22:03:36 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 22:03:36 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:03:37 volumio volumio[1020]: info: CoreCommandRouter::volumioGetQueue Mar 11 22:03:37 volumio volumio[1020]: info: CoreStateMachine::getQueue Mar 11 22:03:37 volumio volumio[1020]: info: CorePlayQueue::getQueue Mar 11 22:03:37 volumio sudo[11082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:03:37 volumio sudo[11082]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:03:37 volumio sudo[11082]: pam_unix(sudo:session): session closed for user root Mar 11 22:03:57 volumio sudo[11233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 11 22:03:57 volumio sudo[11233]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:03:57 volumio sudo[11235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:03:57 volumio sudo[11233]: pam_unix(sudo:session): session closed for user root Mar 11 22:03:57 volumio sudo[11235]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:03:57 volumio sudo[11235]: pam_unix(sudo:session): session closed for user root Mar 11 22:03:57 volumio volumio[1020]: info: CoreCommandRouter::volumioGetVisibleSources Mar 11 22:03:57 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 11 22:03:57 volumio volumio[1020]: info: CoreCommandRouter::volumioGetState Mar 11 22:03:57 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 22:03:57 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:03:57 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 11 22:03:57 volumio sudo[11251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:03:57 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 11 22:03:57 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 11 22:03:57 volumio sudo[11251]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:03:57 volumio sudo[11251]: pam_unix(sudo:session): session closed for user root Mar 11 22:03:57 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 11 22:03:57 volumio volumio[1020]: info: Listing playlists Mar 11 22:03:57 volumio sudo[11260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:03:57 volumio sudo[11260]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:03:57 volumio sudo[11260]: pam_unix(sudo:session): session closed for user root Mar 11 22:04:05 volumio volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 22:04:06 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 11 22:04:06 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 22:04:06 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 11 22:04:06 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:04:06 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:04:06 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:04:06 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:04:06 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:04:06 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:04:06 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:04:06 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 11 22:04:06 volumio volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 22:04:08 volumio volumio[1020]: error: error Mar 11 22:04:08 volumio volumio[1020]: error: error Mar 11 22:04:36 volumio volumio[1020]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Mar 11 22:04:36 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Mar 11 22:04:36 volumio volumio[1020]: info: Volume configurations have been set Mar 11 22:04:36 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 22:04:36 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 22:04:36 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 22:04:36 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 11 22:04:36 volumio volumio[1020]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 11 22:04:36 volumio volumio[1020]: info: Updating Volume Controller Parameters: Device: 1 Name: Allo BOSS Mixer: Digital Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Mar 11 22:04:36 volumio volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 22:04:36 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 11 22:04:36 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 22:04:36 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 11 22:04:37 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:04:37 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:04:37 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:04:37 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:04:37 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:04:37 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:04:37 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:04:37 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 11 22:04:37 volumio volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 22:04:37 volumio volumio[1020]: info: VolumeController:: Volume=100 Mute =false Mar 11 22:04:37 volumio volumio[1020]: info: CoreStateMachine::pushState Mar 11 22:04:37 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 22:04:37 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:04:37 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Mar 11 22:04:37 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:04:37 volumio sudo[11554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:04:37 volumio sudo[11554]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:04:37 volumio sudo[11554]: pam_unix(sudo:session): session closed for user root Mar 11 22:04:45 volumio volumio[1020]: info: CoreCommandRouter::volumioPlay Mar 11 22:04:45 volumio volumio[1020]: UNSET VOLATILE Mar 11 22:04:45 volumio volumio[1020]: info: CoreStateMachine::play index undefined Mar 11 22:04:45 volumio volumio[1020]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 11 22:04:45 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:04:45 volumio volumio[1020]: info: CoreStateMachine::startPlaybackTimer Mar 11 22:04:45 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:04:45 volumio volumio[1020]: info: ControllerMpd::clearAddPlayTracks NAS/Music/_a-L/Andrew Lloyd Webber/2001-The Phantom Of The Opera/1.4 Angel Of Music.flac Mar 11 22:04:45 volumio volumio[1020]: info: ControllerMpd::sendMpdCommand stop Mar 11 22:04:45 volumio volumio[1020]: info: sending command... Mar 11 22:04:45 volumio volumio[1020]: info: parsing response... Mar 11 22:04:45 volumio volumio[1020]: info: ControllerMpd::sendMpdCommand clear Mar 11 22:04:45 volumio volumio[1020]: info: sending command... Mar 11 22:04:45 volumio volumio[1020]: info: Mar 11 22:04:45 volumio volumio[1020]: ---------------------------- MPD announces system playlist update Mar 11 22:04:45 volumio volumio[1020]: info: Ignoring MPD Status Update Mar 11 22:04:45 volumio volumio[1020]: info: parsing response... Mar 11 22:04:45 volumio volumio[1020]: info: ControllerMpd::sendMpdCommand add "NAS/Music/_a-L/Andrew Lloyd Webber/2001-The Phantom Of The Opera/1.4 Angel Of Music.flac" Mar 11 22:04:45 volumio volumio[1020]: info: sending command... Mar 11 22:04:45 volumio volumio[1020]: info: Mar 11 22:04:45 volumio volumio[1020]: info: Mar 11 22:04:45 volumio volumio[1020]: ---------------------------- MPD announces system playlist update Mar 11 22:04:45 volumio volumio[1020]: info: Ignoring MPD Status Update Mar 11 22:04:45 volumio volumio[1020]: info: ------------------------------ 9ms Mar 11 22:04:45 volumio volumio[1020]: info: parsing response... Mar 11 22:04:45 volumio volumio[1020]: info: ControllerMpd::sendMpdCommand play Mar 11 22:04:45 volumio volumio[1020]: info: sending command... Mar 11 22:04:45 volumio volumio[1020]: info: ------------------------------ 6ms Mar 11 22:04:45 volumio volumio[1020]: info: parsing response... Mar 11 22:04:46 volumio volumio[1020]: info: Mar 11 22:04:46 volumio volumio[1020]: ---------------------------- MPD announces state update: player Mar 11 22:04:46 volumio volumio[1020]: info: ControllerMpd::getState Mar 11 22:04:46 volumio volumio[1020]: info: ControllerMpd::sendMpdCommand status Mar 11 22:04:46 volumio volumio[1020]: info: sending command... Mar 11 22:04:46 volumio volumio[1020]: info: Mar 11 22:04:46 volumio volumio[1020]: ---------------------------- MPD announces state update: player Mar 11 22:04:46 volumio volumio[1020]: info: parsing response... Mar 11 22:04:46 volumio volumio[1020]: info: ControllerMpd::getState Mar 11 22:04:46 volumio volumio[1020]: info: ControllerMpd::sendMpdCommand status Mar 11 22:04:46 volumio volumio[1020]: info: ControllerMpd::parseState Mar 11 22:04:46 volumio volumio[1020]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 11 22:04:46 volumio volumio[1020]: info: sending command... Mar 11 22:04:46 volumio volumio[1020]: info: sending command... Mar 11 22:04:46 volumio volumio[1020]: info: parsing response... Mar 11 22:04:46 volumio volumio[1020]: info: parsing response... Mar 11 22:04:46 volumio volumio[1020]: info: ControllerMpd::parseState Mar 11 22:04:46 volumio volumio[1020]: info: ControllerMpd::sendMpdCommand playlistinfo Mar 11 22:04:46 volumio volumio[1020]: info: ControllerMpd::parseTrackInfo Mar 11 22:04:46 volumio volumio[1020]: info: sending command... Mar 11 22:04:46 volumio volumio[1020]: info: ControllerMpd::pushState Mar 11 22:04:46 volumio volumio[1020]: info: CoreCommandRouter::servicePushState Mar 11 22:04:46 volumio volumio[1020]: info: CoreStateMachine::syncState Mar 11 22:04:46 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:04:46 volumio volumio[1020]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":140,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Angel Of Music","artist":"Andrew Lloyd Webber","album":"The Phantom Of The Opera","uri":"NAS/Music/_a-L/Andrew Lloyd Webber/2001-The Phantom Of The Opera/1.4 Angel Of Music.flac","trackType":"flac"} Mar 11 22:04:46 volumio volumio[1020]: info: CURRENT POSITION 0 Mar 11 22:04:46 volumio volumio[1020]: info: CoreStateMachine::syncState stateService play Mar 11 22:04:46 volumio volumio[1020]: info: CoreStateMachine::syncState currentStatus stop Mar 11 22:04:46 volumio volumio[1020]: info: CoreStateMachine::pushState Mar 11 22:04:46 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 22:04:46 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:04:46 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Mar 11 22:04:46 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:04:46 volumio sudo[11627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:04:46 volumio sudo[11627]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:04:46 volumio volumio[1020]: info: ------------------------------ 135ms Mar 11 22:04:46 volumio volumio[1020]: info: parsing response... Mar 11 22:04:46 volumio volumio[1020]: info: ControllerMpd::parseTrackInfo Mar 11 22:04:46 volumio volumio[1020]: info: ControllerMpd::pushState Mar 11 22:04:46 volumio volumio[1020]: info: CoreCommandRouter::servicePushState Mar 11 22:04:46 volumio sudo[11627]: pam_unix(sudo:session): session closed for user root Mar 11 22:04:46 volumio volumio[1020]: info: CoreStateMachine::syncState Mar 11 22:04:46 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:04:46 volumio volumio[1020]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":140,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Angel Of Music","artist":"Andrew Lloyd Webber","album":"The Phantom Of The Opera","uri":"NAS/Music/_a-L/Andrew Lloyd Webber/2001-The Phantom Of The Opera/1.4 Angel Of Music.flac","trackType":"flac"} Mar 11 22:04:46 volumio volumio[1020]: info: CURRENT POSITION 0 Mar 11 22:04:46 volumio volumio[1020]: info: CoreStateMachine::syncState stateService play Mar 11 22:04:46 volumio volumio[1020]: info: CoreStateMachine::syncState currentStatus play Mar 11 22:04:46 volumio volumio[1020]: info: Received an update from plugin. extracting info from payload Mar 11 22:04:46 volumio volumio[1020]: info: CoreStateMachine::pushState Mar 11 22:04:46 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 22:04:46 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:04:46 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Mar 11 22:04:46 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:04:46 volumio volumio[1020]: info: CoreStateMachine::pushState Mar 11 22:04:46 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 22:04:46 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:04:46 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Mar 11 22:04:46 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:04:46 volumio sudo[11636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:04:46 volumio sudo[11636]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:04:46 volumio volumio[1020]: info: ------------------------------ 230ms Mar 11 22:04:46 volumio sudo[11639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:04:46 volumio sudo[11636]: pam_unix(sudo:session): session closed for user root Mar 11 22:04:46 volumio sudo[11639]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:04:46 volumio sudo[11639]: pam_unix(sudo:session): session closed for user root Mar 11 22:04:48 volumio volumio[1020]: info: VolumeController::SetAlsaVolume58 Mar 11 22:04:48 volumio volumio[1020]: info: CoreStateMachine::pushState Mar 11 22:04:48 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 22:04:48 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:04:48 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Mar 11 22:04:48 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:04:48 volumio sudo[11670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:04:48 volumio sudo[11670]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:04:48 volumio sudo[11670]: pam_unix(sudo:session): session closed for user root Mar 11 22:04:49 volumio volumio[1020]: info: VolumeController::SetAlsaVolume63 Mar 11 22:04:49 volumio volumio[1020]: info: CoreStateMachine::pushState Mar 11 22:04:49 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 22:04:49 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:04:49 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Mar 11 22:04:49 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:04:49 volumio sudo[11695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:04:49 volumio sudo[11695]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:04:49 volumio sudo[11695]: pam_unix(sudo:session): session closed for user root Mar 11 22:04:50 volumio volumio[1020]: info: VolumeController::SetAlsaVolume68 Mar 11 22:04:50 volumio volumio[1020]: info: CoreStateMachine::pushState Mar 11 22:04:50 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 22:04:50 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:04:50 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Mar 11 22:04:50 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:04:50 volumio sudo[11705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:04:50 volumio sudo[11705]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:04:50 volumio sudo[11705]: pam_unix(sudo:session): session closed for user root Mar 11 22:04:50 volumio volumio[1020]: info: VolumeController::SetAlsaVolume71 Mar 11 22:04:50 volumio volumio[1020]: info: CoreStateMachine::pushState Mar 11 22:04:50 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 22:04:50 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:04:50 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Mar 11 22:04:50 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:04:50 volumio sudo[11722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:04:50 volumio sudo[11722]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:04:50 volumio sudo[11722]: pam_unix(sudo:session): session closed for user root Mar 11 22:04:52 volumio volumio[1020]: info: VolumeController::SetAlsaVolumemute Mar 11 22:04:52 volumio volumio[1020]: info: CoreStateMachine::pushState Mar 11 22:04:52 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 22:04:52 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:04:52 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Mar 11 22:04:52 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:04:52 volumio sudo[11747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:04:52 volumio sudo[11747]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:04:52 volumio sudo[11747]: pam_unix(sudo:session): session closed for user root Mar 11 22:04:53 volumio volumio[1020]: info: VolumeController::SetAlsaVolumeunmute Mar 11 22:04:53 volumio volumio[1020]: info: CoreStateMachine::pushState Mar 11 22:04:53 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 22:04:53 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:04:53 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Mar 11 22:04:53 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:04:53 volumio sudo[11757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:04:53 volumio sudo[11757]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:04:53 volumio sudo[11757]: pam_unix(sudo:session): session closed for user root Mar 11 22:04:56 volumio volumio[1020]: error: error Mar 11 22:04:57 volumio volumio[1020]: error: error Mar 11 22:05:00 volumio volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 22:05:00 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 11 22:05:00 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 11 22:05:00 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 11 22:05:00 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:05:00 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:05:00 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:05:00 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:05:00 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:05:00 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:05:00 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 11 22:05:00 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 11 22:05:00 volumio volumio[1020]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 11 22:05:46 volumio volumio[1020]: error: error Mar 11 22:05:48 volumio volumio[1020]: error: error Mar 11 22:06:37 volumio volumio[1020]: error: error Mar 11 22:06:38 volumio volumio[1020]: error: error Mar 11 22:07:00 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:07:00 volumio volumio[1020]: info: CorePlayQueue::getTrack 1 Mar 11 22:07:06 volumio volumio[1020]: info: Mar 11 22:07:06 volumio volumio[1020]: ---------------------------- MPD announces state update: player Mar 11 22:07:06 volumio volumio[1020]: info: ControllerMpd::getState Mar 11 22:07:06 volumio volumio[1020]: info: ControllerMpd::sendMpdCommand status Mar 11 22:07:06 volumio volumio[1020]: info: sending command... Mar 11 22:07:06 volumio volumio[1020]: info: parsing response... Mar 11 22:07:06 volumio volumio[1020]: info: ControllerMpd::parseState Mar 11 22:07:06 volumio volumio[1020]: info: ControllerMpd::pushState Mar 11 22:07:06 volumio volumio[1020]: info: CoreCommandRouter::servicePushState Mar 11 22:07:06 volumio volumio[1020]: info: CoreStateMachine::syncState Mar 11 22:07:06 volumio volumio[1020]: info: CoreStateMachine::pushState Mar 11 22:07:06 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 22:07:06 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:07:06 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Mar 11 22:07:06 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:07:06 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:07:06 volumio volumio[1020]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 11 22:07:06 volumio volumio[1020]: info: CURRENT POSITION 0 Mar 11 22:07:06 volumio volumio[1020]: info: CoreStateMachine::syncState stateService stop Mar 11 22:07:06 volumio volumio[1020]: info: CoreStateMachine::syncState currentStatus play Mar 11 22:07:06 volumio volumio[1020]: info: CURRENT POSITION 0 Mar 11 22:07:06 volumio volumio[1020]: info: CURRENT POSITION 1 Mar 11 22:07:06 volumio volumio[1020]: info: END OF QUEUE Mar 11 22:07:06 volumio volumio[1020]: info: CoreStateMachine::pushEmptyState Mar 11 22:07:06 volumio volumio[1020]: info: CoreCommandRouter::volumioPushState Mar 11 22:07:06 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 11 22:07:06 volumio sudo[12697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:07:06 volumio sudo[12697]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:07:06 volumio volumio[1020]: info: CoreStateMachine::stPlaybackTimer Mar 11 22:07:06 volumio sudo[12697]: pam_unix(sudo:session): session closed for user root Mar 11 22:07:06 volumio volumio[1020]: info: ------------------------------ 55ms Mar 11 22:07:06 volumio sudo[12703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:07:06 volumio sudo[12703]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:07:06 volumio sudo[12703]: pam_unix(sudo:session): session closed for user root Mar 11 22:07:26 volumio volumio[1020]: error: error Mar 11 22:07:27 volumio volumio[1020]: error: error Mar 11 22:08:17 volumio volumio[1020]: error: error Mar 11 22:08:18 volumio volumio[1020]: error: error Mar 11 22:09:06 volumio volumio[1020]: error: error Mar 11 22:09:07 volumio volumio[1020]: error: error Mar 11 22:09:56 volumio volumio[1020]: error: error Mar 11 22:09:58 volumio volumio[1020]: error: error Mar 11 22:10:46 volumio volumio[1020]: error: error Mar 11 22:10:48 volumio volumio[1020]: error: error Mar 11 22:11:38 volumio volumio[1020]: error: error Mar 11 22:11:38 volumio volumio[1020]: error: error Mar 11 22:12:26 volumio volumio[1020]: error: error Mar 11 22:12:27 volumio volumio[1020]: error: error Mar 11 22:13:17 volumio volumio[1020]: error: error Mar 11 22:13:17 volumio volumio[1020]: error: error Mar 11 22:14:06 volumio volumio[1020]: error: error Mar 11 22:14:06 volumio volumio[1020]: error: error Mar 11 22:14:57 volumio volumio[1020]: error: error Mar 11 22:14:57 volumio volumio[1020]: error: error Mar 11 22:15:46 volumio volumio[1020]: error: error Mar 11 22:15:47 volumio volumio[1020]: error: error Mar 11 22:16:36 volumio volumio[1020]: error: error Mar 11 22:16:36 volumio volumio[1020]: error: error Mar 11 22:17:26 volumio volumio[1020]: error: error Mar 11 22:17:26 volumio volumio[1020]: error: error Mar 11 22:18:16 volumio volumio[1020]: error: error Mar 11 22:18:16 volumio volumio[1020]: error: error Mar 11 22:19:07 volumio volumio[1020]: error: error Mar 11 22:19:08 volumio volumio[1020]: error: error Mar 11 22:19:56 volumio volumio[1020]: error: error Mar 11 22:19:56 volumio volumio[1020]: error: error Mar 11 22:20:46 volumio volumio[1020]: error: error Mar 11 22:20:47 volumio volumio[1020]: error: error Mar 11 22:21:36 volumio volumio[1020]: error: error Mar 11 22:21:36 volumio volumio[1020]: error: error Mar 11 22:22:28 volumio volumio[1020]: error: error Mar 11 22:22:28 volumio volumio[1020]: error: error Mar 11 22:23:18 volumio volumio[1020]: error: error Mar 11 22:23:18 volumio volumio[1020]: error: error Mar 11 22:24:07 volumio volumio[1020]: error: error Mar 11 22:24:07 volumio volumio[1020]: error: error Mar 11 22:24:58 volumio volumio[1020]: error: error Mar 11 22:24:58 volumio volumio[1020]: error: error Mar 11 22:25:47 volumio volumio[1020]: error: error Mar 11 22:25:48 volumio volumio[1020]: error: error Mar 11 22:26:36 volumio volumio[1020]: error: error Mar 11 22:26:38 volumio volumio[1020]: error: error Mar 11 22:27:26 volumio volumio[1020]: error: error Mar 11 22:27:28 volumio volumio[1020]: error: error Mar 11 22:28:16 volumio volumio[1020]: error: error Mar 11 22:28:18 volumio volumio[1020]: error: error Mar 11 22:29:06 volumio volumio[1020]: error: error Mar 11 22:29:07 volumio volumio[1020]: error: error Mar 11 22:29:57 volumio volumio[1020]: error: error Mar 11 22:29:58 volumio volumio[1020]: error: error Mar 11 22:30:48 volumio volumio[1020]: error: error Mar 11 22:30:48 volumio volumio[1020]: error: error Mar 11 22:31:36 volumio volumio[1020]: error: error Mar 11 22:31:37 volumio volumio[1020]: error: error Mar 11 22:32:26 volumio volumio[1020]: error: error Mar 11 22:32:27 volumio volumio[1020]: error: error Mar 11 22:33:17 volumio volumio[1020]: error: error Mar 11 22:33:18 volumio volumio[1020]: error: error Mar 11 22:34:06 volumio volumio[1020]: error: error Mar 11 22:34:08 volumio volumio[1020]: error: error Mar 11 22:34:56 volumio volumio[1020]: error: error Mar 11 22:34:58 volumio volumio[1020]: error: error Mar 11 22:35:46 volumio volumio[1020]: error: error Mar 11 22:35:47 volumio volumio[1020]: error: error Mar 11 22:36:37 volumio volumio[1020]: error: error Mar 11 22:36:38 volumio volumio[1020]: error: error Mar 11 22:37:26 volumio volumio[1020]: error: error Mar 11 22:37:28 volumio volumio[1020]: error: error Mar 11 22:38:17 volumio volumio[1020]: error: error Mar 11 22:38:18 volumio volumio[1020]: error: error Mar 11 22:39:07 volumio volumio[1020]: error: error Mar 11 22:39:08 volumio volumio[1020]: error: error Mar 11 22:39:56 volumio volumio[1020]: error: error Mar 11 22:39:58 volumio volumio[1020]: error: error Mar 11 22:40:01 volumio wpa_supplicant[910]: wlan0: WPA: Group rekeying completed with 20:aa:4b:60:90:97 [GTK=TKIP] Mar 11 22:40:39 volumio sudo[26717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 11 22:40:39 volumio sudo[26717]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:40:39 volumio sudo[26719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:40:39 volumio sudo[26717]: pam_unix(sudo:session): session closed for user root Mar 11 22:40:39 volumio sudo[26719]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:40:39 volumio sudo[26719]: pam_unix(sudo:session): session closed for user root Mar 11 22:40:39 volumio volumio[1020]: info: CoreCommandRouter::volumioGetState Mar 11 22:40:39 volumio volumio[1020]: info: CoreStateMachine::getState Mar 11 22:40:39 volumio volumio[1020]: info: CorePlayQueue::getTrack 0 Mar 11 22:40:39 volumio volumio[1020]: info: CoreCommandRouter::volumioGetQueue Mar 11 22:40:39 volumio volumio[1020]: info: CoreStateMachine::getQueue Mar 11 22:40:39 volumio sudo[26736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 11 22:40:39 volumio volumio[1020]: info: CorePlayQueue::getQueue Mar 11 22:40:39 volumio sudo[26736]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:40:39 volumio sudo[26736]: pam_unix(sudo:session): session closed for user root Mar 11 22:40:46 volumio volumio[1020]: error: error Mar 11 22:40:47 volumio volumio[1020]: error: error Mar 11 22:40:49 volumio volumio[1020]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Mar 11 22:40:49 volumio volumio[1020]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Mar 11 22:40:49 volumio sudo[26830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Mar 11 22:40:49 volumio sudo[26830]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 11 22:40:49 volumio sudo[26830]: pam_unix(sudo:session): session closed for user root Mar 11 22:40:49 volumio sudo[26847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Mar 11 22:40:49 volumio sudo[26847]: pam_unix(sudo:session): session opened for user root by (uid=0) # cat /var/log/mpd.log --------------- Mar 11 21:39 : zeroconf: No global port, disabling zeroconf Mar 11 21:40 : client: [0] opened from local Mar 11 21:40 : exception: No such directory Mar 11 21:40 : client: [1] opened from 127.0.0.1:41542 Mar 11 22:07 : player: played "NAS/Music/_a-L/Andrew Lloyd Webber/2001-The Phantom Of The Opera/1.4 Angel Of Music.flac"