Fri Oct 13 14:34:35 CEST 2023
Description="'NANOSOUND plugin Install V3 failed 2'"

# cat /proc/version ---------------
Linux version 5.10.92-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1514 SMP Mon Jan 17 17:36:39 GMT 2022

# cat /etc/os-release ---------------
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST"
VOLUMIO_VERSION="3.512"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"

# ifconfig ---------------
eth0: flags=4163  mtu 1500
        inet 193.193.26.185  netmask 255.255.255.0  broadcast 193.193.26.255
        ether b8:27:eb:05:7f:57  txqueuelen 1000  (Ethernet)
        RX packets 1319  bytes 230250 (224.8 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 1100  bytes 224756 (219.4 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

lo: flags=73  mtu 65536
        inet 127.0.0.1  netmask 255.0.0.0
        loop  txqueuelen 1000  (Local Loopback)
        RX packets 634  bytes 158566 (154.8 KiB)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 634  bytes 158566 (154.8 KiB)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0

wlan0: flags=4099  mtu 1500
        ether b8:27:eb:50:2a:02  txqueuelen 1000  (Ethernet)
        RX packets 0  bytes 0 (0.0 B)
        RX errors 0  dropped 0  overruns 0  frame 0
        TX packets 0  bytes 0 (0.0 B)
        TX errors 0  dropped 0 overruns 0  carrier 0  collisions 0


# sudo iwconfig ---------------
lo        no wireless extensions.

eth0      no wireless extensions.

wlan0     IEEE 802.11  ESSID:off/any  
          Mode:Managed  Access Point: Not-Associated   Tx-Power=31 dBm   
          Retry short limit:7   RTS thr:off   Fragment thr:off
          Encryption key:off
          Power Management:off
          

# aplay -l ---------------
**** List of PLAYBACK Hardware Devices ****
card 0: b1 [bcm2835 HDMI 1], device 0: bcm2835 HDMI 1 [bcm2835 HDMI 1]
  Subdevices: 4/4
  Subdevice #0: subdevice #0
  Subdevice #1: subdevice #1
  Subdevice #2: subdevice #2
  Subdevice #3: subdevice #3
card 1: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones]
  Subdevices: 4/4
  Subdevice #0: subdevice #0
  Subdevice #1: subdevice #1
  Subdevice #2: subdevice #2
  Subdevice #3: subdevice #3
card 2: IQaudIODAC [IQaudIODAC], device 0: IQaudIO DAC HiFi pcm512x-hifi-0 [IQaudIO DAC HiFi pcm512x-hifi-0]
  Subdevices: 0/1
  Subdevice #0: subdevice #0

# ps -ef ---------------
UID        PID  PPID  C STIME TTY          TIME CMD
root         1     0  6 14:32 ?        00:00:09 /sbin/init
root         2     0  0 14:32 ?        00:00:00 [kthreadd]
root         3     2  0 14:32 ?        00:00:00 [rcu_gp]
root         4     2  0 14:32 ?        00:00:00 [rcu_par_gp]
root         5     2  0 14:32 ?        00:00:00 [kworker/0:0-events]
root         6     2  0 14:32 ?        00:00:00 [kworker/0:0H-mmc_complete]
root         7     2  0 14:32 ?        00:00:00 [kworker/u8:0-events_unbound]
root         8     2  0 14:32 ?        00:00:00 [mm_percpu_wq]
root         9     2  0 14:32 ?        00:00:00 [rcu_tasks_rude_]
root        10     2  0 14:32 ?        00:00:00 [rcu_tasks_trace]
root        11     2  0 14:32 ?        00:00:00 [ksoftirqd/0]
root        12     2  0 14:32 ?        00:00:00 [rcu_sched]
root        13     2  0 14:32 ?        00:00:00 [migration/0]
root        14     2  0 14:32 ?        00:00:00 [cpuhp/0]
root        15     2  0 14:32 ?        00:00:00 [cpuhp/1]
root        16     2  0 14:32 ?        00:00:00 [migration/1]
root        17     2  0 14:32 ?        00:00:00 [ksoftirqd/1]
root        18     2  0 14:32 ?        00:00:00 [kworker/1:0-events]
root        19     2  0 14:32 ?        00:00:00 [kworker/1:0H-kblockd]
root        20     2  0 14:32 ?        00:00:00 [cpuhp/2]
root        21     2  0 14:32 ?        00:00:00 [migration/2]
root        22     2  0 14:32 ?        00:00:00 [ksoftirqd/2]
root        23     2  0 14:32 ?        00:00:00 [kworker/2:0-events]
root        24     2  0 14:32 ?        00:00:00 [kworker/2:0H-kblockd]
root        25     2  0 14:32 ?        00:00:00 [cpuhp/3]
root        26     2  0 14:32 ?        00:00:00 [migration/3]
root        27     2  0 14:32 ?        00:00:00 [ksoftirqd/3]
root        28     2  0 14:32 ?        00:00:00 [kworker/3:0-events_long]
root        29     2  0 14:32 ?        00:00:00 [kworker/3:0H-events_highpri]
root        30     2  0 14:32 ?        00:00:00 [kdevtmpfs]
root        31     2  0 14:32 ?        00:00:00 [netns]
root        32     2  0 14:32 ?        00:00:00 [kworker/0:1-events]
root        33     2  0 14:32 ?        00:00:00 [kworker/1:1-events_long]
root        34     2  0 14:32 ?        00:00:00 [kauditd]
root        35     2  0 14:32 ?        00:00:00 [kworker/0:2-events]
root        36     2  0 14:32 ?        00:00:00 [khungtaskd]
root        37     2  0 14:32 ?        00:00:00 [oom_reaper]
root        38     2  0 14:32 ?        00:00:00 [writeback]
root        39     2  0 14:32 ?        00:00:00 [kcompactd0]
root        57     2  0 14:32 ?        00:00:00 [kblockd]
root        58     2  0 14:32 ?        00:00:00 [blkcg_punt_bio]
root        59     2  0 14:32 ?        00:00:00 [watchdogd]
root        60     2  0 14:32 ?        00:00:00 [kworker/2:1-events]
root        61     2  0 14:32 ?        00:00:00 [kworker/3:1-events_long]
root        62     2  0 14:32 ?        00:00:00 [kworker/3:1H-kblockd]
root        63     2  0 14:32 ?        00:00:00 [rpciod]
root        64     2  0 14:32 ?        00:00:00 [kworker/u9:0-hci0]
root        65     2  0 14:32 ?        00:00:00 [xprtiod]
root        66     2  0 14:32 ?        00:00:00 [kswapd0]
root        67     2  0 14:32 ?        00:00:00 [nfsiod]
root        68     2  0 14:32 ?        00:00:00 [kworker/2:2-events]
root        69     2  0 14:32 ?        00:00:00 [iscsi_eh]
root        70     2  0 14:32 ?        00:00:00 [iscsi_destroy]
root        71     2  0 14:32 ?        00:00:00 [dwc_otg]
root        72     2  0 14:32 ?        00:00:00 [kworker/1:2-events]
root        73     2  0 14:32 ?        00:00:00 [DWC Notificatio]
root        74     2  0 14:32 ?        00:00:00 [vchiq-slot/0]
root        75     2  0 14:32 ?        00:00:00 [vchiq-recy/0]
root        76     2  0 14:32 ?        00:00:00 [vchiq-sync/0]
root        77     2  0 14:32 ?        00:00:00 [zswap-shrink]
root        78     2  0 14:32 ?        00:00:00 [kworker/1:3-events]
root        79     2  0 14:32 ?        00:00:00 [kworker/1:4-events_long]
root        80     2  0 14:32 ?        00:00:00 [kworker/1:5-mm_percpu_wq]
root        81     2  0 14:32 ?        00:00:00 [kworker/1:6-events_long]
root        82     2  0 14:32 ?        00:00:00 [kworker/1:7-rcu_gp]
root        89     2  0 14:32 ?        00:00:00 [mmc_complete]
root        91     2  0 14:32 ?        00:00:00 [kworker/1:1H-kblockd]
root        92     2  0 14:32 ?        00:00:00 [kworker/0:1H-kblockd]
root       110     2  0 14:32 ?        00:00:00 [kworker/3:2-mm_percpu_wq]
root       113     2  0 14:32 ?        00:00:00 [kworker/3:2H-kblockd]
root       117     2  0 14:32 ?        00:00:00 [kworker/0:2H-kblockd]
root       118     2  0 14:32 ?        00:00:00 [jbd2/mmcblk0p2-]
root       119     2  0 14:32 ?        00:00:00 [ext4-rsv-conver]
root       120     2  0 14:32 ?        00:00:00 [kworker/2:1H-kblockd]
root       122     2  0 14:32 ?        00:00:00 [kworker/u8:1-events_unbound]
root       129     2  1 14:32 ?        00:00:01 [loop0]
root       155     2  0 14:32 ?        00:00:00 [kworker/1:2H-kblockd]
root       156     2  0 14:32 ?        00:00:00 [jbd2/mmcblk0p3-]
root       157     2  0 14:32 ?        00:00:00 [ext4-rsv-conver]
root       164     2  0 14:32 ?        00:00:00 [ipv6_addrconf]
root       195     1  0 14:32 ?        00:00:01 /lib/systemd/systemd-journald
root       200     2  0 14:32 ?        00:00:00 [kworker/2:3-mm_percpu_wq]
root       212     1  0 14:32 ?        00:00:01 /lib/systemd/systemd-udevd
root       220     2  0 14:32 ?        00:00:00 [kworker/u8:2-cfg80211]
root       242     2  0 14:32 ?        00:00:00 [vchiq-keep/0]
root       243     2  0 14:32 ?        00:00:00 [SMIO]
root       270     2  0 14:32 ?        00:00:00 [spi0]
root       291     2  0 14:32 ?        00:00:00 [kworker/2:2H-kblockd]
root       293     2  0 14:32 ?        00:00:00 [mmal-vchiq]
root       294     2  0 14:32 ?        00:00:00 [mmal-vchiq]
root       296     2  0 14:32 ?        00:00:00 [mmal-vchiq]
root       297     2  0 14:32 ?        00:00:00 [mmal-vchiq]
root       298     2  0 14:32 ?        00:00:00 [mmal-vchiq]
root       299     2  0 14:32 ?        00:00:00 [mmal-vchiq]
root       392     2  0 14:32 ?        00:00:00 [cfg80211]
root       395     2  0 14:32 ?        00:00:00 [kworker/3:3-events]
root       396     2  0 14:32 ?        00:00:00 [kworker/3:4]
root       405     2  0 14:32 ?        00:00:00 [brcmf_wq/mmc1:0]
root       406     2  0 14:32 ?        00:00:00 [brcmf_wdog/mmc1]
_rpc       478     1  0 14:32 ?        00:00:00 /sbin/rpcbind -f -w
root       482     1  1 14:32 ?        00:00:01 /usr/sbin/haveged --Foreground --verbose=1 -w 1024
root       493     2  0 14:32 ?        00:00:00 [kworker/u8:3-brcmf_wq/mmc1:0001:1]
root       510     2  0 14:32 ?        00:00:00 [kworker/0:3-cgroup_destroy]
avahi      516     1  0 14:32 ?        00:00:00 avahi-daemon: running [iqaudio.local]
root       518     1  0 14:32 ?        00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon
nobody     524     1  0 14:32 ?        00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event*
volumio    528     1  0 14:32 ?        00:00:00 /usr/local/bin/volumio-remote-updater
message+   533     1  0 14:32 ?        00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only
root       550     1  0 14:32 ?        00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant
root       551     1  0 14:32 ?        00:00:00 /lib/systemd/systemd-logind
avahi      582   516  0 14:32 ?        00:00:00 avahi-daemon: chroot helper
root       671     1  0 14:32 ?        00:00:00 /usr/sbin/nmbd --foreground --no-process-group
root       677     1  0 14:32 tty1     00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux
root       686     1  0 14:32 ?        00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I
ntp        694     1  0 14:32 ?        00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103
root       700     1  0 14:32 ?        00:00:00 /usr/sbin/sshd -D
root       708     2  0 14:32 ?        00:00:00 [kworker/u9:1-hci0]
root       709     1  0 14:32 ?        00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 460800 noflow - b8:27:eb:af:d5:fd
root       710     2  0 14:32 ?        00:00:00 [kworker/u9:2-hci0]
root       712   671  0 14:32 ?        00:00:00 /usr/sbin/nmbd --foreground --no-process-group
root       723     1  0 14:32 ?        00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap
root       781     1  0 14:32 ?        00:00:00 /sbin/dhcpcd eth0
root       827     1  0 14:32 ?        00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0
root       871     1  0 14:32 ?        00:00:00 /sbin/dhcpcd
root       872     1  0 14:32 ?        00:00:00 /usr/sbin/winbindd --foreground --no-process-group
root       876   872  0 14:32 ?        00:00:00 winbindd: domain child [IQAUDIO]
root       880     1  0 14:32 ?        00:00:00 /usr/sbin/smbd --foreground --no-process-group
root       889   880  0 14:32 ?        00:00:00 /usr/sbin/smbd --foreground --no-process-group
root       890   880  0 14:32 ?        00:00:00 /usr/sbin/smbd --foreground --no-process-group
root       891   872  0 14:32 ?        00:00:00 winbindd: idmap child
root       892   872  0 14:32 ?        00:00:00 winbindd: domain child [BUILTIN]
root       893   880  0 14:32 ?        00:00:00 /usr/sbin/smbd --foreground --no-process-group
volumio    952     1 25 14:33 ?        00:00:23 /usr/bin/node /volumio/index.js
volumio    999   952  0 14:33 ?        00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio   1006   999  2 14:33 ?        00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio   1007   999  2 14:33 ?        00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio   1008   999  2 14:33 ?        00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart
volumio   1132   952  2 14:33 ?        00:00:01 /usr/bin/node /myvolumio/plugins/music_service/streaming_services/streamingProxy.js
mpd       1234     1  5 14:33 ?        00:00:03 /usr/bin/mpd --no-daemon
shairpo+  1244     1  0 14:33 ?        00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf
volumio   1256     1  0 14:33 ?        00:00:00 /bin/sh /tmp/sshtunnel.sh
volumio   1257  1256  0 14:33 ?        00:00:00 /usr/lib/autossh/autossh -M 0 -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R y8tVlQpELEOsM5GHsaahPtEzWF22-b8831d18ca3a769b956628f229ff76e7.eu6.myvolumio.org:8091:localhost:3000 eu6.myvolumio.org
volumio   1260  1257  0 14:33 ?        00:00:00 /usr/bin/ssh -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R y8tVlQpELEOsM5GHsaahPtEzWF22-b8831d18ca3a769b956628f229ff76e7.eu6.myvolumio.org:8091:localhost:3000 eu6.myvolumio.org
volumio   1268   952  1 14:34 ?        00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'NANOSOUND plugin Install V3 failed 2'
volumio   1269  1268 38 14:34 ?        00:00:00 /usr/bin/node /volumio/logsubmit.js NANOSOUND plugin Install V3 failed 2
volumio   1289  1269  0 14:34 ?        00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1
volumio   1290  1289  0 14:34 ?        00:00:00 ps -ef

# df -h ---------------
Filesystem      Size  Used Avail Use% Mounted on
/dev/mmcblk0p2  2.5G  505M  1.8G  22% /imgpart
/dev/loop0      443M  443M     0 100% /static
overlay          26G  846M   24G   4% /
devtmpfs        445M     0  445M   0% /dev
tmpfs           484M     0  484M   0% /dev/shm
tmpfs           484M  8.9M  475M   2% /run
tmpfs           5.0M  4.0K  5.0M   1% /run/lock
tmpfs           484M     0  484M   0% /sys/fs/cgroup
tmpfs           484M   56K  484M   1% /tmp
tmpfs           484M     0  484M   0% /var/spool/cups
tmpfs            20M   28K   20M   1% /var/log
tmpfs           484M     0  484M   0% /var/spool/cups/tmp
/dev/mmcblk0p1   92M   54M   38M  59% /boot

# mount ---------------
/dev/mmcblk0p2 on /imgpart type ext4 (rw,relatime)
/dev/loop0 on /static type squashfs (ro,relatime)
overlay on / type overlay (rw,relatime,lowerdir=/mnt/static,upperdir=/mnt/ext/dyn,workdir=/mnt/ext/work)
sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime)
proc on /proc type proc (rw,relatime)
devtmpfs on /dev type devtmpfs (rw,nosuid,size=455216k,nr_inodes=113804,mode=755)
securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime)
tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev,noexec)
devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000)
tmpfs on /run type tmpfs (rw,nosuid,nodev,mode=755)
tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k)
tmpfs on /sys/fs/cgroup type tmpfs (ro,nosuid,nodev,noexec,mode=755)
cgroup2 on /sys/fs/cgroup/unified type cgroup2 (rw,nosuid,nodev,noexec,relatime,nsdelegate)
cgroup on /sys/fs/cgroup/systemd type cgroup (rw,nosuid,nodev,noexec,relatime,xattr,name=systemd)
none on /sys/fs/bpf type bpf (rw,nosuid,nodev,noexec,relatime,mode=700)
cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct)
cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids)
cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio)
cgroup on /sys/fs/cgroup/net_cls,net_prio type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls,net_prio)
cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer)
cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event)
cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices)
cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset)
systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=25,pgrp=1,timeout=0,minproto=5,maxproto=5,direct)
debugfs on /sys/kernel/debug type debugfs (rw,relatime)
sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime)
mqueue on /dev/mqueue type mqueue (rw,relatime)
configfs on /sys/kernel/config type configfs (rw,relatime)
tmpfs on /tmp type tmpfs (rw,noatime,mode=755)
tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755)
tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4)
tmpfs on /var/spool/cups/tmp type tmpfs (rw,noatime,mode=755)
/dev/mmcblk0p1 on /boot type vfat (rw,nosuid,nodev,noexec,relatime,fmask=0111,dmask=0000,allow_utime=0022,codepage=437,iocharset=ascii,shortname=mixed,utf8,errors=remount-ro,user)

# netstat -natp ---------------
(Not all processes could be identified, non-owned process info
 will not be shown, you would have to be root to see it all.)
Active Internet connections (servers and established)
Proto Recv-Q Send-Q Local Address           Foreign Address         State       PID/Program name    
tcp        0      0 0.0.0.0:5000            0.0.0.0:*               LISTEN      -                   
tcp        0      0 0.0.0.0:139             0.0.0.0:*               LISTEN      -                   
tcp        0      0 0.0.0.0:111             0.0.0.0:*               LISTEN      -                   
tcp        0      0 0.0.0.0:22              0.0.0.0:*               LISTEN      -                   
tcp        0      0 0.0.0.0:445             0.0.0.0:*               LISTEN      -                   
tcp        0      0 193.193.26.185:59970    34.120.160.131:443      TIME_WAIT   -                   
tcp        0      0 127.0.0.1:38494         127.0.0.1:3000          ESTABLISHED 528/volumio-remote- 
tcp        0      0 193.193.26.185:59986    34.120.160.131:443      ESTABLISHED 952/node            
tcp        0      0 193.193.26.185:56942    84.247.14.230:80        ESTABLISHED 952/node            
tcp        0      0 127.0.0.1:38522         127.0.0.1:3000          ESTABLISHED 952/node            
tcp        0      0 193.193.26.185:51290    193.193.26.151:3000     ESTABLISHED 952/node            
tcp        0      0 193.193.26.185:57206    207.154.233.230:2222    ESTABLISHED 1260/ssh            
tcp        0      0 127.0.0.1:38564         127.0.0.1:3000          ESTABLISHED 952/node            
tcp        0      0 127.0.0.1:38598         127.0.0.1:3000          TIME_WAIT   -                   
tcp        0      0 127.0.0.1:38574         127.0.0.1:3000          ESTABLISHED 952/node            
tcp        0      0 127.0.0.1:38498         127.0.0.1:3000          ESTABLISHED 952/node            
tcp        0      0 193.193.26.185:59164    149.210.234.235:80      TIME_WAIT   -                   
tcp6       0      0 :::5000                 :::*                    LISTEN      -                   
tcp6       0      0 :::6600                 :::*                    LISTEN      -                   
tcp6       0      0 :::139                  :::*                    LISTEN      -                   
tcp6       0      0 :::3245                 :::*                    LISTEN      1132/node           
tcp6       0      0 :::111                  :::*                    LISTEN      -                   
tcp6       0      0 :::22                   :::*                    LISTEN      -                   
tcp6       0      0 :::3000                 :::*                    LISTEN      952/node            
tcp6       0      0 :::3001                 :::*                    LISTEN      999/node            
tcp6       0      0 :::3005                 :::*                    LISTEN      952/node            
tcp6       0      0 :::445                  :::*                    LISTEN      -                   
tcp6       0      0 127.0.0.1:3000          127.0.0.1:38570         TIME_WAIT   -                   
tcp6       0      0 127.0.0.1:3000          127.0.0.1:38578         TIME_WAIT   -                   
tcp6       0      0 193.193.26.185:3000     193.193.26.190:63712    TIME_WAIT   -                   
tcp6       0      0 193.193.26.185:3000     193.193.26.190:63719    ESTABLISHED 952/node            
tcp6       0      0 127.0.0.1:3000          127.0.0.1:38568         TIME_WAIT   -                   
tcp6       0      0 127.0.0.1:3000          127.0.0.1:38494         ESTABLISHED 952/node            
tcp6       0      0 193.193.26.185:3000     193.193.26.190:63717    TIME_WAIT   -                   
tcp6       0      0 127.0.0.1:3000          127.0.0.1:38566         TIME_WAIT   -                   
tcp6       0      0 127.0.0.1:3000          127.0.0.1:38574         ESTABLISHED 952/node            
tcp6       0      0 127.0.0.1:3000          127.0.0.1:38560         TIME_WAIT   -                   
tcp6       0      0 127.0.0.1:3000          127.0.0.1:38580         TIME_WAIT   -                   
tcp6       0      0 193.193.26.185:3000     193.193.26.190:63714    TIME_WAIT   -                   
tcp6       0      0 127.0.0.1:3000          127.0.0.1:38522         ESTABLISHED 952/node            
tcp6       0      0 127.0.0.1:3000          127.0.0.1:38498         ESTABLISHED 952/node            
tcp6       0      0 193.193.26.185:3000     193.193.26.190:63620    TIME_WAIT   -                   
tcp6       0      0 127.0.0.1:3000          127.0.0.1:38572         TIME_WAIT   -                   
tcp6       0      0 127.0.0.1:3000          127.0.0.1:38564         ESTABLISHED 952/node            
tcp6       0      0 193.193.26.185:3000     193.193.26.190:63718    TIME_WAIT   -                   
tcp6       0      0 193.193.26.185:3000     193.193.26.151:55346    ESTABLISHED 952/node            
tcp6       0      0 127.0.0.1:3000          127.0.0.1:38576         TIME_WAIT   -                   

# cat /etc/asound.conf ---------------
pcm.!default {
    type             empty
    slave.pcm       "volumio"
}

pcm.volumio {
    type             empty
    slave.pcm       "volumioMultiRoomServer"
}

# This PCM switches output to the ALSA target named by the file /tmp/multiroom/server/switch.target
pcm.volumioMultiRoomServer {
   type volumioswitch
   target_file "/tmp/multiroom/server/switch.target"
   control_fifo "/tmp/multiroom/server/switch.fifo"
   
}

# This PCM switches output to the ALSA target named by the file /tmp/multiroom/client/switch.target
pcm.volumioMultiRoomClient {
   type volumioswitch
   target_file "/tmp/multiroom/client/switch.target"
   control_fifo "/tmp/multiroom/client/switch.fifo"
}

# This PCM performs any necessary rate/format/channel conversion before sending on to the Server FIFO
pcm.volumioMultiRoom {
    type plug
    slave {
        pcm "volumioMultiRoomFIFO"
        rate 48000 
        format "S16_LE"            
        channels 2        
    }
    rate_converter "speexrate_medium"
}

# Write to the FIFO watched by the Server
pcm.volumioMultiRoomFIFO {
    type volumiofifo
    fifo "/tmp/multiroom/server/fifo"
    clear_on_drop false
    lead_in_frames 16384
}

# This pcm is used by pcm.volumioMultiRoomServer and pcm.volumioMultiRoomClient to play audio on the local output
pcm.volumioLocalPlayback {
    type empty
    slave.pcm "postMultiRoom"
}

# This pcm is used by pcm.volumioMultiRoomClient to discard audio and allow for faster switching
pcm.volumioDiscard {
    type null
}

pcm.postMultiRoom {
    type             empty
    slave.pcm       "volumioOutput"
}


# There is always a plug before the hardware to be safe
pcm.volumioOutput {
    type plug
    slave.pcm "volumioHw"
}

pcm.volumioHw {
    type hw
    card "IQaudIODAC"
}

# cat /var/log/mpd.log ---------------
Oct 13 14:21 : zeroconf: No global port, disabling zeroconf
Oct 13 14:22 : zeroconf: No global port, disabling zeroconf
Oct 13 14:22 : client: [0] opened from local
Oct 13 14:22 : client: [1] opened from local
Oct 13 14:22 : zeroconf: No global port, disabling zeroconf
Oct 13 14:22 : client: [0] opened from local
Oct 13 14:22 : exception: No such directory
Oct 13 14:22 : zeroconf: No global port, disabling zeroconf
Oct 13 14:22 : client: [0] opened from local
ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback

Oct 13 14:22 : exception: Failed to open "alsa" (alsa)
Oct 13 14:22 : exception: nested: Failed to open ALSA device "volumio": Device or resource busy
Oct 13 14:22 : exception: Failed to open "alsa" (alsa)
Oct 13 14:22 : exception: nested: Failed to open ALSA device "volumio": Device or resource busy
Oct 13 14:22 : player: problems opening audio device while playing "INTERNAL/Soundcheck14/audiocheck.net_pink_192k_-3dBFS.wav"
Oct 13 14:22 : exception: Failed to read mixer for 'alsa': no such mixer control: PCM
Oct 13 14:22 : player: played "INTERNAL/Soundcheck14/audiocheck.net_pink_192k_-3dBFS.wav"
Oct 13 14:22 : exception: Failed to open mixer for 'alsa': no such mixer control: PCM

# volumio plugin list ---------------

This command will list installed plugins on your device

----------------------- Installed Plugins:
[
  {
    prettyName: 'NanoSound by Nanomesher',
    name: 'nanosound',
    category: 'miscellanea',
    version: '2.0.0',
    icon: 'fa-play-circle-o',
    isManuallyInstalled: true,
    enabled: true,
    active: true
  },
  {
    prettyName: 'AutoStart',
    name: 'autostart',
    category: 'system_controller',
    version: '3.0.4',
    icon: 'fa-lightbulb-o',
    isManuallyInstalled: false,
    enabled: true,
    active: true
  }
]
------------------------------------------

# cat /data/configuration/plugins.json ---------------
{
  "music_service": {
    "airplay_emulation": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "inputs": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "mpd": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "webradio": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "example_plugin": {
      "enabled": {
        "type": "boolean",
        "value": false
      },
      "status": {
        "type": "string",
        "value": "STOPPED"
      }
    },
    "last_100": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "upnp_browser": {
      "enabled": {
        "type": "boolean",
        "value": false
      },
      "status": {
        "type": "string",
        "value": "STOPPED"
      }
    },
    "streaming_services": {
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "cd_controller": {
      "status": {
        "type": "string",
        "value": "STOPPED"
      },
      "enabled": {
        "type": "boolean",
        "value": false
      }
    },
    "smart_inputs": {
      "status": {
        "type": "string",
        "value": "STOPPED"
      },
      "enabled": {
        "type": "boolean",
        "value": false
      }
    },
    "hi_res_audio": {
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "tidal": {
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "qobuz": {
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "tidalconnect": {
      "status": {
        "type": "string",
        "value": "STOPPED"
      },
      "enabled": {
        "type": "boolean",
        "value": false
      }
    }
  },
  "audio_interface": {
    "outputs": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "upnp": {
      "enabled": {
        "type": "boolean",
        "value": false
      },
      "status": {
        "type": "string",
        "value": "STOPPED"
      }
    },
    "alsa_controller": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "bluetooth": {
      "status": {
        "type": "string",
        "value": "STOPPED"
      },
      "enabled": {
        "type": "boolean",
        "value": false
      }
    },
    "multiroom": {
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    }
  },
  "miscellanea": {
    "alarm-clock": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "albumart": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "appearance": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "wizard": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "my_music": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "metavolumio": {
      "status": {
        "type": "string",
        "value": "STOPPED"
      },
      "enabled": {
        "type": "boolean",
        "value": false
      }
    },
    "manifestui": {
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "nanosound": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    }
  },
  "user_interface": {
    "websocket": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "mpdemulation": {
      "enabled": {
        "type": "boolean",
        "value": false
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "rest_api": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    }
  },
  "system_controller": {
    "updater_comm": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "network": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "networkfs": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "services": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "system": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "i2s_dacs": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "volumiodiscovery": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "volumio_command_line_client": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "my_volumio": {
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    },
    "autostart": {
      "enabled": {
        "type": "boolean",
        "value": true
      },
      "status": {
        "type": "string",
        "value": "STARTED"
      }
    }
  },
  "system_hardware": {}
}

# cat /etc/mpd.conf ---------------
# Volumio MPD Configuration File

# Files and directories #######################################################
music_directory		"/var/lib/mpd/music"
playlist_directory		"/var/lib/mpd/playlists"
db_file			"/var/lib/mpd/tag_cache"
log_file			"/var/log/mpd.log"
#pid_file			"/var/run/mpd/pid"
#state_file			"/var/lib/mpd/state"
#sticker_file                   "/var/lib/mpd/sticker.sql"
###############################################################################

# General music daemon options ################################################
user				"mpd"
group                          "audio"
bind_to_address		"any"
#port				"6600"
#log_level			"default"
#save_absolute_paths_in_playlists	"no"
#metadata_to_use	"artist,album,title,track,name,genre,date,composer,performer,disc"
auto_update    "no"
#auto_update_depth "3"
###############################################################################
# Symbolic link behavior ######################################################
follow_outside_symlinks	"yes"
follow_inside_symlinks		"yes"
###############################################################################
# Input #######################################################################
#
input {
        plugin "curl"
#       proxy "proxy.isp.com:8080"
#       proxy_user "user"
#       proxy_password "password"
}
###############################################################################

# Decoder ################################################################

decoder {
	plugin "faad"
	enabled "no"
}

 

 

###############################################################################

# Audio Output ################################################################

resampler {      
  		plugin "soxr"
  		quality "high"
  		threads "1"
}

audio_output {
		type		"alsa"
		name		"alsa"
		device		"volumio"
		dop			"no"
		
		
		

}





audio_output {
    type            "fifo"
    enabled         "no"
    name            "multiroom"
    path            "/tmp/snapfifo"
    format          "44100:16:2"
}

#replaygain			"album"
#replaygain_preamp		"0"
volume_normalization		"no"
###############################################################################

# MPD Internal Buffering ######################################################
audio_buffer_size		"2048"
###############################################################################


# Resource Limitations ########################################################
#connection_timeout		"60"
max_connections			"20"
max_playlist_length		"81920"
max_command_list_size		"81920"
max_output_buffer_size		"81920"
###############################################################################

# Character Encoding ##########################################################
filesystem_charset		"UTF-8"
###############################################################################

# volumio endpointstest ---------------
TESTING REMOTE ENDPOINTS
https://google.com, 589 ms: OK
https://www.googleapis.com, 414 ms: OK
https://securetoken.googleapis.com, 551 ms: OK
https://myvolumio.firebaseio.com, 561 ms: OK
https://functions.volumio.cloud, 452 ms: OK
https://oauth-performer.dfs.volumio.org, 431 ms: OK
https://browsing-performer.dfs.volumio.org, 598 ms: OK
http://cddb.volumio.org, 711 ms: OK
https://functions.volumio.cloud, 551 ms: OK
http://pushupdates.volumio.org, 386 ms: OK
http://plugins.volumio.org, 971 ms: OK
https://database.volumio.cloud, 629 ms: OK
https://radio-directory.firebaseapp.com, 544 ms: OK
----------
REMOTE ENDPOINTS TEST OK, all Endpoints are reachable
----------

# cat /data/updatesHistoryFile ---------------
---
Sun Jun 25 2023 17:27:08 GMT+0000 (Coordinated Universal Time)
{"systemversion":"3.512","builddate":"Sun 25 Jun 2023 07:20:58 PM CEST","variant":"volumio","hardware":"pi"}
---
# sudo journalctl -p 7 ---------------
-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2023-10-13 14:34:40 CEST. --
Feb 14 11:11:59 iqaudio kernel: Booting Linux on physical CPU 0x0
Feb 14 11:11:59 iqaudio kernel: Linux version 5.10.92-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1514 SMP Mon Jan 17 17:36:39 GMT 2022
Feb 14 11:11:59 iqaudio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d
Feb 14 11:11:59 iqaudio kernel: CPU: div instructions available: patching division code
Feb 14 11:11:59 iqaudio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache
Feb 14 11:11:59 iqaudio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2
Feb 14 11:11:59 iqaudio kernel: random: fast init done
Feb 14 11:11:59 iqaudio kernel: Memory policy: Data cache writealloc
Feb 14 11:11:59 iqaudio kernel: Reserved memory: created CMA memory pool at 0x3a000000, size 64 MiB
Feb 14 11:11:59 iqaudio kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
Feb 14 11:11:59 iqaudio kernel: Zone ranges:
Feb 14 11:11:59 iqaudio kernel:   DMA      [mem 0x0000000000000000-0x000000003dffffff]
Feb 14 11:11:59 iqaudio kernel:   Normal   empty
Feb 14 11:11:59 iqaudio kernel: Movable zone start for each node
Feb 14 11:11:59 iqaudio kernel: Early memory node ranges
Feb 14 11:11:59 iqaudio kernel:   node   0: [mem 0x0000000000000000-0x000000003dffffff]
Feb 14 11:11:59 iqaudio kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000003dffffff]
Feb 14 11:11:59 iqaudio kernel: On node 0 totalpages: 253952
Feb 14 11:11:59 iqaudio kernel:   DMA zone: 2232 pages used for memmap
Feb 14 11:11:59 iqaudio kernel:   DMA zone: 0 pages reserved
Feb 14 11:11:59 iqaudio kernel:   DMA zone: 253952 pages, LIFO batch:63
Feb 14 11:11:59 iqaudio kernel: percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920
Feb 14 11:11:59 iqaudio kernel: pcpu-alloc: s50828 r8192 d22900 u81920 alloc=20*4096
Feb 14 11:11:59 iqaudio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 
Feb 14 11:11:59 iqaudio kernel: Built 1 zonelists, mobility grouping on.  Total pages: 251720
Feb 14 11:11:59 iqaudio kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000  splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0
Feb 14 11:11:59 iqaudio kernel: Kernel parameter elevator= does not have any effect anymore.
                                Please use sysfs to set IO scheduler for individual devices.
Feb 14 11:11:59 iqaudio kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear)
Feb 14 11:11:59 iqaudio kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear)
Feb 14 11:11:59 iqaudio kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Feb 14 11:11:59 iqaudio kernel: Memory: 910432K/1015808K available (10240K kernel code, 1312K rwdata, 2952K rodata, 1024K init, 862K bss, 39840K reserved, 65536K cma-reserved)
Feb 14 11:11:59 iqaudio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
Feb 14 11:11:59 iqaudio kernel: ftrace: allocating 32087 entries in 95 pages
Feb 14 11:11:59 iqaudio kernel: ftrace: allocated 95 pages with 6 groups
Feb 14 11:11:59 iqaudio kernel: rcu: Hierarchical RCU implementation.
Feb 14 11:11:59 iqaudio kernel:         Rude variant of Tasks RCU enabled.
Feb 14 11:11:59 iqaudio kernel:         Tracing variant of Tasks RCU enabled.
Feb 14 11:11:59 iqaudio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies.
Feb 14 11:11:59 iqaudio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16
Feb 14 11:11:59 iqaudio kernel: random: get_random_bytes called from start_kernel+0x3ac/0x580 with crng_init=1
Feb 14 11:11:59 iqaudio kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys).
Feb 14 11:11:59 iqaudio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns
Feb 14 11:11:59 iqaudio kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns
Feb 14 11:11:59 iqaudio kernel: Switching to timer-based delay loop, resolution 52ns
Feb 14 11:11:59 iqaudio kernel: Console: colour dummy device 80x30
Feb 14 11:11:59 iqaudio kernel: printk: console [tty1] enabled
Feb 14 11:11:59 iqaudio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000)
Feb 14 11:11:59 iqaudio kernel: pid_max: default: 32768 minimum: 301
Feb 14 11:11:59 iqaudio kernel: LSM: Security Framework initializing
Feb 14 11:11:59 iqaudio kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
Feb 14 11:11:59 iqaudio kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear)
Feb 14 11:11:59 iqaudio kernel: cgroup: Disabling memory control group subsystem
Feb 14 11:11:59 iqaudio kernel: CPU: Testing write buffer coherency: ok
Feb 14 11:11:59 iqaudio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000
Feb 14 11:11:59 iqaudio kernel: Setting up static identity map for 0x100000 - 0x10003c
Feb 14 11:11:59 iqaudio kernel: rcu: Hierarchical SRCU implementation.
Feb 14 11:11:59 iqaudio kernel: smp: Bringing up secondary CPUs ...
Feb 14 11:11:59 iqaudio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001
Feb 14 11:11:59 iqaudio kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002
Feb 14 11:11:59 iqaudio kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003
Feb 14 11:11:59 iqaudio kernel: smp: Brought up 1 node, 4 CPUs
Feb 14 11:11:59 iqaudio kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS).
Feb 14 11:11:59 iqaudio kernel: CPU: All CPU(s) started in HYP mode.
Feb 14 11:11:59 iqaudio kernel: CPU: Virtualization extensions available.
Feb 14 11:11:59 iqaudio kernel: devtmpfs: initialized
Feb 14 11:11:59 iqaudio kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4
Feb 14 11:11:59 iqaudio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns
Feb 14 11:11:59 iqaudio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
Feb 14 11:11:59 iqaudio kernel: pinctrl core: initialized pinctrl subsystem
Feb 14 11:11:59 iqaudio kernel: NET: Registered protocol family 16
Feb 14 11:11:59 iqaudio kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations
Feb 14 11:11:59 iqaudio kernel: audit: initializing netlink subsys (disabled)
Feb 14 11:11:59 iqaudio kernel: thermal_sys: Registered thermal governor 'step_wise'
Feb 14 11:11:59 iqaudio kernel: audit: type=2000 audit(0.040:1): state=initialized audit_enabled=0 res=1
Feb 14 11:11:59 iqaudio kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers.
Feb 14 11:11:59 iqaudio kernel: hw-breakpoint: maximum watchpoint size is 8 bytes.
Feb 14 11:11:59 iqaudio kernel: Serial: AMBA PL011 UART driver
Feb 14 11:11:59 iqaudio kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled
Feb 14 11:11:59 iqaudio kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2022-01-20T13:58:22, variant start
Feb 14 11:11:59 iqaudio kernel: raspberrypi-firmware soc:firmware: Firmware hash is bd88f66f8952d34e4e0613a85c7a6d3da49e13e2
Feb 14 11:11:59 iqaudio kernel: Kprobes globally optimized
Feb 14 11:11:59 iqaudio kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1
Feb 14 11:11:59 iqaudio kernel: SCSI subsystem initialized
Feb 14 11:11:59 iqaudio kernel: usbcore: registered new interface driver usbfs
Feb 14 11:11:59 iqaudio kernel: usbcore: registered new interface driver hub
Feb 14 11:11:59 iqaudio kernel: usbcore: registered new device driver usb
Feb 14 11:11:59 iqaudio kernel: clocksource: Switched to clocksource arch_sys_counter
Feb 14 11:11:59 iqaudio kernel: VFS: Disk quotas dquot_6.6.0
Feb 14 11:11:59 iqaudio kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
Feb 14 11:11:59 iqaudio kernel: FS-Cache: Loaded
Feb 14 11:11:59 iqaudio kernel: CacheFiles: Loaded
Feb 14 11:11:59 iqaudio kernel: NET: Registered protocol family 2
Feb 14 11:11:59 iqaudio kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear)
Feb 14 11:11:59 iqaudio kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear)
Feb 14 11:11:59 iqaudio kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear)
Feb 14 11:11:59 iqaudio kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear)
Feb 14 11:11:59 iqaudio kernel: TCP: Hash tables configured (established 8192 bind 8192)
Feb 14 11:11:59 iqaudio kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear)
Feb 14 11:11:59 iqaudio kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear)
Feb 14 11:11:59 iqaudio kernel: NET: Registered protocol family 1
Feb 14 11:11:59 iqaudio kernel: RPC: Registered named UNIX socket transport module.
Feb 14 11:11:59 iqaudio kernel: RPC: Registered udp transport module.
Feb 14 11:11:59 iqaudio kernel: RPC: Registered tcp transport module.
Feb 14 11:11:59 iqaudio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module.
Feb 14 11:11:59 iqaudio kernel: Trying to unpack rootfs image as initramfs...
Feb 14 11:11:59 iqaudio kernel: Freeing initrd memory: 13028K
Feb 14 11:11:59 iqaudio kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available
Feb 14 11:11:59 iqaudio kernel: Initialise system trusted keyrings
Feb 14 11:11:59 iqaudio kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4
Feb 14 11:11:59 iqaudio kernel: zbud: loaded
Feb 14 11:11:59 iqaudio kernel: FS-Cache: Netfs 'nfs' registered for caching
Feb 14 11:11:59 iqaudio kernel: NFS: Registering the id_resolver key type
Feb 14 11:11:59 iqaudio kernel: Key type id_resolver registered
Feb 14 11:11:59 iqaudio kernel: Key type id_legacy registered
Feb 14 11:11:59 iqaudio kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering...
Feb 14 11:11:59 iqaudio kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
Feb 14 11:11:59 iqaudio kernel: Key type asymmetric registered
Feb 14 11:11:59 iqaudio kernel: Asymmetric key parser 'x509' registered
Feb 14 11:11:59 iqaudio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Feb 14 11:11:59 iqaudio kernel: io scheduler mq-deadline registered
Feb 14 11:11:59 iqaudio kernel: io scheduler kyber registered
Feb 14 11:11:59 iqaudio kernel: bcm2708_fb soc:fb: FB found 1 display(s)
Feb 14 11:11:59 iqaudio kernel: Console: switching to colour frame buffer device 80x30
Feb 14 11:11:59 iqaudio kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480
Feb 14 11:11:59 iqaudio kernel: bcm2835-rng 3f104000.rng: hwrng registered
Feb 14 11:11:59 iqaudio kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB)
Feb 14 11:11:59 iqaudio kernel: gpiomem-bcm2835 3f200000.gpiomem: Initialised: Registers at 0x3f200000
Feb 14 11:11:59 iqaudio kernel: brd: module loaded
Feb 14 11:11:59 iqaudio kernel: loop: module loaded
Feb 14 11:11:59 iqaudio kernel: Loading iSCSI transport class v2.0-870.
Feb 14 11:11:59 iqaudio kernel: libphy: Fixed MDIO Bus: probed
Feb 14 11:11:59 iqaudio kernel: usbcore: registered new interface driver lan78xx
Feb 14 11:11:59 iqaudio kernel: usbcore: registered new interface driver smsc95xx
Feb 14 11:11:59 iqaudio kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
Feb 14 11:11:59 iqaudio kernel: Core Release: 2.80a
Feb 14 11:11:59 iqaudio kernel: Setting default values for core params
Feb 14 11:11:59 iqaudio kernel: Finished setting default values for core params
Feb 14 11:11:59 iqaudio kernel: Using Buffer DMA mode
Feb 14 11:11:59 iqaudio kernel: Periodic Transfer Interrupt Enhancement - disabled
Feb 14 11:11:59 iqaudio kernel: Multiprocessor Interrupt Enhancement - disabled
Feb 14 11:11:59 iqaudio kernel: OTG VER PARAM: 0, OTG VER FLAG: 0
Feb 14 11:11:59 iqaudio kernel: Dedicated Tx FIFOs mode
Feb 14 11:11:59 iqaudio kernel: 
Feb 14 11:11:59 iqaudio kernel: WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = ba104000 dma = 0xfa104000 len=9024
Feb 14 11:11:59 iqaudio kernel: FIQ FSM acceleration enabled for :
                                Non-periodic Split Transactions
                                Periodic Split Transactions
                                High-Speed Isochronous Endpoints
                                Interrupt/Control Split Transaction hack enabled
Feb 14 11:11:59 iqaudio kernel: dwc_otg: Microframe scheduler enabled
Feb 14 11:11:59 iqaudio kernel: 
Feb 14 11:11:59 iqaudio kernel: WARN::hcd_init_fiq:457: FIQ on core 1
Feb 14 11:11:59 iqaudio kernel: 
Feb 14 11:11:59 iqaudio kernel: WARN::hcd_init_fiq:458: FIQ ASM at 807cc05c length 36
Feb 14 11:11:59 iqaudio kernel: 
Feb 14 11:11:59 iqaudio kernel: WARN::hcd_init_fiq:497: MPHI regs_base at be810000
Feb 14 11:11:59 iqaudio kernel: dwc_otg 3f980000.usb: DWC OTG Controller
Feb 14 11:11:59 iqaudio kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1
Feb 14 11:11:59 iqaudio kernel: dwc_otg 3f980000.usb: irq 89, io mem 0x00000000
Feb 14 11:11:59 iqaudio kernel: Init: Port Power? op_state=1
Feb 14 11:11:59 iqaudio kernel: Init: Power Port (0)
Feb 14 11:11:59 iqaudio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10
Feb 14 11:11:59 iqaudio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
Feb 14 11:11:59 iqaudio kernel: usb usb1: Product: DWC OTG Controller
Feb 14 11:11:59 iqaudio kernel: usb usb1: Manufacturer: Linux 5.10.92-v7+ dwc_otg_hcd
Feb 14 11:11:59 iqaudio kernel: usb usb1: SerialNumber: 3f980000.usb
Feb 14 11:11:59 iqaudio kernel: hub 1-0:1.0: USB hub found
Feb 14 11:11:59 iqaudio kernel: hub 1-0:1.0: 1 port detected
Oct 13 14:21:17 iqaudio kernel: dwc_otg: FIQ enabled
Oct 13 14:21:17 iqaudio kernel: dwc_otg: NAK holdoff enabled
Oct 13 14:21:17 iqaudio kernel: dwc_otg: FIQ split-transaction FSM enabled
Oct 13 14:21:17 iqaudio kernel: Module dwc_common_port init
Oct 13 14:21:17 iqaudio kernel: usbcore: registered new interface driver usb-storage
Oct 13 14:21:17 iqaudio kernel: mousedev: PS/2 mouse device common for all mice
Oct 13 14:21:17 iqaudio kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
Oct 13 14:21:17 iqaudio kernel: sdhci: Secure Digital Host Controller Interface driver
Oct 13 14:21:17 iqaudio kernel: sdhci: Copyright(c) Pierre Ossman
Oct 13 14:21:17 iqaudio kernel: mmc-bcm2835 3f300000.mmcnr: could not get clk, deferring probe
Oct 13 14:21:17 iqaudio kernel: sdhost-bcm2835 3f202000.mmc: could not get clk, deferring probe
Oct 13 14:21:17 iqaudio kernel: sdhci-pltfm: SDHCI platform and OF driver helper
Oct 13 14:21:17 iqaudio kernel: ledtrig-cpu: registered to indicate activity on CPUs
Oct 13 14:21:17 iqaudio kernel: hid: raw HID events driver (C) Jiri Kosina
Oct 13 14:21:17 iqaudio kernel: usbcore: registered new interface driver usbhid
Oct 13 14:21:17 iqaudio kernel: usbhid: USB HID core driver
Oct 13 14:21:17 iqaudio kernel: Initializing XFRM netlink socket
Oct 13 14:21:17 iqaudio kernel: NET: Registered protocol family 17
Oct 13 14:21:17 iqaudio kernel: Key type dns_resolver registered
Oct 13 14:21:17 iqaudio kernel: Registering SWP/SWPB emulation handler
Oct 13 14:21:17 iqaudio kernel: registered taskstats version 1
Oct 13 14:21:17 iqaudio kernel: Loading compiled-in X.509 certificates
Oct 13 14:21:17 iqaudio kernel: Key type ._fscrypt registered
Oct 13 14:21:17 iqaudio kernel: Key type .fscrypt registered
Oct 13 14:21:17 iqaudio kernel: Key type fscrypt-provisioning registered
Oct 13 14:21:17 iqaudio kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled
Oct 13 14:21:17 iqaudio kernel: 3f201000.serial: ttyAMA0 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2
Oct 13 14:21:17 iqaudio kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
Oct 13 14:21:17 iqaudio kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0
Oct 13 14:21:17 iqaudio kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated
Oct 13 14:21:17 iqaudio kernel: sdhost: log_buf @ (ptrval) (fa107000)
Oct 13 14:21:17 iqaudio kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes)
Oct 13 14:21:17 iqaudio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Oct 13 14:21:17 iqaudio kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes)
Oct 13 14:21:17 iqaudio kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes)
Oct 13 14:21:17 iqaudio kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1)
Oct 13 14:21:17 iqaudio kernel: of_cfs_init
Oct 13 14:21:17 iqaudio kernel: of_cfs_init: OK
Oct 13 14:21:17 iqaudio kernel: Indeed it is in host mode hprt0 = 00021501
Oct 13 14:21:17 iqaudio kernel: Freeing unused kernel memory: 1024K
Oct 13 14:21:17 iqaudio kernel: Run /init as init process
Oct 13 14:21:17 iqaudio kernel:   with arguments:
Oct 13 14:21:17 iqaudio kernel:     /init
Oct 13 14:21:17 iqaudio kernel:     splash
Oct 13 14:21:17 iqaudio kernel:   with environment:
Oct 13 14:21:17 iqaudio kernel:     HOME=/
Oct 13 14:21:17 iqaudio kernel:     TERM=linux
Oct 13 14:21:17 iqaudio kernel:     imgpart=/dev/mmcblk0p2
Oct 13 14:21:17 iqaudio kernel:     imgfile=/volumio_current.sqsh
Oct 13 14:21:17 iqaudio kernel:     bootdelay=5
Oct 13 14:21:17 iqaudio kernel: mmc0: host does not support reading read-only switch, assuming write-enable
Oct 13 14:21:17 iqaudio kernel: mmc0: new high speed SDHC card at address 5048
Oct 13 14:21:17 iqaudio kernel: mmcblk0: mmc0:5048 SD32G 28.8 GiB
Oct 13 14:21:17 iqaudio kernel:  mmcblk0: p1 p2 p3
Oct 13 14:21:17 iqaudio kernel: mmc1: new high speed SDIO card at address 0001
Oct 13 14:21:17 iqaudio kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg
Oct 13 14:21:17 iqaudio kernel: Indeed it is in host mode hprt0 = 00001101
Oct 13 14:21:17 iqaudio initramfs: Booting Volumio for BCM2835
Oct 13 14:21:17 iqaudio initramfs: This script mounts rootfs RO with an overlay RW layer.
Oct 13 14:21:17 iqaudio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Oct 13 14:21:17 iqaudio initramfs: IMGPART=/dev/mmcblk0p2
Oct 13 14:21:17 iqaudio initramfs: IMGFILE=/volumio_current.sqsh
Oct 13 14:21:17 iqaudio initramfs: Boot delay (except first time) will be 5 seconds
Oct 13 14:21:17 iqaudio initramfs: /dev/mmcblk0p2:
Oct 13 14:21:17 iqaudio kernel: EXT4-fs (mmcblk0p2): recovery complete
Oct 13 14:21:17 iqaudio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null)
Oct 13 14:21:17 iqaudio initramfs: Doing a 5 second delay here to give kernel load a headstart
Oct 13 14:21:17 iqaudio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00
Oct 13 14:21:17 iqaudio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Oct 13 14:21:17 iqaudio kernel: hub 1-1:1.0: USB hub found
Oct 13 14:21:17 iqaudio kernel: hub 1-1:1.0: 5 ports detected
Oct 13 14:21:17 iqaudio kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg
Oct 13 14:21:17 iqaudio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00
Oct 13 14:21:17 iqaudio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0
Oct 13 14:21:17 iqaudio kernel: smsc95xx v2.0.0
Oct 13 14:21:17 iqaudio kernel: libphy: smsc95xx-mdiobus: probed
Oct 13 14:21:17 iqaudio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:05:7f:57
Oct 13 14:21:17 iqaudio initramfs: Checking for USB updates
Oct 13 14:21:17 iqaudio initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline)
Oct 13 14:21:17 iqaudio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Oct 13 14:21:17 iqaudio kernel: FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck.
Oct 13 14:21:17 iqaudio kernel: EXT4-fs (mmcblk0p3): recovery complete
Oct 13 14:21:17 iqaudio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null)
Oct 13 14:21:17 iqaudio initramfs: With Option:
Oct 13 14:21:17 iqaudio initramfs: VOLUMIO_VERSION="3.512"
Oct 13 14:21:17 iqaudio initramfs: Finish initramfs, continue booting Volumio
Oct 13 14:21:17 iqaudio systemd[1]: System time before build time, advancing clock.
Oct 13 14:21:17 iqaudio kernel: NET: Registered protocol family 10
Oct 13 14:21:17 iqaudio kernel: Segment Routing with IPv6
Oct 13 14:21:17 iqaudio systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)
Oct 13 14:21:17 iqaudio systemd[1]: Detected architecture arm.
Oct 13 14:21:17 iqaudio systemd[1]: Set hostname to .
Oct 13 14:21:17 iqaudio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly.
Oct 13 14:21:17 iqaudio kernel: random: systemd: uninitialized urandom read (16 bytes read)
Oct 13 14:21:17 iqaudio kernel: random: systemd: uninitialized urandom read (16 bytes read)
Oct 13 14:21:17 iqaudio kernel: random: systemd: uninitialized urandom read (16 bytes read)
Oct 13 14:21:17 iqaudio systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point.
Oct 13 14:21:17 iqaudio systemd[1]: Listening on fsck to fsckd communication Socket.
Oct 13 14:21:17 iqaudio systemd[1]: Listening on udev Control Socket.
Oct 13 14:21:17 iqaudio systemd[1]: Listening on Journal Socket (/dev/log).
Oct 13 14:21:17 iqaudio systemd[1]: Started Forward Password Requests to Wall Directory Watch.
Oct 13 14:21:17 iqaudio systemd[1]: Created slice system-getty.slice.
Oct 13 14:21:17 iqaudio kernel: i2c /dev entries driver
Oct 13 14:21:17 iqaudio systemd-journald[195]: Journal started
Oct 13 14:21:17 iqaudio systemd-journald[195]: Runtime journal (/run/log/journal/a4d620ccb4a6a12010c39c5d649876fe) is 7.5M, max 30.0M, 22.5M free.
Oct 13 14:21:17 iqaudio systemd-modules-load[190]: Inserted module 'i2c_dev'
Oct 13 14:21:17 iqaudio fake-hwclock[181]: Fri Oct 13 12:21:17 UTC 2023
Oct 13 14:21:17 iqaudio systemd[1]: Started Create System Users.
Oct 13 14:21:17 iqaudio systemd[1]: Starting Create Static Device Nodes in /dev...
Oct 13 14:21:17 iqaudio systemd[1]: dynamicswap.service: Succeeded.
Oct 13 14:21:17 iqaudio systemd[1]: Started Create Static Device Nodes in /dev.
Oct 13 14:21:17 iqaudio systemd[1]: Starting udev Kernel Device Manager...
Oct 13 14:21:17 iqaudio systemd[1]: Reached target Local File Systems (Pre).
Oct 13 14:21:17 iqaudio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway.
Oct 13 14:21:17 iqaudio systemd[1]: Mounting /tmp...
Oct 13 14:21:17 iqaudio systemd[1]: Mounting /var/spool/cups...
Oct 13 14:21:17 iqaudio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway.
Oct 13 14:21:17 iqaudio systemd[1]: Mounting /var/log...
Oct 13 14:21:17 iqaudio systemd[1]: Mounted /tmp.
Oct 13 14:21:17 iqaudio systemd[1]: Started udev Coldplug all Devices.
Oct 13 14:21:17 iqaudio systemd[1]: Mounted /var/spool/cups.
Oct 13 14:21:17 iqaudio systemd[1]: Mounted /var/log.
Oct 13 14:21:17 iqaudio systemd[1]: Starting Flush Journal to Persistent Storage...
Oct 13 14:21:17 iqaudio systemd-udevd[212]: Network interface NamePolicy= disabled on kernel command line, ignoring.
Oct 13 14:21:17 iqaudio systemd[1]: Mounting /var/spool/cups/tmp...
Oct 13 14:21:17 iqaudio systemd[1]: Starting Helper to synchronize boot up for ifupdown...
Oct 13 14:21:17 iqaudio systemd[1]: Mounted /var/spool/cups/tmp.
Oct 13 14:21:17 iqaudio systemd[1]: Started Helper to synchronize boot up for ifupdown.
Oct 13 14:21:17 iqaudio systemd-journald[195]: Runtime journal (/run/log/journal/a4d620ccb4a6a12010c39c5d649876fe) is 7.5M, max 30.0M, 22.5M free.
Oct 13 14:21:17 iqaudio systemd[1]: Started Flush Journal to Persistent Storage.
Oct 13 14:21:17 iqaudio systemd[1]: Started udev Kernel Device Manager.
Oct 13 14:21:17 iqaudio systemd[1]: Starting Show Plymouth Boot Screen...
Oct 13 14:21:17 iqaudio systemd[1]: Received SIGRTMIN+20 from PID 232 (plymouthd).
Oct 13 14:21:17 iqaudio systemd[1]: Started Show Plymouth Boot Screen.
Oct 13 14:21:17 iqaudio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch.
Oct 13 14:21:17 iqaudio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped.
Oct 13 14:21:17 iqaudio systemd[1]: Reached target Paths.
Oct 13 14:21:17 iqaudio systemd[1]: Reached target Local Encrypted Volumes.
Oct 13 14:21:17 iqaudio kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
Oct 13 14:21:17 iqaudio kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver
Oct 13 14:21:17 iqaudio kernel: [vc_sm_connected_init]: start
Oct 13 14:21:17 iqaudio kernel: [vc_sm_connected_init]: installed successfully
Oct 13 14:21:17 iqaudio kernel: mc: Linux media interface: v0.10
Oct 13 14:21:18 iqaudio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
Oct 13 14:21:18 iqaudio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels
Oct 13 14:21:18 iqaudio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels
Oct 13 14:21:18 iqaudio kernel: videodev: Linux video capture interface: v2.00
Oct 13 14:21:18 iqaudio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Oct 13 14:21:18 iqaudio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Oct 13 14:21:18 iqaudio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
Oct 13 14:21:18 iqaudio kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
Oct 13 14:21:18 iqaudio kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
Oct 13 14:21:18 iqaudio kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
Oct 13 14:21:18 iqaudio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10
Oct 13 14:21:18 iqaudio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
Oct 13 14:21:18 iqaudio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
Oct 13 14:21:18 iqaudio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
Oct 13 14:21:18 iqaudio kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
Oct 13 14:21:18 iqaudio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12
Oct 13 14:21:18 iqaudio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp
Oct 13 14:21:18 iqaudio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18
Oct 13 14:21:18 iqaudio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
Oct 13 14:21:18 iqaudio systemd-udevd[225]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Oct 13 14:21:18 iqaudio systemd-udevd[227]: Using default interface naming scheme 'v240'.
Oct 13 14:21:19 iqaudio systemd[1]: Found device /dev/mmcblk0p1.
Oct 13 14:21:19 iqaudio systemd-udevd[227]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Oct 13 14:21:20 iqaudio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database
Oct 13 14:21:20 iqaudio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
Oct 13 14:21:20 iqaudio kernel: cfg80211: loaded regulatory.db is malformed or signature is missing/invalid
Oct 13 14:21:20 iqaudio kernel: snd-rpi-iqaudio-dac soc:sound: snd_rpi_iqaudio_gpio_unmute: un-muting amp using GPIO22
Oct 13 14:21:21 iqaudio kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6
Oct 13 14:21:21 iqaudio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Oct 13 14:21:21 iqaudio kernel: usbcore: registered new interface driver brcmfmac
Oct 13 14:21:21 iqaudio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1
Oct 13 14:21:21 iqaudio kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897
Oct 13 14:21:21 iqaudio systemd-udevd[221]: Using default interface naming scheme 'v240'.
Oct 13 14:21:21 iqaudio systemd-udevd[221]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable.
Oct 13 14:21:21 iqaudio systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped.
Oct 13 14:21:21 iqaudio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
Oct 13 14:21:21 iqaudio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Oct 13 14:21:21 iqaudio systemd[1]: Condition check resulted in Huge Pages File System being skipped.
Oct 13 14:21:21 iqaudio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped.
Oct 13 14:21:21 iqaudio systemd[1]: Condition check resulted in FUSE Control File System being skipped.
Oct 13 14:21:21 iqaudio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped.
Oct 13 14:21:21 iqaudio systemd[1]: Starting File System Check on /dev/mmcblk0p1...
Oct 13 14:21:22 iqaudio systemd[1]: Starting Load/Save RF Kill Switch Status...
Oct 13 14:21:22 iqaudio systemd[1]: Started File System Check Daemon to report status.
Oct 13 14:21:22 iqaudio systemd[1]: Started Load/Save RF Kill Switch Status.
Oct 13 14:21:22 iqaudio systemd-fsck[418]: fsck.fat 4.1 (2017-01-24)
Oct 13 14:21:22 iqaudio systemd-fsck[418]: 0x25: Dirty bit is set. Fs was not properly unmounted and some data may be corrupt.
Oct 13 14:21:22 iqaudio systemd-fsck[418]:  Automatically removing dirty bit.
Oct 13 14:21:22 iqaudio systemd-fsck[418]: Performing changes.
Oct 13 14:21:22 iqaudio systemd-fsck[418]: /dev/mmcblk0p1: 296 files, 27486/46774 clusters
Oct 13 14:21:22 iqaudio systemd[1]: Found device /sys/subsystem/net/devices/wlan0.
Oct 13 14:21:22 iqaudio systemd[1]: Started File System Check on /dev/mmcblk0p1.
Oct 13 14:21:22 iqaudio systemd[1]: Mounting /boot...
Oct 13 14:21:22 iqaudio systemd[1]: Mounted /boot.
Oct 13 14:21:22 iqaudio systemd[1]: Reached target Local File Systems.
Oct 13 14:21:22 iqaudio systemd[1]: Starting Raise network interfaces...
Oct 13 14:21:22 iqaudio systemd[1]: Started ifup for wlan0.
Oct 13 14:21:22 iqaudio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data...
Oct 13 14:21:22 iqaudio systemd[1]: Starting Preprocess NFS configuration...
Oct 13 14:21:22 iqaudio systemd[1]: Started ifup for eth0.
Oct 13 14:21:22 iqaudio systemd[1]: Starting Create Volatile Files and Directories...
Oct 13 14:21:22 iqaudio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped.
Oct 13 14:21:22 iqaudio systemd[1]: plymouth-read-write.service: Succeeded.
Oct 13 14:21:22 iqaudio systemd[1]: Started Tell Plymouth To Write Out Runtime Data.
Oct 13 14:21:22 iqaudio systemd[1]: Received SIGRTMIN+20 from PID 232 (plymouthd).
Oct 13 14:21:22 iqaudio systemd[1]: nfs-config.service: Succeeded.
Oct 13 14:21:22 iqaudio systemd[1]: Started Preprocess NFS configuration.
Oct 13 14:21:22 iqaudio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped.
Oct 13 14:21:22 iqaudio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped.
Oct 13 14:21:22 iqaudio systemd[1]: Reached target NFS client services.
Oct 13 14:21:22 iqaudio systemd[1]: Started Create Volatile Files and Directories.
Oct 13 14:21:22 iqaudio systemd[1]: Condition check resulted in Network Time Synchronization being skipped.
Oct 13 14:21:22 iqaudio systemd[1]: Starting RPC bind portmap service...
Oct 13 14:21:22 iqaudio systemd[1]: Started Entropy daemon using the HAVEGE algorithm.
Oct 13 14:21:22 iqaudio systemd[1]: Starting Update UTMP about System Boot/Shutdown...
Oct 13 14:21:23 iqaudio systemd[1]: Started Update UTMP about System Boot/Shutdown.
Oct 13 14:21:23 iqaudio kernel: 8021q: 802.1Q VLAN Support v1.8
Oct 13 14:21:23 iqaudio systemd[1]: Reached target System Initialization.
Oct 13 14:21:23 iqaudio systemd[1]: Listening on triggerhappy.socket.
Oct 13 14:21:23 iqaudio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Oct 13 14:21:23 iqaudio systemd[1]: Listening on D-Bus System Message Bus Socket.
Oct 13 14:21:23 iqaudio systemd[1]: Listening on mpd.socket.
Oct 13 14:21:23 iqaudio systemd[1]: Started Daily apt download activities.
Oct 13 14:21:23 iqaudio systemd[1]: Started Daily apt upgrade and clean activities.
Oct 13 14:21:23 iqaudio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Oct 13 14:21:23 iqaudio systemd[1]: Reached target Sockets.
Oct 13 14:21:23 iqaudio systemd[1]: Reached target Basic System.
Oct 13 14:21:23 iqaudio systemd[1]: Started NanoSound LIRC Service.
Oct 13 14:21:23 iqaudio systemd[506]: nanosound_lirc.service: Failed to execute command: No such file or directory
Oct 13 14:21:23 iqaudio systemd[506]: nanosound_lirc.service: Failed at step EXEC spawning /home/volumio/nanosound_oled/nanodac_lirc.py: No such file or directory
Oct 13 14:21:23 iqaudio systemd[1]: Starting Configure Bluetooth Modems connected by UART...
Oct 13 14:21:23 iqaudio systemd[1]: Started NanoSound OLED Diplay Service.
Oct 13 14:21:23 iqaudio systemd[1]: Starting Wireless Services...
Oct 13 14:21:23 iqaudio systemd[511]: nanosound_oled.service: Changing to the requested working directory failed: No such file or directory
Oct 13 14:21:23 iqaudio systemd[511]: nanosound_oled.service: Failed at step CHDIR spawning /home/volumio/nanosound_oled/nanodac_oled.py: No such file or directory
Oct 13 14:21:23 iqaudio systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Oct 13 14:21:23 iqaudio systemd[1]: Started Manage Sound Card State (restore and store).
Oct 13 14:21:23 iqaudio systemd[1]: Started UPnP Renderer front-end to MPD.
Oct 13 14:21:23 iqaudio alsactl[518]: alsactl 1.1.8 daemon started
Oct 13 14:21:23 iqaudio systemd[1]: Starting triggerhappy global hotkey daemon...
Oct 13 14:21:23 iqaudio systemd[1]: Started volumio-remote-updater.service.
Oct 13 14:21:23 iqaudio systemd[1]: Started NanoSound Rotary Volume Control.
Oct 13 14:21:23 iqaudio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped.
Oct 13 14:21:23 iqaudio systemd[529]: nanosound_rotary.service: Failed to execute command: No such file or directory
Oct 13 14:21:23 iqaudio systemd[529]: nanosound_rotary.service: Failed at step EXEC spawning /home/volumio/nanosound_oled/nanodac_rotary.py: No such file or directory
Oct 13 14:21:23 iqaudio systemd[1]: Started D-Bus System Message Bus.
Oct 13 14:21:23 iqaudio thd[524]: Unable to parse trigger line:
Oct 13 14:21:23 iqaudio thd[524]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle
Oct 13 14:21:23 iqaudio thd[524]: Unable to parse trigger line:
Oct 13 14:21:23 iqaudio thd[524]: Found socket passed from systemd
Oct 13 14:21:23 iqaudio systemd[1]: Starting Save/Restore Sound Card State...
Oct 13 14:21:23 iqaudio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped.
Oct 13 14:21:23 iqaudio systemd[1]: Starting dhcpcd on all interfaces...
Oct 13 14:21:23 iqaudio systemd[1]: Starting WPA supplicant...
Oct 13 14:21:23 iqaudio systemd[1]: Starting Login Service...
Oct 13 14:21:23 iqaudio systemd[1]: Started Volumio Iptables Module.
Oct 13 14:21:23 iqaudio systemd[1]: Started Daily Cleanup of Temporary Directories.
Oct 13 14:21:23 iqaudio systemd[1]: Reached target Timers.
Oct 13 14:21:23 iqaudio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped.
Oct 13 14:21:23 iqaudio systemd[1]: Started triggerhappy global hotkey daemon.
Oct 13 14:21:23 iqaudio systemd[1]: Started RPC bind portmap service.
Oct 13 14:21:23 iqaudio systemd[1]: nanosound_lirc.service: Main process exited, code=exited, status=203/EXEC
Oct 13 14:21:23 iqaudio systemd[1]: nanosound_lirc.service: Failed with result 'exit-code'.
Oct 13 14:21:23 iqaudio systemd[1]: nanosound_oled.service: Main process exited, code=exited, status=200/CHDIR
Oct 13 14:21:23 iqaudio dhcpcd[546]: Not running dhcpcd because /etc/network/interfaces
Oct 13 14:21:23 iqaudio dhcpcd[546]: defines some interfaces that will use a
Oct 13 14:21:23 iqaudio dhcpcd[546]: DHCP client or static address
Oct 13 14:21:23 iqaudio systemd[1]: nanosound_oled.service: Failed with result 'exit-code'.
Oct 13 14:21:23 iqaudio systemd[1]: nanosound_rotary.service: Main process exited, code=exited, status=203/EXEC
Oct 13 14:21:23 iqaudio systemd[1]: nanosound_rotary.service: Failed with result 'exit-code'.
Oct 13 14:21:23 iqaudio systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED
Oct 13 14:21:23 iqaudio systemd[1]: dhcpcd.service: Failed with result 'exit-code'.
Oct 13 14:21:23 iqaudio systemd[1]: Failed to start dhcpcd on all interfaces.
Oct 13 14:21:23 iqaudio systemd[1]: Started Save/Restore Sound Card State.
Oct 13 14:21:23 iqaudio systemd[1]: Reached target Sound Card.
Oct 13 14:21:23 iqaudio systemd[1]: Reached target Remote File Systems (Pre).
Oct 13 14:21:23 iqaudio systemd[1]: Reached target Remote File Systems.
Oct 13 14:21:23 iqaudio avahi-daemon[516]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113).
Oct 13 14:21:23 iqaudio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling...
Oct 13 14:21:23 iqaudio sh[426]: wlan0=wlan0
Oct 13 14:21:23 iqaudio avahi-daemon[516]: Successfully dropped root privileges.
Oct 13 14:21:23 iqaudio systemd[1]: Reached target RPC Port Mapper.
Oct 13 14:21:23 iqaudio avahi-daemon[516]: avahi-daemon 0.7 starting up.
Oct 13 14:21:23 iqaudio kernel: uart-pl011 3f201000.serial: no DMA platform data
Oct 13 14:21:23 iqaudio sh[433]: eth0: waiting for carrier
Oct 13 14:21:23 iqaudio kernel: SMSC LAN8700 usb-001:003:01: attached PHY driver [SMSC LAN8700] (mii_bus:phy_addr=usb-001:003:01, irq=POLL)
Oct 13 14:21:23 iqaudio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup
Oct 13 14:21:23 iqaudio dhcpcd[473]: eth0: waiting for carrier
Oct 13 14:21:24 iqaudio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down
Oct 13 14:21:24 iqaudio systemd-logind[551]: New seat seat0.
Oct 13 14:21:24 iqaudio avahi-daemon[516]: Successfully called chroot().
Oct 13 14:21:24 iqaudio avahi-daemon[516]: Successfully dropped remaining capabilities.
Oct 13 14:21:24 iqaudio avahi-daemon[516]: Loading service file /services/volumio.service.
Oct 13 14:21:24 iqaudio avahi-daemon[516]: Network interface enumeration completed.
Oct 13 14:21:24 iqaudio avahi-daemon[516]: Server startup complete. Host name is iqaudio.local. Local service cookie is 2802467512.
Oct 13 14:21:24 iqaudio avahi-daemon[516]: Service "iqaudio" (/services/volumio.service) successfully established.
Oct 13 14:21:24 iqaudio systemd[1]: Started Login Service.
Oct 13 14:21:24 iqaudio systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Oct 13 14:21:24 iqaudio systemd[1]: Started Raise network interfaces.
Oct 13 14:21:25 iqaudio loadcpufreq[583]: Loading cpufreq kernel modules...done (none).
Oct 13 14:21:25 iqaudio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling.
Oct 13 14:21:25 iqaudio systemd[1]: Starting LSB: set CPUFreq kernel parameters...
Oct 13 14:21:25 iqaudio kernel: random: crng init done
Oct 13 14:21:25 iqaudio kernel: random: 7 urandom warning(s) missed due to ratelimiting
Oct 13 14:21:25 iqaudio cpufrequtils[637]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done.
Oct 13 14:21:25 iqaudio systemd[1]: Started LSB: set CPUFreq kernel parameters.
Oct 13 14:21:25 iqaudio systemd[1]: iptables.service: Succeeded.
Oct 13 14:21:25 iqaudio haveged[482]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K
Oct 13 14:21:25 iqaudio haveged[482]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488
Oct 13 14:21:25 iqaudio haveged[482]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B):  last entropy estimate 7.99935
Oct 13 14:21:25 iqaudio haveged[482]: haveged: fills: 0, generated: 0
Oct 13 14:21:26 iqaudio sh[433]: eth0: carrier acquired
Oct 13 14:21:26 iqaudio dhcpcd[473]: eth0: carrier acquired
Oct 13 14:21:26 iqaudio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off
Oct 13 14:21:26 iqaudio sh[433]: DUID 00:01:00:01:2c:2b:35:42:b8:27:eb:05:7f:57
Oct 13 14:21:26 iqaudio sh[433]: eth0: IAID eb:05:7f:57
Oct 13 14:21:26 iqaudio dhcpcd[473]: DUID 00:01:00:01:2c:2b:35:42:b8:27:eb:05:7f:57
Oct 13 14:21:26 iqaudio dhcpcd[473]: eth0: IAID eb:05:7f:57
Oct 13 14:21:26 iqaudio sh[433]: eth0: adding address fe80::c5e:55c2:7142:e28a
Oct 13 14:21:26 iqaudio sh[433]: ipv6_addaddr1: Permission denied
Oct 13 14:21:26 iqaudio dhcpcd[473]: eth0: adding address fe80::c5e:55c2:7142:e28a
Oct 13 14:21:26 iqaudio dhcpcd[473]: ipv6_addaddr1: Permission denied
Oct 13 14:21:26 iqaudio sh[433]: eth0: rebinding lease of 193.193.26.185
Oct 13 14:21:26 iqaudio dhcpcd[473]: eth0: rebinding lease of 193.193.26.185
Oct 13 14:21:26 iqaudio sh[433]: eth0: probing address 193.193.26.185/24
Oct 13 14:21:26 iqaudio dhcpcd[473]: eth0: probing address 193.193.26.185/24
Oct 13 14:21:26 iqaudio systemd[1]: Started WPA supplicant.
Oct 13 14:21:26 iqaudio systemd[1]: Reached target Network.
Oct 13 14:21:26 iqaudio wpa_supplicant[550]: Successfully initialized wpa_supplicant
Oct 13 14:21:26 iqaudio systemd[1]: Starting Permit User Sessions...
Oct 13 14:21:26 iqaudio systemd[1]: Starting Music Player Daemon...
Oct 13 14:21:26 iqaudio systemd[1]: Starting Network Time Service...
Oct 13 14:21:26 iqaudio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
Oct 13 14:21:26 iqaudio systemd[1]: Starting OpenBSD Secure Shell server...
Oct 13 14:21:26 iqaudio systemd[1]: Reached target Network is Online.
Oct 13 14:21:26 iqaudio systemd[1]: Starting Samba NMB Daemon...
Oct 13 14:21:26 iqaudio systemd[1]: Starting /etc/rc.local Compatibility...
Oct 13 14:21:26 iqaudio systemd[1]: Starting LSB: Brings up/down network automatically...
Oct 13 14:21:26 iqaudio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 13 14:21:26 iqaudio systemd[1]: Started Permit User Sessions.
Oct 13 14:21:26 iqaudio systemd[1]: Started /etc/rc.local Compatibility.
Oct 13 14:21:26 iqaudio systemd[1]: Starting Terminate Plymouth Boot Screen...
Oct 13 14:21:26 iqaudio systemd[1]: Starting Hold until boot process finishes up...
Oct 13 14:21:26 iqaudio systemd[1]: Received SIGRTMIN+21 from PID 232 (plymouthd).
Oct 13 14:21:26 iqaudio systemd[1]: Received SIGRTMIN+21 from PID 232 (plymouthd).
Oct 13 14:21:26 iqaudio systemd[1]: plymouth-quit.service: Succeeded.
Oct 13 14:21:26 iqaudio systemd[1]: Started Terminate Plymouth Boot Screen.
Oct 13 14:21:26 iqaudio systemd[1]: plymouth-start.service: Succeeded.
Oct 13 14:21:26 iqaudio systemd[1]: plymouth-quit-wait.service: Succeeded.
Oct 13 14:21:26 iqaudio systemd[1]: Started Hold until boot process finishes up.
Oct 13 14:21:26 iqaudio systemd[1]: Started Getty on tty1.
Oct 13 14:21:26 iqaudio systemd[1]: Reached target Login Prompts.
Oct 13 14:21:26 iqaudio sh[433]: eth0: soliciting an IPv6 router
Oct 13 14:21:26 iqaudio dhcpcd[473]: eth0: soliciting an IPv6 router
Oct 13 14:21:26 iqaudio ifplugd(eth0)[686]: ifplugd 0.28 initializing.
Oct 13 14:21:26 iqaudio ifplugd(eth0)[686]: Using interface eth0/B8:27:EB:05:7F:57 with driver  (version: 5.10.92-v7+)
Oct 13 14:21:26 iqaudio ifplugd(eth0)[686]: Using detection mode: SIOCETHTOOL
Oct 13 14:21:26 iqaudio ifplugd(eth0)[686]: Initialization complete, link beat detected.
Oct 13 14:21:26 iqaudio ifplugd(eth0)[686]: Executing '/etc/ifplugd/ifplugd.action eth0 up'.
Oct 13 14:21:26 iqaudio ifplugd(eth0)[686]: client: ifup: waiting for lock on /run/network/ifstate.eth0
Oct 13 14:21:26 iqaudio ntpd[660]: ntpd 4.2.8p12@1.3728-o (1): Starting
Oct 13 14:21:26 iqaudio systemd[1]: Started Network Time Service.
Oct 13 14:21:26 iqaudio ntpd[660]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103
Oct 13 14:21:26 iqaudio volumio-remote-updater[528]: Error: No active session
Oct 13 14:21:26 iqaudio volumio-remote-updater[528]: [2023-10-13 14:21:26] [info] asio async_connect error: system:111 (Connection refused)
Oct 13 14:21:26 iqaudio volumio-remote-updater[528]: [2023-10-13 14:21:26] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Oct 13 14:21:26 iqaudio volumio-remote-updater[528]: [2023-10-13 14:21:26] [error] handle_connect error: Underlying Transport Error
Oct 13 14:21:26 iqaudio ntpd[694]: proto: precision = 0.729 usec (-20)
Oct 13 14:21:26 iqaudio ntpd[694]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Oct 13 14:21:26 iqaudio ntpd[694]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Oct 13 14:21:26 iqaudio ntpd[694]: Listen and drop on 0 v6wildcard [::]:123
Oct 13 14:21:26 iqaudio ntpd[694]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Oct 13 14:21:26 iqaudio ntpd[694]: Listen normally on 2 lo 127.0.0.1:123
Oct 13 14:21:26 iqaudio ntpd[694]: Listening on routing socket on fd #19 for interface updates
Oct 13 14:21:26 iqaudio ntpd[694]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Oct 13 14:21:26 iqaudio ntpd[694]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized
Oct 13 14:21:27 iqaudio systemd[1]: systemd-rfkill.service: Succeeded.
Oct 13 14:21:27 iqaudio sshd[700]: Server listening on 0.0.0.0 port 22.
Oct 13 14:21:27 iqaudio sshd[700]: Server listening on :: port 22.
Oct 13 14:21:27 iqaudio systemd[1]: Started OpenBSD Secure Shell server.
Oct 13 14:21:27 iqaudio volumio[520]: Could not open config: /tmp/upmpdcli.conf
Oct 13 14:21:27 iqaudio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Oct 13 14:21:27 iqaudio systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Oct 13 14:21:27 iqaudio ntpd[694]: error resolving pool 0.debian.pool.ntp.org: System error (-11)
Oct 13 14:21:28 iqaudio kernel: Bluetooth: Core ver 2.22
Oct 13 14:21:28 iqaudio kernel: NET: Registered protocol family 31
Oct 13 14:21:28 iqaudio kernel: Bluetooth: HCI device and connection manager initialized
Oct 13 14:21:28 iqaudio kernel: Bluetooth: HCI socket layer initialized
Oct 13 14:21:28 iqaudio kernel: Bluetooth: L2CAP socket layer initialized
Oct 13 14:21:28 iqaudio kernel: Bluetooth: SCO socket layer initialized
Oct 13 14:21:28 iqaudio kernel: Bluetooth: HCI UART driver ver 2.3
Oct 13 14:21:28 iqaudio kernel: Bluetooth: HCI UART protocol H4 registered
Oct 13 14:21:28 iqaudio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered
Oct 13 14:21:28 iqaudio kernel: Bluetooth: HCI UART protocol Broadcom registered
Oct 13 14:21:28 iqaudio btuart[507]: bcm43xx_init
Oct 13 14:21:28 iqaudio btuart[507]: Flash firmware /lib/firmware/brcm/BCM43430A1.hcd
Oct 13 14:21:28 iqaudio btuart[507]: Set BDADDR UART: b8:27:eb:af:d5:fd
Oct 13 14:21:28 iqaudio btuart[507]: Set Controller UART speed to 460800 bit/s
Oct 13 14:21:28 iqaudio btuart[507]: Device setup complete
Oct 13 14:21:28 iqaudio systemd[1]: Starting Load/Save RF Kill Switch Status...
Oct 13 14:21:28 iqaudio systemd[1]: Started Configure Bluetooth Modems connected by UART.
Oct 13 14:21:28 iqaudio nmbd[671]: [2023/10/13 14:21:28.930830,  0] ../source3/nmbd/asyncdns.c:158(start_async_dns)
Oct 13 14:21:28 iqaudio nmbd[671]:   started asyncdns process 712
Oct 13 14:21:28 iqaudio ntpd[694]: error resolving pool 1.debian.pool.ntp.org: System error (-11)
Oct 13 14:21:28 iqaudio nmbd[671]: [2023/10/13 14:21:28.933477,  0] ../lib/util/become_daemon.c:149(daemon_status)
Oct 13 14:21:28 iqaudio nmbd[671]:   daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ...
Oct 13 14:21:28 iqaudio nmbd[671]: [2023/10/13 14:21:28.933676,  0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets)
Oct 13 14:21:28 iqaudio nmbd[671]:   NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6).
Oct 13 14:21:28 iqaudio systemd[1]: Started Load/Save RF Kill Switch Status.
Oct 13 14:21:28 iqaudio systemd[1]: Created slice system-bthelper.slice.
Oct 13 14:21:28 iqaudio systemd[1]: Starting Raspberry Pi bluetooth helper...
Oct 13 14:21:29 iqaudio bthelper[713]: Raspberry Pi BDADDR already set
Oct 13 14:21:29 iqaudio systemd[1]: Started Raspberry Pi bluetooth helper.
Oct 13 14:21:29 iqaudio systemd[1]: Starting Bluetooth service...
Oct 13 14:21:29 iqaudio bluetoothd[723]: Bluetooth daemon 5.50
Oct 13 14:21:29 iqaudio bluetoothd[723]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf
Oct 13 14:21:29 iqaudio systemd[1]: Started Bluetooth service.
Oct 13 14:21:29 iqaudio systemd[1]: Reached target Bluetooth.
Oct 13 14:21:29 iqaudio bluetoothd[723]: Starting SDP server
Oct 13 14:21:29 iqaudio bluetoothd[723]: Excluding (cli) sap
Oct 13 14:21:29 iqaudio bluetoothd[723]: Bluetooth management interface 1.18 initialized
Oct 13 14:21:29 iqaudio dbus-daemon[533]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=723 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ")
Oct 13 14:21:29 iqaudio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
Oct 13 14:21:29 iqaudio kernel: Bluetooth: BNEP filters: protocol multicast
Oct 13 14:21:29 iqaudio kernel: Bluetooth: BNEP socket layer initialized
Oct 13 14:21:29 iqaudio systemd[1]: Starting Hostname Service...
Oct 13 14:21:29 iqaudio bluetoothd[723]: Failed to set privacy: Rejected (0x0b)
Oct 13 14:21:29 iqaudio dbus-daemon[533]: [system] Successfully activated service 'org.freedesktop.hostname1'
Oct 13 14:21:29 iqaudio systemd[1]: Started Hostname Service.
Oct 13 14:21:29 iqaudio ntpd[694]: error resolving pool 2.debian.pool.ntp.org: System error (-11)
Oct 13 14:21:30 iqaudio wireless.js[512]: Cleaning previous...
Oct 13 14:21:30 iqaudio sudo[734]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Oct 13 14:21:30 iqaudio sudo[734]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:30 iqaudio sudo[734]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:30 iqaudio sudo[736]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Oct 13 14:21:30 iqaudio sudo[736]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:30 iqaudio ntpd[694]: error resolving pool 3.debian.pool.ntp.org: System error (-11)
Oct 13 14:21:31 iqaudio sudo[736]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:31 iqaudio wireless.js[512]: Stopped aP
Oct 13 14:21:31 iqaudio sudo[743]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Oct 13 14:21:31 iqaudio sudo[743]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:31 iqaudio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Oct 13 14:21:31 iqaudio sudo[743]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:31 iqaudio sudo[745]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get
Oct 13 14:21:31 iqaudio sudo[745]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:31 iqaudio sudo[745]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:31 iqaudio sudo[752]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Oct 13 14:21:31 iqaudio sudo[752]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:31 iqaudio sudo[752]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:31 iqaudio mpd[687]: Oct 13 14:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 13 14:21:31 iqaudio sudo[754]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan
Oct 13 14:21:31 iqaudio sudo[754]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:31 iqaudio mpd[687]: Oct 13 14:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Oct 13 14:21:31 iqaudio mpd[687]: Oct 13 14:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Oct 13 14:21:31 iqaudio systemd[1]: Started Music Player Daemon.
Oct 13 14:21:31 iqaudio sh[433]: eth0: leased 193.193.26.185 for 30000 seconds
Oct 13 14:21:31 iqaudio sh[433]: eth0: adding route to 193.193.26.0/24
Oct 13 14:21:31 iqaudio sh[433]: eth0: adding default route via 193.193.26.232
Oct 13 14:21:31 iqaudio dhcpcd[473]: eth0: leased 193.193.26.185 for 30000 seconds
Oct 13 14:21:31 iqaudio avahi-daemon[516]: Joining mDNS multicast group on interface eth0.IPv4 with address 193.193.26.185.
Oct 13 14:21:31 iqaudio dhcpcd[473]: eth0: adding route to 193.193.26.0/24
Oct 13 14:21:31 iqaudio avahi-daemon[516]: New relevant interface eth0.IPv4 for mDNS.
Oct 13 14:21:31 iqaudio dhcpcd[473]: eth0: adding default route via 193.193.26.232
Oct 13 14:21:31 iqaudio avahi-daemon[516]: Registering new address record for 193.193.26.185 on eth0.IPv4.
Oct 13 14:21:31 iqaudio sh[433]: forked to background, child pid 781
Oct 13 14:21:31 iqaudio dhcpcd[473]: forked to background, child pid 781
Oct 13 14:21:31 iqaudio ifplugd(eth0)[686]: client: ifup: interface eth0 already configured
Oct 13 14:21:31 iqaudio sh[433]: eth0=eth0
Oct 13 14:21:31 iqaudio ifplugd(eth0)[686]: Program executed successfully.
Oct 13 14:21:31 iqaudio ifplugd[667]: Network Interface Plugging Daemon...start eth0...done.
Oct 13 14:21:31 iqaudio systemd[1]: Started LSB: Brings up/down network automatically.
Oct 13 14:21:31 iqaudio sudo[754]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:31 iqaudio wireless.js[512]: Start wireless flow
Oct 13 14:21:31 iqaudio wireless.js[512]: Stopped hotspot (if there)..
Oct 13 14:21:31 iqaudio sudo[808]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Oct 13 14:21:31 iqaudio sudo[808]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:31 iqaudio sudo[808]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:31 iqaudio sudo[810]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Oct 13 14:21:31 iqaudio sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:32 iqaudio volumio-remote-updater[528]: [2023-10-13 14:21:32] [info] asio async_connect error: system:111 (Connection refused)
Oct 13 14:21:32 iqaudio volumio-remote-updater[528]: [2023-10-13 14:21:32] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Oct 13 14:21:32 iqaudio volumio-remote-updater[528]: [2023-10-13 14:21:32] [error] handle_connect error: Underlying Transport Error
Oct 13 14:21:32 iqaudio sudo[810]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:32 iqaudio wireless.js[512]: DHCP IP
Oct 13 14:21:32 iqaudio wireless.js[512]: Start ap
Oct 13 14:21:32 iqaudio wpa_supplicant[812]: Successfully initialized wpa_supplicant
Oct 13 14:21:32 iqaudio sudo[813]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd
Oct 13 14:21:32 iqaudio sudo[813]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:32 iqaudio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Oct 13 14:21:32 iqaudio dhcpcd[814]: dev: loaded udev
Oct 13 14:21:32 iqaudio dhcpcd-run-hooks[823]: wlan0: starting wpa_supplicant
Oct 13 14:21:32 iqaudio dhcpcd-run-hooks[828]: wlan0: failed to start wpa_supplicant
Oct 13 14:21:32 iqaudio dhcpcd-run-hooks[829]: wlan0: Successfully initialized wpa_supplicant
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Match already configured
                                               nl80211: kernel reports: Ma
Oct 13 14:21:32 iqaudio dhcpcd[814]: wlan0: connected to Access Point `'
Oct 13 14:21:32 iqaudio dhcpcd[814]: DUID 00:01:00:01:2c:2b:35:42:b8:27:eb:05:7f:57
Oct 13 14:21:32 iqaudio dhcpcd[814]: eth0: IAID eb:05:7f:57
Oct 13 14:21:32 iqaudio dhcpcd[814]: eth0: adding address fe80::c5e:55c2:7142:e28a
Oct 13 14:21:32 iqaudio dhcpcd[814]: ipv6_addaddr1: Permission denied
Oct 13 14:21:32 iqaudio dhcpcd[814]: wlan0: waiting for carrier
Oct 13 14:21:33 iqaudio wireless.js[512]: trying...
Oct 13 14:21:33 iqaudio sudo[848]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:33 iqaudio sudo[848]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:33 iqaudio sudo[848]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:33 iqaudio dhcpcd[814]: eth0: rebinding lease of 193.193.26.185
Oct 13 14:21:33 iqaudio dhcpcd[814]: eth0: leased 193.193.26.185 for 30000 seconds
Oct 13 14:21:33 iqaudio dhcpcd[814]: eth0: adding route to 193.193.26.0/24
Oct 13 14:21:33 iqaudio dhcpcd[814]: eth0: adding default route via 193.193.26.232
Oct 13 14:21:33 iqaudio dhcpcd[814]: forked to background, child pid 871
Oct 13 14:21:33 iqaudio dhcpcd[871]: eth0: soliciting an IPv6 router
Oct 13 14:21:33 iqaudio sudo[813]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:33 iqaudio systemd[1]: systemd-rfkill.service: Succeeded.
Oct 13 14:21:33 iqaudio nmbd[671]: [2023/10/13 14:21:33.943539,  0] ../lib/util/become_daemon.c:138(daemon_ready)
Oct 13 14:21:33 iqaudio nmbd[671]:   daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections
Oct 13 14:21:33 iqaudio systemd[1]: Started Samba NMB Daemon.
Oct 13 14:21:33 iqaudio systemd[1]: Starting Samba Winbind Daemon...
Oct 13 14:21:34 iqaudio bthelper[713]: Changing power off succeeded
Oct 13 14:21:34 iqaudio winbindd[872]: [2023/10/13 14:21:34.261360,  0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache)
Oct 13 14:21:34 iqaudio winbindd[872]:   initialize_winbindd_cache: clearing cache and re-creating with version number 2
Oct 13 14:21:34 iqaudio winbindd[872]: [2023/10/13 14:21:34.279819,  0] ../lib/util/become_daemon.c:138(daemon_ready)
Oct 13 14:21:34 iqaudio winbindd[872]:   daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections
Oct 13 14:21:34 iqaudio systemd[1]: Started Samba Winbind Daemon.
Oct 13 14:21:34 iqaudio systemd[1]: Starting Samba SMB Daemon...
Oct 13 14:21:34 iqaudio bthelper[713]: [CHG] Controller B8:27:EB:AF:D5:FD Class: 0x0000041c
Oct 13 14:21:34 iqaudio bthelper[713]: Changing power on succeeded
Oct 13 14:21:34 iqaudio bthelper[713]: [CHG] Controller B8:27:EB:AF:D5:FD Powered: yes
Oct 13 14:21:34 iqaudio wireless.js[512]: trying...
Oct 13 14:21:34 iqaudio sudo[886]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:34 iqaudio sudo[886]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:34 iqaudio sudo[886]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:34 iqaudio smbd[880]: [2023/10/13 14:21:34.841138,  0] ../lib/util/become_daemon.c:138(daemon_ready)
Oct 13 14:21:34 iqaudio smbd[880]:   daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections
Oct 13 14:21:34 iqaudio systemd[1]: Started Samba SMB Daemon.
Oct 13 14:21:34 iqaudio ntpd[694]: Listen normally on 3 eth0 193.193.26.185:123
Oct 13 14:21:34 iqaudio ntpd[694]: new interface(s) found: waking up resolver
Oct 13 14:21:35 iqaudio wireless.js[512]: trying...
Oct 13 14:21:35 iqaudio sudo[896]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:35 iqaudio sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:35 iqaudio sudo[896]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:36 iqaudio wireless.js[512]: trying...
Oct 13 14:21:36 iqaudio sudo[899]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:36 iqaudio sudo[899]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:36 iqaudio sudo[899]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:37 iqaudio wireless.js[512]: trying...
Oct 13 14:21:37 iqaudio sudo[902]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:37 iqaudio sudo[902]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:37 iqaudio sudo[902]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:38 iqaudio kernel: cam1-reg: disabling
Oct 13 14:21:38 iqaudio kernel: cam-dummy-reg: disabling
Oct 13 14:21:38 iqaudio wireless.js[512]: trying...
Oct 13 14:21:38 iqaudio sudo[905]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:38 iqaudio sudo[905]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:38 iqaudio sudo[905]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:39 iqaudio wireless.js[512]: trying...
Oct 13 14:21:39 iqaudio sudo[908]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:39 iqaudio sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:39 iqaudio sudo[908]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:39 iqaudio volumio-remote-updater[528]: [2023-10-13 14:21:39] [info] asio async_connect error: system:111 (Connection refused)
Oct 13 14:21:39 iqaudio volumio-remote-updater[528]: [2023-10-13 14:21:39] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Oct 13 14:21:39 iqaudio volumio-remote-updater[528]: [2023-10-13 14:21:39] [error] handle_connect error: Underlying Transport Error
Oct 13 14:21:40 iqaudio wireless.js[512]: trying...
Oct 13 14:21:40 iqaudio sudo[911]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:40 iqaudio sudo[911]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:40 iqaudio sudo[911]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:41 iqaudio wireless.js[512]: trying...
Oct 13 14:21:41 iqaudio sudo[914]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:41 iqaudio sudo[914]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:41 iqaudio sudo[914]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:42 iqaudio wireless.js[512]: trying...
Oct 13 14:21:42 iqaudio sudo[917]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:42 iqaudio sudo[917]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:42 iqaudio sudo[917]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:43 iqaudio wireless.js[512]: trying...
Oct 13 14:21:43 iqaudio sudo[920]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:43 iqaudio sudo[920]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:43 iqaudio sudo[920]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:44 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:21:44 iqaudio wireless.js[512]: trying...
Oct 13 14:21:44 iqaudio sudo[923]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:44 iqaudio sudo[923]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:44 iqaudio sudo[923]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:45 iqaudio wireless.js[512]: trying...
Oct 13 14:21:45 iqaudio sudo[926]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:45 iqaudio sudo[926]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:45 iqaudio sudo[926]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:46 iqaudio wireless.js[512]: trying...
Oct 13 14:21:46 iqaudio sudo[929]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:46 iqaudio sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:46 iqaudio sudo[929]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:47 iqaudio wireless.js[512]: trying...
Oct 13 14:21:47 iqaudio sudo[932]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:47 iqaudio sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:47 iqaudio sudo[932]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:48 iqaudio wireless.js[512]: trying...
Oct 13 14:21:48 iqaudio sudo[935]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:48 iqaudio sudo[935]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:48 iqaudio sudo[935]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:49 iqaudio wireless.js[512]: trying...
Oct 13 14:21:49 iqaudio sudo[938]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:49 iqaudio sudo[938]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:49 iqaudio sudo[938]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:49 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:21:49 iqaudio volumio-remote-updater[528]: [2023-10-13 14:21:49] [info] asio async_connect error: system:111 (Connection refused)
Oct 13 14:21:49 iqaudio volumio-remote-updater[528]: [2023-10-13 14:21:49] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Oct 13 14:21:49 iqaudio volumio-remote-updater[528]: [2023-10-13 14:21:49] [error] handle_connect error: Underlying Transport Error
Oct 13 14:21:50 iqaudio wireless.js[512]: trying...
Oct 13 14:21:50 iqaudio sudo[941]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:50 iqaudio sudo[941]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:50 iqaudio sudo[941]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:51 iqaudio wireless.js[512]: trying...
Oct 13 14:21:51 iqaudio sudo[944]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:51 iqaudio sudo[944]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:51 iqaudio sudo[944]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:52 iqaudio systemd[1]: systemd-fsckd.service: Succeeded.
Oct 13 14:21:52 iqaudio wireless.js[512]: trying...
Oct 13 14:21:52 iqaudio sudo[947]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Oct 13 14:21:52 iqaudio sudo[947]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:52 iqaudio sudo[947]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:53 iqaudio wireless.js[512]: Overtime, starting plan B
Oct 13 14:21:53 iqaudio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled
Oct 13 14:21:53 iqaudio systemd[1]: Started Wireless Services.
Oct 13 14:21:53 iqaudio systemd[1]: Started Volumio Backend Module.
Oct 13 14:21:53 iqaudio systemd[1]: Reached target Multi-User System.
Oct 13 14:21:53 iqaudio systemd[1]: Reached target Graphical Interface.
Oct 13 14:21:53 iqaudio systemd[1]: Starting Update UTMP about System Runlevel Changes...
Oct 13 14:21:53 iqaudio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Oct 13 14:21:53 iqaudio systemd[1]: Started Update UTMP about System Runlevel Changes.
Oct 13 14:21:53 iqaudio systemd[1]: Startup finished in 11.204s (kernel) + 38.518s (userspace) = 49.723s.
Oct 13 14:21:55 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:21:56 iqaudio volumio[952]: info: -------------------------------------------
Oct 13 14:21:56 iqaudio volumio[952]: info: -----            Volumio3              ----
Oct 13 14:21:56 iqaudio volumio[952]: info: -------------------------------------------
Oct 13 14:21:56 iqaudio volumio[952]: info: -----          System startup          ----
Oct 13 14:21:56 iqaudio volumio[952]: info: -------------------------------------------
Oct 13 14:21:57 iqaudio volumio[952]: info: MYVOLUMIO Environment detected
Oct 13 14:21:57 iqaudio volumio[952]: info: Plugin folders cleanup
Oct 13 14:21:57 iqaudio volumio[952]: info: Scanning into folder /volumio/app/plugins/
Oct 13 14:21:57 iqaudio volumio[952]: info: Scanning category audio_interface
Oct 13 14:21:57 iqaudio volumio[952]: info: Scanning category miscellanea
Oct 13 14:21:57 iqaudio volumio[952]: info: Scanning category music_service
Oct 13 14:21:57 iqaudio volumio[952]: info: Scanning category plugins.json
Oct 13 14:21:57 iqaudio volumio[952]: info: Scanning category system_controller
Oct 13 14:21:57 iqaudio volumio[952]: info: Scanning category user_interface
Oct 13 14:21:57 iqaudio volumio[952]: info: Scanning into folder /data/plugins/
Oct 13 14:21:57 iqaudio volumio[952]: info: Scanning category miscellanea
Oct 13 14:21:57 iqaudio volumio[952]: info: Scanning category system_controller
Oct 13 14:21:57 iqaudio volumio[952]: info: Plugin folders cleanup completed
Oct 13 14:21:57 iqaudio volumio[952]: info: -------------------------------------------
Oct 13 14:21:57 iqaudio volumio[952]: info: -----      Core plugins startup        ----
Oct 13 14:21:57 iqaudio volumio[952]: info: -------------------------------------------
Oct 13 14:21:57 iqaudio volumio[952]: info: Loading plugins from folder /volumio/app/plugins/
Oct 13 14:21:57 iqaudio volumio[952]: info: Adding plugin upnp to MyMusic Plugins
Oct 13 14:21:57 iqaudio volumio[952]: info: Adding plugin airplay_emulation to MyMusic Plugins
Oct 13 14:21:57 iqaudio volumio[952]: info: Adding plugin upnp_browser to MyMusic Plugins
Oct 13 14:21:57 iqaudio volumio[952]: info: Loading plugins from folder /data/plugins/
Oct 13 14:21:57 iqaudio volumio[952]: info: Loading plugin "system"...
Oct 13 14:21:57 iqaudio volumio[952]: info: Loading plugin "appearance"...
Oct 13 14:21:59 iqaudio volumio[952]: info: Loading plugin "network"...
Oct 13 14:21:59 iqaudio volumio[952]: info: Refreshing Cached IP Addresses
Oct 13 14:21:59 iqaudio sudo[971]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Oct 13 14:21:59 iqaudio sudo[971]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:59 iqaudio sudo[971]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:59 iqaudio volumio[952]: info: Loading plugin "services"...
Oct 13 14:21:59 iqaudio sudo[973]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Oct 13 14:21:59 iqaudio sudo[973]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:59 iqaudio volumio[952]: info: Loading plugin "alsa_controller"...
Oct 13 14:21:59 iqaudio sudo[973]: pam_unix(sudo:session): session closed for user root
Oct 13 14:21:59 iqaudio sudo[976]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Oct 13 14:21:59 iqaudio sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:21:59 iqaudio systemd[1]: systemd-hostnamed.service: Succeeded.
Oct 13 14:21:59 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 13 14:21:59 iqaudio volumio[952]: info: Loading plugin "wizard"...
Oct 13 14:21:59 iqaudio volumio[952]: info: Loading plugin "volumio_command_line_client"...
Oct 13 14:21:59 iqaudio volumio[952]: info: Plugin upnp is not enabled
Oct 13 14:21:59 iqaudio volumio[952]: info: Loading plugin "my_music"...
Oct 13 14:21:59 iqaudio volumio[952]: info: Loading plugin "mpd"...
Oct 13 14:21:59 iqaudio volumio-remote-updater[528]: [2023-10-13 14:21:59] [connect] Successful connection
Oct 13 14:22:00 iqaudio sudo[976]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:00 iqaudio volumio[952]: info: Creating MPD Configuration file
Oct 13 14:22:00 iqaudio volumio[952]: info: Plugin upnp_browser is not enabled
Oct 13 14:22:00 iqaudio volumio[952]: info: Loading plugin "networkfs"...
Oct 13 14:22:00 iqaudio sudo[986]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 13 14:22:00 iqaudio sudo[986]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:00 iqaudio sudo[986]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:00 iqaudio sudo[988]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 13 14:22:00 iqaudio sudo[988]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:00 iqaudio systemd[1]: Stopping Music Player Daemon...
Oct 13 14:22:00 iqaudio systemd[1]: mpd.service: Succeeded.
Oct 13 14:22:00 iqaudio systemd[1]: Stopped Music Player Daemon.
Oct 13 14:22:00 iqaudio systemd[1]: Starting Music Player Daemon...
Oct 13 14:22:00 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:22:00 iqaudio volumio[952]: info: Starting Udev Watcher for removable devices
Oct 13 14:22:01 iqaudio volumio[952]: info: Ignoring mount for partition: boot
Oct 13 14:22:01 iqaudio volumio[952]: info: Ignoring mount for partition: volumio
Oct 13 14:22:01 iqaudio volumio[952]: info: Ignoring mount for partition: volumio_data
Oct 13 14:22:01 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 13 14:22:01 iqaudio volumio[952]: info: Loading plugin "alarm-clock"...
Oct 13 14:22:01 iqaudio volumio[952]: info: Loading plugin "airplay_emulation"...
Oct 13 14:22:01 iqaudio volumio[952]: info: Starting Shairport Sync
Oct 13 14:22:01 iqaudio volumio[952]: info: Loading plugin "last_100"...
Oct 13 14:22:01 iqaudio volumio[952]: info: Loading plugin "webradio"...
Oct 13 14:22:01 iqaudio volumio[952]: info: Loading plugin "i2s_dacs"...
Oct 13 14:22:01 iqaudio volumio[952]: info: Loading plugin "volumiodiscovery"...
Oct 13 14:22:01 iqaudio volumio[952]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Oct 13 14:22:01 iqaudio volumio[952]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 13 14:22:01 iqaudio volumio[952]: *** WARNING *** For more information see 
Oct 13 14:22:01 iqaudio node[952]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Oct 13 14:22:01 iqaudio volumio[952]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Oct 13 14:22:01 iqaudio volumio[952]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 13 14:22:01 iqaudio volumio[952]: *** WARNING *** For more information see 
Oct 13 14:22:01 iqaudio node[952]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 13 14:22:01 iqaudio node[952]: *** WARNING *** For more information see 
Oct 13 14:22:01 iqaudio node[952]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Oct 13 14:22:01 iqaudio node[952]: *** WARNING *** Please fix your application to use the native API of Avahi!
Oct 13 14:22:01 iqaudio node[952]: *** WARNING *** For more information see 
Oct 13 14:22:01 iqaudio volumio[952]: info: Applying required configuration parameters for plugin volumiodiscovery
Oct 13 14:22:01 iqaudio volumio[952]: info: Discovery: Started advertising with name: iqaudio
Oct 13 14:22:01 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Oct 13 14:22:01 iqaudio volumio[952]: info: Loading plugin "autostart"...
Oct 13 14:22:01 iqaudio mpd[993]: Oct 13 14:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 13 14:22:01 iqaudio mpd[993]: Oct 13 14:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Oct 13 14:22:01 iqaudio mpd[993]: Oct 13 14:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Oct 13 14:22:01 iqaudio systemd[1]: Started Music Player Daemon.
Oct 13 14:22:01 iqaudio sudo[988]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:02 iqaudio volumio[952]: info: Applying required configuration parameters for plugin autostart
Oct 13 14:22:02 iqaudio volumio[952]: info: AutoStart - onVolumioStart -  read config.json
Oct 13 14:22:02 iqaudio volumio[952]: info: Loading plugin "outputs"...
Oct 13 14:22:02 iqaudio volumio[952]: info: Loading plugin "albumart"...
Oct 13 14:22:02 iqaudio volumio[952]: info: Plugin example_plugin is not enabled
Oct 13 14:22:02 iqaudio volumio[952]: info: Loading plugin "inputs"...
Oct 13 14:22:02 iqaudio volumio[952]: info: Loading plugin "updater_comm"...
Oct 13 14:22:02 iqaudio volumio[952]: info: Plugin mpdemulation is not enabled
Oct 13 14:22:02 iqaudio volumio[952]: info: Loading plugin "rest_api"...
Oct 13 14:22:02 iqaudio volumio[952]: info: Loading plugin "websocket"...
Oct 13 14:22:02 iqaudio volumio[952]: info: Loading plugin "nanosound"...
Oct 13 14:22:02 iqaudio volumio[952]: Forking 3 albumart workers
Oct 13 14:22:03 iqaudio volumio[952]: info: Starting NanoSound Plug-in
Oct 13 14:22:03 iqaudio volumio[952]: info: Applying required configuration parameters for plugin nanosound
Oct 13 14:22:03 iqaudio volumio[952]: info: Starting NanoSound
Oct 13 14:22:03 iqaudio volumio[952]: info: Loading i18n strings for locale de
Oct 13 14:22:03 iqaudio volumio[952]: Updating browse sources language
Oct 13 14:22:03 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 13 14:22:03 iqaudio volumio[952]: Starting albumart workers
Oct 13 14:22:03 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 13 14:22:03 iqaudio volumio[952]: info: CoreCommandRouter::initPlayerControls
Oct 13 14:22:03 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:03 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:03 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:03 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:03 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:03 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:03 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:03 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:03 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 13 14:22:03 iqaudio volumio[952]: Express server listening on port 3000
Oct 13 14:22:03 iqaudio volumio[952]:  [Metrics]  WebUI:  8s 584.82ms 
Oct 13 14:22:03 iqaudio volumio[952]: Starting albumart workers
Oct 13 14:22:03 iqaudio volumio[952]: Starting albumart workers
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreStateMachine::resetVolumioState
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreStateMachine::getcurrentVolume
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::volumioRetrievevolume
Oct 13 14:22:04 iqaudio volumio[952]: info: MPD Permissions set
Oct 13 14:22:04 iqaudio volumio[952]: verbose: New Socket.io Connection to 193.193.26.185 from 193.193.26.190 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/118.0 Total Clients: 1
Oct 13 14:22:04 iqaudio volumio[952]: verbose: New Socket.io Connection to 193.193.26.185:3000 from 193.193.26.151 UA: node-XMLHttpRequest Total Clients: 2
Oct 13 14:22:04 iqaudio volumio-remote-updater[528]: [2023-10-13 14:22:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1697199719 101
Oct 13 14:22:04 iqaudio volumio[952]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3
Oct 13 14:22:04 iqaudio volumio[952]: verbose: New Socket.io Connection to 193.193.26.185 from 193.193.26.190 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/118.0 Total Clients: 4
Oct 13 14:22:04 iqaudio volumio[952]: info: MPD running with PID993
Oct 13 14:22:04 iqaudio volumio[952]:  ,establishing connection
Oct 13 14:22:04 iqaudio volumio[952]: error: MPD error: The expression evaluated to a falsy value:
Oct 13 14:22:04 iqaudio volumio[952]:   assert.ok(self.idling)
Oct 13 14:22:04 iqaudio volumio[952]: error: The expression evaluated to a falsy value:
Oct 13 14:22:04 iqaudio volumio[952]:   assert.ok(self.idling)
Oct 13 14:22:04 iqaudio volumio[952]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:04 iqaudio volumio[952]: info: Reloading queue from file
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:04 iqaudio volumio[952]: info: Executing start script for DAC IQaudIO DAC Plus
Oct 13 14:22:04 iqaudio volumio[952]: info: VolumeController:: Volume=83 Mute =false
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreStateMachine::pushState
Oct 13 14:22:04 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::volumioPushState
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreStateMachine::updateTrackBlock
Oct 13 14:22:04 iqaudio volumio[952]: info: CorePlayQueue::getTrackBlock
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::volumioRetrievevolume
Oct 13 14:22:04 iqaudio volumio[952]: info: Setting Device type: Raspberry PI
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreStateMachine::setRepeat true single undefined
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreStateMachine::pushState
Oct 13 14:22:04 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::volumioPushState
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreStateMachine::setRandom null
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreStateMachine::pushState
Oct 13 14:22:04 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::volumioPushState
Oct 13 14:22:04 iqaudio volumio[952]: error: updateQueue error: null
Oct 13 14:22:04 iqaudio volumio[952]: info: DAC script executed
Oct 13 14:22:04 iqaudio volumio[952]: info: Completed loading Core Plugins
Oct 13 14:22:04 iqaudio volumio[952]: info: Preparing to generate the ALSA configuration file
Oct 13 14:22:04 iqaudio volumio[952]: info: Discovery: adding 356e4c09-6e58-4b03-ad89-f23707632c94
Oct 13 14:22:04 iqaudio volumio[952]: info: Discovery: Found device iqaudio
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::volumioGetState
Oct 13 14:22:04 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:04 iqaudio volumio[952]: info: VolumeController:: Volume=83 Mute =false
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreStateMachine::pushState
Oct 13 14:22:04 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:04 iqaudio volumio[952]: info: CoreCommandRouter::volumioPushState
Oct 13 14:22:04 iqaudio volumio[952]: info: Discovery: adding 389b7132-d629-452a-9428-330acda8e3b8
Oct 13 14:22:04 iqaudio volumio[952]: info: Discovery: Found device Sabreberry
Oct 13 14:22:04 iqaudio volumio[952]: info: Discovery: Connecting to remote: 193.193.26.151
Oct 13 14:22:05 iqaudio volumio[952]: info: Asound.conf file written
Oct 13 14:22:05 iqaudio sudo[1076]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Oct 13 14:22:05 iqaudio sudo[1076]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:05 iqaudio sudo[1076]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:05 iqaudio volumio[952]: info: Output device has changed, restarting MPD
Oct 13 14:22:05 iqaudio volumio[952]: info: Output device has changed, restarting Shairport Sync
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:05 iqaudio sudo[1081]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 13 14:22:05 iqaudio sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:05 iqaudio sudo[1081]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:05 iqaudio sudo[1083]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 13 14:22:05 iqaudio sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:05 iqaudio systemd[1]: Stopping Music Player Daemon...
Oct 13 14:22:05 iqaudio volumio[952]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 13 14:22:05 iqaudio volumio[952]: info: ___________ START PLUGINS ___________
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:05 iqaudio systemd[1]: mpd.service: Succeeded.
Oct 13 14:22:05 iqaudio systemd[1]: Stopped Music Player Daemon.
Oct 13 14:22:05 iqaudio systemd[1]: Starting Music Player Daemon...
Oct 13 14:22:05 iqaudio volumio[952]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:05 iqaudio volumio[952]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 13 14:22:05 iqaudio volumio[952]: info: [1697199725346] CoreMusicLibrary::Adding element Last_100
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Oct 13 14:22:05 iqaudio volumio[952]: info: [1697199725353] CoreMusicLibrary::Adding element Webradio
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 13 14:22:05 iqaudio volumio[952]: info: AutoStart - onStart
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:05 iqaudio volumio[952]: info: NanoSound - Creating button triggers
Oct 13 14:22:05 iqaudio volumio[952]: info: NanoSound - Creating button triggers for PiSwitchCap
Oct 13 14:22:05 iqaudio sudo[1098]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop nanosound_lirc
Oct 13 14:22:05 iqaudio sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:05 iqaudio volumio[952]: info: Volumio Calling Home
Oct 13 14:22:05 iqaudio sudo[1100]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start nanosound_oled
Oct 13 14:22:05 iqaudio sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:05 iqaudio systemd[1105]: nanosound_oled.service: Changing to the requested working directory failed: No such file or directory
Oct 13 14:22:05 iqaudio systemd[1105]: nanosound_oled.service: Failed at step CHDIR spawning /home/volumio/nanosound_oled/nanodac_oled.py: No such file or directory
Oct 13 14:22:05 iqaudio systemd[1]: Started NanoSound OLED Diplay Service.
Oct 13 14:22:05 iqaudio systemd[1]: nanosound_oled.service: Main process exited, code=exited, status=200/CHDIR
Oct 13 14:22:05 iqaudio systemd[1]: nanosound_oled.service: Failed with result 'exit-code'.
Oct 13 14:22:05 iqaudio sudo[1100]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:05 iqaudio sudo[1098]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:05 iqaudio volumio[952]: info: NanoSound OLED daemon started
Oct 13 14:22:05 iqaudio volumio[952]: info: Completed starting Core Plugins
Oct 13 14:22:05 iqaudio volumio[952]: info: -------------------------------------------
Oct 13 14:22:05 iqaudio volumio[952]: info: -----    MyVolumio plugins startup     ----
Oct 13 14:22:05 iqaudio volumio[952]: info: -------------------------------------------
Oct 13 14:22:05 iqaudio volumio[952]: info: [MyVolumio PluginManager] Fetching plans data....
Oct 13 14:22:05 iqaudio volumio[952]: info: MPD Permissions set
Oct 13 14:22:05 iqaudio volumio[952]: info: Discovery: Connected to remote: 193.193.26.151
Oct 13 14:22:05 iqaudio volumio[952]: info: Volumio called home
Oct 13 14:22:05 iqaudio volumio[952]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:05 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:05 iqaudio volumio[952]: info: Starting Shairport Sync
Oct 13 14:22:05 iqaudio volumio[952]: info: Starting Shairport Sync
Oct 13 14:22:05 iqaudio volumio[952]: info: Starting Shairport Sync
Oct 13 14:22:05 iqaudio sudo[1113]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 13 14:22:05 iqaudio sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:05 iqaudio sudo[1115]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 13 14:22:05 iqaudio sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:05 iqaudio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 13 14:22:05 iqaudio systemd[1]: shairport-sync.service: Succeeded.
Oct 13 14:22:05 iqaudio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 13 14:22:06 iqaudio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 13 14:22:06 iqaudio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 13 14:22:06 iqaudio sudo[1113]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:06 iqaudio sudo[1119]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 13 14:22:06 iqaudio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Oct 13 14:22:06 iqaudio systemd[1]: shairport-sync.service: Succeeded.
Oct 13 14:22:06 iqaudio sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:06 iqaudio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 13 14:22:06 iqaudio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 13 14:22:06 iqaudio sudo[1115]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:06 iqaudio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 13 14:22:06 iqaudio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Oct 13 14:22:06 iqaudio systemd[1]: shairport-sync.service: Succeeded.
Oct 13 14:22:06 iqaudio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 13 14:22:06 iqaudio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 13 14:22:06 iqaudio sudo[1119]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:06 iqaudio volumio[952]: info: Shairport-Sync Started
Oct 13 14:22:06 iqaudio volumio[952]: Error adding Membership: Error: addMembership EINVAL
Oct 13 14:22:06 iqaudio volumio[952]: info: Shairport-Sync Started
Oct 13 14:22:06 iqaudio volumio[952]: info: Shairport-Sync Started
Oct 13 14:22:06 iqaudio volumio[952]: info: CoreCommandRouter::volumioGetState
Oct 13 14:22:06 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:06 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:22:06 iqaudio mpd[1107]: Oct 13 14:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 13 14:22:06 iqaudio mpd[1107]: Oct 13 14:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Oct 13 14:22:06 iqaudio mpd[1107]: Oct 13 14:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Oct 13 14:22:06 iqaudio systemd[1]: Started Music Player Daemon.
Oct 13 14:22:06 iqaudio sudo[1083]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:06 iqaudio volumio[952]: error: updateQueue error: null
Oct 13 14:22:06 iqaudio sudo[1129]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start nanosound_lirc
Oct 13 14:22:06 iqaudio sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:06 iqaudio systemd[1]: Started NanoSound LIRC Service.
Oct 13 14:22:06 iqaudio systemd[1131]: nanosound_lirc.service: Failed to execute command: No such file or directory
Oct 13 14:22:06 iqaudio systemd[1131]: nanosound_lirc.service: Failed at step EXEC spawning /home/volumio/nanosound_oled/nanodac_lirc.py: No such file or directory
Oct 13 14:22:06 iqaudio systemd[1]: nanosound_lirc.service: Main process exited, code=exited, status=203/EXEC
Oct 13 14:22:06 iqaudio systemd[1]: nanosound_lirc.service: Failed with result 'exit-code'.
Oct 13 14:22:06 iqaudio sudo[1129]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:06 iqaudio volumio[952]: info: NanoSound lirc correctly started
Oct 13 14:22:07 iqaudio volumio[952]: verbose: New Socket.io Connection to 193.193.26.185:3000 from 193.193.26.151 UA: node-XMLHttpRequest Total Clients: 7
Oct 13 14:22:07 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreCommandRouter::volumioGetState
Oct 13 14:22:08 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:08 iqaudio volumio[952]: verbose: New Socket.io Connection to 193.193.26.185 from 193.193.26.190 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/118.0 Total Clients: 8
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreCommandRouter::volumioGetVisibleSources
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreCommandRouter::volumioGetState
Oct 13 14:22:08 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreCommandRouter::volumioGetState
Oct 13 14:22:08 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Oct 13 14:22:08 iqaudio volumio[952]: info: Received Get System Info
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Oct 13 14:22:08 iqaudio volumio[952]: info: Discovery: Getting this device information
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreCommandRouter::volumioGetState
Oct 13 14:22:08 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreCommandRouter::volumioGetState
Oct 13 14:22:08 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:08 iqaudio volumio[952]: info: Listing playlists
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreCommandRouter::volumioGetQueue
Oct 13 14:22:08 iqaudio volumio[952]: info: CoreStateMachine::getQueue
Oct 13 14:22:08 iqaudio volumio[952]: info: CorePlayQueue::getQueue
Oct 13 14:22:09 iqaudio volumio[952]: error: Failed LSINFO: Error:  [50@0] {lsinfo} No such directory
Oct 13 14:22:11 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Oct 13 14:22:14 iqaudio volumio[952]: info: Adding plugin bluetooth to MyMusic Plugins
Oct 13 14:22:14 iqaudio volumio[952]: info: Adding plugin multiroom to MyMusic Plugins
Oct 13 14:22:14 iqaudio volumio[952]: info: Adding plugin metavolumio to MyMusic Plugins
Oct 13 14:22:14 iqaudio volumio[952]: info: Adding plugin cd_controller to MyMusic Plugins
Oct 13 14:22:14 iqaudio volumio[952]: info: Adding plugin smart_inputs to MyMusic Plugins
Oct 13 14:22:14 iqaudio volumio[952]: info: Adding plugin tidalconnect to MyMusic Plugins
Oct 13 14:22:14 iqaudio volumio[952]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Oct 13 14:22:15 iqaudio volumio[952]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Oct 13 14:22:15 iqaudio volumio[952]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Oct 13 14:22:15 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:15 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:15 iqaudio volumio[952]: info: Starting MyVolumio Remote Streaming Endpoints
Oct 13 14:22:15 iqaudio volumio[952]: info: MyVolumio login type: Token
Oct 13 14:22:15 iqaudio volumio[952]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Oct 13 14:22:15 iqaudio volumio[952]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Oct 13 14:22:17 iqaudio volumio[952]: info: Starting Streaming Service Transparent Proxy
Oct 13 14:22:17 iqaudio volumio[952]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Oct 13 14:22:17 iqaudio volumio[952]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Oct 13 14:22:17 iqaudio volumio[952]: info: Streaming services startup
Oct 13 14:22:17 iqaudio volumio[952]: info: Starting Streaming Daemon
Oct 13 14:22:17 iqaudio sudo[1139]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Oct 13 14:22:17 iqaudio sudo[1139]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:17 iqaudio volumio[952]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Oct 13 14:22:17 iqaudio sudo[1139]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:17 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:22:17 iqaudio volumio[952]: error: Cannot start Volumio Streaming Daemon
Oct 13 14:22:17 iqaudio volumio[952]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Oct 13 14:22:17 iqaudio volumio[952]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Oct 13 14:22:17 iqaudio volumio[952]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Oct 13 14:22:17 iqaudio volumio[952]: info: MYVOLUMIO: Adding device
Oct 13 14:22:17 iqaudio volumio[952]: info: MYVOLUMIO: Evaluating Server
Oct 13 14:22:18 iqaudio volumio[952]: info: MyVolumio Plan changed: premium
Oct 13 14:22:18 iqaudio volumio[952]: info: [MyVolumio PluginManager] Subscribed plan changed to premium
Oct 13 14:22:18 iqaudio volumio[952]: info: Removing browser output: myVolumio user plan is not superstar
Oct 13 14:22:18 iqaudio volumio[952]: info: Removing audio output:
Oct 13 14:22:18 iqaudio volumio[952]: info: MYVOLUMIO: Adding device
Oct 13 14:22:18 iqaudio volumio[952]: info: MYVOLUMIO: Evaluating Server
Oct 13 14:22:18 iqaudio volumio[952]: info: Remote config written successfully
Oct 13 14:22:18 iqaudio volumio[952]: info: Starting Tunnel 1
Oct 13 14:22:18 iqaudio volumio[952]: info: Starting Tunnel Connection Checker
Oct 13 14:22:18 iqaudio volumio[952]: info: Completed starting MyVolumio Plugin
Oct 13 14:22:18 iqaudio volumio[952]: STREAMING PROXY: Starting server on port 3245
Oct 13 14:22:18 iqaudio volumio[952]: Node JS runtime: 14
Oct 13 14:22:18 iqaudio volumio[952]: info: MYVolumio Device enabled
Oct 13 14:22:18 iqaudio volumio[952]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins...
Oct 13 14:22:18 iqaudio volumio[952]: info: MyVolumio status changed
Oct 13 14:22:18 iqaudio volumio[952]: info: Streaming services startup
Oct 13 14:22:18 iqaudio volumio[952]: info: Starting Streaming Daemon
Oct 13 14:22:18 iqaudio sudo[1177]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Oct 13 14:22:18 iqaudio sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:18 iqaudio sudo[1177]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:18 iqaudio volumio[952]: info: Setting Geolocation for MyVolumio to eu6
Oct 13 14:22:18 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:18 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:18 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:18 iqaudio volumio[952]: error: Cannot start Volumio Streaming Daemon
Oct 13 14:22:18 iqaudio volumio[952]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Oct 13 14:22:18 iqaudio volumio[952]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Oct 13 14:22:18 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid
Oct 13 14:22:18 iqaudio volumio[952]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"...
Oct 13 14:22:19 iqaudio volumio[952]: info: [1697199739189] Starting BluetoothController
Oct 13 14:22:19 iqaudio volumio[952]: info: [MyVolumio PluginManager] Loading plugin "multiroom"...
Oct 13 14:22:21 iqaudio volumio[952]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom
Oct 13 14:22:21 iqaudio sudo[1182]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast
Oct 13 14:22:21 iqaudio sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:21 iqaudio sudo[1182]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:21 iqaudio sudo[1184]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Oct 13 14:22:21 iqaudio sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:21 iqaudio sudo[1184]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:21 iqaudio volumio[952]: info: MRS: MultiRoom plugin initialized
Oct 13 14:22:21 iqaudio volumio[952]: info: MRS: STOPPING SNAPCLIENT
Oct 13 14:22:21 iqaudio volumio[952]: info: MRS: Snap server stop
Oct 13 14:22:21 iqaudio sudo[1202]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Oct 13 14:22:21 iqaudio sudo[1202]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:21 iqaudio volumio[952]: info: MRS: STOPPING volumioStreaming
Oct 13 14:22:21 iqaudio sudo[1205]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Oct 13 14:22:21 iqaudio sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:21 iqaudio sudo[1202]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:21 iqaudio sudo[1207]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Oct 13 14:22:21 iqaudio volumio[952]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"...
Oct 13 14:22:21 iqaudio sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:21 iqaudio sudo[1211]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Oct 13 14:22:21 iqaudio sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:21 iqaudio sudo[1211]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:21 iqaudio sudo[1205]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:21 iqaudio volumio[952]: info: [MyVolumio PluginManager] Loading plugin "manifestui"...
Oct 13 14:22:21 iqaudio sudo[1207]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:21 iqaudio volumio[952]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"...
Oct 13 14:22:21 iqaudio volumio[952]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"...
Oct 13 14:22:21 iqaudio volumio[952]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"...
Oct 13 14:22:21 iqaudio volumio[952]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio
Oct 13 14:22:21 iqaudio volumio[952]: info: [MyVolumio PluginManager] Loading plugin "tidal"...
Oct 13 14:22:21 iqaudio volumio[952]: info: [MyVolumio PluginManager] Loading plugin "qobuz"...
Oct 13 14:22:21 iqaudio volumio[952]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"...
Oct 13 14:22:21 iqaudio volumio[952]: info: Preparing to generate the ALSA configuration file
Oct 13 14:22:21 iqaudio volumio[952]: info: Updating MyVolumio device info
Oct 13 14:22:21 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:21 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:21 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:21 iqaudio volumio[952]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Oct 13 14:22:21 iqaudio volumio[952]: info: Reading ALSA contributions from plugins.
Oct 13 14:22:21 iqaudio volumio[952]: info: Setting Geolocation for MyVolumio to eu6
Oct 13 14:22:21 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:21 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:21 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:21 iqaudio volumio[952]: info: MRS: Removed streaming files
Oct 13 14:22:21 iqaudio volumio[952]: info: MRS: volumioStreaming STOPPED
Oct 13 14:22:21 iqaudio volumio[952]: info: MRS: SNAPSERVER STOPPED
Oct 13 14:22:21 iqaudio volumio[952]: info: MRS: SNAPCLIENT STOPPED
Oct 13 14:22:21 iqaudio volumio[952]: info: Snap server and client installed
Oct 13 14:22:21 iqaudio volumio[952]: info: Asound.conf file written
Oct 13 14:22:21 iqaudio sudo[1214]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Oct 13 14:22:21 iqaudio sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:21 iqaudio sudo[1214]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:21 iqaudio volumio[952]: info: Output device has changed, restarting MPD
Oct 13 14:22:21 iqaudio sudo[1219]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Oct 13 14:22:21 iqaudio volumio[952]: info: Output device has changed, restarting Shairport Sync
Oct 13 14:22:21 iqaudio sudo[1219]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:21 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:21 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:21 iqaudio sudo[1219]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:21 iqaudio sudo[1222]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Oct 13 14:22:21 iqaudio sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:21 iqaudio systemd[1]: Stopping Music Player Daemon...
Oct 13 14:22:21 iqaudio volumio[952]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Oct 13 14:22:21 iqaudio volumio[952]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Oct 13 14:22:21 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:21 iqaudio systemd[1]: mpd.service: Succeeded.
Oct 13 14:22:22 iqaudio systemd[1]: Stopped Music Player Daemon.
Oct 13 14:22:22 iqaudio systemd[1]: Starting Music Player Daemon...
Oct 13 14:22:22 iqaudio volumio[952]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth
Oct 13 14:22:22 iqaudio volumio[952]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting
Oct 13 14:22:22 iqaudio volumio[952]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Oct 13 14:22:22 iqaudio volumio[952]: info: MRS: Pushing multiroomSync output for this device
Oct 13 14:22:22 iqaudio volumio[952]: info: MRS: Pushing multiroomSync output
Oct 13 14:22:22 iqaudio volumio[952]: info: Adding audio output:
Oct 13 14:22:22 iqaudio volumio[952]: info: Adding audio output:
Oct 13 14:22:22 iqaudio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2
Oct 13 14:22:22 iqaudio volumio[952]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio
Oct 13 14:22:22 iqaudio volumio[952]: info: [MyVolumio PluginManager] Plugin metavolumio disabled by user. Not starting
Oct 13 14:22:22 iqaudio volumio[952]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui
Oct 13 14:22:22 iqaudio volumio[952]: info: Adding Manifest REST API Endpoints
Oct 13 14:22:22 iqaudio volumio[952]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui
Oct 13 14:22:22 iqaudio volumio[952]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller
Oct 13 14:22:22 iqaudio volumio[952]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting
Oct 13 14:22:22 iqaudio volumio[952]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs
Oct 13 14:22:22 iqaudio volumio[952]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting
Oct 13 14:22:22 iqaudio volumio[952]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio
Oct 13 14:22:22 iqaudio volumio[952]: error: Hi Res Audio Failed Login: Missing Login Data
Oct 13 14:22:22 iqaudio volumio[952]: info: Adding HIGHRESAUDIO REST API Endpoints
Oct 13 14:22:22 iqaudio volumio[952]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Oct 13 14:22:22 iqaudio volumio[952]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal
Oct 13 14:22:22 iqaudio volumio[952]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz
Oct 13 14:22:22 iqaudio volumio[952]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect
Oct 13 14:22:22 iqaudio volumio[952]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting
Oct 13 14:22:22 iqaudio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2
Oct 13 14:22:22 iqaudio volumio[952]: info: Stopping AccessToken refresher cron
Oct 13 14:22:22 iqaudio volumio[952]: info: AccessToken refresher cron started
Oct 13 14:22:22 iqaudio volumio[952]: info: Stopping AccessToken refresher cron for QOBUZ
Oct 13 14:22:22 iqaudio volumio[952]: info: AccessToken refresher cron started for QOBUZ
Oct 13 14:22:22 iqaudio volumio[952]: info: Adding TIDAL REST API Endpoints
Oct 13 14:22:22 iqaudio volumio[952]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal
Oct 13 14:22:22 iqaudio volumio[952]: info: Adding QOBUZ REST API Endpoints
Oct 13 14:22:22 iqaudio volumio[952]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz
Oct 13 14:22:22 iqaudio volumio[952]: info: Successfully Added MyVolumio device
Oct 13 14:22:22 iqaudio volumio[952]: info: MPD Permissions set
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: BOOT COMPLETED
Oct 13 14:22:22 iqaudio volumio[952]:  [Metrics]  CommandRouter:  25s 983.87ms 
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::volumiosetStartupVolume
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::Close All Modals sent
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::Close All Modals sent
Oct 13 14:22:22 iqaudio volumio[952]: info: MRS: Adding multiroomSync output
Oct 13 14:22:22 iqaudio volumio[952]: info: Adding audio output:
Oct 13 14:22:22 iqaudio volumio[952]: info: MRS: Pushing multiroomSync output
Oct 13 14:22:22 iqaudio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2
Oct 13 14:22:22 iqaudio volumio[952]: info: Starting Shairport Sync
Oct 13 14:22:22 iqaudio sudo[1242]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Oct 13 14:22:22 iqaudio sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:22 iqaudio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Oct 13 14:22:22 iqaudio systemd[1]: shairport-sync.service: Succeeded.
Oct 13 14:22:22 iqaudio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Oct 13 14:22:22 iqaudio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Oct 13 14:22:22 iqaudio sudo[1242]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:22 iqaudio volumio[952]: info: Shairport-Sync Started
Oct 13 14:22:22 iqaudio volumio[952]: info: Successfully Updated MyVolumio device
Oct 13 14:22:22 iqaudio volumio[952]: info: Updating MyVolumio device info
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:22 iqaudio volumio[952]: info: Successfully Added MyVolumio device
Oct 13 14:22:22 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:22:23 iqaudio volumio[952]: info: Successfully Updated MyVolumio device
Oct 13 14:22:23 iqaudio mpd[1234]: Oct 13 14:22 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Oct 13 14:22:23 iqaudio mpd[1234]: Oct 13 14:22 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Oct 13 14:22:23 iqaudio mpd[1234]: Oct 13 14:22 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Oct 13 14:22:23 iqaudio systemd[1]: Started Music Player Daemon.
Oct 13 14:22:23 iqaudio sudo[1222]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:23 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Oct 13 14:22:23 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Oct 13 14:22:23 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Oct 13 14:22:23 iqaudio volumio[952]: error: updateQueue error: null
Oct 13 14:22:23 iqaudio sudo[1254]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service
Oct 13 14:22:23 iqaudio sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:22:23 iqaudio systemd[1]: Started MyVolumio SSH Tunnel.
Oct 13 14:22:23 iqaudio sudo[1254]: pam_unix(sudo:session): session closed for user root
Oct 13 14:22:23 iqaudio volumio[952]: info: Remote SSH Started
Oct 13 14:22:23 iqaudio autossh[1257]: port set to 0, monitoring disabled
Oct 13 14:22:23 iqaudio autossh[1257]: starting ssh (count 1)
Oct 13 14:22:23 iqaudio autossh[1257]: ssh child pid is 1260
Oct 13 14:22:23 iqaudio volumiossh-tunnel[1256]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts.
Oct 13 14:22:23 iqaudio volumio[952]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9
Oct 13 14:22:23 iqaudio volumio[952]: info: CoreCommandRouter::volumioGetState
Oct 13 14:22:23 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:25 iqaudio volumio[952]: info: AutoStart - Plugin is starting
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreCommandRouter::volumioGetQueue
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreStateMachine::getQueue
Oct 13 14:22:25 iqaudio volumio[952]: info: CorePlayQueue::getQueue
Oct 13 14:22:25 iqaudio volumio[952]: info: AutoStart - start playing
Oct 13 14:22:25 iqaudio volumio[952]: info: AutoStart - start playing with no specific position
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreCommandRouter::volumioPlay
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreStateMachine::play index 0
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreStateMachine::stop
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreStateMachine::play index undefined
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 13 14:22:25 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreStateMachine::startPlaybackTimer
Oct 13 14:22:25 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:25 iqaudio volumio[952]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Soundcheck14/audiocheck.net_pink_192k_-3dBFS.wav
Oct 13 14:22:25 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand stop
Oct 13 14:22:25 iqaudio volumio[952]: info: sendMpdCommand stop took 5 milliseconds
Oct 13 14:22:25 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand clear
Oct 13 14:22:25 iqaudio volumio[952]: info:
Oct 13 14:22:25 iqaudio volumio[952]: ---------------------------- MPD announces system playlist update
Oct 13 14:22:25 iqaudio volumio[952]: info: Ignoring MPD Status Update
Oct 13 14:22:25 iqaudio volumio[952]: info: sendMpdCommand clear took 5 milliseconds
Oct 13 14:22:25 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Soundcheck14/audiocheck.net_pink_192k_-3dBFS.wav"
Oct 13 14:22:25 iqaudio volumio[952]: error: updateQueue error: null
Oct 13 14:22:25 iqaudio volumio[952]: info:
Oct 13 14:22:25 iqaudio volumio[952]: ---------------------------- MPD announces system playlist update
Oct 13 14:22:25 iqaudio volumio[952]: info: Ignoring MPD Status Update
Oct 13 14:22:25 iqaudio volumio[952]: info: ------------------------------ 9ms
Oct 13 14:22:25 iqaudio volumio[952]: info: sendMpdCommand add "INTERNAL/Soundcheck14/audiocheck.net_pink_192k_-3dBFS.wav" took 5 milliseconds
Oct 13 14:22:25 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand play
Oct 13 14:22:25 iqaudio volumio[952]: info: ------------------------------ 7ms
Oct 13 14:22:25 iqaudio volumio[952]: info: sendMpdCommand play took 3 milliseconds
Oct 13 14:22:25 iqaudio volumio[952]: info:
Oct 13 14:22:25 iqaudio volumio[952]: ---------------------------- MPD announces state update: player
Oct 13 14:22:25 iqaudio volumio[952]: info: ControllerMpd::getState
Oct 13 14:22:25 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand status
Oct 13 14:22:25 iqaudio volumio[952]: info:
Oct 13 14:22:25 iqaudio volumio[952]: ---------------------------- MPD announces state update: player
Oct 13 14:22:25 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand clearerror
Oct 13 14:22:25 iqaudio volumio[952]: info: sendMpdCommand status took 56 milliseconds
Oct 13 14:22:25 iqaudio volumio[952]: info: ControllerMpd::getState
Oct 13 14:22:25 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand status
Oct 13 14:22:25 iqaudio volumio[952]: verbose: ControllerMpd::parseState
Oct 13 14:22:25 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 13 14:22:25 iqaudio volumio[952]: info: sendMpdCommand clearerror took 30 milliseconds
Oct 13 14:22:25 iqaudio volumio[952]: info: sendMpdCommand status took 31 milliseconds
Oct 13 14:22:25 iqaudio volumio[952]: info: sendMpdCommand playlistinfo took 29 milliseconds
Oct 13 14:22:25 iqaudio volumio[952]: verbose: ControllerMpd::parseState
Oct 13 14:22:25 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 13 14:22:25 iqaudio volumio[952]: verbose: ControllerMpd::parseTrackInfo
Oct 13 14:22:25 iqaudio volumio[952]: info: ControllerMpd::pushState
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreCommandRouter::servicePushState
Oct 13 14:22:25 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:25 iqaudio volumio[952]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":30,"samplerate":"192 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"audiocheck.net_pink_192k_-3dBFS.wav","artist":null,"album":null,"uri":"INTERNAL/Soundcheck14/audiocheck.net_pink_192k_-3dBFS.wav","trackType":"wav"}
Oct 13 14:22:25 iqaudio volumio[952]: verbose: CURRENT POSITION 0
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreStateMachine::syncState   stateService pause
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreStateMachine::syncState   currentStatus stop
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreStateMachine::pushState
Oct 13 14:22:25 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreCommandRouter::volumioPushState
Oct 13 14:22:25 iqaudio volumio[952]: info: MRS: Pushing multiroomSync output update for this device
Oct 13 14:22:25 iqaudio volumio[952]: info: MRS: Pushing multiroomSync output
Oct 13 14:22:25 iqaudio volumio[952]: info: ------------------------------ 160ms
Oct 13 14:22:25 iqaudio volumio[952]: info: sendMpdCommand playlistinfo took 92 milliseconds
Oct 13 14:22:25 iqaudio volumio[952]: verbose: ControllerMpd::parseTrackInfo
Oct 13 14:22:25 iqaudio volumio[952]: info: ControllerMpd::pushState
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreCommandRouter::servicePushState
Oct 13 14:22:25 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:25 iqaudio volumio[952]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":30,"samplerate":"192 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"audiocheck.net_pink_192k_-3dBFS.wav","artist":null,"album":null,"uri":"INTERNAL/Soundcheck14/audiocheck.net_pink_192k_-3dBFS.wav","trackType":"wav"}
Oct 13 14:22:25 iqaudio volumio[952]: verbose: CURRENT POSITION 0
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreStateMachine::syncState   stateService pause
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreStateMachine::syncState   currentStatus stop
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreStateMachine::pushState
Oct 13 14:22:25 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:25 iqaudio volumio[952]: info: CoreCommandRouter::volumioPushState
Oct 13 14:22:25 iqaudio volumio[952]: info: MRS: Pushing multiroomSync output update for this device
Oct 13 14:22:25 iqaudio volumio[952]: info: MRS: Pushing multiroomSync output
Oct 13 14:22:25 iqaudio volumio[952]: info: ------------------------------ 153ms
Oct 13 14:22:27 iqaudio volumio[952]: info: MRS: Getting audio outputs on start
Oct 13 14:22:27 iqaudio volumio[952]: info: MRS: Requesting all other devices output
Oct 13 14:22:28 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:22:30 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Oct 13 14:22:30 iqaudio volumio[952]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10
Oct 13 14:22:30 iqaudio volumio[952]: info: CoreCommandRouter::volumioGetState
Oct 13 14:22:30 iqaudio volumio[952]: info: CorePlayQueue::getTrack 0
Oct 13 14:22:33 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:22:34 iqaudio ntpd[694]: Soliciting pool server 178.215.228.24
Oct 13 14:22:34 iqaudio ntpd[694]: Soliciting pool server 217.91.44.17
Oct 13 14:22:35 iqaudio ntpd[694]: Soliciting pool server 144.76.66.156
Oct 13 14:22:35 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 13 14:22:35 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 13 14:22:35 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Oct 13 14:22:35 iqaudio volumio-remote-updater[528]: No test mode
Oct 13 14:22:35 iqaudio volumio-remote-updater[528]: No alpha test mode
Oct 13 14:22:35 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Oct 13 14:22:35 iqaudio volumio[952]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
Fix translations not showing on some occasions
 
NEW ADDITIONS
Select sources available for Infinity Playback and SuperSearch
More info on USB devices in debug logs
Add Innomaker DACs
Add Orchard Audio DACs","title":"Update v3.546","updateavailable":true}
Oct 13 14:22:35 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Oct 13 14:22:35 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:35 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Oct 13 14:22:35 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Oct 13 14:22:35 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Oct 13 14:22:35 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Oct 13 14:22:35 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Oct 13 14:22:35 iqaudio volumio[952]: info: CoreCommandRouter::volumioGetBrowseSources
Oct 13 14:22:35 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Oct 13 14:22:35 iqaudio ntpd[694]: Soliciting pool server 144.76.139.8
Oct 13 14:22:35 iqaudio ntpd[694]: Soliciting pool server 217.197.91.176
Oct 13 14:22:35 iqaudio ntpd[694]: Soliciting pool server 185.120.22.14
Oct 13 14:22:36 iqaudio ntpd[694]: Soliciting pool server 85.215.93.134
Oct 13 14:22:36 iqaudio ntpd[694]: Soliciting pool server 78.46.60.40
Oct 13 14:22:36 iqaudio ntpd[694]: Soliciting pool server 129.70.132.34
Oct 13 14:22:36 iqaudio ntpd[694]: Soliciting pool server 193.158.22.13
Oct 13 14:22:37 iqaudio ntpd[694]: Soliciting pool server 85.214.83.151
Oct 13 14:22:37 iqaudio ntpd[694]: Soliciting pool server 144.76.59.37
Oct 13 14:22:37 iqaudio ntpd[694]: Soliciting pool server 78.46.204.247
Oct 13 14:22:37 iqaudio ntpd[694]: Soliciting pool server 144.76.0.164
Oct 13 14:22:38 iqaudio ntpd[694]: Soliciting pool server 129.70.132.33
Oct 13 14:22:39 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:22:39 iqaudio ntpd[694]: Soliciting pool server 31.209.85.242
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreCommandRouter::volumioNext
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreStateMachine::next
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreStateMachine::stop
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreStateMachine::play index undefined
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreStateMachine::setConsumeUpdateService undefined
Oct 13 14:22:40 iqaudio volumio[952]: info: CorePlayQueue::getTrack 1
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreStateMachine::startPlaybackTimer
Oct 13 14:22:40 iqaudio volumio[952]: info: CorePlayQueue::getTrack 1
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Soundcheck14/07- Carlos_Santana-Singing_Winds_Crying_Beasts-SMRP.flac
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand stop
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreStateMachine::updateTrackBlock
Oct 13 14:22:40 iqaudio volumio[952]: info: CorePlayQueue::getTrackBlock
Oct 13 14:22:40 iqaudio volumio[952]: info:
Oct 13 14:22:40 iqaudio volumio[952]: ---------------------------- MPD announces state update: player
Oct 13 14:22:40 iqaudio volumio[952]: info: sendMpdCommand stop took 4 milliseconds
Oct 13 14:22:40 iqaudio volumio[952]: info: ControllerMpd::getState
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand status
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand clear
Oct 13 14:22:40 iqaudio volumio[952]: info:
Oct 13 14:22:40 iqaudio volumio[952]: ---------------------------- MPD announces system playlist update
Oct 13 14:22:40 iqaudio volumio[952]: info: Ignoring MPD Status Update
Oct 13 14:22:40 iqaudio volumio[952]: info: sendMpdCommand status took 3 milliseconds
Oct 13 14:22:40 iqaudio volumio[952]: info: sendMpdCommand clear took 3 milliseconds
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::parseState
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Soundcheck14/07- Carlos_Santana-Singing_Winds_Crying_Beasts-SMRP.flac"
Oct 13 14:22:40 iqaudio volumio[952]: error: updateQueue error: null
Oct 13 14:22:40 iqaudio volumio[952]: info:
Oct 13 14:22:40 iqaudio volumio[952]: ---------------------------- MPD announces system playlist update
Oct 13 14:22:40 iqaudio volumio[952]: info: Ignoring MPD Status Update
Oct 13 14:22:40 iqaudio volumio[952]: info: ------------------------------ 7ms
Oct 13 14:22:40 iqaudio volumio[952]: info: sendMpdCommand playlistinfo took 4 milliseconds
Oct 13 14:22:40 iqaudio volumio[952]: info: sendMpdCommand add "INTERNAL/Soundcheck14/07- Carlos_Santana-Singing_Winds_Crying_Beasts-SMRP.flac" took 4 milliseconds
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::parseTrackInfo
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand play
Oct 13 14:22:40 iqaudio volumio[952]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined
Oct 13 14:22:40 iqaudio volumio[952]: info: ------------------------------ 14ms
Oct 13 14:22:40 iqaudio volumio[952]: info: ------------------------------ 8ms
Oct 13 14:22:40 iqaudio volumio[952]: info: sendMpdCommand play took 5 milliseconds
Oct 13 14:22:40 iqaudio volumio[952]: info:
Oct 13 14:22:40 iqaudio volumio[952]: ---------------------------- MPD announces state update: player
Oct 13 14:22:40 iqaudio volumio[952]: info: ControllerMpd::getState
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand status
Oct 13 14:22:40 iqaudio kernel: pcm512x 1-004c: No SCLK, using BCLK: -2
Oct 13 14:22:40 iqaudio volumio[952]: info:
Oct 13 14:22:40 iqaudio volumio[952]: ---------------------------- MPD announces state update: player
Oct 13 14:22:40 iqaudio volumio[952]: info: sendMpdCommand status took 24 milliseconds
Oct 13 14:22:40 iqaudio volumio[952]: info: ControllerMpd::getState
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand status
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::parseState
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 13 14:22:40 iqaudio volumio[952]: info: sendMpdCommand status took 3 milliseconds
Oct 13 14:22:40 iqaudio volumio[952]: info: sendMpdCommand playlistinfo took 3 milliseconds
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::parseState
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::parseTrackInfo
Oct 13 14:22:40 iqaudio volumio[952]: info: ControllerMpd::pushState
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreCommandRouter::servicePushState
Oct 13 14:22:40 iqaudio volumio[952]: info: CorePlayQueue::getTrack 1
Oct 13 14:22:40 iqaudio volumio[952]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":291,"samplerate":"176.4 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Singing Winds, Crying Beasts","artist":"Santana","album":"Abraxas","uri":"INTERNAL/Soundcheck14/07- Carlos_Santana-Singing_Winds_Crying_Beasts-SMRP.flac","trackType":"flac"}
Oct 13 14:22:40 iqaudio volumio[952]: verbose: CURRENT POSITION 1
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreStateMachine::syncState   stateService play
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreStateMachine::syncState   currentStatus stop
Oct 13 14:22:40 iqaudio volumio[952]: info: ------------------------------ 36ms
Oct 13 14:22:40 iqaudio volumio[952]: info: sendMpdCommand playlistinfo took 4 milliseconds
Oct 13 14:22:40 iqaudio volumio[952]: verbose: ControllerMpd::parseTrackInfo
Oct 13 14:22:40 iqaudio volumio[952]: info: ControllerMpd::pushState
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreCommandRouter::servicePushState
Oct 13 14:22:40 iqaudio volumio[952]: info: CorePlayQueue::getTrack 1
Oct 13 14:22:40 iqaudio volumio[952]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":291,"samplerate":"176.4 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Singing Winds, Crying Beasts","artist":"Santana","album":"Abraxas","uri":"INTERNAL/Soundcheck14/07- Carlos_Santana-Singing_Winds_Crying_Beasts-SMRP.flac","trackType":"flac"}
Oct 13 14:22:40 iqaudio volumio[952]: verbose: CURRENT POSITION 1
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreStateMachine::syncState   stateService play
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreStateMachine::syncState   currentStatus play
Oct 13 14:22:40 iqaudio volumio[952]: info: Received an update from plugin. extracting info from payload
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreStateMachine::pushState
Oct 13 14:22:40 iqaudio volumio[952]: info: CorePlayQueue::getTrack 1
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreCommandRouter::volumioPushState
Oct 13 14:22:40 iqaudio volumio[952]: info: MRS: Pushing multiroomSync output update for this device
Oct 13 14:22:40 iqaudio volumio[952]: info: MRS: Pushing multiroomSync output
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreStateMachine::pushState
Oct 13 14:22:40 iqaudio volumio[952]: info: CorePlayQueue::getTrack 1
Oct 13 14:22:40 iqaudio volumio[952]: info: CoreCommandRouter::volumioPushState
Oct 13 14:22:40 iqaudio volumio[952]: info: MRS: Pushing multiroomSync output update for this device
Oct 13 14:22:40 iqaudio volumio[952]: info: MRS: Pushing multiroomSync output
Oct 13 14:22:40 iqaudio volumio[952]: info: ------------------------------ 71ms
Oct 13 14:22:40 iqaudio ntpd[694]: Soliciting pool server 2a03:b0c0:3:d0::1d2a:1
Oct 13 14:33:52 iqaudio ntpd[694]: receive: Unexpected origin timestamp 0xe8d3b511.edafc0a6 does not match aorg 0000000000.00000000 from server@85.214.83.151 xmt 0xe8d3b7b0.96c063a0
Oct 13 14:33:52 iqaudio ntpd[694]: receive: Unexpected origin timestamp 0xe8d3b511.eda6e544 does not match aorg 0000000000.00000000 from server@129.70.132.33 xmt 0xe8d3b7b0.96e82bca
Oct 13 14:33:52 iqaudio volumio[952]: info: CorePlayQueue::getTrack 1
Oct 13 14:33:52 iqaudio volumio[952]: info: CorePlayQueue::getTrack 2
Oct 13 14:33:52 iqaudio volumio[952]: info: Prefetching next song
Oct 13 14:33:52 iqaudio volumio[952]: info: DOING PREFETCH IN MPD
Oct 13 14:33:52 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Soundcheck14/02- Deron_Johnson-It_s_Not_That_Easy-SMR.flac"
Oct 13 14:33:52 iqaudio ntpd[694]: receive: Unexpected origin timestamp 0xe8d3b511.edb68dd3 does not match aorg 0000000000.00000000 from server@85.215.93.134 xmt 0xe8d3b7b0.957c3f98
Oct 13 14:33:52 iqaudio volumio[952]: info: CoreStateMachine::startPlaybackTimer
Oct 13 14:33:52 iqaudio volumio[952]: info: CorePlayQueue::getTrack 2
Oct 13 14:33:52 iqaudio ntpd[694]: receive: Unexpected origin timestamp 0xe8d3b511.edb477a8 does not match aorg 0000000000.00000000 from server@129.70.132.34 xmt 0xe8d3b7b0.9702421e
Oct 13 14:33:52 iqaudio volumio[952]: info:
Oct 13 14:33:52 iqaudio volumio[952]: ---------------------------- MPD announces system playlist update
Oct 13 14:33:52 iqaudio volumio[952]: info: Ignoring MPD Status Update
Oct 13 14:33:52 iqaudio volumio[952]: info: sendMpdCommand add "INTERNAL/Soundcheck14/02- Deron_Johnson-It_s_Not_That_Easy-SMR.flac" took 3 milliseconds
Oct 13 14:33:52 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand consume 1
Oct 13 14:33:52 iqaudio volumio[952]: info:
Oct 13 14:33:52 iqaudio volumio[952]: ---------------------------- MPD announces state update: options
Oct 13 14:33:52 iqaudio volumio[952]: info: ------------------------------ 4ms
Oct 13 14:33:52 iqaudio volumio[952]: info: sendMpdCommand consume 1 took 3 milliseconds
Oct 13 14:33:52 iqaudio volumio[952]: info: ControllerMpd::getState
Oct 13 14:33:52 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand status
Oct 13 14:33:52 iqaudio volumio[952]: info: sendMpdCommand status took 1 milliseconds
Oct 13 14:33:52 iqaudio volumio[952]: verbose: ControllerMpd::parseState
Oct 13 14:33:52 iqaudio volumio[952]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Oct 13 14:33:52 iqaudio volumio[952]: info: sendMpdCommand playlistinfo took 1 milliseconds
Oct 13 14:33:52 iqaudio volumio[952]: verbose: ControllerMpd::parseTrackInfo
Oct 13 14:33:52 iqaudio volumio[952]: info: ControllerMpd::pushState
Oct 13 14:33:52 iqaudio volumio[952]: info: CoreCommandRouter::servicePushState
Oct 13 14:33:52 iqaudio volumio[952]: info: CorePlayQueue::getTrack 2
Oct 13 14:33:52 iqaudio volumio[952]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2225,"duration":291,"samplerate":"176.4 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5765 Kbps","isStreaming":false,"title":"Singing Winds, Crying Beasts","artist":"Santana","album":"Abraxas","uri":"INTERNAL/Soundcheck14/07- Carlos_Santana-Singing_Winds_Crying_Beasts-SMRP.flac","trackType":"flac"}
Oct 13 14:33:52 iqaudio volumio[952]: verbose: CURRENT POSITION 2
Oct 13 14:33:52 iqaudio volumio[952]: info: CoreStateMachine::syncState   stateService play
Oct 13 14:33:52 iqaudio volumio[952]: info: CoreStateMachine::syncState   currentStatus play
Oct 13 14:33:52 iqaudio volumio[952]: info: Received an update from plugin. extracting info from payload
Oct 13 14:33:52 iqaudio volumio[952]: info: CoreStateMachine::pushState
Oct 13 14:33:52 iqaudio volumio[952]: info: CorePlayQueue::getTrack 2
Oct 13 14:33:52 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Oct 13 14:33:52 iqaudio volumio[952]: info: CoreCommandRouter::volumioPushState
Oct 13 14:33:52 iqaudio volumio[952]: info: MRS: Pushing multiroomSync output update for this device
Oct 13 14:33:52 iqaudio volumio[952]: info: MRS: Pushing multiroomSync output
Oct 13 14:33:52 iqaudio volumio[952]: info: CoreStateMachine::pushState
Oct 13 14:33:52 iqaudio volumio[952]: info: CorePlayQueue::getTrack 2
Oct 13 14:33:52 iqaudio volumio[952]: info: CoreCommandRouter::volumioPushState
Oct 13 14:33:52 iqaudio volumio[952]: info: MRS: Pushing multiroomSync output update for this device
Oct 13 14:33:52 iqaudio volumio[952]: info: MRS: Pushing multiroomSync output
Oct 13 14:33:52 iqaudio volumio[952]: info: ------------------------------ 46ms
Oct 13 14:33:55 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:34:01 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:34:06 iqaudio volumio[952]: verbose: New Socket.io Connection to iqaudio from 193.193.26.190 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:109.0) Gecko/20100101 Firefox/118.0 Total Clients: 10
Oct 13 14:34:06 iqaudio volumio[952]: info: CoreCommandRouter::volumioGetState
Oct 13 14:34:06 iqaudio volumio[952]: info: CorePlayQueue::getTrack 2
Oct 13 14:34:06 iqaudio volumio[952]: info: CoreCommandRouter::volumioGetQueue
Oct 13 14:34:06 iqaudio volumio[952]: info: CoreStateMachine::getQueue
Oct 13 14:34:06 iqaudio volumio[952]: info: CorePlayQueue::getQueue
Oct 13 14:34:06 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid
Oct 13 14:34:06 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:34:12 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:34:17 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:34:23 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:34:28 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:34:34 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:34:35 iqaudio volumio[952]: info: CALLMETHOD: system_controller system sendBugReport [object Object]
Oct 13 14:34:35 iqaudio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport
Oct 13 14:34:35 iqaudio sudo[1285]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig
Oct 13 14:34:35 iqaudio sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0)
Oct 13 14:34:35 iqaudio sudo[1285]: pam_unix(sudo:session): session closed for user root
Oct 13 14:34:39 iqaudio wpa_supplicant[827]: wlan0: Failed to initiate sched scan
Oct 13 14:34:40 iqaudio sudo[1331]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7
Oct 13 14:34:40 iqaudio sudo[1331]: pam_unix(sudo:session): session opened for user root by (uid=0)