Fri Sep 28 22:13:03 UTC 2018
Description="'No audio using D50 dac on usbridge'"
# cat /proc/version ---------------
Linux version 3.10.38 (root@debian-cem) (gcc version 4.8.1 (Sourcery CodeBench Lite 2013.11-33) ) #4 SMP PREEMPT Fri Dec 22 11:54:41 IST 2017
# 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="bb50b126b7ce9c472a6d2bc5081c70d6a0cc24de"
VOLUMIO_FE_VERSION="5eef25b064c35dc730df3ef6fd49a4997952bfdf"
VOLUMIO_BE_VERSION="01b5fe34a75e315803f7754605ac102d8defc6c6"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Sep 20 23:26:51 CEST 2018"
VOLUMIO_VERSION="2.459"
VOLUMIO_HARDWARE="sparky"
VOLUMIO_HASH="bb35132501d10722028b261f4f54a619"
# ifconfig ---------------
eth0 Link encap:Ethernet HWaddr 00:17:f7:01:09:85
inet addr:10.0.0.6 Bcast:10.0.0.255 Mask:255.255.255.0
UP BROADCAST RUNNING MULTICAST MTU:1500 Metric:1
RX packets:20337 errors:0 dropped:0 overruns:0 frame:0
TX packets:13876 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:1000
RX bytes:27426417 (26.1 MiB) TX bytes:1446284 (1.3 MiB)
Interrupt:32
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:1398 errors:0 dropped:0 overruns:0 frame:0
TX packets:1398 errors:0 dropped:0 overruns:0 carrier:0
collisions:0 txqueuelen:0
RX bytes:152446 (148.8 KiB) TX bytes:152446 (148.8 KiB)
# iwconfig ---------------
lo no wireless extensions.
eth0 no wireless extensions.
# aplay -l ---------------
**** List of PLAYBACK Hardware Devices ****
card 0: atm7059link [atm7059_link], device 0: ATC2603C PCM atc2603c-dai-0 []
Subdevices: 1/1
Subdevice #0: subdevice #0
card 0: atm7059link [atm7059_link], device 1: HDMI PCM atm7059-hdmi-dai-1 []
Subdevices: 1/1
Subdevice #0: subdevice #0
card 0: atm7059link [atm7059_link], device 2: SPDIF PCM atm7059-spdif-dai-2 []
Subdevices: 1/1
Subdevice #0: subdevice #0
card 1: D50 [D50], device 0: USB Audio [USB Audio]
Subdevices: 0/1
Subdevice #0: subdevice #0
# ps -ef ---------------
UID PID PPID C STIME TTY TIME CMD
root 1 0 1 22:09 ? 00:00:03 /sbin/init
root 2 0 0 22:09 ? 00:00:00 [kthreadd]
root 3 2 0 22:09 ? 00:00:00 [ksoftirqd/0]
root 4 2 0 22:09 ? 00:00:01 [kworker/0:0]
root 5 2 0 22:09 ? 00:00:00 [kworker/0:0H]
root 6 2 0 22:09 ? 00:00:00 [kworker/u8:0]
root 7 2 0 22:09 ? 00:00:00 [migration/0]
root 8 2 0 22:09 ? 00:00:00 [rcu_preempt]
root 9 2 0 22:09 ? 00:00:00 [rcu_bh]
root 10 2 0 22:09 ? 00:00:00 [rcu_sched]
root 11 2 0 22:09 ? 00:00:00 [migration/1]
root 12 2 0 22:09 ? 00:00:00 [ksoftirqd/1]
root 13 2 0 22:09 ? 00:00:00 [kworker/1:0]
root 14 2 0 22:09 ? 00:00:00 [kworker/1:0H]
root 15 2 0 22:09 ? 00:00:00 [migration/2]
root 16 2 0 22:09 ? 00:00:00 [ksoftirqd/2]
root 17 2 0 22:09 ? 00:00:00 [kworker/2:0]
root 18 2 0 22:09 ? 00:00:00 [kworker/2:0H]
root 19 2 0 22:09 ? 00:00:00 [migration/3]
root 20 2 0 22:09 ? 00:00:00 [ksoftirqd/3]
root 21 2 0 22:09 ? 00:00:00 [kworker/3:0]
root 22 2 0 22:09 ? 00:00:00 [kworker/3:0H]
root 23 2 0 22:09 ? 00:00:00 [khelper]
root 24 2 0 22:09 ? 00:00:00 [kdevtmpfs]
root 25 2 0 22:09 ? 00:00:00 [netns]
root 26 2 0 22:09 ? 00:00:00 [kworker/3:1]
root 27 2 0 22:09 ? 00:00:00 [kworker/0:1]
root 28 2 0 22:09 ? 00:00:00 [kworker/2:1]
root 29 2 0 22:09 ? 00:00:00 [kworker/1:1]
root 30 2 0 22:09 ? 00:00:00 [kworker/u8:1]
root 51 2 0 22:09 ? 00:00:00 [writeback]
root 52 2 0 22:09 ? 00:00:00 [bioset]
root 53 2 0 22:09 ? 00:00:00 [kblockd]
root 54 2 0 22:09 ? 00:00:00 [irq/215-atc2603]
root 55 2 0 22:09 ? 00:00:00 [b0200000.spi]
root 56 2 0 22:09 ? 00:00:00 [khubd]
root 57 2 0 22:09 ? 00:00:00 [ion_fb]
root 58 2 0 22:09 ? 00:00:00 [ion_system]
root 59 2 0 22:09 ? 00:00:00 [cfg80211]
root 60 2 0 22:09 ? 00:00:00 [cfinteractive]
root 61 2 0 22:09 ? 00:00:00 [rpciod]
root 62 2 0 22:09 ? 00:00:00 [khungtaskd]
root 63 2 0 22:09 ? 00:00:00 [kswapd0]
root 64 2 0 22:09 ? 00:00:00 [ksmd]
root 65 2 0 22:09 ? 00:00:00 [fsnotify_mark]
root 66 2 0 22:09 ? 00:00:00 [nfsiod]
root 67 2 0 22:09 ? 00:00:00 [cifsiod]
root 68 2 0 22:09 ? 00:00:00 [crypto]
root 85 2 0 22:09 ? 00:00:00 [atm705a-hdmi-hd]
root 86 2 0 22:09 ? 00:00:00 [aotg_start_mon_]
root 87 2 0 22:09 ? 00:00:00 [kpsmoused]
root 88 2 0 22:09 ? 00:00:00 [dm_bufio_cache]
root 89 2 0 22:09 ? 00:00:00 [host_add_work0]
root 90 2 0 22:09 ? 00:00:00 [host_add_work2]
root 91 2 0 22:09 ? 00:00:00 [binder]
root 92 2 0 22:09 ? 00:00:00 [kworker/0:2]
root 93 2 0 22:09 ? 00:00:00 [kworker/u8:2]
root 94 2 0 22:09 ? 00:00:00 [kworker/u8:3]
root 95 2 0 22:09 ? 00:00:00 [kworker/u8:4]
root 96 2 0 22:09 ? 00:00:00 [krfcommd]
root 97 2 0 22:09 ? 00:00:01 [mmcqd/0]
root 98 2 0 22:09 ? 00:00:00 [owlfb]
root 99 2 0 22:09 ? 00:00:00 [deferwq]
root 100 2 0 22:09 ? 00:00:00 [atc260x_charger]
root 101 2 0 22:09 ? 00:00:00 [usb_monitor]
root 128 2 0 22:09 ? 00:00:00 [kworker/0:1H]
root 132 2 0 22:09 ? 00:00:00 [jbd2/mmcblk0p2-]
root 133 2 0 22:09 ? 00:00:00 [ext4-dio-unwrit]
root 139 2 0 22:09 ? 00:00:01 [loop0]
root 142 2 0 22:09 ? 00:00:00 [kworker/1:1H]
root 160 2 0 22:10 ? 00:00:00 [jbd2/mmcblk0p3-]
root 161 2 0 22:10 ? 00:00:00 [ext4-dio-unwrit]
root 168 2 0 22:10 ? 00:00:00 [kworker/3:1H]
root 172 2 0 22:10 ? 00:00:00 [kworker/2:1H]
root 182 2 0 22:10 ? 00:00:00 [kauditd]
root 191 2 0 22:10 ? 00:00:00 [kworker/1:2]
root 202 1 1 22:10 ? 00:00:02 /lib/systemd/systemd-journald
root 225 1 0 22:10 ? 00:00:00 /lib/systemd/systemd-udevd
root 253 2 0 22:10 ? 00:00:00 [atv5201-IRKEYPA]
root 255 2 0 22:10 ? 00:00:00 [oo]
root 256 2 0 22:10 ? 00:00:00 [kethernet_resum]
root 258 2 0 22:10 ? 00:00:00 [ethernet_work_q]
root 259 2 0 22:10 ? 00:00:00 [phy_detect_work]
root 271 2 0 22:10 ? 00:00:00 [atc260x_gauge_w]
root 277 2 0 22:10 ? 00:00:00 [pvr_timer]
root 428 1 0 22:10 ? 00:00:00 /sbin/rpcbind -w
statd 438 1 0 22:10 ? 00:00:00 /sbin/rpc.statd
root 450 1 0 22:10 ? 00:00:00 /usr/sbin/rpc.idmapd
root 452 1 1 22:10 ? 00:00:01 /usr/sbin/haveged --Foreground --verbose=1 --write=1024
volumio 455 1 0 22:10 ? 00:00:00 /usr/bin/udisks-glue --foreground
volumio 457 1 0 22:10 ? 00:00:00 /usr/local/bin/volumio-remote-updater
root 462 1 0 22:10 ? 00:00:00 /lib/systemd/systemd-logind
avahi 486 1 0 22:10 ? 00:00:00 avahi-daemon: running [volumio.local]
message+ 498 1 0 22:10 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation
root 519 1 0 22:10 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I
avahi 542 486 0 22:10 ? 00:00:00 avahi-daemon: chroot helper
ntp 543 1 0 22:10 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 100:104
root 564 1 0 22:10 ? 00:00:00 /usr/lib/udisks/udisks-daemon --no-debug
root 572 1 0 22:10 tty1 00:00:00 /sbin/agetty --noclear tty1 linux
root 573 1 0 22:10 ttyS3 00:00:00 /sbin/agetty --keep-baud 115200 38400 9600 ttyS3 vt102
root 577 564 0 22:10 ? 00:00:00 udisks-daemon: not polling any devices
root 591 1 0 22:10 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug
root 629 1 0 22:10 ? 00:00:00 dhcpcd eth0
root 676 2 0 22:10 ? 00:00:00 [aotg_dev0_onoff]
root 677 2 0 22:10 ? 00:00:00 [aotg_dev1_onoff]
volumio 741 1 11 22:10 ? 00:00:19 /usr/local/bin/node /volumio/index.js
root 752 1 0 22:10 ? 00:00:00 /usr/sbin/nmbd -D
root 753 752 0 22:10 ? 00:00:00 /usr/sbin/nmbd -D
root 767 1 0 22:10 ? 00:00:00 /usr/sbin/winbindd
root 769 767 0 22:10 ? 00:00:00 /usr/sbin/winbindd
root 783 2 0 22:10 ? 00:00:00 [kworker/3:2]
root 795 1 0 22:10 ? 00:00:00 /usr/sbin/smbd -D
root 800 767 0 22:10 ? 00:00:00 /usr/sbin/winbindd
root 801 767 0 22:10 ? 00:00:00 /usr/sbin/winbindd
root 802 795 0 22:10 ? 00:00:00 /usr/sbin/smbd -D
root 836 2 0 22:10 ? 00:00:00 [cifsd]
volumio 841 741 0 22:10 ? 00:00:00 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 847 841 1 22:10 ? 00:00:02 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 848 841 1 22:10 ? 00:00:02 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 853 841 1 22:10 ? 00:00:02 /bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio 868 1 0 22:10 ? 00:00:00 /bin/bash /data/plugins/music_service/volspotconnect2/startconnect.sh
volumio 869 868 0 22:10 ? 00:00:00 ./librespot -b 320 -c /tmp --name Volumio --backend alsa --device plughw:1 --disable-audio-cache --initial-volume 33
volumio 905 1 0 22:10 ? 00:00:00 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf
root 1499 2 0 22:11 ? 00:00:00 [kworker/2:2]
mpd 1501 1 3 22:11 ? 00:00:03 /usr/bin/mpd --no-daemon
volumio 1517 1 0 22:11 ? 00:00:00 /usr/local/bin/shairport-sync
volumio 1955 741 0 22:13 ? 00:00:00 /bin/sh -c /usr/local/bin/node /volumio/logsubmit.js 'No audio using D50 dac on usbridge'
volumio 1956 1955 48 22:13 ? 00:00:00 /usr/local/bin/node /volumio/logsubmit.js No audio using D50 dac on usbridge
volumio 1981 1956 0 22:13 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1
volumio 1982 1981 0 22:13 ? 00:00:00 ps -ef
# sudo journalctl -p 7 ---------------
-- Logs begin at Fri 2018-09-28 22:09:30 UTC, end at Fri 2018-09-28 22:13:03 UTC. --
Sep 28 22:09:30 volumio systemd-journal[202]: Runtime journal is using 4.0M (max allowed 30.0M, trying to leave 74.2M free of 490.5M available → current limit 30.0M).
Sep 28 22:09:30 volumio kernel: Booting Linux on physical CPU 0x0
Sep 28 22:09:30 volumio kernel: Initializing cgroup subsys cpu
Sep 28 22:09:30 volumio kernel: Initializing cgroup subsys cpuacct
Sep 28 22:09:30 volumio kernel: Linux version 3.10.38 (root@debian-cem) (gcc version 4.8.1 (Sourcery CodeBench Lite 2013.11-33) ) #4 SMP PREEMPT Fri Dec 22 11:54:41 IST 2017
Sep 28 22:09:30 volumio kernel: CPU: ARMv7 Processor [414fc091] revision 1 (ARMv7), cr=10c53c7d
Sep 28 22:09:30 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Sep 28 22:09:30 volumio kernel: Machine: gs705a, model: Actions GS705A board
Sep 28 22:09:30 volumio kernel: bootconsole [earlycon0] enabled
Sep 28 22:09:30 volumio kernel: owl_reserve: pyhsical memory size 1073741824 bytes, end @0x2f800000
Sep 28 22:09:30 volumio kernel: find owl_fb_size=0x800000
Sep 28 22:09:30 volumio kernel: find owl_kinfo_size=0x100000
Sep 28 22:09:30 volumio kernel: find owl_ion0_size=0x100000
Sep 28 22:09:30 volumio kernel: find owl_ion1_size=0xc000000
Sep 28 22:09:30 volumio kernel: ion_reserve: ion_fb reserved base 2ee00000 size 1048576
Sep 28 22:09:30 volumio kernel: Reserved memory 193MB
Sep 28 22:09:30 volumio kernel: FB: 0x2f000000, 8MB
KINFO: 0x2ef00000, 1MB
ION0: 0x2ee00000, 1MB
ION1: 0x22e00000, 192MB
Sep 28 22:09:30 volumio kernel: cma: CMA: reserved 192 MiB at 22c00000
Sep 28 22:09:30 volumio kernel: Memory policy: ECC disabled, Data cache writealloc
Sep 28 22:09:30 volumio kernel: On node 0 totalpages: 262144
Sep 28 22:09:30 volumio kernel: free_area_init_node: node 0, pgdat c0d3de40, node_mem_map c0ead000
Sep 28 22:09:30 volumio kernel: Normal zone: 1520 pages used for memmap
Sep 28 22:09:30 volumio kernel: Normal zone: 0 pages reserved
Sep 28 22:09:30 volumio kernel: Normal zone: 194560 pages, LIFO batch:31
Sep 28 22:09:30 volumio kernel: HighMem zone: 528 pages used for memmap
Sep 28 22:09:30 volumio kernel: HighMem zone: 67584 pages, LIFO batch:15
Sep 28 22:09:30 volumio kernel: owl_smp_init_cpus(): ncores 4
Sep 28 22:09:30 volumio kernel: PERCPU: Embedded 9 pages/cpu @c16c1000 s13824 r8192 d14848 u36864
Sep 28 22:09:30 volumio kernel: pcpu-alloc: s13824 r8192 d14848 u36864 alloc=9*4096
Sep 28 22:09:30 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
Sep 28 22:09:30 volumio kernel: Built 1 zonelists in Zone order, mobility grouping on. Total pages: 260624
Sep 28 22:09:30 volumio kernel: Kernel command line: console=ttyS3 earlyprintk clk_ignore_unused selinux=0 scandelay console=ttyS3,115200n8 console=tty0 loglevel=0 real_rootflag=rw root=/dev/mmcblk0p2 rootwait imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh boot_dev=sd2
Sep 28 22:09:30 volumio kernel: boot_mode_process
Sep 28 22:09:30 volumio kernel: PID hash table entries: 4096 (order: 2, 16384 bytes)
Sep 28 22:09:30 volumio kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
Sep 28 22:09:30 volumio kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
Sep 28 22:09:30 volumio kernel: allocated 2097152 bytes of page_cgroup
Sep 28 22:09:30 volumio kernel: please try 'cgroup_disable=memory' option if you don't want memory cgroups
Sep 28 22:09:30 volumio kernel: Memory: 1024MB = 1024MB total
Sep 28 22:09:30 volumio kernel: Memory: 811148k/811148k available, 237428k reserved, 270336K highmem
Sep 28 22:09:30 volumio kernel: Virtual kernel memory layout:
vector : 0xffff0000 - 0xffff1000 ( 4 kB)
fixmap : 0xfff00000 - 0xfffe0000 ( 896 kB)
vmalloc : 0xf0000000 - 0xff000000 ( 240 MB)
lowmem : 0xc0000000 - 0xef800000 ( 760 MB)
pkmap : 0xbfe00000 - 0xc0000000 ( 2 MB)
modules : 0xbf000000 - 0xbfe00000 ( 14 MB)
.text : 0xc0008000 - 0xc0c15ae4 (12343 kB)
.init : 0xc0c16000 - 0xc0c8c600 ( 474 kB)
.data : 0xc0c8e000 - 0xc0d5ac74 ( 820 kB)
.bss : 0xc0d5ac74 - 0xc0eacfb4 (1353 kB)
Sep 28 22:09:30 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Sep 28 22:09:30 volumio kernel: Preemptible hierarchical RCU implementation.
Sep 28 22:09:30 volumio kernel: NR_IRQS:247
Sep 28 22:09:30 volumio kernel: sched_clock: 32 bits at 24MHz, resolution 41ns, wraps every 178956ms
Sep 28 22:09:30 volumio kernel: Console: colour dummy device 80x30
Sep 28 22:09:30 volumio kernel: console [tty0] enabled, bootconsole disabled
Sep 28 22:09:30 volumio kernel: kmemleak: Kernel memory leak detector disabled
Sep 28 22:09:30 volumio kernel: Calibrating delay loop... 1196.85 BogoMIPS (lpj=5984256)
Sep 28 22:09:30 volumio kernel: pid_max: default: 32768 minimum: 301
Sep 28 22:09:30 volumio kernel: Security Framework initialized
Sep 28 22:09:30 volumio kernel: SELinux: Disabled at boot.
Sep 28 22:09:30 volumio kernel: AppArmor: AppArmor disabled by boot time parameter
Sep 28 22:09:30 volumio kernel: Mount-cache hash table entries: 512
Sep 28 22:09:30 volumio kernel: Initializing cgroup subsys debug
Sep 28 22:09:30 volumio kernel: Initializing cgroup subsys memory
Sep 28 22:09:30 volumio kernel: Initializing cgroup subsys freezer
Sep 28 22:09:30 volumio kernel: CPU: Testing write buffer coherency: ok
Sep 28 22:09:30 volumio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
Sep 28 22:09:30 volumio kernel: owl_smp_prepare_cpus(max_cpus:4)
Sep 28 22:09:30 volumio kernel: Setting up static identity map for 0xc07e6a90 - 0xc07e6b50
Sep 28 22:09:30 volumio kernel: owl_l2x0_init()
Sep 28 22:09:30 volumio kernel: L310 cache controller enabled
Sep 28 22:09:30 volumio kernel: l2x0: 16 ways, CACHE_ID 0x410080c9, AUX_CTRL 0x3e050000, Cache size: 524288 B
Sep 28 22:09:30 volumio kernel: ftrace: Allocated trace_printk buffers
Sep 28 22:09:30 volumio kernel: cpu:1
Sep 28 22:09:30 volumio kernel: po 1
Sep 28 22:09:30 volumio kernel: wu 1
Sep 28 22:09:30 volumio kernel: CPU1: Booted secondary processor
Sep 28 22:09:30 volumio kernel: pr:-1
Sep 28 22:09:30 volumio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
Sep 28 22:09:30 volumio kernel: cpu:2
Sep 28 22:09:30 volumio kernel: po 2
Sep 28 22:09:30 volumio kernel: wu 2
Sep 28 22:09:30 volumio kernel: CPU2: Booted secondary processor
Sep 28 22:09:30 volumio kernel: pr:-1
Sep 28 22:09:30 volumio kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
Sep 28 22:09:30 volumio kernel: cpu:3
Sep 28 22:09:30 volumio kernel: po 3
Sep 28 22:09:30 volumio kernel: wu 3
Sep 28 22:09:30 volumio kernel: CPU3: Booted secondary processor
Sep 28 22:09:30 volumio kernel: pr:-1
Sep 28 22:09:30 volumio kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
Sep 28 22:09:30 volumio kernel: Brought up 4 CPUs
Sep 28 22:09:30 volumio kernel: SMP: Total of 4 processors activated (4787.40 BogoMIPS).
Sep 28 22:09:30 volumio kernel: CPU: All CPU(s) started in SVC mode.
Sep 28 22:09:30 volumio kernel: devtmpfs: initialized
Sep 28 22:09:30 volumio kernel: pinctrl core: initialized pinctrl subsystem
Sep 28 22:09:30 volumio kernel: regulator-dummy: no parameters
Sep 28 22:09:30 volumio kernel: NET: Registered protocol family 16
Sep 28 22:09:30 volumio kernel: DMA: preallocated 14336 KiB pool for atomic coherent allocations
Sep 28 22:09:30 volumio kernel: init sirqs
Sep 28 22:09:30 volumio kernel: owl_gpio_init()
Sep 28 22:09:30 volumio kernel:
sparky sbc spi0 registration Calling
Sep 28 22:09:30 volumio kernel: owl_gpio_probe()
Sep 28 22:09:30 volumio kernel: owl gpio bank0 - irq68
Sep 28 22:09:30 volumio kernel: owl gpio bank1 - irq69
Sep 28 22:09:30 volumio kernel: owl gpio bank2 - irq70
Sep 28 22:09:30 volumio kernel: owl gpio bank3 - irq71
Sep 28 22:09:30 volumio kernel: owl gpio bank4 - irq72
Sep 28 22:09:30 volumio kernel: owl_sirq_probe()
Sep 28 22:09:30 volumio kernel: owl sirq bank0 - irq45
Sep 28 22:09:30 volumio kernel: owl sirq bank1 - irq46
Sep 28 22:09:30 volumio kernel: owl sirq bank2 - irq47
Sep 28 22:09:30 volumio kernel: sirq map 45 -> 213
Sep 28 22:09:30 volumio kernel: sirq map 46 -> 214
Sep 28 22:09:30 volumio kernel: sirq map 47 -> 215
Sep 28 22:09:30 volumio kernel: owl_board_init()
Sep 28 22:09:30 volumio kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 1 watchpoint registers.
Sep 28 22:09:30 volumio kernel: hw-breakpoint: maximum watchpoint size is 4 bytes.
Sep 28 22:09:30 volumio kernel: free reserve pages 256 to buddy system
Sep 28 22:09:30 volumio kernel: owl_common_init()
Sep 28 22:09:30 volumio kernel: [PowerGate] name: 'dma', on: 0, before SPS_PG_CTL: 0x703360
Sep 28 22:09:30 volumio kernel: [PowerGate] name: 'dma', on: 0, after SPS_PG_CTL: 0x703260
Sep 28 22:09:30 volumio kernel: pwm-owl b01b0050.pwm: Probing...
Sep 28 22:09:30 volumio kernel: dmm debugfs support
Sep 28 22:09:30 volumio kernel: i2c i2c-2: of_i2c: invalid reg on /i2c@b0178000/mpu6515@68
Sep 28 22:09:30 volumio kernel: bio: create slab at 0
Sep 28 22:09:30 volumio kernel: owl_pwm_bl_probe: name = backlight.2
Sep 28 22:09:30 volumio kernel: owl_dma_init
Sep 28 22:09:30 volumio kernel: owl_dma_probe
Sep 28 22:09:30 volumio kernel: [PowerGate] name: 'dma', on: 1, before SPS_PG_CTL: 0x702260
Sep 28 22:09:30 volumio kernel: [PowerGate] name: 'dma', on: 1, after SPS_PG_CTL: 0x703360
Sep 28 22:09:30 volumio kernel: owl_dma b0260000.dma: actions AHB DMA Controller ( cpy slave memset ), 12 channels
Sep 28 22:09:30 volumio kernel: atc260x_i2c 0-0065: Probing...
Sep 28 22:09:30 volumio kernel: atc260x_i2c 0-0065: atc260x_core_dev_init() enter
Sep 28 22:09:30 volumio kernel: atc260x_i2c 0-0065: set bdg ctl, ret=0 value=0xd5
Sep 28 22:09:30 volumio kernel: atc260x_i2c 0-0065: detect PMU chip type 1 ver A
Sep 28 22:09:30 volumio kernel: atc260x_i2c 0-0065: PMU root IRQ 215
Sep 28 22:09:30 volumio kernel: atc260x_i2c 0-0065: icm_ohm not config, use default value
Sep 28 22:09:30 volumio kernel: atc260x_i2c 0-0065: auxadc icm_ohm = 20
Sep 28 22:09:30 volumio kernel: atc260x-regulator atc2603c-dcdc1.1: Probing atc2603c-dcdc1, id=1
Sep 28 22:09:30 volumio kernel: dcdc1: 700 <--> 1400 mV at 975 mV
Sep 28 22:09:30 volumio kernel: atc260x-regulator atc2603c-dcdc2.2: Probing atc2603c-dcdc2, id=2
Sep 28 22:09:30 volumio kernel: dcdc2: 1300 <--> 2150 mV at 1350 mV
Sep 28 22:09:30 volumio kernel: atc260x-regulator atc2603c-dcdc3.3: Probing atc2603c-dcdc3, id=3
Sep 28 22:09:30 volumio kernel: dcdc3: 2600 <--> 3300 mV at 3100 mV
Sep 28 22:09:30 volumio kernel: atc260x-regulator atc2603c-ldo1.1: Probing atc2603c-ldo1, id=1
Sep 28 22:09:30 volumio kernel: ldo1: 2800 mV
Sep 28 22:09:30 volumio kernel: atc260x-regulator atc2603c-ldo2.2: Probing atc2603c-ldo2, id=2
Sep 28 22:09:30 volumio kernel: ldo2: 2600 <--> 3300 mV at 3100 mV
Sep 28 22:09:30 volumio kernel: atc260x-regulator atc2603c-ldo3.3: Probing atc2603c-ldo3, id=3
Sep 28 22:09:30 volumio kernel: ldo3: 1500 <--> 2000 mV at 1800 mV
Sep 28 22:09:30 volumio kernel: atc260x-regulator atc2603c-ldo5.5: Probing atc2603c-ldo5, id=5
Sep 28 22:09:30 volumio kernel: ldo5: 2600 <--> 3300 mV at 3100 mV
Sep 28 22:09:30 volumio kernel: atc260x-regulator atc2603c-ldo6.6: Probing atc2603c-ldo6, id=6
Sep 28 22:09:30 volumio kernel: ldo6: 700 <--> 1400 mV at 1100 mV
Sep 28 22:09:30 volumio kernel: atc260x-regulator atc2603c-ldo7.7: Probing atc2603c-ldo7, id=7
Sep 28 22:09:30 volumio kernel: ldo7: 1800 mV
Sep 28 22:09:30 volumio kernel: atc260x-regulator atc2603c-ldo8.8: Probing atc2603c-ldo8, id=8
Sep 28 22:09:30 volumio kernel: ldo8: 2300 <--> 3300 mV at 3100 mV
Sep 28 22:09:30 volumio kernel: atc260x-regulator atc2603c-ldo11.11: Probing atc2603c-ldo11, id=11
Sep 28 22:09:30 volumio kernel: ldo11: 2600 <--> 3300 mV at 3100 mV
Sep 28 22:09:30 volumio kernel: atc260x-switch-ldo atc2603c-switch1.1: Probing atc2603c-switch1, id=1
Sep 28 22:09:30 volumio kernel: atc260x-switch-ldo atc2603c-switch1.1: pmic_ver=0
Sep 28 22:09:30 volumio kernel: [SWITCH1] set switch 1 to ldo mode Version D!
Sep 28 22:09:30 volumio kernel: switch1: 3000 <--> 3300 mV at 3100 mV
Sep 28 22:09:30 volumio kernel: atc260x-ext-pwm-dcdc atc2603c-ext-pwm-dcdc1.1: Probing atc2603c-ext-pwm-dcdc1
Sep 28 22:09:30 volumio kernel: atc260x-ext-pwm-dcdc atc2603c-ext-pwm-dcdc1.1: table_len = 15
Sep 28 22:09:30 volumio kernel: atc260x-ext-pwm-dcdc atc2603c-ext-pwm-dcdc1.1: got init voltage selector 8
Sep 28 22:09:30 volumio kernel: vdd-gpu-dcdc: 900 <--> 1250 mV at 1100 mV normal
Sep 28 22:09:30 volumio kernel: atc260x-ext-pwm-dcdc atc2603c-ext-pwm-dcdc2.2: Probing atc2603c-ext-pwm-dcdc2
Sep 28 22:09:30 volumio kernel: atc260x-ext-pwm-dcdc atc2603c-ext-pwm-dcdc2.2: table_len = 15
Sep 28 22:09:30 volumio kernel: atc260x-ext-pwm-dcdc atc2603c-ext-pwm-dcdc2.2: got init voltage selector 6
Sep 28 22:09:30 volumio kernel: vdd-core-dcdc: 900 <--> 1250 mV at 1050 mV normal
Sep 28 22:09:30 volumio kernel: atc260x_i2c 0-0065: atc260x_core_dev_init() exit
Sep 28 22:09:30 volumio kernel: atc260x-pm atc2603c-pm.0: Probing...
Sep 28 22:09:30 volumio kernel: atc260x-pm atc2603c-pm.0: _set_s2_mode() can not get of_property s2_mode
Sep 28 22:09:30 volumio kernel: atc260x-pm atc2603c-pm.0: _atc260x_pm_set_wakeup_src_inner() enter, mask=0x7fff src=0x706
Sep 28 22:09:30 volumio kernel: atc260x-pm atc2603c-pm.0: _atc260x_pm_set_wakeup_src_inner() setup reg=0x0 msk=0xffe0 val=0xe0c0 readback=0xe0cb
Sep 28 22:09:30 volumio kernel: atc260x-pm atc2603c-pm.0: _atc260x_pm_set_wakeup_src_inner() setup reg=0x3 msk=0xc val=0x0 readback=0x4280
Sep 28 22:09:30 volumio kernel: atc260x-pm atc2603c-pm.0: _atc260x_pm_set_wakeup_src_inner() enter, mask=0x8 src=0x0
Sep 28 22:09:30 volumio kernel: atc260x-pm atc2603c-pm.0: _atc260x_pm_set_wakeup_src_inner() setup reg=0x0 msk=0x100 val=0x0 readback=0xe0cb
Sep 28 22:09:30 volumio kernel: atc260x-pm atc2603c-pm.0: _clear_status() no need to restore old alarm
Sep 28 22:09:30 volumio kernel: atc260x-pm atc2603c-pm.0: got support_adaptor_type: 3
Sep 28 22:09:30 volumio kernel: atc260x-pm atc2603c-pm.0: translated wakeup falgs: 0x0
Sep 28 22:09:30 volumio kernel: [PM] set pmic suspend ops 0xc0cdae10
Sep 28 22:09:30 volumio kernel: atc260x-pm atc2603c-pm.0: #####atc260x_pm_probe PMU_SYS_CTL0:0xe0cb ####
Sep 28 22:09:30 volumio kernel: SCSI subsystem initialized
Sep 28 22:09:30 volumio kernel: asoc_spi0 b0200000.spi: master is unqueued, this is deprecated
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver usbfs
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver hub
Sep 28 22:09:30 volumio kernel: usbcore: registered new device driver usb
Sep 28 22:09:30 volumio kernel: media: Linux media interface: v0.10
Sep 28 22:09:30 volumio kernel: Linux video capture interface: v2.00
Sep 28 22:09:30 volumio kernel: owl_ion_probe: add heap ion_fb
Sep 28 22:09:30 volumio kernel: owl_ion_probe: add heap ion_pmem
Sep 28 22:09:30 volumio kernel: owl_ion_probe: add heap ion_system
Sep 28 22:09:30 volumio kernel: Advanced Linux Sound Architecture Driver Initialized.
Sep 28 22:09:30 volumio kernel: Bluetooth: Core ver 2.16
Sep 28 22:09:30 volumio kernel: NET: Registered protocol family 31
Sep 28 22:09:30 volumio kernel: Bluetooth: HCI device and connection manager initialized
Sep 28 22:09:30 volumio kernel: Bluetooth: HCI socket layer initialized
Sep 28 22:09:30 volumio kernel: Bluetooth: L2CAP socket layer initialized
Sep 28 22:09:30 volumio kernel: Bluetooth: SCO socket layer initialized
Sep 28 22:09:30 volumio kernel: cfg80211: Calling CRDA to update world regulatory domain
Sep 28 22:09:30 volumio kernel: Switching to clocksource timer0
Sep 28 22:09:30 volumio kernel: FS-Cache: Loaded
Sep 28 22:09:30 volumio kernel: NET: Registered protocol family 2
Sep 28 22:09:30 volumio kernel: TCP established hash table entries: 8192 (order: 4, 65536 bytes)
Sep 28 22:09:30 volumio kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes)
Sep 28 22:09:30 volumio kernel: TCP: Hash tables configured (established 8192 bind 8192)
Sep 28 22:09:30 volumio kernel: TCP: reno registered
Sep 28 22:09:30 volumio kernel: UDP hash table entries: 512 (order: 2, 16384 bytes)
Sep 28 22:09:30 volumio kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
Sep 28 22:09:30 volumio kernel: NET: Registered protocol family 1
Sep 28 22:09:30 volumio kernel: RPC: Registered named UNIX socket transport module.
Sep 28 22:09:30 volumio kernel: RPC: Registered udp transport module.
Sep 28 22:09:30 volumio kernel: RPC: Registered tcp transport module.
Sep 28 22:09:30 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module.
Sep 28 22:09:30 volumio kernel: Trying to unpack rootfs image as initramfs...
Sep 28 22:09:30 volumio kernel: Freeing initrd memory: 3852K (c2000000 - c23c3000)
Sep 28 22:09:30 volumio kernel: hw perfevents: enabled with ARMv7_Cortex_A9 PMU driver, 7 counters available
Sep 28 22:09:30 volumio kernel: owl_uart: no device is found
Sep 28 22:09:30 volumio kernel: b0126000.serial: ttyS3 at MMIO 0xb0126000 (irq = 64) is a OWL_SERIAL
Sep 28 22:09:30 volumio kernel: console [ttyS3] enabled
Sep 28 22:09:30 volumio kernel: b012a000.serial: ttyS5 at MMIO 0xb012a000 (irq = 66) is a OWL_SERIAL
Sep 28 22:09:30 volumio kernel: ## boot_dev: 0x22
Sep 28 22:09:30 volumio kernel: audit: initializing netlink socket (disabled)
Sep 28 22:09:30 volumio kernel: type=2000 audit(0.850:1): initialized
Sep 28 22:09:30 volumio kernel: VFS: Disk quotas dquot_6.5.2
Sep 28 22:09:30 volumio kernel: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Sep 28 22:09:30 volumio kernel: NTFS driver 2.1.30 [Flags: R/W].
Sep 28 22:09:30 volumio kernel: fuse init (API version 7.22)
Sep 28 22:09:30 volumio kernel: exFAT: Core Version 1.1.3
Sep 28 22:09:30 volumio kernel: exFAT: FS Version 1.1.3
Sep 28 22:09:30 volumio kernel: msgmni has been set to 1449
Sep 28 22:09:30 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
Sep 28 22:09:30 volumio kernel: io scheduler noop registered
Sep 28 22:09:30 volumio kernel: io scheduler deadline registered
Sep 28 22:09:30 volumio kernel: io scheduler cfq registered (default)
Sep 28 22:09:30 volumio kernel: #### insmod vde driver!
Sep 28 22:09:30 volumio kernel: mali0 b0280000.vde: Probe vde device
Sep 28 22:09:30 volumio kernel: info ic_type 0x5206
Sep 28 22:09:30 volumio kernel: vde->irq =52
Sep 28 22:09:30 volumio kernel: cputherm_register_notifier list 0
Sep 28 22:09:30 volumio kernel: mali0 b0280000.vde: resource: iomem: [mem 0xb0280000-0xb0280107] mapping to f8280000, irq 82
Sep 28 22:09:30 volumio kernel: sps base = 0xf81b0100
Sep 28 22:09:30 volumio kernel: cmu base = 0xf8160000
Sep 28 22:09:30 volumio kernel: SPS_PG_CTL = 0xf81b0100
Sep 28 22:09:30 volumio kernel: Share_Mem_REG = 0xf8240004
Sep 28 22:09:30 volumio kernel: CMU_COREPLL = 0xf8160000
Sep 28 22:09:30 volumio kernel: CMU_VDE_CLK = 0xf8160040
Sep 28 22:09:30 volumio kernel: CMU_DEVRST0 = 0xf81600a8
Sep 28 22:09:30 volumio kernel: CMU_DEVCLKEN0 = 0xf81600a0
Sep 28 22:09:30 volumio kernel: device_create_file !
Sep 28 22:09:30 volumio kernel: owl_dss DE: owl_de_probe, pdev = 0xe22f1600
Sep 28 22:09:30 volumio kernel: owl_dss DE: DE INITED FROM UBOOT ?? 1
Sep 28 22:09:30 volumio kernel: owl_dss DE: de irq init ok
Sep 28 22:09:30 volumio kernel: owl_dss MMU: mmu_init: base 0xe3a00000 base_phys 0xc0c4165c23a00000 free_info->addr 0xe3a00000
Sep 28 22:09:30 volumio kernel: 1 portname = dsi
Sep 28 22:09:30 volumio kernel: generic_dsi_panel_drv_init
Sep 28 22:09:30 volumio kernel: owl_dsihw_probe
Sep 28 22:09:30 volumio kernel: owl_dss DSI: error!dsihw_parse_params, fail to get lcd reset gpio
Sep 28 22:09:30 volumio kernel: owl_dss DSI: owl_dsihw_probe called ok ~~~~~~~~~~~~~
Sep 28 22:09:30 volumio kernel: 1 portname = dsi
Sep 28 22:09:30 volumio kernel: current type dsi
Sep 28 22:09:30 volumio kernel: OWL_HDMI: Enter owldss_hdmihw_probe
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi info ic_type 0x3
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: of_get_hdmi_data
Sep 28 22:09:30 volumio kernel: OWL_HDMI: of_get_hdmi_data ~~~~~~~~~~~~~ hdmi.ip_data.cfg.cm.code 0
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: base=f82c0000
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: init_display
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: ENTER hdmi_panel_probe
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_panel_probe x_res= 0 y_res = 0
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: owl_dss_device hdmi p->0xc0cd3808
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hpd_enable start
Sep 28 22:09:30 volumio kernel: timings : xres 1280 yres 720 size of 40 4
Sep 28 22:09:30 volumio kernel: OWL_HDMI: owldss_hdmi_display_set_vid vid 2 ,code 4 mode 1
Sep 28 22:09:30 volumio kernel: OWL_HDMI: register irq ON!
Sep 28 22:09:30 volumio kernel: Actions CEC for OWL Driver, (c) 2009 ACTIONS Electronics
Sep 28 22:09:30 volumio kernel: atm7059a-hdmi-cec b02c0000.hdmi_cec: probe successful
Sep 28 22:09:30 volumio kernel: brd: module loaded
Sep 28 22:09:30 volumio kernel: loop: module loaded
Sep 28 22:09:30 volumio kernel: misc_info_init, line 1059, Dec 22 2017 11:42:15
Sep 28 22:09:30 volumio kernel: mi_debug_init, line 372
Sep 28 22:09:30 volumio kernel: tun: Universal TUN/TAP device driver, 1.6
Sep 28 22:09:30 volumio kernel: tun: (C) 1999-2004 Max Krasnyansky
Sep 28 22:09:30 volumio kernel: PPP generic driver version 2.4.2
Sep 28 22:09:30 volumio kernel: PPP BSD Compression module registered
Sep 28 22:09:30 volumio kernel: PPP Deflate Compression module registered
Sep 28 22:09:30 volumio kernel: PPP MPPE Compression module registered
Sep 28 22:09:30 volumio kernel: NET: Registered protocol family 24
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver rtl8150
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver r8152
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver ax88179_178a
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver cdc_ether
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver cdc_subset
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver cdc_ncm
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver asix
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver sr9700_android
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver CoreChips
Sep 28 22:09:30 volumio kernel: port_host_plug_detect[0]:3
Sep 28 22:09:30 volumio kernel: port0_vubs_en:-1
Sep 28 22:09:30 volumio kernel: port_host_plug_detect[1]:3
Sep 28 22:09:30 volumio kernel: port1_vubs_en:116
Sep 28 22:09:30 volumio kernel: can't find aotg_udc_enable config
Sep 28 22:09:30 volumio kernel: can't find aotg_udc_enable config
Sep 28 22:09:30 volumio kernel: No aotg_udc being enabled!
Sep 28 22:09:30 volumio kernel:
------------dwc3_clk_init-----ic=1-----
Sep 28 22:09:30 volumio kernel: [PowerGate] name: 'usb3', on: 1, before SPS_PG_CTL: 0x703360
Sep 28 22:09:30 volumio kernel: [PowerGate] name: 'usb3', on: 1, after SPS_PG_CTL: 0x707760
Sep 28 22:09:30 volumio kernel: GS705A phy init for dwc3 gadget 11:51:14
Sep 28 22:09:30 volumio kernel: GS705A phy init for dwc3 gadget 11:51:14
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver usb-storage
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver usbserial
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver option
Sep 28 22:09:30 volumio kernel: usbserial: USB Serial support registered for GSM modem (1-port)
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver pl2303
Sep 28 22:09:30 volumio kernel: usbserial: USB Serial support registered for pl2303
Sep 28 22:09:30 volumio kernel: mousedev: PS/2 mouse device common for all mice
Sep 28 22:09:30 volumio kernel: atc260x-onoff atc2603c-onoff.0: Probing atc2603c-onoff
Sep 28 22:09:30 volumio kernel: atc260x-onoff atc2603c-onoff.0: atc260x_onoff IRQ num : 216
Sep 28 22:09:30 volumio kernel: input: atc260x_onoff as /devices/b0170000.i2c/i2c-0/0-0065/atc2603c-onoff.0/input/input0
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver xpad
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver usb_acecad
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver aiptek
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver gtco
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver hanwang
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver kbtab
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver wacom
Sep 28 22:09:30 volumio kernel: atc260x-rtc atc2603c-rtc.0: Probing...
Sep 28 22:09:30 volumio kernel: no blk dev
Sep 28 22:09:30 volumio kernel: read MiscInfoHeader failed
Sep 28 22:09:30 volumio kernel: read Head failed
Sep 28 22:09:30 volumio kernel: OWL_HDMI_ERR: failed to read hdcp key from secure storage
Sep 28 22:09:30 volumio kernel: atc260x-rtc atc2603c-rtc.0: rtc core: registered atc260x-RTC as rtc0
Sep 28 22:09:30 volumio kernel: atc260x-rtc atc2603c-rtc.0: RTC alarm IRQ num : 217
Sep 28 22:09:30 volumio kernel: i2c /dev entries driver
Sep 28 22:09:30 volumio kernel: IR NEC protocol handler initialized
Sep 28 22:09:30 volumio kernel: IR RC5(x) protocol handler initialized
Sep 28 22:09:30 volumio kernel: IR RC6 protocol handler initialized
Sep 28 22:09:30 volumio kernel: IR JVC protocol handler initialized
Sep 28 22:09:30 volumio kernel: IR Sony protocol handler initialized
Sep 28 22:09:30 volumio kernel: IR RC5 (streamzap) protocol handler initialized
Sep 28 22:09:30 volumio kernel: IR SANYO protocol handler initialized
Sep 28 22:09:30 volumio kernel: IR MCE Keyboard/mouse protocol handler initialized
Sep 28 22:09:30 volumio kernel: atc260x-hwmon atc2603c-hwmon.0: Probing atc2603c-hwmon
Sep 28 22:09:30 volumio kernel: tmu-mem:start=0xb01b00e8
Sep 28 22:09:30 volumio kernel: owl_wdt b0020600.twd-watchdog: watchdog soft mode
Sep 28 22:09:30 volumio kernel: device-mapper: uevent: version 1.0.3
Sep 28 22:09:30 volumio kernel: device-mapper: ioctl: 4.24.0-ioctl (2013-01-15) initialised: dm-devel@redhat.com
Sep 28 22:09:30 volumio kernel: Bluetooth: HCI UART driver ver 2.2
Sep 28 22:09:30 volumio kernel: Bluetooth: HCI H4 protocol initialized
Sep 28 22:09:30 volumio kernel: Bluetooth: HCI BCSP protocol initialized
Sep 28 22:09:30 volumio kernel: Bluetooth: HCILL protocol initialized
Sep 28 22:09:30 volumio kernel: Bluetooth: HCIATH3K protocol initialized
Sep 28 22:09:30 volumio kernel: Bluetooth: HCI Three-wire UART (H5) protocol initialized
Sep 28 22:09:30 volumio kernel: Bluetooth: Realtek h5 driver version 1.0.1
Sep 28 22:09:30 volumio kernel: Realtek h5 driver version 1.0.1
Sep 28 22:09:30 volumio kernel: Bluetooth: HCI Realtek H5 protocol registration failed
Sep 28 22:09:30 volumio kernel: HCI Realtek H5 protocol registration failed
Sep 28 22:09:30 volumio kernel: rtk_btusb: Realtek Bluetooth USB driver module init, version 2.21
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver rtk_btusb
Sep 28 22:09:30 volumio kernel: cpuidle: using governor ladder
Sep 28 22:09:30 volumio kernel: cpuidle: using governor menu
Sep 28 22:09:30 volumio kernel: ## host_id: 0 boot_dev 22 host->index: 1
Sep 28 22:09:30 volumio kernel: gl520x_mmc b0230000.mmc: using dma0chan2 for DMA transfers
Sep 28 22:09:30 volumio kernel: use GPIO to detect SD/MMC card
Sep 28 22:09:30 volumio kernel: host0: sure rescan mmc
Sep 28 22:09:30 volumio kernel: ## host_id: 2 boot_dev 22 host->index: 0
Sep 28 22:09:30 volumio kernel: gl520x_mmc b0238000.mmc: using dma0chan3 for DMA transfers
Sep 28 22:09:30 volumio kernel: hidraw: raw HID events driver (C) Jiri Kosina
Sep 28 22:09:30 volumio kernel: usbcore: registered new interface driver usbhid
Sep 28 22:09:30 volumio kernel: usbhid: USB HID core driver
Sep 28 22:09:30 volumio kernel: zram: Created 1 device(s) ...
Sep 28 22:09:30 volumio kernel: ashmem: initialized
Sep 28 22:09:30 volumio kernel: logger: created 256K log 'log_main'
Sep 28 22:09:30 volumio kernel: logger: created 256K log 'log_events'
Sep 28 22:09:30 volumio kernel: logger: created 256K log 'log_radio'
Sep 28 22:09:30 volumio kernel: logger: created 256K log 'log_system'
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio kernel: pcm512x 2-004c: Failed to reset device: -6
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio kernel: pcm512x 2-004d: Failed to reset device: -6
Sep 28 22:09:30 volumio kernel: [SNDRV]:[atm7059_pcm_init] atm7059_pcm_init!!
Sep 28 22:09:30 volumio kernel: atm7059-pcm-audio atm7059-pcm-audio: atm7059_pcm_probe!!
Sep 28 22:09:30 volumio kernel: atc2603a_init
Sep 28 22:09:30 volumio kernel: [52B blob data]
Sep 28 22:09:30 volumio kernel: [47B blob data]
Sep 28 22:09:30 volumio kernel: atc2603c_init
Sep 28 22:09:30 volumio kernel: [52B blob data]
Sep 28 22:09:30 volumio kernel: [59B blob data]
Sep 28 22:09:30 volumio kernel: [SNDRV]:[atc2603c_platform_probe] what's my lucky draw 220
Sep 28 22:09:30 volumio kernel: [SNDRV]:[atm7059_dai_probe] it's ok 0
Sep 28 22:09:30 volumio kernel: owl-audio-i2s b0100000.gl5203-audio-i2s: atm7059_dai_probe
Sep 28 22:09:30 volumio kernel: atm7059-hdmi-audio atm7059-hdmi-audio: atm7059_hdmi_probe!!
Sep 28 22:09:30 volumio kernel: atm7059_spdif_probe 225
Sep 28 22:09:30 volumio kernel: [SNDRV]:[atm7059_link_init] atm7059_link_init
Sep 28 22:09:30 volumio kernel: [SNDRV]:[atm7059_audio_gpio_init] find i2s_switch_gpio property fail
Sep 28 22:09:30 volumio kernel: [SNDRV]:[atm7059_audio_gpio_init] find earphone_detect_gpios property fail
Sep 28 22:09:30 volumio kernel: [60B blob data]
Sep 28 22:09:30 volumio kernel: [SNDRV]:[atm7059_link_init] maybe using irq
Sep 28 22:09:30 volumio kernel: soc-audio soc-audio: ASoC: machine atm7059_link should use snd_soc_register_card()
Sep 28 22:09:30 volumio kernel: [SNDRV]:[atc2603c_probe] atc2603c_probe!
Sep 28 22:09:30 volumio kernel: [54B blob data]
Sep 28 22:09:30 volumio kernel: host2,platfrom request already
Sep 28 22:09:30 volumio kernel: atc2603c-audio atc260x-audio: ASoC: no dapm match for AOUT FL FR Mixer --> AOUT MIXER --> ADC0 Mux
Sep 28 22:09:30 volumio kernel: atc2603c-audio atc260x-audio: ASoC: Failed to add route AOUT FL FR Mixer -> AOUT MIXER -> ADC0 Mux
Sep 28 22:09:30 volumio kernel: [SNDRV]:[codec_hdmi_probe] codec_hdmi_probe!
Sep 28 22:09:30 volumio kernel: [SNDRV]:[atm7059_link_snd_init] atm7059_link_init() called
Sep 28 22:09:30 volumio kernel: soc-audio soc-audio: atc2603c-dai <-> owl-audio-i2s mapping ok
Sep 28 22:09:30 volumio kernel: [SNDRV]:[atm7059_link_snd_init] atm7059_link_init() called
Sep 28 22:09:30 volumio kernel: soc-audio soc-audio: atm7059-hdmi-dai <-> owl-audio-i2s mapping ok
Sep 28 22:09:30 volumio kernel: [SNDRV]:[atm7059_link_snd_init] atm7059_link_init() called
Sep 28 22:09:30 volumio kernel: soc-audio soc-audio: atm7059-spdif-dai <-> owl-audio-i2s mapping ok
Sep 28 22:09:30 volumio kernel: u32 classifier
Sep 28 22:09:30 volumio kernel: Actions configured
Sep 28 22:09:30 volumio kernel: Netfilter messages via NETLINK v0.30.
Sep 28 22:09:30 volumio kernel: nf_conntrack version 0.5.0 (15822 buckets, 63288 max)
Sep 28 22:09:30 volumio kernel: ctnetlink v0.93: registering with nfnetlink.
Sep 28 22:09:30 volumio kernel: NF_TPROXY: Transparent proxy support initialized, version 4.1.0
Sep 28 22:09:30 volumio kernel: NF_TPROXY: Copyright (c) 2006-2007 BalaBit IT Ltd.
Sep 28 22:09:30 volumio kernel: xt_time: kernel timezone is -0000
Sep 28 22:09:30 volumio kernel: ip_tables: (C) 2000-2006 Netfilter Core Team
Sep 28 22:09:30 volumio kernel: ipt_CLUSTERIP: ClusterIP Version 0.8 loaded successfully
Sep 28 22:09:30 volumio kernel: arp_tables: (C) 2002 David S. Miller
Sep 28 22:09:30 volumio kernel: TCP: cubic registered
Sep 28 22:09:30 volumio kernel: Initializing XFRM netlink socket
Sep 28 22:09:30 volumio kernel: NET: Registered protocol family 10
Sep 28 22:09:30 volumio kernel: mip6: Mobile IPv6
Sep 28 22:09:30 volumio kernel: ip6_tables: (C) 2000-2006 Netfilter Core Team
Sep 28 22:09:30 volumio kernel: NET: Registered protocol family 17
Sep 28 22:09:30 volumio kernel: NET: Registered protocol family 15
Sep 28 22:09:30 volumio kernel: Bluetooth: RFCOMM TTY layer initialized
Sep 28 22:09:30 volumio kernel: Bluetooth: RFCOMM socket layer initialized
Sep 28 22:09:30 volumio kernel: Bluetooth: RFCOMM ver 1.11
Sep 28 22:09:30 volumio kernel: Bluetooth: HIDP (Human Interface Emulation) ver 1.2
Sep 28 22:09:30 volumio kernel: Bluetooth: HIDP socket layer initialized
Sep 28 22:09:30 volumio kernel: l2tp_core: L2TP core driver, V2.0
Sep 28 22:09:30 volumio kernel: l2tp_ppp: PPPoL2TP kernel driver, V2.0
Sep 28 22:09:30 volumio kernel: lib80211: common routines for IEEE802.11 drivers
Sep 28 22:09:30 volumio kernel: lib80211_crypt: registered algorithm 'NULL'
Sep 28 22:09:30 volumio kernel: lib80211_crypt: registered algorithm 'WEP'
Sep 28 22:09:30 volumio kernel: lib80211_crypt: registered algorithm 'CCMP'
Sep 28 22:09:30 volumio kernel: lib80211_crypt: registered algorithm 'TKIP'
Sep 28 22:09:30 volumio kernel: Key type dns_resolver registered
Sep 28 22:09:30 volumio kernel: VFP support v0.3: implementor 41 architecture 3 part 30 variant 9 rev 4
Sep 28 22:09:30 volumio kernel: ThumbEE CPU extension supported.
Sep 28 22:09:30 volumio kernel: [PM] owl_pm_init() 1035
Sep 28 22:09:30 volumio kernel: train_Msize:1024, train_ces:1
Sep 28 22:09:30 volumio kernel: start:0xc0008000, end:0xc0c15ae4, checksum:0xe4551983
Sep 28 22:09:30 volumio kernel: fbdev info : xres 1280 yres 800 refresh 61 name dsi
Sep 28 22:09:30 volumio kernel: fb_infos allocated
Sep 28 22:09:30 volumio kernel: mmc0: BKOPS_EN bit is not set
Sep 28 22:09:30 volumio kernel: mmc_init_card:ok
Sep 28 22:09:30 volumio kernel: emmc init ok
Sep 28 22:09:30 volumio kernel: mmc_add_card: bootdev 0x22
Sep 28 22:09:30 volumio kernel: force sd2(emmc) host emmc_boot_card
Sep 28 22:09:30 volumio kernel: mmc0: new high speed DDR MMC card at address 0001
Sep 28 22:09:30 volumio kernel: ## md->name_idx: 0
Sep 28 22:09:30 volumio kernel: mmcblk0: emmc_boot_card Biwin 14.4 GiB
Sep 28 22:09:30 volumio kernel: mmcblk0: p1 p2 p3
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_read_edid
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: ENTER owldss_hdmi_display_get_cable_status 0
Sep 28 22:09:30 volumio kernel: owldss_hdmi_read_edid ~~~ error hdmi not connected
Sep 28 22:09:30 volumio kernel: set_par(0)
Sep 28 22:09:30 volumio kernel: Console: switching to colour frame buffer device 160x50
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_get_over_scan
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_get_over_scan
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_get_cable_status
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: ENTER owldss_hdmi_display_get_cable_status 0
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_get_over_scan
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_get_cable_status
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: ENTER owldss_hdmi_display_get_cable_status 0
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_get_over_scan
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_get_cable_status
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: ENTER owldss_hdmi_display_get_cable_status 0
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_get_over_scan
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_get_cable_status
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: ENTER owldss_hdmi_display_get_cable_status 0
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_get_over_scan
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_get_cable_status
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: ENTER owldss_hdmi_display_get_cable_status 0
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_get_over_scan
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_get_cable_status
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: ENTER owldss_hdmi_display_get_cable_status 0
Sep 28 22:09:30 volumio kernel: OWL_HDMI_DEBUG: hdmi_get_over_scan
Sep 28 22:09:30 volumio kernel: np c16bc4f0
Sep 28 22:09:30 volumio kernel: boot_hdmi_rotate 0
Sep 28 22:09:30 volumio kernel: of_property_read_u32 is error
Sep 28 22:09:30 volumio kernel: of_property_read bootrotate is error
Sep 28 22:09:30 volumio kernel: boot_cvbs_enable 0
Sep 28 22:09:30 volumio kernel: boot_hdmi_rotate 0 boot_cvbs_rotate=0
Sep 28 22:09:30 volumio kernel: regulator-dummy: disabling
Sep 28 22:09:30 volumio kernel: atc260x-rtc atc2603c-rtc.0: setting system clock to 2018-09-28 22:07:53 UTC (1538172473)
Sep 28 22:09:30 volumio kernel: ATC260X_POWER: create sysfs for atc260x power
Sep 28 22:09:30 volumio kernel: charger=e1c8c118 2705
Sep 28 22:09:30 volumio kernel: set_vbus_path=c04d4328 set_apds_vbus_pd=c04d43fc
Sep 28 22:09:30 volumio kernel: clk: Not disabling unused clocks
Sep 28 22:09:30 volumio kernel: ====otgvbus_gpio: num-78, active-high---detect_type=3,idpin_type=0,vbus_type=2---
Sep 28 22:09:30 volumio kernel: ALSA device list:
Sep 28 22:09:30 volumio kernel: #0: atm7059_link
Sep 28 22:09:30 volumio kernel: Freeing unused kernel memory: 472K (c0c16000 - c0c8c000)
Sep 28 22:09:30 volumio initramfs: Booting Volumio for gs705a
Sep 28 22:09:30 volumio initramfs: This script mounts rootfs RO with an overlay RW layer.
Sep 28 22:09:30 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Sep 28 22:09:30 volumio initramfs: IMGPART=/dev/mmcblk0p2
Sep 28 22:09:30 volumio initramfs: IMGFILE=/volumio_current.sqsh
Sep 28 22:09:30 volumio initramfs: /dev/mmcblk0p2:
Sep 28 22:09:30 volumio kernel: EXT4-fs (mmcblk0p2): recovery complete
Sep 28 22:09:30 volumio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Sep 28 22:09:30 volumio initramfs: Checking for USB updates
Sep 28 22:09:30 volumio kernel:
[power] No battery detected
Sep 28 22:09:30 volumio initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline)
Sep 28 22:09:30 volumio kernel: sndrv: speaker off
Sep 28 22:09:30 volumio kernel: [atc260x_usb_get_prop] first_power_on = false!
Sep 28 22:09:30 volumio kernel:
========usb_detect_plugout_event===start det!!========
Sep 28 22:09:30 volumio kernel:
drivers/usb/monitor/umonitor_core.c--497, SYS_MSG_USB_B_OUT
Sep 28 22:09:30 volumio kernel:
----udc_set_plugstate--PLUGSTATE_OUT--
Sep 28 22:09:30 volumio kernel: [PowerGate] name: 'usb3', on: 0, before SPS_PG_CTL: 0x717761
Sep 28 22:09:30 volumio kernel: [PowerGate] name: 'usb3', on: 0, after SPS_PG_CTL: 0x717361
Sep 28 22:09:30 volumio kernel:
------------dwc3_clk_exit----------
Sep 28 22:09:30 volumio kernel: ++++++++cpufreq ready++++++++
Sep 28 22:09:30 volumio kernel: ++++++++pdata->freq_tab[0].freq_clip_max:504000++++++++
Sep 28 22:09:30 volumio kernel: ++++++++pdata->freq_tab[1].freq_clip_max:504000++++++++
Sep 28 22:09:30 volumio kernel: ------------[ cut here ]------------
Sep 28 22:09:30 volumio kernel: WARNING: at drivers/gpio/gpiolib.c:160 gpio_ensure_requested+0x64/0xb4()
Sep 28 22:09:30 volumio kernel: autorequest GPIO-114
Sep 28 22:09:30 volumio kernel: Modules linked in: nls_cp437 squashfs overlayfs
Sep 28 22:09:30 volumio kernel: CPU: 0 PID: 4 Comm: kworker/0:0 Not tainted 3.10.38 #4
Sep 28 22:09:30 volumio kernel: Workqueue: events wait_cpufreq_ready
Sep 28 22:09:30 volumio kernel: [] (unwind_backtrace+0x0/0x134) from [] (show_stack+0x24/0x34)
Sep 28 22:09:30 volumio kernel: [] (show_stack+0x24/0x34) from [] (warn_slowpath_common+0x54/0x6c)
Sep 28 22:09:30 volumio kernel: [] (warn_slowpath_common+0x54/0x6c) from [] (warn_slowpath_fmt+0x30/0x40)
Sep 28 22:09:30 volumio kernel: [] (warn_slowpath_fmt+0x30/0x40) from [] (gpio_ensure_requested+0x64/0xb4)
Sep 28 22:09:30 volumio kernel: [] (gpio_ensure_requested+0x64/0xb4) from [] (gpiod_direction_output+0x68/0x304)
Sep 28 22:09:30 volumio kernel: [] (gpiod_direction_output+0x68/0x304) from [] (owl_tmu_read+0x160/0x1b8)
Sep 28 22:09:30 volumio kernel: [] (owl_tmu_read+0x160/0x1b8) from [] (owl_get_temp+0x2c/0x58)
Sep 28 22:09:30 volumio kernel: [] (owl_get_temp+0x2c/0x58) from [] (thermal_zone_get_temp+0x54/0x108)
Sep 28 22:09:30 volumio kernel: [] (thermal_zone_get_temp+0x54/0x108) from [] (thermal_zone_device_update+0x24/0xd4)
Sep 28 22:09:30 volumio kernel: [] (thermal_zone_device_update+0x24/0xd4) from [] (thermal_zone_device_register+0x6c8/0x7d0)
Sep 28 22:09:30 volumio kernel: [] (thermal_zone_device_register+0x6c8/0x7d0) from [] (wait_cpufreq_ready+0x114/0x1c8)
Sep 28 22:09:30 volumio kernel: [] (wait_cpufreq_ready+0x114/0x1c8) from [] (process_one_work+0x134/0x44c)
Sep 28 22:09:30 volumio kernel: [] (process_one_work+0x134/0x44c) from [] (worker_thread+0x138/0x3d4)
Sep 28 22:09:30 volumio kernel: [] (worker_thread+0x138/0x3d4) from [] (kthread+0xb4/0xb8)
Sep 28 22:09:30 volumio kernel: [] (kthread+0xb4/0xb8) from [] (ret_from_fork+0x14/0x3c)
Sep 28 22:09:30 volumio kernel: ---[ end trace f0868dbfc660adfc ]---
Sep 28 22:09:30 volumio kernel: OWL: Kernel Thermal management registered
Sep 28 22:09:30 volumio kernel: EXT4-fs (mmcblk0p3): recovery complete
Sep 28 22:09:30 volumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null)
Sep 28 22:09:30 volumio initramfs: Without Option:
Sep 28 22:09:30 volumio initramfs: VOLUMIO_VERSION="2.459"
Sep 28 22:09:30 volumio initramfs: Finish initramfs, continue booting Volumio
Sep 28 22:09:30 volumio systemd[1]: systemd 215 running in system mode. (+PAM +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ -SECCOMP -APPARMOR)
Sep 28 22:09:30 volumio systemd[1]: Detected architecture 'arm'.
Sep 28 22:09:30 volumio kernel:
drivers/usb/monitor/umonitor_core.c--746, SYS_MSG_USB_A_IN
Sep 28 22:09:30 volumio kernel: drivers/usb/monitor/umonitor_api.c--1212, wake_lock !!!
Sep 28 22:09:30 volumio kernel:
----udc_set_plugstate--PLUGSTATE_IN-2--
Sep 28 22:09:30 volumio kernel:
------------dwc3_clk_init-----ic=1-----
Sep 28 22:09:30 volumio kernel: [PowerGate] name: 'usb3', on: 1, before SPS_PG_CTL: 0x713361
Sep 28 22:09:30 volumio kernel: [PowerGate] name: 'usb3', on: 1, after SPS_PG_CTL: 0x717761
Sep 28 22:09:30 volumio systemd[1]: Inserted module 'autofs4'
Sep 28 22:09:30 volumio systemd[1]: Set hostname to .
Sep 28 22:09:30 volumio kernel: open ttyS3
Sep 28 22:09:30 volumio kernel: GS705A phy init for dwc3 gadget 11:51:14
Sep 28 22:09:30 volumio kernel: GS705A phy init for dwc3 gadget 11:51:14
Sep 28 22:09:30 volumio kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
Sep 28 22:09:30 volumio kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1
Sep 28 22:09:30 volumio kernel: GS705A phy init for xhci 11:51:14
Sep 28 22:09:30 volumio kernel: xhci-hcd xhci-hcd.0.auto: irq 55, io mem 0xb0400000
Sep 28 22:09:30 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
Sep 28 22:09:30 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Sep 28 22:09:30 volumio kernel: usb usb1: Product: xHCI Host Controller
Sep 28 22:09:30 volumio kernel: usb usb1: Manufacturer: Linux 3.10.38 xhci-hcd
Sep 28 22:09:30 volumio kernel: usb usb1: SerialNumber: xhci-hcd.0.auto
Sep 28 22:09:30 volumio kernel: hub 1-0:1.0: USB hub found
Sep 28 22:09:30 volumio kernel: hub 1-0:1.0: 1 port detected
Sep 28 22:09:30 volumio kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller
Sep 28 22:09:30 volumio kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2
Sep 28 22:09:30 volumio kernel:
#xhci_start#
Sep 28 22:09:30 volumio kernel: ss_try_next_mode:lfps_src_mode=1
Sep 28 22:09:30 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service'
Sep 28 22:09:30 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service'
Sep 28 22:09:30 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service'
Sep 28 22:09:30 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service'
Sep 28 22:09:30 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.
Sep 28 22:09:30 volumio systemd[1]: Starting Forward Password Requests to Wall Directory Watch.
Sep 28 22:09:30 volumio systemd[1]: Started Forward Password Requests to Wall Directory Watch.
Sep 28 22:09:30 volumio systemd[1]: Expecting device dev-ttyS3.device...
Sep 28 22:09:30 volumio systemd[1]: Starting Remote File Systems (Pre).
Sep 28 22:09:30 volumio systemd[1]: Reached target Remote File Systems (Pre).
Sep 28 22:09:30 volumio systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
Sep 28 22:09:30 volumio systemd[1]: Starting Encrypted Volumes.
Sep 28 22:09:30 volumio systemd[1]: Reached target Encrypted Volumes.
Sep 28 22:09:30 volumio systemd[1]: Expecting device dev-mmcblk0p1.device...
Sep 28 22:09:30 volumio systemd[1]: Starting Root Slice.
Sep 28 22:09:30 volumio systemd[1]: Created slice Root Slice.
Sep 28 22:09:30 volumio systemd[1]: Starting User and Session Slice.
Sep 28 22:09:30 volumio systemd[1]: Created slice User and Session Slice.
Sep 28 22:09:30 volumio systemd[1]: Starting /dev/initctl Compatibility Named Pipe.
Sep 28 22:09:30 volumio systemd[1]: Listening on /dev/initctl Compatibility Named Pipe.
Sep 28 22:09:30 volumio systemd[1]: Starting Delayed Shutdown Socket.
Sep 28 22:09:30 volumio systemd[1]: Listening on Delayed Shutdown Socket.
Sep 28 22:09:30 volumio systemd[1]: Starting Journal Socket (/dev/log).
Sep 28 22:09:30 volumio systemd[1]: Listening on Journal Socket (/dev/log).
Sep 28 22:09:30 volumio systemd[1]: Starting udev Control Socket.
Sep 28 22:09:30 volumio systemd[1]: Listening on udev Control Socket.
Sep 28 22:09:30 volumio systemd[1]: Starting udev Kernel Socket.
Sep 28 22:09:30 volumio systemd[1]: Listening on udev Kernel Socket.
Sep 28 22:09:30 volumio systemd[1]: Starting Journal Socket.
Sep 28 22:09:30 volumio systemd[1]: Listening on Journal Socket.
Sep 28 22:09:30 volumio systemd[1]: Starting System Slice.
Sep 28 22:09:30 volumio systemd[1]: Created slice System Slice.
Sep 28 22:09:30 volumio systemd[1]: Starting system-systemd\x2dfsck.slice.
Sep 28 22:09:30 volumio systemd[1]: Created slice system-systemd\x2dfsck.slice.
Sep 28 22:09:30 volumio systemd[1]: Starting dynamicswap service...
Sep 28 22:09:30 volumio systemd[1]: Started dynamicswap service.
Sep 28 22:09:30 volumio systemd[1]: Starting Swap.
Sep 28 22:09:30 volumio systemd[1]: Reached target Swap.
Sep 28 22:09:30 volumio systemd[1]: Starting system-getty.slice.
Sep 28 22:09:30 volumio systemd[1]: Created slice system-getty.slice.
Sep 28 22:09:30 volumio systemd[1]: Starting system-serial\x2dgetty.slice.
Sep 28 22:09:30 volumio systemd[1]: Created slice system-serial\x2dgetty.slice.
Sep 28 22:09:30 volumio systemd[1]: Starting Restore / save the current clock...
Sep 28 22:09:30 volumio systemd[1]: Mounted Huge Pages File System.
Sep 28 22:09:30 volumio systemd[1]: Mounting POSIX Message Queue File System...
Sep 28 22:09:30 volumio systemd[1]: Starting Create list of required static device nodes for the current kernel...
Sep 28 22:09:30 volumio systemd[1]: Mounting Debug File System...
Sep 28 22:09:30 volumio systemd[1]: Started Set Up Additional Binary Formats.
Sep 28 22:09:30 volumio systemd[1]: Starting Load Kernel Modules...
Sep 28 22:09:30 volumio systemd[1]: Starting udev Coldplug all Devices...
Sep 28 22:09:30 volumio systemd[1]: Starting Journal Service...
Sep 28 22:09:30 volumio systemd[1]: Started Journal Service.
Sep 28 22:09:30 volumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
Sep 28 22:09:30 volumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Sep 28 22:09:30 volumio kernel: usb usb2: Product: xHCI Host Controller
Sep 28 22:09:30 volumio kernel: usb usb2: Manufacturer: Linux 3.10.38 xhci-hcd
Sep 28 22:09:30 volumio kernel: usb usb2: SerialNumber: xhci-hcd.0.auto
Sep 28 22:09:30 volumio kernel: hub 2-0:1.0: USB hub found
Sep 28 22:09:30 volumio kernel: hub 2-0:1.0: 1 port detected
Sep 28 22:09:30 volumio kernel: ----monitor_wake_lock for 10s
Sep 28 22:09:30 volumio kernel: read_mi_head, line 429, magic 0x0, length 0, item num 0
Sep 28 22:09:30 volumio kernel: read Head null
Sep 28 22:09:30 volumio kernel: OWL_HDMI_ERR: failed to read hdcp key from secure storage
Sep 28 22:09:30 volumio systemd-journal[202]: Journal started
Sep 28 22:09:30 volumio systemd[1]: Starting Slices.
Sep 28 22:09:30 volumio systemd[1]: Reached target Slices.
Sep 28 22:09:30 volumio systemd[1]: Starting Remount Root and Kernel File Systems...
Sep 28 22:09:30 volumio systemd[1]: Mounted Debug File System.
Sep 28 22:09:30 volumio systemd[1]: Mounted POSIX Message Queue File System.
Sep 28 22:09:30 volumio systemd-modules-load[198]: Module 'fuse' is builtin
Sep 28 22:09:30 volumio systemd[1]: Started Restore / save the current clock.
Sep 28 22:09:30 volumio systemd[1]: Started Create list of required static device nodes for the current kernel.
Sep 28 22:09:30 volumio systemd[1]: Started Load Kernel Modules.
Sep 28 22:09:30 volumio systemd[1]: Time has been changed
Sep 28 22:09:30 volumio systemd[1]: Started Remount Root and Kernel File Systems.
Sep 28 22:09:30 volumio systemd[1]: Started udev Coldplug all Devices.
Sep 28 22:09:30 volumio systemd[1]: Started Load/Save Random Seed.
Sep 28 22:09:30 volumio systemd[1]: Started Apply Kernel Variables.
Sep 28 22:09:30 volumio systemd[1]: Started Create Static Device Nodes in /dev.
Sep 28 22:09:30 volumio fake-hwclock[183]: Fri Sep 28 22:09:30 UTC 2018
Sep 28 22:09:30 volumio systemd[1]: Starting udev Kernel Device Manager...
Sep 28 22:09:30 volumio systemd[1]: Starting Local File Systems (Pre).
Sep 28 22:09:30 volumio systemd[1]: Reached target Local File Systems (Pre).
Sep 28 22:09:30 volumio systemd[1]: Mounting /tmp...
Sep 28 22:09:30 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway.
Sep 28 22:09:30 volumio systemd[1]: Mounting /var/spool/cups...
Sep 28 22:09:30 volumio systemd[1]: Mounting /var/log...
Sep 28 22:09:30 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway.
Sep 28 22:09:30 volumio systemd-udevd[225]: starting version 215
Sep 28 22:09:30 volumio systemd[1]: Mounted /var/spool/cups.
Sep 28 22:09:30 volumio systemd[1]: Mounted /tmp.
Sep 28 22:09:30 volumio systemd[1]: Started udev Kernel Device Manager.
Sep 28 22:09:30 volumio systemd[1]: Mounted /var/log.
Sep 28 22:09:30 volumio systemd[1]: Started Show Plymouth Boot Screen.
Sep 28 22:09:30 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
Sep 28 22:09:30 volumio systemd[1]: Starting Dispatch Password Requests to Console Directory Watch.
Sep 28 22:09:30 volumio systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
Sep 28 22:09:30 volumio systemd[1]: Starting Paths.
Sep 28 22:09:30 volumio systemd[1]: Reached target Paths.
Sep 28 22:09:30 volumio systemd[1]: Starting Copy rules generated while the root was ro...
Sep 28 22:09:30 volumio systemd[1]: Mounting /var/spool/cups/tmp...
Sep 28 22:09:30 volumio systemd[1]: Started Copy rules generated while the root was ro.
Sep 28 22:09:30 volumio systemd[1]: Mounted /var/spool/cups/tmp.
Sep 28 22:09:30 volumio systemd[1]: Found device /dev/ttyS3.
Sep 28 22:09:30 volumio kernel: [atc260x_irkeypad_probe start]
Sep 28 22:09:30 volumio kernel: atc260x-irkeypad atc2603c-irkeypad.0: size = 28
Sep 28 22:09:30 volumio kernel: [66B blob data]
Sep 28 22:09:30 volumio kernel: atc260x_irkeypad: ir_ch = 0
Sep 28 22:09:30 volumio kernel: atc260x-irkeypad atc2603c-irkeypad.0: user_code = 60976
Sep 28 22:09:30 volumio kernel: atc260x-irkeypad atc2603c-irkeypad.0: protocol = 1
Sep 28 22:09:30 volumio kernel: atc260x-irkeypad atc2603c-irkeypad.0: wk_code = 77
Sep 28 22:09:30 volumio kernel: atc260x-irkeypad atc2603c-irkeypad.0: period = 140
Sep 28 22:09:30 volumio kernel: gl5201_irkeypad_drv: [atc260x_irkeypad_probe] get irq: 219
Sep 28 22:09:30 volumio kernel: input: atc2603c-irkeypad as /devices/b0170000.i2c/i2c-0/0-0065/atc2603c-irkeypad.0/input/input1
Sep 28 22:09:30 volumio kernel: gl5201_irkeypad_drv: [asoc_irkeypad_open start]
Sep 28 22:09:30 volumio kernel: gl5201_irkeypad_drv: [asoc_irkeypad_start start]
Sep 28 22:09:30 volumio kernel: gl5201_irkeypad_drv: [asoc_irkeypad_config start]
Sep 28 22:09:30 volumio kernel: gl5201_irkeypad_drv: [asoc_irkeypad_config finished]
Sep 28 22:09:30 volumio kernel: gl5201_irkeypad_drv: [asoc_irkeypad_start finished]
Sep 28 22:09:30 volumio kernel: gl5201_irkeypad_drv: [asoc_irkeypad_open finished]
Sep 28 22:09:30 volumio kernel: [atc260x_irkeypad_probe finished]
Sep 28 22:09:30 volumio kernel: ATC260X_GAUGE: ========This is a new reconstructed gauge by actions==========
Sep 28 22:09:30 volumio kernel: ATC260X_GAUGE: [atc260x_gauge_init] drv name:atc260x_cap_gauge, drv version:Actions-v1-20150720151405
Sep 28 22:09:30 volumio kernel: ATC260X_GAUGE: [gauge_create_sysfs] create sysfs for gauge
Sep 28 22:09:30 volumio kernel: ATC260X_GAUGE: [get_pmu_ic_type]pmu type is 1
Sep 28 22:09:30 volumio kernel: ATC260X_GAUGE: [init_gauge] PMU current ratio:3
Sep 28 22:09:30 volumio kernel: ATC260X_GAUGE: [init_gauge] first_product:0
Sep 28 22:09:30 volumio kernel: ATC260X_GAUGE: [resistor_init] inited ch_resistor : 150, inited disch_resistor:250
Sep 28 22:09:30 volumio kernel: gpio num:35, reset level:0, i2c_addr:38, irq_number:213,x_pixel:1280, y_pixel:800, max_point:5, rotate:180, i2cNum:1
Sep 28 22:09:30 volumio kernel: ft5x06_hw_init 1077
Sep 28 22:09:30 volumio kernel: bma222_probe,1765, addr:0x18
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio kernel: bma222 not found! I2c error 250
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio kernel: actions,atm7059
Sep 28 22:09:30 volumio kernel: SI paddr is f8270000res->start is : 0xb0160020,resource size is 0x4
Sep 28 22:09:30 volumio kernel: [owl_camera] (ERR) line:1935--gpio_init() no config gpios
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio kernel: [MIR3DA] Do softreset failed !
Sep 28 22:09:30 volumio kernel: [MIR3DA] chip resume fail!!
Sep 28 22:09:30 volumio kernel: [MIR3DA] chip init failed !
Sep 28 22:09:30 volumio kernel: mir3da: probe of 2-0027 failed with error -1
Sep 28 22:09:30 volumio kernel: stk831x_probe: driver version:1.6.1 2013/05/24
Sep 28 22:09:30 volumio kernel: [STK831x][stk831x_probe][LINE:2239]Use Polling_mode
Sep 28 22:09:30 volumio kernel: [68B blob data]
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio kernel: asoc_ethernet_init 3786
Sep 28 22:09:30 volumio kernel: found sparky chip 100e4639ca06500d provisioned
Sep 28 22:09:30 volumio kernel: idx 2436 newmac 17f7010985
Sep 28 22:09:30 volumio kernel: phy_mode_str: rmii
Sep 28 22:09:30 volumio kernel: phy_mode: 0
Sep 28 22:09:30 volumio kernel: target freq: 50000000
Sep 28 22:09:30 volumio kernel: pdev->name: b0310000.ethernet
Sep 28 22:09:30 volumio kernel: phy_mode: 0
Sep 28 22:09:30 volumio kernel: gpio phy-power-gpios num 85 active 0
Sep 28 22:09:30 volumio kernel: gpio phy-reset-gpios num 127 active 0
Sep 28 22:09:30 volumio kernel: ethernet phy name: sr8201g
Sep 28 22:09:30 volumio kernel: ethernet phy's compatible: SR8201G,sr8201g
Sep 28 22:09:30 volumio kernel: phy model is sr8201g
Sep 28 22:09:30 volumio kernel: phy_model: 4
Sep 28 22:09:30 volumio kernel: res->start: b0310000, ecp->hwrp: f8310000
Sep 28 22:09:30 volumio kernel: phy model: SR8201G
Sep 28 22:09:30 volumio kernel: ETHERNET_BASE: 0xb0310000
Sep 28 22:09:30 volumio kernel: ecp->hwrp: 0xf8310000
Sep 28 22:09:30 volumio systemd[1]: Starting system-systemd\x2dbacklight.slice.
Sep 28 22:09:30 volumio kernel: ecp->tx_bd_base:e5b30000, ecp->tx_bd_paddr:25b30000
Sep 28 22:09:30 volumio kernel: ecp->rx_bd_base:e5b31000, ecp->rx_bd_paddr:25b31000
Sep 28 22:09:30 volumio kernel: virt_to_phys(ecp->tx_bd_base):25b30000
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio systemd[1]: Created slice system-systemd\x2dbacklight.slice.
Sep 28 22:09:30 volumio systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:backlight.2...
Sep 28 22:09:30 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c2] no ACK, fifostat 0x20013
Sep 28 22:09:30 volumio kernel: [drm] Initialized drm 1.1.0 20060810
Sep 28 22:09:30 volumio kernel: [31B blob data]
Sep 28 22:09:30 volumio kernel: ethernet eth0 probe finish
Sep 28 22:09:30 volumio kernel: STK_i2c_Tx: retry over 3
Sep 28 22:09:30 volumio kernel: STK831X_Init:failed
Sep 28 22:09:30 volumio kernel: [80B blob data]
Sep 28 22:09:30 volumio kernel: stk831x: probe of 2-0022 failed with error -5
Sep 28 22:09:30 volumio systemd[1]: Starting Sound Card.
Sep 28 22:09:30 volumio systemd[1]: Reached target Sound Card.
Sep 28 22:09:30 volumio systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:backlight.2.
Sep 28 22:09:31 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x20013
Sep 28 22:09:31 volumio kernel: [FT5X06-WARN]I2C read failed
Sep 28 22:09:31 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x20013
Sep 28 22:09:31 volumio kernel: [FT5X06-WARN]I2C read failed
Sep 28 22:09:31 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x20013
Sep 28 22:09:31 volumio kernel: [FT5X06-WARN]I2C read failed
Sep 28 22:09:31 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x20013
Sep 28 22:09:31 volumio kernel: [FT5X06-WARN]I2C write failed
Sep 28 22:09:31 volumio kernel: [FT5X06-WARN]read config failed
Sep 28 22:09:31 volumio kernel: ft5x06: probe of 1-0038 failed with error -11
Sep 28 22:09:31 volumio kernel: i2c-core: driver [ft5x06] using legacy suspend method
Sep 28 22:09:31 volumio kernel: i2c-core: driver [ft5x06] using legacy resume method
Sep 28 22:09:31 volumio kernel: touch_ft5x06_init 2184
Sep 28 22:09:31 volumio kernel: mode: [0-->2] !
Sep 28 22:09:31 volumio kernel: cputherm_register_notifier list 0
Sep 28 22:09:31 volumio kernel: GPU Init success!
Sep 28 22:09:31 volumio kernel: In normal mode 2
Sep 28 22:09:31 volumio kernel: omaplfb OMAPLFBInitFBDev: Device 1: Couldn't open framebuffer(-1)
Sep 28 22:09:31 volumio kernel: [drm] Initialized pvrsrvkm 1.14.3625561 20110701 on minor 0
Sep 28 22:09:31 volumio systemd[1]: Found device /dev/mmcblk0p1.
Sep 28 22:09:31 volumio systemd[1]: Starting File System Check on /dev/mmcblk0p1...
Sep 28 22:09:31 volumio systemd[1]: Starting system-ifup.slice.
Sep 28 22:09:31 volumio systemd[1]: Created slice system-ifup.slice.
Sep 28 22:09:31 volumio systemd-fsck[290]: fsck.fat 3.0.27 (2014-11-12)
Sep 28 22:09:31 volumio systemd-fsck[290]: /dev/mmcblk0p1: 7 files, 5060/30651 clusters
Sep 28 22:09:31 volumio systemd[1]: Started File System Check on /dev/mmcblk0p1.
Sep 28 22:09:31 volumio systemd[1]: Mounting /boot...
Sep 28 22:09:31 volumio systemd[1]: Mounted /boot.
Sep 28 22:09:31 volumio systemd[1]: Starting Local File Systems.
Sep 28 22:09:31 volumio systemd[1]: Reached target Local File Systems.
Sep 28 22:09:31 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
Sep 28 22:09:31 volumio systemd[1]: Starting Remote File Systems.
Sep 28 22:09:31 volumio systemd[1]: Reached target Remote File Systems.
Sep 28 22:09:31 volumio systemd[1]: Starting Trigger Flushing of Journal to Persistent Storage...
Sep 28 22:09:31 volumio systemd[1]: Starting Create Volatile Files and Directories...
Sep 28 22:09:31 volumio systemd[1]: Starting LSB: Raise network interfaces....
Sep 28 22:09:31 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
Sep 28 22:09:31 volumio systemd[1]: Started Create Volatile Files and Directories.
Sep 28 22:09:31 volumio systemd[1]: Started Trigger Flushing of Journal to Persistent Storage.
Sep 28 22:09:31 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Sep 28 22:09:31 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown.
Sep 28 22:09:31 volumio networking[302]: Configuring network interfaces...command failed: No such device (-19)
Sep 28 22:09:31 volumio networking[302]: command failed: No such device (-19)
Sep 28 22:09:31 volumio kernel: phy_reg_set_bits 339 write reg_val:0xb100
Sep 28 22:09:31 volumio kernel: phy_reg_set_bits 342 write after read reg_val:0x3100
Sep 28 22:09:31 volumio kernel: MII_ADVERTISE: 0x01e1
Sep 28 22:09:31 volumio kernel: phy_init 442 MII_BMCR: 0x3100
Sep 28 22:09:31 volumio kernel: phy_reg_set_bits 339 write reg_val:0x3300
Sep 28 22:09:31 volumio kernel: phy_reg_set_bits 342 write after read reg_val:0x3100
Sep 28 22:09:31 volumio kernel: start aneg...
Sep 28 22:09:31 volumio kernel: phy_init 447 MII_BMCR: 0x3100
Sep 28 22:09:31 volumio kernel: link status is: false
Sep 28 22:09:31 volumio kernel: eth0 link off.
Sep 28 22:09:31 volumio kernel: IRQ 32 requested
Sep 28 22:09:31 volumio kernel: ec_netdev_set_multicast_list 2741
Sep 28 22:09:31 volumio kernel: ec_netdev_set_multicast_list 2741
Sep 28 22:09:31 volumio kernel: ec_netdev_set_multicast_list 2741
Sep 28 22:09:31 volumio kernel: IPv6: ADDRCONF(NETDEV_UP): eth0: link is not ready
Sep 28 22:09:31 volumio networking[302]: done.
Sep 28 22:09:31 volumio systemd[1]: Started LSB: Raise network interfaces..
Sep 28 22:09:32 volumio systemd[1]: Starting ifup for eth0...
Sep 28 22:09:32 volumio systemd[1]: Started ifup for eth0.
Sep 28 22:09:32 volumio systemd[1]: Starting Network.
Sep 28 22:09:32 volumio systemd[1]: Reached target Network.
Sep 28 22:09:32 volumio systemd[1]: Starting Network is Online.
Sep 28 22:09:32 volumio systemd[1]: Reached target Network is Online.
Sep 28 22:09:32 volumio systemd[1]: Starting LSB: RPC portmapper replacement...
Sep 28 22:09:32 volumio ifup[390]: command failed: No such device (-19)
Sep 28 22:09:32 volumio ifup[390]: dhcpcd[415]: version 6.0.5 starting
Sep 28 22:09:32 volumio dhcpcd[415]: version 6.0.5 starting
Sep 28 22:09:32 volumio dhcpcd[415]: eth0: waiting for carrier
Sep 28 22:09:32 volumio ifup[390]: dhcpcd[415]: eth0: waiting for carrier
Sep 28 22:09:32 volumio rpcbind[396]: Starting rpcbind daemon....
Sep 28 22:09:32 volumio systemd[1]: Started LSB: RPC portmapper replacement.
Sep 28 22:09:32 volumio systemd[1]: Starting RPC Port Mapper.
Sep 28 22:09:32 volumio systemd[1]: Reached target RPC Port Mapper.
Sep 28 22:09:32 volumio systemd[1]: Starting LSB: NFS support files common to client and server...
Sep 28 22:09:32 volumio rpc.statd[438]: Version 1.2.8 starting
Sep 28 22:09:32 volumio sm-notify[439]: Version 1.2.8 starting
Sep 28 22:09:32 volumio kernel: Installing knfsd (copyright (C) 1996 okir@monad.swb.de).
Sep 28 22:09:32 volumio nfs-common[432]: Starting NFS common utilities: statd idmapd.
Sep 28 22:09:32 volumio systemd[1]: Started LSB: NFS support files common to client and server.
Sep 28 22:09:32 volumio systemd[1]: Starting System Initialization.
Sep 28 22:09:32 volumio systemd[1]: Reached target System Initialization.
Sep 28 22:09:32 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack Activation Socket.
Sep 28 22:09:32 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Sep 28 22:09:32 volumio systemd[1]: Starting mpd.socket.
Sep 28 22:09:32 volumio systemd[1]: Listening on mpd.socket.
Sep 28 22:09:32 volumio systemd[1]: Starting D-Bus System Message Bus Socket.
Sep 28 22:09:32 volumio systemd[1]: Listening on D-Bus System Message Bus Socket.
Sep 28 22:09:32 volumio systemd[1]: Starting Sockets.
Sep 28 22:09:32 volumio systemd[1]: Reached target Sockets.
Sep 28 22:09:32 volumio systemd[1]: Starting Daily Cleanup of Temporary Directories.
Sep 28 22:09:32 volumio systemd[1]: Started Daily Cleanup of Temporary Directories.
Sep 28 22:09:32 volumio systemd[1]: Starting Timers.
Sep 28 22:09:32 volumio systemd[1]: Reached target Timers.
Sep 28 22:09:32 volumio systemd[1]: Started Manage Sound Card State (restore and store).
Sep 28 22:09:32 volumio systemd[1]: Starting Restore Sound Card State...
Sep 28 22:09:32 volumio systemd[1]: Starting Basic System.
Sep 28 22:09:32 volumio systemd[1]: Reached target Basic System.
Sep 28 22:09:32 volumio systemd[1]: Starting Entropy daemon using the HAVEGE algorithm...
Sep 28 22:09:32 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm.
Sep 28 22:09:32 volumio systemd[1]: Starting Volumio Iptables Module...
Sep 28 22:09:32 volumio alsactl[451]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Dummy mic Gain:0' : Operation not permitted
Sep 28 22:09:32 volumio alsactl[451]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Dummy earphone gain:0' : Operation not permitted
Sep 28 22:09:32 volumio alsactl[451]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Dummy speaker gain:0' : Operation not permitted
Sep 28 22:09:32 volumio alsactl[451]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Dummy speaker volume:0' : Operation not permitted
Sep 28 22:09:32 volumio alsactl[451]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Dummy earphone volume:0' : Operation not permitted
Sep 28 22:09:32 volumio alsactl[451]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Dummy mic num:0' : Operation not permitted
Sep 28 22:09:32 volumio alsactl[451]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Dummy mic mode:0' : Operation not permitted
Sep 28 22:09:32 volumio alsactl[451]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Dummy earphone detect method:0' : Operation not permitted
Sep 28 22:09:32 volumio systemd[1]: Started Volumio Iptables Module.
Sep 28 22:09:32 volumio systemd[1]: Starting mount disks automatically with standby...
Sep 28 22:09:32 volumio systemd[1]: Started mount disks automatically with standby.
Sep 28 22:09:32 volumio systemd[1]: Starting UPnP Renderer front-end to MPD...
Sep 28 22:09:32 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Sep 28 22:09:32 volumio systemd[1]: Starting volumio-remote-updater.service...
Sep 28 22:09:32 volumio systemd[1]: Started volumio-remote-updater.service.
Sep 28 22:09:32 volumio systemd[1]: Starting Volumio SSH enabler...
Sep 28 22:09:32 volumio systemd[1]: Started Volumio SSH enabler.
Sep 28 22:09:32 volumio systemd[1]: Starting Wireless Services...
Sep 28 22:09:32 volumio systemd[1]: Starting /etc/rc.local Compatibility...
Sep 28 22:09:32 volumio systemd[1]: Started getty on tty2-tty6 if dbus and logind are not available.
Sep 28 22:09:32 volumio systemd[1]: Starting Login Service...
Sep 28 22:09:32 volumio systemd[1]: Starting LSB: start Winbind daemon...
Sep 28 22:09:32 volumio systemd[1]: Starting LSB: start Samba daemons for the AD DC...
Sep 28 22:09:32 volumio systemd[1]: Starting LSB: Start NTP daemon...
Sep 28 22:09:32 volumio systemd[1]: Starting LSB: start Samba NetBIOS nameserver (nmbd)...
Sep 28 22:09:32 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling...
Sep 28 22:09:32 volumio systemd[1]: Starting LSB: Brings up/down network automatically...
Sep 28 22:09:32 volumio systemd[1]: Starting LSB: Advanced IEEE 802.11 management daemon...
Sep 28 22:09:32 volumio systemd[1]: Starting LSB: IPv4 DHCP client with IPv4LL support...
Sep 28 22:09:33 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Sep 28 22:09:33 volumio systemd[1]: Starting Music Player Daemon...
Sep 28 22:09:33 volumio systemd[1]: Starting D-Bus System Message Bus...
Sep 28 22:09:33 volumio systemd[1]: Started D-Bus System Message Bus.
Sep 28 22:09:33 volumio dhcpcd[480]: Not running dhcpcd because there is aleady an interface specific instance ... failed!
Sep 28 22:09:33 volumio dhcpcd[480]: /var/run/dhcpcd-eth0.pid ... failed!
Sep 28 22:09:33 volumio ifplugd(eth0)[519]: ifplugd 0.28 initializing.
Sep 28 22:09:33 volumio ifplugd(eth0)[519]: Using interface eth0/00:17:F7:01:09:85 with driver (version: Version - 0.1)
Sep 28 22:09:33 volumio ifplugd(eth0)[519]: Using detection mode: SIOCETHTOOL
Sep 28 22:09:33 volumio ifplugd(eth0)[519]: Initialization complete, link beat not detected.
Sep 28 22:09:33 volumio ifplugd[473]: Network Interface Plugging Daemon...start eth0...done.
Sep 28 22:09:33 volumio loadcpufreq[470]: Loading cpufreq kernel modules...done (none).
Sep 28 22:09:33 volumio avahi-daemon[486]: Found user 'avahi' (UID 104) and group 'avahi' (GID 106).
Sep 28 22:09:33 volumio avahi-daemon[486]: Successfully dropped root privileges.
Sep 28 22:09:33 volumio avahi-daemon[486]: avahi-daemon 0.6.31 starting up.
Sep 28 22:09:33 volumio ntpd[526]: ntpd 4.2.6p5@1.2349-o Mon Jul 25 22:35:28 UTC 2016 (1)
Sep 28 22:09:33 volumio ntp[466]: Starting NTP server: ntpd.
Sep 28 22:09:33 volumio sudo[471]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 set power_save off
Sep 28 22:09:33 volumio ntpd[543]: proto: precision = 0.666 usec
Sep 28 22:09:33 volumio ntpd[543]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123
Sep 28 22:09:33 volumio sudo[471]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:09:33 volumio dhcpcd[415]: eth0: carrier acquired
Sep 28 22:09:33 volumio ifup[390]: dhcpcd[415]: eth0: carrier acquired
Sep 28 22:09:33 volumio kernel: exit
Sep 28 22:09:33 volumio kernel: ecp->autoneg:1
autoneg -> speed:100, duplex:full, pause: non-supported
Sep 28 22:09:33 volumio kernel: set_mac_according_aneg
Sep 28 22:09:33 volumio kernel: opmode regs old value - 0x2202
Sep 28 22:09:33 volumio kernel: old MII_BMCR: 0x3100
Sep 28 22:09:33 volumio kernel: new MII_BMCR: 0x3100
Sep 28 22:09:33 volumio kernel: hw_regs->er_opmode:0x2202
Sep 28 22:09:33 volumio kernel: link established.
Sep 28 22:09:33 volumio kernel: ethernet link state:linked
Sep 28 22:09:33 volumio kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Sep 28 22:09:33 volumio ntpd[543]: Listen and drop on 1 v6wildcard :: UDP 123
Sep 28 22:09:33 volumio ntpd[543]: Listen normally on 2 lo 127.0.0.1 UDP 123
Sep 28 22:09:33 volumio ntpd[543]: peers refreshed
Sep 28 22:09:33 volumio ntpd[543]: Listening on routing socket on fd #19 for interface updates
Sep 28 22:09:33 volumio avahi-daemon[486]: Successfully called chroot().
Sep 28 22:09:33 volumio avahi-daemon[486]: Successfully dropped remaining capabilities.
Sep 28 22:09:33 volumio dbus[498]: [system] Successfully activated service 'org.freedesktop.systemd1'
Sep 28 22:09:33 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Sep 28 22:09:33 volumio wifistart.sh[459]: command failed: No such device (-19)
Sep 28 22:09:33 volumio sudo[471]: pam_unix(sudo:session): session closed for user root
Sep 28 22:09:33 volumio systemd[1]: Starting Permit User Sessions...
Sep 28 22:09:33 volumio avahi-daemon[486]: Loading service file /services/volumio.service.
Sep 28 22:09:33 volumio avahi-daemon[486]: Network interface enumeration completed.
Sep 28 22:09:33 volumio systemd[1]: Started Restore Sound Card State.
Sep 28 22:09:33 volumio avahi-daemon[486]: Registering HINFO record with values 'ARMV7L'/'LINUX'.
Sep 28 22:09:33 volumio avahi-daemon[486]: Server startup complete. Host name is volumio.local. Local service cookie is 1682037816.
Sep 28 22:09:33 volumio avahi-daemon[486]: Service "Volumio" (/services/volumio.service) successfully established.
Sep 28 22:09:33 volumio systemd[1]: Started /etc/rc.local Compatibility.
Sep 28 22:09:33 volumio dhcpcd[415]: eth0: soliciting a DHCP lease
Sep 28 22:09:33 volumio ifup[390]: dhcpcd[415]: eth0: soliciting a DHCP lease
Sep 28 22:09:33 volumio systemd[1]: Started LSB: Start NTP daemon.
Sep 28 22:09:33 volumio dbus[498]: [system] Activating via systemd: service name='org.freedesktop.UDisks' unit='udisks.service'
Sep 28 22:09:33 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling.
Sep 28 22:09:33 volumio systemd[1]: Started LSB: Brings up/down network automatically.
Sep 28 22:09:33 volumio systemd[1]: Started LSB: Advanced IEEE 802.11 management daemon.
Sep 28 22:09:33 volumio systemd[1]: dhcpcd.service: control process exited, code=exited status=6
Sep 28 22:09:34 volumio systemd[1]: Failed to start LSB: IPv4 DHCP client with IPv4LL support.
Sep 28 22:09:34 volumio systemd[1]: Unit dhcpcd.service entered failed state.
Sep 28 22:09:34 volumio systemd[1]: Started Permit User Sessions.
Sep 28 22:09:34 volumio systemd-logind[462]: New seat seat0.
Sep 28 22:09:34 volumio systemd[1]: Started Login Service.
Sep 28 22:09:34 volumio systemd[1]: Starting Disk Manager (legacy version)...
Sep 28 22:09:34 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters...
Sep 28 22:09:34 volumio systemd[1]: Starting Wait for Plymouth Boot Screen to Quit...
Sep 28 22:09:34 volumio systemd[1]: Starting Terminate Plymouth Boot Screen...
Sep 28 22:09:34 volumio systemd[1]: Started Wait for Plymouth Boot Screen to Quit.
Sep 28 22:09:34 volumio systemd[1]: Starting Getty on tty1...
Sep 28 22:09:34 volumio systemd[1]: Started Getty on tty1.
Sep 28 22:09:34 volumio systemd[1]: Starting Serial Getty on ttyS3...
Sep 28 22:09:34 volumio systemd[1]: Started Serial Getty on ttyS3.
Sep 28 22:09:34 volumio systemd[1]: Starting Login Prompts.
Sep 28 22:09:34 volumio systemd[1]: Reached target Login Prompts.
Sep 28 22:09:34 volumio systemd[1]: Started Terminate Plymouth Boot Screen.
Sep 28 22:09:34 volumio ifplugd(eth0)[519]: Link beat detected.
Sep 28 22:09:34 volumio dbus[498]: [system] Successfully activated service 'org.freedesktop.UDisks'
Sep 28 22:09:34 volumio systemd[1]: Started Disk Manager (legacy version).
Sep 28 22:09:34 volumio ifplugd(eth0)[519]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Sep 28 22:09:34 volumio kernel: atc260x-rtc atc2603c-rtc.0: atc260x_rtc_settime(): 2018-09-28 22:09:34
Sep 28 22:09:34 volumio cpufrequtils[565]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done.
Sep 28 22:09:34 volumio volumio-remote-updater[457]: Error: No active session
Sep 28 22:09:34 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters.
Sep 28 22:09:34 volumio volumio-remote-updater[457]: [2018-09-28 22:09:34] [info] asio async_connect error: system:111 (Connection refused)
Sep 28 22:09:34 volumio volumio-remote-updater[457]: [2018-09-28 22:09:34] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Sep 28 22:09:34 volumio volumio-remote-updater[457]: [2018-09-28 22:09:34] [error] handle_connect error: Underlying Transport Error
Sep 28 22:09:34 volumio ifplugd(eth0)[519]: client: /sbin/ifup: interface eth0 already configured
Sep 28 22:09:34 volumio dbus[498]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkitd.service'
Sep 28 22:09:34 volumio haveged[452]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 4.8.2 CTV); collect: 128K
Sep 28 22:09:34 volumio haveged[452]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 8/40; sz: 15248/74244
Sep 28 22:09:34 volumio haveged[452]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99733
Sep 28 22:09:34 volumio haveged[452]: haveged: fills: 0, generated: 0
Sep 28 22:09:34 volumio systemd[1]: Starting Authenticate and Authorize Users to Run Privileged Tasks...
Sep 28 22:09:34 volumio ifplugd(eth0)[519]: Program executed successfully.
Sep 28 22:09:34 volumio volumio[456]: Could not open config: /tmp/upmpdcli.conf
Sep 28 22:09:34 volumio systemd[1]: upmpdcli.service: main process exited, code=exited, status=1/FAILURE
Sep 28 22:09:34 volumio systemd[1]: Unit upmpdcli.service entered failed state.
Sep 28 22:09:35 volumio polkitd[591]: started daemon version 0.105 using authority implementation `local' version `0.105'
Sep 28 22:09:35 volumio dbus[498]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Sep 28 22:09:35 volumio systemd[1]: Started Authenticate and Authorize Users to Run Privileged Tasks.
Sep 28 22:09:35 volumio udisks-glue[455]: Device file /dev/mmcblk0 inserted
Sep 28 22:09:35 volumio udisks-glue[455]: Device file /dev/mmcblk0p3 inserted
Sep 28 22:09:35 volumio ntpd[543]: Deferring DNS for 0.debian.pool.ntp.org 1
Sep 28 22:09:35 volumio ntpd[543]: Deferring DNS for 1.debian.pool.ntp.org 1
Sep 28 22:09:35 volumio ntpd[543]: Deferring DNS for 2.debian.pool.ntp.org 1
Sep 28 22:09:35 volumio ntpd[543]: Deferring DNS for 3.debian.pool.ntp.org 1
Sep 28 22:09:35 volumio ntpd[600]: signal_no_reset: signal 17 had flags 4000000
Sep 28 22:09:36 volumio dhcpcd[415]: eth0: offered 10.0.0.6 from 10.0.0.138
Sep 28 22:09:36 volumio ifup[390]: dhcpcd[415]: eth0: offered 10.0.0.6 from 10.0.0.138
Sep 28 22:09:36 volumio dhcpcd[415]: eth0: leased 10.0.0.6 for 3600 seconds
Sep 28 22:09:36 volumio avahi-daemon[486]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.0.0.6.
Sep 28 22:09:36 volumio avahi-daemon[486]: New relevant interface eth0.IPv4 for mDNS.
Sep 28 22:09:36 volumio dhcpcd[415]: eth0: adding host route to 10.0.0.6 via 127.0.0.1
Sep 28 22:09:36 volumio avahi-daemon[486]: Registering new address record for 10.0.0.6 on eth0.IPv4.
Sep 28 22:09:36 volumio dhcpcd[415]: eth0: adding route to 10.0.0.0/24
Sep 28 22:09:36 volumio dhcpcd[415]: eth0: adding default route via 10.0.0.138
Sep 28 22:09:36 volumio kernel: ec_netdev_set_multicast_list 2741
Sep 28 22:09:36 volumio ifup[390]: dhcpcd[415]: eth0: leased 10.0.0.6 for 3600 seconds
Sep 28 22:09:36 volumio ifup[390]: dhcpcd[415]: eth0: adding host route to 10.0.0.6 via 127.0.0.1
Sep 28 22:09:36 volumio ifup[390]: dhcpcd[415]: eth0: adding route to 10.0.0.0/24
Sep 28 22:09:36 volumio ifup[390]: dhcpcd[415]: eth0: adding default route via 10.0.0.138
Sep 28 22:09:37 volumio ifup[390]: dhcpcd[415]: forked to background, child pid 629
Sep 28 22:09:37 volumio dhcpcd[415]: forked to background, child pid 629
Sep 28 22:09:37 volumio ntpd_intres[600]: DNS 0.debian.pool.ntp.org -> 27.124.125.251
Sep 28 22:09:37 volumio ntpd[543]: Listen normally on 3 eth0 10.0.0.6 UDP 123
Sep 28 22:09:37 volumio ntpd[543]: peers refreshed
Sep 28 22:09:37 volumio wireless.js[562]: WIRELESS DAEMON: start
Sep 28 22:09:37 volumio wireless.js[562]: WIRELESS: Loaded configuration
Sep 28 22:09:37 volumio wireless.js[562]: Cleaning previous...
Sep 28 22:09:38 volumio systemd[1]: Stopped hotspot.service.
Sep 28 22:09:38 volumio kernel: [PowerGate] name: 'usb2_0', on: 1, before SPS_PG_CTL: 0x717761
Sep 28 22:09:38 volumio kernel: [PowerGate] name: 'usb2_0', on: 1, after SPS_PG_CTL: 0x71ff61
Sep 28 22:09:38 volumio kernel: start mon 0 ......
Sep 28 22:09:38 volumio kernel: [PowerGate] name: 'usb2_1', on: 1, before SPS_PG_CTL: 0x71ff61
Sep 28 22:09:38 volumio kernel: [PowerGate] name: 'usb2_1', on: 1, after SPS_PG_CTL: 0x75ff65
Sep 28 22:09:38 volumio kernel: start mon 1 ......
Sep 28 22:09:38 volumio sudo[685]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Sep 28 22:09:38 volumio sudo[685]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:09:38 volumio sudo[685]: pam_unix(sudo:session): session closed for user root
Sep 28 22:09:38 volumio wireless.js[562]: Stopped aP
Sep 28 22:09:38 volumio systemd[1]: Started Music Player Daemon.
Sep 28 22:09:38 volumio systemd[1]: Stopped hotspot.service.
Sep 28 22:09:38 volumio sudo[702]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Sep 28 22:09:38 volumio sudo[702]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:09:38 volumio sudo[702]: pam_unix(sudo:session): session closed for user root
Sep 28 22:09:38 volumio kernel: [PowerGate] name: 'usb2_1', on: 0, before SPS_PG_CTL: 0x75ff65
Sep 28 22:09:38 volumio kernel: [PowerGate] name: 'usb2_1', on: 0, after SPS_PG_CTL: 0x75ff61
Sep 28 22:09:38 volumio kernel: [PowerGate] name: 'usb2_0', on: 0, before SPS_PG_CTL: 0x71ff61
Sep 28 22:09:38 volumio kernel: [PowerGate] name: 'usb2_0', on: 0, after SPS_PG_CTL: 0x71f761
Sep 28 22:09:38 volumio kernel: res_mem->start--end = 0xb0700000--0xb0700fff
Sep 28 22:09:38 volumio kernel: aotg_hcd aotg_hcd.1: pdev->id probe:1
Sep 28 22:09:38 volumio kernel: [PowerGate] name: 'usb2_1', on: 1, before SPS_PG_CTL: 0x717761
Sep 28 22:09:38 volumio kernel: [PowerGate] name: 'usb2_1', on: 1, after SPS_PG_CTL: 0x757765
Sep 28 22:09:38 volumio kernel: usb reset OK: 0.
Sep 28 22:09:38 volumio kernel: aotg_hcd aotg_hcd.1: AOTG USB Host Controller Driver
Sep 28 22:09:38 volumio kernel: aotg_hcd aotg_hcd.1: new USB bus registered, assigned bus number 3
Sep 28 22:09:38 volumio kernel: aotg_hcd aotg_hcd.1: irq 93, io mem 0xb0700000
Sep 28 22:09:38 volumio kernel: aotg_hcd aotg_hcd.1: start
Sep 28 22:09:38 volumio kernel: usb usb3: New USB device found, idVendor=1d6b, idProduct=0002
Sep 28 22:09:38 volumio kernel: usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Sep 28 22:09:38 volumio kernel: usb usb3: Product: AOTG USB Host Controller Driver
Sep 28 22:09:38 volumio kernel: usb usb3: Manufacturer: Linux 3.10.38 aotg_hub_hcd
Sep 28 22:09:38 volumio kernel: usb usb3: SerialNumber: aotg_hcd.1
Sep 28 22:09:38 volumio kernel: hub 3-0:1.0: USB hub found
Sep 28 22:09:38 volumio kernel: hub 3-0:1.0: 1 port detected
Sep 28 22:09:38 volumio kernel: USBEIEN(0xf093650b): 0x80
Sep 28 22:09:38 volumio kernel: aotg_hcd aotg_hcd.1: hcd controller initialized. OTGIRQ: 0x01, OTGSTATE: 0x02
Sep 28 22:09:38 volumio kernel: USBEIRQ(0xf093650a): 0x00
Sep 28 22:09:38 volumio kernel: res_mem->start--end = 0xb0600000--0xb0600fff
Sep 28 22:09:38 volumio kernel: aotg_hcd aotg_hcd.0: pdev->id probe:0
Sep 28 22:09:38 volumio kernel: [PowerGate] name: 'usb2_0', on: 1, before SPS_PG_CTL: 0x757765
Sep 28 22:09:38 volumio kernel: [PowerGate] name: 'usb2_0', on: 1, after SPS_PG_CTL: 0x75ff65
Sep 28 22:09:38 volumio kernel: usb reset OK: 0.
Sep 28 22:09:38 volumio kernel: aotg_hcd aotg_hcd.0: AOTG USB Host Controller Driver
Sep 28 22:09:38 volumio kernel: aotg_hcd aotg_hcd.0: new USB bus registered, assigned bus number 4
Sep 28 22:09:38 volumio kernel: aotg_hcd aotg_hcd.0: irq 56, io mem 0xb0600000
Sep 28 22:09:38 volumio kernel: aotg_hcd aotg_hcd.0: start
Sep 28 22:09:38 volumio kernel: usb usb4: New USB device found, idVendor=1d6b, idProduct=0002
Sep 28 22:09:38 volumio kernel: usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Sep 28 22:09:38 volumio kernel: usb usb4: Product: AOTG USB Host Controller Driver
Sep 28 22:09:38 volumio kernel: usb usb4: Manufacturer: Linux 3.10.38 aotg_hub_hcd
Sep 28 22:09:38 volumio kernel: usb usb4: SerialNumber: aotg_hcd.0
Sep 28 22:09:38 volumio kernel: hub 4-0:1.0: USB hub found
Sep 28 22:09:38 volumio kernel: hub 4-0:1.0: 1 port detected
Sep 28 22:09:38 volumio kernel: USBEIEN(0xf093850b): 0x80
Sep 28 22:09:38 volumio kernel: aotg_hcd aotg_hcd.0: hcd controller initialized. OTGIRQ: 0x01, OTGSTATE: 0x02
Sep 28 22:09:38 volumio kernel: USBEIRQ(0xf093850a): 0x00
Sep 28 22:09:38 volumio systemd[1]: Starting hotspot.service...
Sep 28 22:09:38 volumio systemd[1]: Started hotspot.service.
Sep 28 22:09:38 volumio kernel: port_no:1 OTG IRQ, OTGSTATE: 0x03, USBIRQ:0x00
Sep 28 22:09:38 volumio kernel: port_no:0 OTG IRQ, OTGSTATE: 0x03, USBIRQ:0x00
Sep 28 22:09:38 volumio kernel: aotg_hcd aotg_hcd.1: aotg_hcd.1 connection changed: 1, acthcd->inserted: 1
Sep 28 22:09:38 volumio kernel: aotg_hub_hotplug_timer:1768, dbg!
Sep 28 22:09:38 volumio kernel: aotg_hcd aotg_hcd.0: aotg_hcd.0 connection changed: 1, acthcd->inserted: 1
Sep 28 22:09:38 volumio kernel: aotg_hub_hotplug_timer:1768, dbg!
Sep 28 22:09:38 volumio systemd[1]: Starting dhcpd.service...
Sep 28 22:09:38 volumio systemd[1]: Starting dnsmasq.service...
Sep 28 22:09:38 volumio systemd[1]: Started LSB: start Samba daemons for the AD DC.
Sep 28 22:09:38 volumio systemd[1]: Started Wireless Services.
Sep 28 22:09:38 volumio systemd[1]: Starting Volumio Backend Module...
Sep 28 22:09:38 volumio systemd[1]: Started Volumio Backend Module.
Sep 28 22:09:38 volumio dnsmasq[743]: started, version 2.72 cachesize 150
Sep 28 22:09:38 volumio dnsmasq[743]: compile time options: IPv6 GNU-getopt DBus i18n IDN DHCP DHCPv6 no-Lua TFTP conntrack ipset auth DNSSEC loop-detect
Sep 28 22:09:38 volumio dnsmasq[743]: reading /etc/resolv.conf
Sep 28 22:09:38 volumio dnsmasq[743]: using nameserver 10.0.0.138#53
Sep 28 22:09:38 volumio dnsmasq[743]: using nameserver 208.67.222.222#53
Sep 28 22:09:38 volumio dnsmasq[743]: using nameserver 208.67.220.220#53
Sep 28 22:09:38 volumio dnsmasq[743]: read /etc/hosts - 1 addresses
Sep 28 22:09:38 volumio kernel: set port reset!80
Sep 28 22:09:38 volumio kernel: USBRESET IRQ
Sep 28 22:09:38 volumio kernel: aotg_hub_irq: USB device is 2
Sep 28 22:09:38 volumio systemd[1]: Started dnsmasq.service.
Sep 28 22:09:38 volumio systemd[1]: Starting Host and Network Name Lookups.
Sep 28 22:09:38 volumio systemd[1]: Reached target Host and Network Name Lookups.
Sep 28 22:09:38 volumio dhcpd[730]: Internet Systems Consortium DHCP Server 4.3.1
Sep 28 22:09:38 volumio dhcpd[730]: Copyright 2004-2014 Internet Systems Consortium.
Sep 28 22:09:38 volumio dhcpd[730]: All rights reserved.
Sep 28 22:09:38 volumio dhcpd[730]: For info, please visit https://www.isc.org/software/dhcp/
Sep 28 22:09:38 volumio dhcpd[730]: Config file: /etc/dhcp/dhcpd.conf
Sep 28 22:09:38 volumio dhcpd[730]: Database file: /var/lib/dhcp/dhcpd.leases
Sep 28 22:09:38 volumio dhcpd[730]: PID file: /var/run/dhcpd.pid
Sep 28 22:09:38 volumio kernel: USBRESET IRQ
Sep 28 22:09:38 volumio kernel: aotg_hub_irq: USB device is 3
Sep 28 22:09:38 volumio hotspot[730]: Internet Systems Consortium DHCP Server 4.3.1
Sep 28 22:09:38 volumio hotspot[730]: Copyright 2004-2014 Internet Systems Consortium.
Sep 28 22:09:38 volumio hotspot[730]: All rights reserved.
Sep 28 22:09:38 volumio hotspot[730]: For info, please visit https://www.isc.org/software/dhcp/
Sep 28 22:09:38 volumio hotspot[730]: Config file: /etc/dhcp/dhcpd.conf
Sep 28 22:09:38 volumio hotspot[730]: Database file: /var/lib/dhcp/dhcpd.leases
Sep 28 22:09:38 volumio hotspot[730]: PID file: /var/run/dhcpd.pid
Sep 28 22:09:38 volumio dhcpd[730]: Internet Systems Consortium DHCP Server 4.3.1
Sep 28 22:09:38 volumio dhcpd[730]: Copyright 2004-2014 Internet Systems Consortium.
Sep 28 22:09:38 volumio dhcpd[730]: All rights reserved.
Sep 28 22:09:38 volumio dhcpd[730]: For info, please visit https://www.isc.org/software/dhcp/
Sep 28 22:09:38 volumio dhcpd[730]: Wrote 0 leases to leases file.
Sep 28 22:09:38 volumio hotspot[730]: Wrote 0 leases to leases file.
Sep 28 22:09:38 volumio kernel: usb 3-1: new high-speed USB device number 2 using aotg_hcd
Sep 28 22:09:38 volumio kernel: aotg_hcd aotg_hcd.1: device addr : 0x00000000
Sep 28 22:09:38 volumio kernel: set port reset!80
Sep 28 22:09:38 volumio dhcpd[730]:
Sep 28 22:09:38 volumio dhcpd[730]: No subnet declaration for eth0 (10.0.0.6).
Sep 28 22:09:38 volumio dhcpd[730]: ** Ignoring requests on eth0. If this is not what
Sep 28 22:09:38 volumio dhcpd[730]: you want, please write a subnet declaration
Sep 28 22:09:38 volumio dhcpd[730]: in your dhcpd.conf file for the network segment
Sep 28 22:09:38 volumio dhcpd[730]: to which interface eth0 is attached. **
Sep 28 22:09:38 volumio dhcpd[730]:
Sep 28 22:09:38 volumio dhcpd[730]:
Sep 28 22:09:38 volumio dhcpd[730]: Not configured to listen on any interfaces!
Sep 28 22:09:38 volumio dhcpd[730]:
Sep 28 22:09:38 volumio dhcpd[730]: If you think you have received this message due to a bug rather
Sep 28 22:09:38 volumio dhcpd[730]: than a configuration issue please read the section on submitting
Sep 28 22:09:38 volumio dhcpd[730]: bugs on either our web page at www.isc.org or in the README file
Sep 28 22:09:38 volumio dhcpd[730]: before submitting a bug. These pages explain the proper
Sep 28 22:09:38 volumio dhcpd[730]: process and the information we find helpful for debugging..
Sep 28 22:09:38 volumio dhcpd[730]:
Sep 28 22:09:38 volumio dhcpd[730]: exiting.
Sep 28 22:09:39 volumio systemd[1]: dhcpd.service: control process exited, code=exited status=1
Sep 28 22:09:39 volumio systemd[1]: Failed to start dhcpd.service.
Sep 28 22:09:39 volumio systemd[1]: Unit dhcpd.service entered failed state.
Sep 28 22:09:39 volumio kernel: USBRESET IRQ
Sep 28 22:09:39 volumio kernel: aotg_hub_irq: USB device is 3
Sep 28 22:09:39 volumio hotspot[730]: No subnet declaration for eth0 (10.0.0.6).
Sep 28 22:09:39 volumio hotspot[730]: ** Ignoring requests on eth0. If this is not what
Sep 28 22:09:39 volumio hotspot[730]: you want, please write a subnet declaration
Sep 28 22:09:39 volumio hotspot[730]: in your dhcpd.conf file for the network segment
Sep 28 22:09:39 volumio hotspot[730]: to which interface eth0 is attached. **
Sep 28 22:09:39 volumio hotspot[730]: Not configured to listen on any interfaces!
Sep 28 22:09:39 volumio hotspot[730]: If you think you have received this message due to a bug rather
Sep 28 22:09:39 volumio hotspot[730]: than a configuration issue please read the section on submitting
Sep 28 22:09:39 volumio hotspot[730]: bugs on either our web page at www.isc.org or in the README file
Sep 28 22:09:39 volumio hotspot[730]: before submitting a bug. These pages explain the proper
Sep 28 22:09:39 volumio hotspot[730]: process and the information we find helpful for debugging..
Sep 28 22:09:39 volumio hotspot[730]: exiting.
Sep 28 22:09:39 volumio kernel: aotg_hcd aotg_hcd.1: ep0 index 0 from ep [in]
Sep 28 22:09:39 volumio kernel: aotg_hcd aotg_hcd.1: device addr : 0x00000002
Sep 28 22:09:39 volumio kernel: usb 3-1: New USB device found, idVendor=0424, idProduct=2412
Sep 28 22:09:39 volumio kernel: usb 3-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Sep 28 22:09:39 volumio kernel: hub 3-1:1.0: USB hub found
Sep 28 22:09:39 volumio kernel: hub 3-1:1.0: 2 ports detected
Sep 28 22:09:39 volumio nmbd[467]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory
Sep 28 22:09:39 volumio nmbd[467]: Failed to create /var/log/samba/cores for user 0 with mode 0700
Sep 28 22:09:39 volumio nmbd[467]: Unable to setup corepath for nmbd: No such file or directory
Sep 28 22:09:39 volumio nmbd[751]: [2018/09/28 22:09:39.218104, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:39 volumio nmbd[751]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Sep 28 22:09:39 volumio nmbd[751]: [2018/09/28 22:09:39.222116, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:39 volumio nmbd[751]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Sep 28 22:09:39 volumio nmbd[752]: [2018/09/28 22:09:39.236459, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns)
Sep 28 22:09:39 volumio nmbd[752]: started asyncdns process 753
Sep 28 22:09:39 volumio systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd).
Sep 28 22:09:39 volumio systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)...
Sep 28 22:09:39 volumio kernel: nf_conntrack: automatic helper assignment is deprecated and it will be removed soon. Use the iptables CT target to attach helpers instead.
Sep 28 22:09:39 volumio nmbd[752]: [2018/09/28 22:09:39.259831, 0] ../lib/util/become_daemon.c:124(daemon_ready)
Sep 28 22:09:39 volumio nmbd[752]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Sep 28 22:09:39 volumio nmbd[467]: [2018/09/28 22:09:39, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:39 volumio nmbd[467]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Sep 28 22:09:39 volumio nmbd[467]: [2018/09/28 22:09:39, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:39 volumio nmbd[467]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Sep 28 22:09:39 volumio nmbd[467]: [2018/09/28 22:09:39, 0] ../source3/nmbd/nmbd.c:908(main)
Sep 28 22:09:39 volumio nmbd[467]: nmbd version 4.2.14-Debian started.
Sep 28 22:09:39 volumio nmbd[467]: Copyright Andrew Tridgell and the Samba Team 1992-2014
Sep 28 22:09:39 volumio nmbd[467]: [2018/09/28 22:09:39.218104, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:39 volumio nmbd[467]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Sep 28 22:09:39 volumio nmbd[467]: [2018/09/28 22:09:39.222116, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:39 volumio nmbd[467]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory
Sep 28 22:09:39 volumio nmbd[467]: .
Sep 28 22:09:39 volumio kernel: set port reset!80
Sep 28 22:09:39 volumio kernel: USBRESET IRQ
Sep 28 22:09:39 volumio kernel: aotg_hub_irq: USB device is 2
Sep 28 22:09:39 volumio kernel: open ttyS3
Sep 28 22:09:39 volumio kernel: open ttyS3
Sep 28 22:09:39 volumio kernel: OWL_HDMI_DEBUG: hdmi_get_over_scan
Sep 28 22:09:39 volumio kernel: USBRESET IRQ
Sep 28 22:09:39 volumio kernel: aotg_hub_irq: USB device is 3
Sep 28 22:09:39 volumio kernel: open ttyS3
Sep 28 22:09:39 volumio kernel: open ttyS3
Sep 28 22:09:39 volumio winbind[463]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory
Sep 28 22:09:39 volumio winbind[463]: Failed to create /var/log/samba/cores for user 0 with mode 0700
Sep 28 22:09:39 volumio winbind[463]: Unable to setup corepath for winbindd: No such file or directory
Sep 28 22:09:39 volumio winbind[463]: mkdir failed on directory /var/log/samba/cores: No such file or directory
Sep 28 22:09:39 volumio winbind[463]: Failed to create /var/log/samba/cores for user 0 with mode 0700
Sep 28 22:09:39 volumio winbind[463]: Unable to setup corepath for winbindd: No such file or directory
Sep 28 22:09:39 volumio winbind[463]: [2018/09/28 22:09:39, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:39 volumio winbind[463]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Sep 28 22:09:39 volumio winbind[463]: [2018/09/28 22:09:39, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:39 volumio winbind[463]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Sep 28 22:09:39 volumio winbind[463]: [2018/09/28 22:09:39, 0] ../source3/winbindd/winbindd.c:1549(main)
Sep 28 22:09:39 volumio winbind[463]: winbindd version 4.2.14-Debian started.
Sep 28 22:09:39 volumio winbind[463]: Copyright Andrew Tridgell and the Samba Team 1992-2014
Sep 28 22:09:39 volumio winbind[463]: [2018/09/28 22:09:39, 0] ../lib/util/util.c:220(directory_create_or_exist)
Sep 28 22:09:39 volumio winbind[463]: mkdir failed on directory /var/log/samba/cores: No such file or directory
Sep 28 22:09:39 volumio winbind[463]: [2018/09/28 22:09:39, 0] ../source3/lib/dumpcore.c:59(get_default_corepath)
Sep 28 22:09:39 volumio winbind[463]: Failed to create /var/log/samba/cores for user 0 with mode 0700
Sep 28 22:09:39 volumio winbind[463]: [2018/09/28 22:09:39, 0] ../source3/lib/dumpcore.c:250(dump_core_setup)
Sep 28 22:09:39 volumio winbind[463]: Unable to setup corepath for winbindd: No such file or directory
Sep 28 22:09:39 volumio kernel: usb 4-1: new high-speed USB device number 2 using aotg_hcd
Sep 28 22:09:39 volumio kernel: aotg_hcd aotg_hcd.0: device addr : 0x00000000
Sep 28 22:09:39 volumio kernel: set port reset!80
Sep 28 22:09:39 volumio winbindd[740]: [2018/09/28 22:09:39.386551, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:39 volumio winbindd[740]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Sep 28 22:09:39 volumio winbindd[740]: [2018/09/28 22:09:39.388670, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:39 volumio winbindd[740]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Sep 28 22:09:39 volumio systemd[1]: Started LSB: start Winbind daemon.
Sep 28 22:09:39 volumio winbind[463]: [2018/09/28 22:09:39.386551, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:39 volumio winbind[463]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Sep 28 22:09:39 volumio winbind[463]: [2018/09/28 22:09:39.388670, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:39 volumio winbind[463]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory
Sep 28 22:09:39 volumio hotspot[728]: basename: missing operand
Sep 28 22:09:39 volumio hotspot[728]: Try 'basename --help' for more information.
Sep 28 22:09:39 volumio kernel: USBRESET IRQ
Sep 28 22:09:39 volumio kernel: aotg_hub_irq: USB device is 3
Sep 28 22:09:39 volumio winbind[463]: .
Sep 28 22:09:39 volumio winbindd[767]: [2018/09/28 22:09:39.454280, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache)
Sep 28 22:09:39 volumio winbindd[767]: initialize_winbindd_cache: clearing cache and re-creating with version number 2
Sep 28 22:09:39 volumio hotspot[728]: /bin/hotspot.sh: line 7: [: too many arguments
Sep 28 22:09:39 volumio hotspot[728]: /bin/hotspot.sh: line 10: [: too many arguments
Sep 28 22:09:39 volumio hotspot[728]: Launching Ordinary Hostapd
Sep 28 22:09:39 volumio winbindd[767]: [2018/09/28 22:09:39.492279, 0] ../lib/util/become_daemon.c:124(daemon_ready)
Sep 28 22:09:39 volumio winbindd[767]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
Sep 28 22:09:39 volumio winbindd[769]: [2018/09/28 22:09:39.502190, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:39 volumio winbindd[769]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO': No such file or directory
Sep 28 22:09:39 volumio kernel: aotg_hcd aotg_hcd.0: ep0 index 0 from ep [in]
Sep 28 22:09:39 volumio kernel: aotg_hcd aotg_hcd.0: device addr : 0x00000002
Sep 28 22:09:39 volumio kernel: usb 4-1: New USB device found, idVendor=1a40, idProduct=0101
Sep 28 22:09:39 volumio kernel: usb 4-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
Sep 28 22:09:39 volumio kernel: usb 4-1: Product: USB 2.0 Hub
Sep 28 22:09:39 volumio kernel: hub 4-1:1.0: USB hub found
Sep 28 22:09:39 volumio kernel: hub 4-1:1.0: 4 ports detected
Sep 28 22:09:39 volumio volumio-remote-updater[457]: [2018-09-28 22:09:39] [info] asio async_connect error: system:111 (Connection refused)
Sep 28 22:09:39 volumio volumio-remote-updater[457]: [2018-09-28 22:09:39] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Sep 28 22:09:39 volumio volumio-remote-updater[457]: [2018-09-28 22:09:39] [error] handle_connect error: Underlying Transport Error
Sep 28 22:09:39 volumio kernel: usb 3-1.1: new high-speed USB device number 3 using aotg_hcd
Sep 28 22:09:39 volumio kernel: aotg_hcd aotg_hcd.1: device addr : 0x00000082
Sep 28 22:09:39 volumio hotspot[728]: Configuration file: /etc/hostapd/hostapd.conf
Sep 28 22:09:39 volumio hotspot[728]: Could not read interface wlan0 flags: No such device
Sep 28 22:09:39 volumio hotspot[728]: nl80211: Driver does not support authentication/association or connect commands
Sep 28 22:09:39 volumio hotspot[728]: Could not read interface wlan0 flags: No such device
Sep 28 22:09:39 volumio hotspot[728]: nl80211 driver initialization failed.
Sep 28 22:09:39 volumio hotspot[728]: hostapd_free_hapd_data: Interface wlan0 wasn't started
Sep 28 22:09:39 volumio systemd[1]: hotspot.service: main process exited, code=exited, status=1/FAILURE
Sep 28 22:09:39 volumio systemd[1]: Stopping Host and Network Name Lookups.
Sep 28 22:09:39 volumio systemd[1]: Stopped target Host and Network Name Lookups.
Sep 28 22:09:39 volumio systemd[1]: Stopping dnsmasq.service...
Sep 28 22:09:39 volumio systemd[1]: Stopped dnsmasq.service.
Sep 28 22:09:39 volumio dnsmasq[743]: exiting on receipt of SIGTERM
Sep 28 22:09:39 volumio hotspot[782]: Killing Hostapd
Sep 28 22:09:39 volumio kernel: aotg_hcd aotg_hcd.1: ep0 index 0 from ep [in]
Sep 28 22:09:39 volumio sudo[785]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall hostapd
Sep 28 22:09:39 volumio sudo[785]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:09:39 volumio kernel: aotg_hcd aotg_hcd.1: device addr : 0x00000082
Sep 28 22:09:39 volumio kernel: usb 3-1.1: New USB device found, idVendor=152a, idProduct=8750
Sep 28 22:09:39 volumio kernel: usb 3-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=0
Sep 28 22:09:39 volumio kernel: usb 3-1.1: Product: D50
Sep 28 22:09:39 volumio kernel: usb 3-1.1: Manufacturer: Topping
Sep 28 22:09:39 volumio hotspot[782]: hostapd: no process found
Sep 28 22:09:39 volumio sudo[785]: pam_unix(sudo:session): session closed for user root
Sep 28 22:09:39 volumio hotspot[782]: Killing Dhcpd
Sep 28 22:09:39 volumio sudo[787]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall dhcpd
Sep 28 22:09:39 volumio sudo[787]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:09:39 volumio hotspot[782]: dhcpd: no process found
Sep 28 22:09:39 volumio sudo[787]: pam_unix(sudo:session): session closed for user root
Sep 28 22:09:39 volumio systemd[1]: hotspot.service: control process exited, code=exited status=1
Sep 28 22:09:39 volumio systemd[1]: Unit hotspot.service entered failed state.
Sep 28 22:09:39 volumio kernel: usbcore: registered new interface driver snd-usb-audio
Sep 28 22:09:40 volumio smbd[754]: Starting SMB/CIFS daemon: smbd[2018/09/28 22:09:40, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:40 volumio smbd[754]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Sep 28 22:09:40 volumio smbd[754]: [2018/09/28 22:09:40, 0] ../lib/util/util.c:220(directory_create_or_exist)
Sep 28 22:09:40 volumio smbd[754]: mkdir failed on directory /var/log/samba/cores: No such file or directory
Sep 28 22:09:40 volumio smbd[754]: [2018/09/28 22:09:40, 0] ../source3/lib/dumpcore.c:59(get_default_corepath)
Sep 28 22:09:40 volumio smbd[754]: Failed to create /var/log/samba/cores for user 0 with mode 0700
Sep 28 22:09:40 volumio smbd[754]: [2018/09/28 22:09:40, 0] ../source3/lib/dumpcore.c:250(dump_core_setup)
Sep 28 22:09:40 volumio smbd[754]: Unable to setup corepath for smbd: No such file or directory
Sep 28 22:09:40 volumio smbd[754]: [2018/09/28 22:09:40, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:40 volumio smbd[754]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Sep 28 22:09:40 volumio smbd[754]: [2018/09/28 22:09:40, 0] ../source3/smbd/server.c:1241(main)
Sep 28 22:09:40 volumio smbd[754]: smbd version 4.2.14-Debian started.
Sep 28 22:09:40 volumio smbd[754]: Copyright Andrew Tridgell and the Samba Team 1992-2014
Sep 28 22:09:40 volumio smbd[794]: [2018/09/28 22:09:40.961040, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:40 volumio smbd[794]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Sep 28 22:09:40 volumio smbd[794]: [2018/09/28 22:09:40.963665, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:40 volumio smbd[794]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Sep 28 22:09:40 volumio smbd[794]: [2018/09/28 22:09:40.964089, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:40 volumio smbd[794]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Sep 28 22:09:40 volumio smbd[754]: [2018/09/28 22:09:40.961040, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:40 volumio smbd[754]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Sep 28 22:09:40 volumio smbd[754]: [2018/09/28 22:09:40.963665, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:40 volumio smbd[754]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Sep 28 22:09:40 volumio smbd[754]: [2018/09/28 22:09:40.964089, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:40 volumio smbd[754]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Sep 28 22:09:40 volumio systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd).
Sep 28 22:09:40 volumio smbd[754]: .
Sep 28 22:09:40 volumio systemd[1]: Starting Multi-User System.
Sep 28 22:09:40 volumio systemd[1]: Reached target Multi-User System.
Sep 28 22:09:40 volumio systemd[1]: Starting Graphical Interface.
Sep 28 22:09:40 volumio systemd[1]: Reached target Graphical Interface.
Sep 28 22:09:40 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes...
Sep 28 22:09:41 volumio systemd[1]: Started Update UTMP about System Runlevel Changes.
Sep 28 22:09:41 volumio systemd[1]: Startup finished in 2.455s (kernel) + 11.451s (userspace) = 13.906s.
Sep 28 22:09:41 volumio winbindd[800]: [2018/09/28 22:09:41.054759, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:41 volumio winbindd[800]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory
Sep 28 22:09:41 volumio winbindd[801]: [2018/09/28 22:09:41.098215, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:41 volumio winbindd[801]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory
Sep 28 22:09:41 volumio smbd[795]: [2018/09/28 22:09:41.156661, 0] ../lib/util/become_daemon.c:124(daemon_ready)
Sep 28 22:09:41 volumio smbd[795]: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Sep 28 22:09:41 volumio smbd[802]: [2018/09/28 22:09:41.165043, 0] ../lib/util/debug.c:603(reopen_logs_internal)
Sep 28 22:09:41 volumio smbd[802]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory
Sep 28 22:09:42 volumio volumio[741]: info: -------------------------------------------
Sep 28 22:09:42 volumio volumio[741]: info: ----- Volumio2 ----
Sep 28 22:09:42 volumio volumio[741]: info: -------------------------------------------
Sep 28 22:09:42 volumio volumio[741]: info: ----- System startup ----
Sep 28 22:09:42 volumio volumio[741]: info: -------------------------------------------
Sep 28 22:09:42 volumio ntpd_intres[600]: DNS 1.debian.pool.ntp.org -> 45.76.113.31
Sep 28 22:09:42 volumio volumio[741]: info: Plugin folders cleanup
Sep 28 22:09:42 volumio volumio[741]: info: Scanning into folder /volumio/app/plugins/
Sep 28 22:09:42 volumio volumio[741]: info: Scanning category audio_interface
Sep 28 22:09:42 volumio volumio[741]: info: Scanning category miscellanea
Sep 28 22:09:42 volumio volumio[741]: info: Scanning category music_service
Sep 28 22:09:42 volumio volumio[741]: info: Scanning category plugins.json
Sep 28 22:09:42 volumio volumio[741]: info: Scanning category system_controller
Sep 28 22:09:42 volumio volumio[741]: info: Scanning category user_interface
Sep 28 22:09:42 volumio volumio[741]: info: Scanning into folder /data/plugins/
Sep 28 22:09:42 volumio volumio[741]: info: Scanning category music_service
Sep 28 22:09:42 volumio volumio[741]: info: Plugin folders cleanup completed
Sep 28 22:09:42 volumio volumio[741]: info: -------------------------------------------
Sep 28 22:09:42 volumio volumio[741]: info: ----- Core plugins startup ----
Sep 28 22:09:42 volumio volumio[741]: info: -------------------------------------------
Sep 28 22:09:42 volumio volumio[741]: info: Loading plugins from folder /volumio/app/plugins/
Sep 28 22:09:42 volumio volumio[741]: info: Adding plugin upnp to MyMusic Plugins
Sep 28 22:09:42 volumio volumio[741]: info: Adding plugin airplay_emulation to MyMusic Plugins
Sep 28 22:09:42 volumio volumio[741]: info: Adding plugin upnp_browser to MyMusic Plugins
Sep 28 22:09:42 volumio volumio[741]: info: Loading plugins from folder /data/plugins/
Sep 28 22:09:42 volumio volumio[741]: info: Loading plugin "system"...
Sep 28 22:09:42 volumio volumio[741]: info: Loading plugin "appearance"...
Sep 28 22:09:43 volumio ntpd_intres[600]: DNS 2.debian.pool.ntp.org -> 27.124.125.251
Sep 28 22:09:43 volumio ntpd_intres[600]: DNS 3.debian.pool.ntp.org -> 80.74.64.1
Sep 28 22:09:44 volumio volumio[741]: info: Loading plugin "network"...
Sep 28 22:09:44 volumio volumio[741]: info: Loading plugin "services"...
Sep 28 22:09:44 volumio volumio[741]: info: Loading plugin "alsa_controller"...
Sep 28 22:10:16 volumio systemd[1]: Time has been changed
Sep 28 22:10:16 volumio volumio[741]: info: Loading plugin "wizard"...
Sep 28 22:10:16 volumio volumio[741]: info: Loading plugin "volumio_command_line_client"...
Sep 28 22:10:16 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:10:16 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:10:16 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:10:16 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:10:16 volumio volumio[741]: info: Loading plugin "upnp"...
Sep 28 22:10:16 volumio volumio[741]: info: [1538172616619] Starting Upmpd Daemon
Sep 28 22:10:16 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 28 22:10:16 volumio volumio[741]: info: Loading plugin "my_music"...
Sep 28 22:10:16 volumio volumio[741]: info: Loading plugin "mpd"...
Sep 28 22:10:17 volumio volumio[741]: info: Loading plugin "upnp_browser"...
Sep 28 22:10:18 volumio volumio[741]: info: Loading plugin "networkfs"...
Sep 28 22:10:18 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 28 22:10:18 volumio volumio[741]: info: Loading plugin "alarm-clock"...
Sep 28 22:10:18 volumio sudo[819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //VORTEXBOX/files /mnt/NAS/files
Sep 28 22:10:18 volumio sudo[819]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:10:18 volumio volumio-remote-updater[457]: [2018-09-28 22:10:18] [connect] Successful connection
Sep 28 22:10:18 volumio sudo[819]: pam_unix(sudo:session): session closed for user root
Sep 28 22:10:18 volumio volumio[741]: info: Loading plugin "airplay_emulation"...
Sep 28 22:10:18 volumio volumio[741]: info: Starting Shairport Sync
Sep 28 22:10:18 volumio volumio[741]: info: Loading plugin "last_100"...
Sep 28 22:10:18 volumio volumio[741]: info: Loading plugin "webradio"...
Sep 28 22:10:18 volumio volumio[741]: info: Loading plugin "i2s_dacs"...
Sep 28 22:10:18 volumio volumio[741]: info: I2S DAC not set, start Auto-detection
Sep 28 22:10:18 volumio volumio[741]: info: Loading plugin "volumiodiscovery"...
Sep 28 22:10:18 volumio volumio[741]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Sep 28 22:10:18 volumio node[741]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Sep 28 22:10:18 volumio node[741]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 28 22:10:18 volumio node[741]: *** WARNING *** For more information see
Sep 28 22:10:18 volumio node[741]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Sep 28 22:10:18 volumio node[741]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 28 22:10:18 volumio node[741]: *** WARNING *** For more information see
Sep 28 22:10:18 volumio volumio[741]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 28 22:10:18 volumio volumio[741]: *** WARNING *** For more information see
Sep 28 22:10:18 volumio volumio[741]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Sep 28 22:10:18 volumio volumio[741]: *** WARNING *** Please fix your application to use the native API of Avahi!
Sep 28 22:10:18 volumio volumio[741]: *** WARNING *** For more information see
Sep 28 22:10:18 volumio volumio[741]: info: Applying required configuration parameters for plugin volumiodiscovery
Sep 28 22:10:18 volumio volumio[741]: Discovery: StartAdv! undefined
Sep 28 22:10:18 volumio volumio[741]: Discovery: Started advertising... Volumio - undefined
Sep 28 22:10:18 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Sep 28 22:10:18 volumio volumio[741]: info: Loading plugin "albumart"...
Sep 28 22:10:19 volumio volumio[741]: info: Plugin example_plugin is not enabled
Sep 28 22:10:19 volumio volumio[741]: info: Loading plugin "updater_comm"...
Sep 28 22:10:19 volumio volumio[741]: info: Plugin mpdemulation is not enabled
Sep 28 22:10:19 volumio volumio[741]: info: Loading plugin "rest_api"...
Sep 28 22:10:19 volumio volumio[741]: info: Loading plugin "websocket"...
Sep 28 22:10:19 volumio volumio[741]: info: Plugin spop is not enabled
Sep 28 22:10:19 volumio volumio[741]: info: Loading plugin "volspotconnect2"...
Sep 28 22:10:19 volumio volumio[741]: Forking 3 albumart workers
Sep 28 22:10:20 volumio volumio[741]: info: ___________ START PLUGINS ___________
Sep 28 22:10:20 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 28 22:10:20 volumio volumio[741]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 28 22:10:20 volumio volumio[741]: info: [1538172620872] CoreMusicLibrary::Adding element Media Servers
Sep 28 22:10:20 volumio volumio[741]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 28 22:10:20 volumio volumio[741]: info: [1538172620986] CoreMusicLibrary::Adding element Last_100
Sep 28 22:10:21 volumio volumio[741]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Sep 28 22:10:21 volumio volumio[741]: info: [1538172621002] CoreMusicLibrary::Adding element Webradio
Sep 28 22:10:21 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 28 22:10:21 volumio volumio[741]: info: -------------------------------------------
Sep 28 22:10:21 volumio volumio[741]: info: ----- MyVolumio plugins startup ----
Sep 28 22:10:21 volumio volumio[741]: info: -------------------------------------------
Sep 28 22:10:21 volumio volumio[741]: info: Loading plugins from folder /myvolumio/plugins
Sep 28 22:10:21 volumio volumio[741]: info: Loading plugins from folder /data/myvolumio/plugins
Sep 28 22:10:21 volumio volumio[741]: info: Loading i18n strings for locale en
Sep 28 22:10:21 volumio volumio[741]: Updating browse sources language
Sep 28 22:10:21 volumio volumio[741]: Cannot find translation for sourceMedia Servers
Sep 28 22:10:21 volumio sudo[866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service
Sep 28 22:10:21 volumio sudo[866]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:10:21 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 28 22:10:21 volumio systemd[1]: Starting Volspotconnect2 Daemon...
Sep 28 22:10:21 volumio volumio[741]: info: CoreCommandRouter::initPlayerControls
Sep 28 22:10:21 volumio systemd[1]: Started Volspotconnect2 Daemon.
Sep 28 22:10:21 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:10:21 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards
Sep 28 22:10:21 volumio sudo[866]: pam_unix(sudo:session): session closed for user root
Sep 28 22:10:21 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:10:21 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:10:21 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:10:21 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:10:21 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:10:21 volumio volumio[868]: INFO:librespot: librespot 9b85d33 (2018-05-18). Built on 2018-05-18. Build ID: u91GgpUM
Sep 28 22:10:21 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 28 22:10:21 volumio volumio[741]: info: BOOT COMPLETED
Sep 28 22:10:21 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 28 22:10:21 volumio kernel: snd_pcm_mmap,default
Sep 28 22:10:21 volumio kernel: iso_packets:0, bInterval:1, urb_interval:1, reg_con:0x4
Sep 28 22:10:21 volumio kernel: iso_packets:0, bInterval:4, urb_interval:8, reg_con:0x4
Sep 28 22:10:21 volumio volumio[868]: WARN:mdns: Failed to register IPv6 receiver: Error { repr: Os { code: 19, message: "No such device" } }
Sep 28 22:10:21 volumio volumio[741]: Starting albumart workers
Sep 28 22:10:21 volumio volumio[741]: Starting albumart workers
Sep 28 22:10:21 volumio volumio[741]: Starting albumart workers
Sep 28 22:10:26 volumio volumio[741]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo
Sep 28 22:10:26 volumio volumio[741]: info: CoreCommandRouter::Close All Modals sent
Sep 28 22:10:26 volumio volumio[741]: info: CoreCommandRouter::Close All Modals sent
Sep 28 22:10:26 volumio kernel: aotg_hub_endpoint_disable
Sep 28 22:10:26 volumio kernel: aotg_hcd aotg_hcd.1: ep1 index 1 from ep [out]
Sep 28 22:10:26 volumio kernel: aotg_hub_endpoint_disable
Sep 28 22:10:26 volumio kernel: aotg_hcd aotg_hcd.1: ep1 index 2 from ep [in]
Sep 28 22:10:26 volumio volumio[741]: Express server listening on port 3000
Sep 28 22:10:26 volumio volumio[741]: Volumio Calling Home
Sep 28 22:10:26 volumio kernel: ec_netdev_set_multicast_list 2741
Sep 28 22:10:26 volumio volumio[741]: info: CoreStateMachine::resetVolumioState
Sep 28 22:10:26 volumio volumio[741]: info: CoreStateMachine::getcurrentVolume
Sep 28 22:10:26 volumio volumio[741]: info: CoreCommandRouter::volumioRetrievevolume
Sep 28 22:10:26 volumio volumio[741]: info: CoreStateMachine::updateTrackBlock
Sep 28 22:10:26 volumio volumio[741]: info: CorePlayQueue::getTrackBlock
Sep 28 22:10:27 volumio sudo[884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 28 22:10:27 volumio sudo[884]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:10:27 volumio sudo[884]: pam_unix(sudo:session): session closed for user root
Sep 28 22:10:27 volumio volumio[741]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Sep 28 22:10:27 volumio volumio[741]: info: Setting Device type: Sparky
Sep 28 22:10:27 volumio volumio[741]: info: Volspotconnect2 Daemon Started
Sep 28 22:10:27 volumio volumio[741]: info: [SpConDebug] Volspotconnect2 Started
Sep 28 22:10:27 volumio volumio[741]: info: [SpConDebug] Starting metadata listener
Sep 28 22:10:27 volumio volumio[741]: info: MPD running with PID493 ,establishing connection
Sep 28 22:10:27 volumio sudo[892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1
Sep 28 22:10:27 volumio sudo[892]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio kernel: owl_i2c: owl_i2c_fifo_irq(): [i2c1] no ACK, fifostat 0x3
Sep 28 22:10:27 volumio sudo[892]: pam_unix(sudo:session): session closed for user root
Sep 28 22:10:27 volumio volumio[741]: info: VolumeController:: Volume=96 Mute =false
Sep 28 22:10:27 volumio volumio[741]: info: CoreStateMachine::pushState
Sep 28 22:10:27 volumio volumio[741]: info: CoreStateMachine::getState
Sep 28 22:10:27 volumio volumio[741]: info: CorePlayQueue::getTrack 0
Sep 28 22:10:27 volumio volumio[741]: info: CoreCommandRouter::volumioPushState
Sep 28 22:10:27 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 28 22:10:27 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 28 22:10:27 volumio volumio[741]: info: Reloading queue from file
Sep 28 22:10:27 volumio volumio[741]: info:
Sep 28 22:10:27 volumio volumio[741]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory
Sep 28 22:10:27 volumio volumio[741]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14)
Sep 28 22:10:27 volumio volumio[741]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10)
Sep 28 22:10:27 volumio volumio[741]: at emitOne (events.js:116:13)
Sep 28 22:10:27 volumio volumio[741]: at Socket.emit (events.js:211:7)
Sep 28 22:10:27 volumio volumio[741]: at addChunk (_stream_readable.js:263:12)
Sep 28 22:10:27 volumio volumio[741]: at readableAddChunk (_stream_readable.js:246:13)
Sep 28 22:10:27 volumio volumio[741]: at Socket.Readable.push (_stream_readable.js:208:10)
Sep 28 22:10:27 volumio volumio[741]: at Pipe.onread (net.js:607:20)
Sep 28 22:10:27 volumio volumio[741]: info: Volumio called home
Sep 28 22:10:27 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Sep 28 22:10:27 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Sep 28 22:10:27 volumio volumio[741]: info: Version has changed, forcing UI Reload
Sep 28 22:10:27 volumio volumio[741]: info: CoreCommandRouter::Reload Ui
Sep 28 22:10:27 volumio volumio[741]: info: CoreStateMachine::setRepeat null single undefined
Sep 28 22:10:27 volumio volumio[741]: info: CoreStateMachine::pushState
Sep 28 22:10:27 volumio volumio[741]: info: CoreStateMachine::getState
Sep 28 22:10:27 volumio volumio[741]: info: CorePlayQueue::getTrack 0
Sep 28 22:10:27 volumio volumio[741]: info: CoreCommandRouter::volumioPushState
Sep 28 22:10:27 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 28 22:10:27 volumio volumio[741]: info: CoreStateMachine::setRandom null
Sep 28 22:10:27 volumio volumio[741]: info: CoreStateMachine::pushState
Sep 28 22:10:27 volumio volumio[741]: info: CoreStateMachine::getState
Sep 28 22:10:27 volumio volumio[741]: info: CorePlayQueue::getTrack 0
Sep 28 22:10:27 volumio volumio[741]: info: CoreCommandRouter::volumioPushState
Sep 28 22:10:27 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 28 22:10:28 volumio sudo[898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Sep 28 22:10:28 volumio sudo[898]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:10:28 volumio sudo[900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay
Sep 28 22:10:28 volumio sudo[900]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:10:28 volumio systemd[1]: Starting UPnP Renderer front-end to MPD...
Sep 28 22:10:28 volumio systemd[1]: Started UPnP Renderer front-end to MPD.
Sep 28 22:10:28 volumio sudo[898]: pam_unix(sudo:session): session closed for user root
Sep 28 22:10:28 volumio sudo[903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay
Sep 28 22:10:28 volumio sudo[903]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:10:28 volumio systemd[1]: Starting ShairportSync AirTunes receiver...
Sep 28 22:10:28 volumio systemd[1]: Started ShairportSync AirTunes receiver.
Sep 28 22:10:28 volumio sudo[900]: pam_unix(sudo:session): session closed for user root
Sep 28 22:10:28 volumio sudo[903]: pam_unix(sudo:session): session closed for user root
Sep 28 22:10:28 volumio volumio[741]: Discovery: adding 880ca0b6-35ac-43e2-855f-0f6f4514b800
Sep 28 22:10:28 volumio volumio[741]: info: mDNS: Found device Volumio
Sep 28 22:10:28 volumio shairport-sync[907]: Successful Startup
Sep 28 22:10:28 volumio sudo[909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 28 22:10:28 volumio sudo[909]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:10:28 volumio sudo[909]: pam_unix(sudo:session): session closed for user root
Sep 28 22:10:28 volumio volumio[741]: info: Upmpdcli Daemon Started
Sep 28 22:10:29 volumio volumio[741]: info: Shairport-Sync Started
Sep 28 22:10:29 volumio volumio[741]: Error adding Membership: Error: addMembership EINVAL
Sep 28 22:10:29 volumio volumio[741]: info: Shairport-Sync Started
Sep 28 22:10:30 volumio volumio[741]: error: error
Sep 28 22:10:30 volumio volumio[741]: error: error
Sep 28 22:10:30 volumio volumio[741]: error: error
Sep 28 22:10:30 volumio volumio[741]: info: CoreCommandRouter::volumioGetState
Sep 28 22:10:30 volumio volumio[741]: info: CoreStateMachine::getState
Sep 28 22:10:30 volumio volumio[741]: info: CorePlayQueue::getTrack 0
Sep 28 22:10:30 volumio sudo[918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 28 22:10:30 volumio sudo[918]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:10:30 volumio sudo[918]: pam_unix(sudo:session): session closed for user root
Sep 28 22:10:31 volumio volumio[741]: info: CoreCommandRouter::volumioGetState
Sep 28 22:10:31 volumio volumio[741]: info: CoreStateMachine::getState
Sep 28 22:10:31 volumio volumio[741]: info: CorePlayQueue::getTrack 0
Sep 28 22:10:31 volumio sudo[949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 28 22:10:31 volumio sudo[949]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:10:31 volumio volumio[741]: info: CoreCommandRouter::volumioGetBrowseSources
Sep 28 22:10:31 volumio sudo[949]: pam_unix(sudo:session): session closed for user root
Sep 28 22:10:31 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Sep 28 22:10:31 volumio sudo[956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 28 22:10:31 volumio volumio[741]: info: Listing playlists
Sep 28 22:10:31 volumio sudo[956]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:10:31 volumio sudo[956]: pam_unix(sudo:session): session closed for user root
Sep 28 22:10:33 volumio volumio-remote-updater[457]: [2018-09-28 22:10:33] [connect] Successful connection
Sep 28 22:10:33 volumio volumio-remote-updater[457]: [2018-09-28 22:10:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1538172633 101
Sep 28 22:10:51 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 28 22:10:51 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 28 22:11:16 volumio volumio[741]: error: error
Sep 28 22:11:16 volumio volumio[741]: error: error
Sep 28 22:11:16 volumio volumio[741]: error: error
Sep 28 22:11:33 volumio volumio[741]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 28 22:11:33 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Sep 28 22:11:33 volumio volumio[741]: info: Received Get System Version
Sep 28 22:11:33 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Sep 28 22:11:38 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Sep 28 22:11:38 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Sep 28 22:11:40 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Sep 28 22:11:40 volumio volumio[741]: info: Loading i18n strings for locale en
Sep 28 22:11:40 volumio volumio[741]: Updating browse sources language
Sep 28 22:11:40 volumio volumio[741]: Cannot find translation for sourceMedia Servers
Sep 28 22:11:41 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings
Sep 28 22:11:41 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices
Sep 28 22:11:41 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Sep 28 22:11:41 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 28 22:11:41 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Sep 28 22:11:43 volumio volumio[741]: info: Output device has changed, restarting MPD
Sep 28 22:11:43 volumio volumio[741]: info: Output device has changed, restarting Shairport Sync
Sep 28 22:11:43 volumio sudo[1479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Sep 28 22:11:43 volumio sudo[1479]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:11:43 volumio sudo[1479]: pam_unix(sudo:session): session closed for user root
Sep 28 22:11:43 volumio sudo[1481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Sep 28 22:11:43 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Sep 28 22:11:43 volumio systemd[1]: Stopping Music Player Daemon...
Sep 28 22:11:43 volumio volumio[741]: info: Setting mixer Topping Clock Selector for card D50
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Sep 28 22:11:43 volumio volumio[741]: info: Updating Volume Controller Parameters: Device: 1 Name: Cheapo S/PDIF Mixer: Topping Clock Selector Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::getUIConfigOnPlugin
Sep 28 22:11:43 volumio volumio[741]: info: MPD Permissions set
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Sep 28 22:11:43 volumio sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Sep 28 22:11:43 volumio sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:11:43 volumio sudo[1491]: pam_unix(sudo:session): session closed for user root
Sep 28 22:11:43 volumio systemd[1]: Starting Music Player Daemon...
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Sep 28 22:11:43 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Sep 28 22:11:43 volumio sudo[1505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay
Sep 28 22:11:43 volumio sudo[1505]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:11:43 volumio volumio[741]: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Sep 28 22:11:43 volumio volumio[741]: wlan0 Interface doesn't support scanning.
Sep 28 22:11:43 volumio volumio[741]: Cannot use regular scanning, forcing with ap-force
Sep 28 22:11:43 volumio sudo[1507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Sep 28 22:11:43 volumio sudo[1507]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:11:43 volumio systemd[1]: Stopping ShairportSync AirTunes receiver...
Sep 28 22:11:43 volumio sudo[1507]: pam_unix(sudo:session): session closed for user root
Sep 28 22:11:43 volumio systemd[1]: Starting ShairportSync AirTunes receiver...
Sep 28 22:11:43 volumio sudo[1512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Sep 28 22:11:43 volumio systemd[1]: Started ShairportSync AirTunes receiver.
Sep 28 22:11:43 volumio sudo[1512]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:11:43 volumio sudo[1505]: pam_unix(sudo:session): session closed for user root
Sep 28 22:11:43 volumio sudo[1512]: pam_unix(sudo:session): session closed for user root
Sep 28 22:11:43 volumio volumio[741]: command failed: No such device (-19)
Sep 28 22:11:43 volumio volumio[741]: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Sep 28 22:11:43 volumio volumio[741]: command failed: No such device (-19)
Sep 28 22:11:43 volumio volumio[741]: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Sep 28 22:11:43 volumio volumio[741]: wlan0 Interface doesn't support scanning.
Sep 28 22:11:43 volumio shairport-sync[1517]: Successful Startup
Sep 28 22:11:43 volumio volumio[741]: Cannot use regular scanning, forcing with ap-force
Sep 28 22:11:43 volumio sudo[1525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Sep 28 22:11:43 volumio sudo[1525]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:11:43 volumio sudo[1525]: pam_unix(sudo:session): session closed for user root
Sep 28 22:11:43 volumio volumio[741]: command failed: No such device (-19)
Sep 28 22:11:43 volumio volumio[741]: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Sep 28 22:11:43 volumio volumio[741]: command failed: No such device (-19)
Sep 28 22:11:43 volumio volumio[741]: info: Shairport-Sync Started
Sep 28 22:11:43 volumio volumio[741]: error: Upnp client error: Error: This socket has been ended by the other party
Sep 28 22:11:44 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Sep 28 22:11:47 volumio systemd[1]: Started Music Player Daemon.
Sep 28 22:11:47 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root
Sep 28 22:11:47 volumio volumio[741]: info:
Sep 28 22:11:48 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Sep 28 22:11:48 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Sep 28 22:11:48 volumio sudo[1540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Sep 28 22:11:48 volumio sudo[1540]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:11:48 volumio sudo[1540]: pam_unix(sudo:session): session closed for user root
Sep 28 22:11:48 volumio sudo[1542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Sep 28 22:11:48 volumio sudo[1542]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:11:48 volumio volumio[741]: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Sep 28 22:11:48 volumio volumio[741]: wlan0 Interface doesn't support scanning.
Sep 28 22:11:48 volumio volumio[741]: Cannot use regular scanning, forcing with ap-force
Sep 28 22:11:48 volumio sudo[1542]: pam_unix(sudo:session): session closed for user root
Sep 28 22:11:48 volumio sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Sep 28 22:11:48 volumio sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:11:48 volumio sudo[1554]: pam_unix(sudo:session): session closed for user root
Sep 28 22:11:48 volumio volumio[741]: command failed: No such device (-19)
Sep 28 22:11:48 volumio volumio[741]: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Sep 28 22:11:48 volumio volumio[741]: command failed: No such device (-19)
Sep 28 22:11:48 volumio volumio[741]: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan
Sep 28 22:11:48 volumio volumio[741]: wlan0 Interface doesn't support scanning.
Sep 28 22:11:48 volumio volumio[741]: Cannot use regular scanning, forcing with ap-force
Sep 28 22:11:48 volumio sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force
Sep 28 22:11:48 volumio sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:11:48 volumio sudo[1561]: pam_unix(sudo:session): session closed for user root
Sep 28 22:11:48 volumio volumio[741]: command failed: No such device (-19)
Sep 28 22:11:48 volumio volumio[741]: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force
Sep 28 22:11:48 volumio volumio[741]: command failed: No such device (-19)
Sep 28 22:12:06 volumio volumio[741]: error: error
Sep 28 22:12:06 volumio volumio[741]: error: error
Sep 28 22:12:06 volumio volumio[741]: error: error
Sep 28 22:12:21 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Sep 28 22:12:22 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation
Sep 28 22:12:22 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray
Sep 28 22:12:22 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage
Sep 28 22:12:28 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction
Sep 28 22:12:28 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard
Sep 28 22:12:28 volumio volumio[741]: info: Wizard terminated Successfully
Sep 28 22:12:32 volumio volumio[741]: info: CoreCommandRouter::volumioPlay
Sep 28 22:12:32 volumio volumio[741]: UNSET VOLATILE
Sep 28 22:12:32 volumio volumio[741]: info: CoreStateMachine::play index undefined
Sep 28 22:12:32 volumio volumio[741]: info: CoreStateMachine::setConsumeUpdateService undefined
Sep 28 22:12:32 volumio volumio[741]: info: CorePlayQueue::getTrack 0
Sep 28 22:12:32 volumio volumio[741]: info: CoreStateMachine::startPlaybackTimer
Sep 28 22:12:32 volumio volumio[741]: info: CorePlayQueue::getTrack 0
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::clearAddPlayTracks NAS/files/music/flac/Andrew LLyod Webber/very best of andrew llyod webber/1 - Memory__96000.flac
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::sendMpdCommand stop
Sep 28 22:12:32 volumio volumio[741]: info: sending command...
Sep 28 22:12:32 volumio volumio[741]: info: parsing response...
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::sendMpdCommand clear
Sep 28 22:12:32 volumio volumio[741]: info: sending command...
Sep 28 22:12:32 volumio volumio[741]: info:
Sep 28 22:12:32 volumio volumio[741]: ---------------------------- MPD announces system playlist update
Sep 28 22:12:32 volumio volumio[741]: info: Ignoring MPD Status Update
Sep 28 22:12:32 volumio volumio[741]: info: parsing response...
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::sendMpdCommand add "NAS/files/music/flac/Andrew LLyod Webber/very best of andrew llyod webber/1 - Memory__96000.flac"
Sep 28 22:12:32 volumio volumio[741]: info: sending command...
Sep 28 22:12:32 volumio volumio[741]: info:
Sep 28 22:12:32 volumio volumio[741]: info:
Sep 28 22:12:32 volumio volumio[741]: ---------------------------- MPD announces system playlist update
Sep 28 22:12:32 volumio volumio[741]: info: Ignoring MPD Status Update
Sep 28 22:12:32 volumio volumio[741]: info: ------------------------------ 17ms
Sep 28 22:12:32 volumio volumio[741]: info: parsing response...
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::sendMpdCommand play
Sep 28 22:12:32 volumio volumio[741]: info: sending command...
Sep 28 22:12:32 volumio volumio[741]: info: ------------------------------ 13ms
Sep 28 22:12:32 volumio volumio[741]: info: parsing response...
Sep 28 22:12:32 volumio volumio[741]: info:
Sep 28 22:12:32 volumio volumio[741]: ---------------------------- MPD announces state update: player
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::getState
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::sendMpdCommand status
Sep 28 22:12:32 volumio volumio[741]: info: sending command...
Sep 28 22:12:32 volumio kernel: iso_packets:0, bInterval:1, urb_interval:1, reg_con:0x4
Sep 28 22:12:32 volumio kernel: iso_packets:0, bInterval:4, urb_interval:8, reg_con:0x4
Sep 28 22:12:32 volumio volumio[741]: info: parsing response...
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::parseState
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::sendMpdCommand playlistinfo
Sep 28 22:12:32 volumio volumio[741]: info: sending command...
Sep 28 22:12:32 volumio volumio[741]: info:
Sep 28 22:12:32 volumio volumio[741]: ---------------------------- MPD announces state update: player
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::getState
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::sendMpdCommand status
Sep 28 22:12:32 volumio volumio[741]: info: parsing response...
Sep 28 22:12:32 volumio volumio[741]: info: sending command...
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::parseTrackInfo
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::pushState
Sep 28 22:12:32 volumio volumio[741]: info: CoreCommandRouter::servicePushState
Sep 28 22:12:32 volumio volumio[741]: info: CoreStateMachine::syncState
Sep 28 22:12:32 volumio volumio[741]: info: CorePlayQueue::getTrack 0
Sep 28 22:12:32 volumio volumio[741]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":257,"samplerate":"96 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"1 - Memory__96000.flac","artist":null,"album":null,"uri":"NAS/files/music/flac/Andrew LLyod Webber/very best of andrew llyod webber/1 - Memory__96000.flac","trackType":"flac"}
Sep 28 22:12:32 volumio volumio[741]: info: CURRENT POSITION 0
Sep 28 22:12:32 volumio volumio[741]: info: CoreStateMachine::syncState stateService play
Sep 28 22:12:32 volumio volumio[741]: info: CoreStateMachine::syncState currentStatus stop
Sep 28 22:12:32 volumio volumio[741]: info: CoreStateMachine::pushState
Sep 28 22:12:32 volumio volumio[741]: info: CoreStateMachine::getState
Sep 28 22:12:32 volumio volumio[741]: info: CorePlayQueue::getTrack 0
Sep 28 22:12:32 volumio volumio[741]: info: CoreCommandRouter::volumioPushState
Sep 28 22:12:32 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 28 22:12:32 volumio volumio[741]: info: ------------------------------ 145ms
Sep 28 22:12:32 volumio volumio[741]: info: parsing response...
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::parseState
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::sendMpdCommand playlistinfo
Sep 28 22:12:32 volumio sudo[1696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 28 22:12:32 volumio sudo[1696]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:12:32 volumio volumio[741]: info: sending command...
Sep 28 22:12:32 volumio sudo[1696]: pam_unix(sudo:session): session closed for user root
Sep 28 22:12:32 volumio volumio[741]: info: parsing response...
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::parseTrackInfo
Sep 28 22:12:32 volumio volumio[741]: info: ControllerMpd::pushState
Sep 28 22:12:32 volumio volumio[741]: info: CoreCommandRouter::servicePushState
Sep 28 22:12:32 volumio volumio[741]: info: CoreStateMachine::syncState
Sep 28 22:12:32 volumio volumio[741]: info: CorePlayQueue::getTrack 0
Sep 28 22:12:32 volumio volumio[741]: info: STATE SERVICE {"status":"play","position":0,"seek":496,"duration":257,"samplerate":"96 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"1 - Memory__96000.flac","artist":null,"album":null,"uri":"NAS/files/music/flac/Andrew LLyod Webber/very best of andrew llyod webber/1 - Memory__96000.flac","trackType":"flac"}
Sep 28 22:12:32 volumio volumio[741]: info: CURRENT POSITION 0
Sep 28 22:12:32 volumio volumio[741]: info: CoreStateMachine::syncState stateService play
Sep 28 22:12:32 volumio volumio[741]: info: CoreStateMachine::syncState currentStatus play
Sep 28 22:12:32 volumio volumio[741]: info: Received an update from plugin. extracting info from payload
Sep 28 22:12:32 volumio volumio[741]: info: CoreStateMachine::pushState
Sep 28 22:12:32 volumio volumio[741]: info: CoreStateMachine::getState
Sep 28 22:12:32 volumio volumio[741]: info: CorePlayQueue::getTrack 0
Sep 28 22:12:32 volumio volumio[741]: info: CoreCommandRouter::volumioPushState
Sep 28 22:12:32 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 28 22:12:32 volumio volumio[741]: info: CoreStateMachine::pushState
Sep 28 22:12:32 volumio volumio[741]: info: CoreStateMachine::getState
Sep 28 22:12:32 volumio volumio[741]: info: CorePlayQueue::getTrack 0
Sep 28 22:12:32 volumio volumio[741]: info: CoreCommandRouter::volumioPushState
Sep 28 22:12:32 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 28 22:12:32 volumio sudo[1703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 28 22:12:32 volumio sudo[1703]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:12:32 volumio volumio[741]: info: ------------------------------ 246ms
Sep 28 22:12:32 volumio sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 28 22:12:32 volumio sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:12:32 volumio sudo[1703]: pam_unix(sudo:session): session closed for user root
Sep 28 22:12:32 volumio sudo[1705]: pam_unix(sudo:session): session closed for user root
Sep 28 22:12:54 volumio volumio[741]: info: CoreCommandRouter::volumioGetState
Sep 28 22:12:54 volumio volumio[741]: info: CoreStateMachine::getState
Sep 28 22:12:54 volumio volumio[741]: info: CorePlayQueue::getTrack 0
Sep 28 22:12:54 volumio volumio[741]: info: CoreCommandRouter::volumioGetQueue
Sep 28 22:12:54 volumio volumio[741]: info: CoreStateMachine::getQueue
Sep 28 22:12:54 volumio volumio[741]: info: CorePlayQueue::getQueue
Sep 28 22:12:54 volumio sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 28 22:12:54 volumio sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 28 22:12:54 volumio sudo[1886]: pam_unix(sudo:session): session closed for user root
Sep 28 22:12:55 volumio volumio[741]: error: error
Sep 28 22:12:55 volumio volumio[741]: error: error
Sep 28 22:12:55 volumio volumio[741]: error: error
Sep 28 22:13:03 volumio volumio[741]: info: CALLMETHOD: system_controller system sendBugReport [object Object]
Sep 28 22:13:03 volumio volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport
Sep 28 22:13:03 volumio sudo[1984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7
Sep 28 22:13:03 volumio sudo[1984]: pam_unix(sudo:session): session opened for user root by (uid=0)