Thu Apr 20 18:33:30 UTC 2023 Description="'No info available'" # cat /proc/version --------------- Linux version 4.9.241+ (odroidc4@busteros) (gcc version 6.3.1 20170109 (Linaro GCC 6.3-2017.02) ) #15 SMP PREEMPT Thu Feb 10 11:27:37 CET 2022 # cat /etc/os-release --------------- PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7af731278c5ff0dd86032995ce2e12b0049aa451" VOLUMIO_FE_VERSION="e6f7875b0e497e33187ca2553d7998c4aeb06af9" VOLUMIO_FE3_VERSION="8d9f9503eeacae3e8f349def90b368b32462d83b" VOLUMIO_BE_VERSION="68f86c210c699e1a06cf4b94b596b0545110dc8e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 10 Feb 2022 12:10:20 PM CET" VOLUMIO_VERSION="3.224" VOLUMIO_HARDWARE="odroidc4" VOLUMIO_DEVICENAME="Odroid-C4" VOLUMIO_HASH="adf6f814aa01c22a1c5ecff3ecfa327a" # ifconfig --------------- eth0: flags=4163 mtu 1500 inet 192.168.7.70 netmask 255.255.255.0 broadcast 192.168.7.255 ether 00:1e:06:48:e6:46 txqueuelen 1000 (Ethernet) RX packets 1859 bytes 344022 (335.9 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 1055 bytes 307096 (299.8 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 device interrupt 21 lo: flags=73 mtu 65536 inet 127.0.0.1 netmask 255.0.0.0 loop txqueuelen 1 (Local Loopback) RX packets 1319 bytes 172815 (168.7 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 1319 bytes 172815 (168.7 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 wlan0: flags=4099 mtu 1500 ether 30:de:4b:f4:ca:09 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 --------------- eth0 no wireless extensions. lo no wireless extensions. wlan0 IEEE 802.11 ESSID:off/any Mode:Managed Access Point: Not-Associated Tx-Power=20 dBm Retry short limit:7 RTS thr=2347 B Fragment thr:off Encryption key:off Power Management:off # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 0: ODROIDHDMI [ODROID-HDMI], device 0: SPDIF-dit-hifi dit-hifi-0 [] Subdevices: 1/1 Subdevice #0: subdevice #0 card 1: ODROIDDAC [ODROID-DAC], device 0: TDM-B-pcm5102a-hifi multicodec-0 [] Subdevices: 1/1 Subdevice #0: subdevice #0 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 1 18:28 ? 00:00:03 /sbin/init root 2 0 0 18:28 ? 00:00:00 [kthreadd] root 3 2 0 18:28 ? 00:00:00 [ksoftirqd/0] root 4 2 0 18:28 ? 00:00:00 [kworker/0:0] root 5 2 0 18:28 ? 00:00:00 [kworker/0:0H] root 6 2 0 18:28 ? 00:00:00 [kworker/u8:0] root 7 2 0 18:28 ? 00:00:00 [rcu_preempt] root 8 2 0 18:28 ? 00:00:00 [rcu_sched] root 9 2 0 18:28 ? 00:00:00 [rcu_bh] root 10 2 0 18:28 ? 00:00:00 [migration/0] root 11 2 0 18:28 ? 00:00:00 [lru-add-drain] root 12 2 0 18:28 ? 00:00:00 [watchdog/0] root 13 2 0 18:28 ? 00:00:00 [cpuhp/0] root 14 2 0 18:28 ? 00:00:00 [cpuhp/1] root 15 2 0 18:28 ? 00:00:00 [watchdog/1] root 16 2 0 18:28 ? 00:00:00 [migration/1] root 17 2 0 18:28 ? 00:00:00 [ksoftirqd/1] root 18 2 0 18:28 ? 00:00:00 [kworker/1:0] root 19 2 0 18:28 ? 00:00:00 [kworker/1:0H] root 20 2 0 18:28 ? 00:00:00 [cpuhp/2] root 21 2 0 18:28 ? 00:00:00 [watchdog/2] root 22 2 0 18:28 ? 00:00:00 [migration/2] root 23 2 0 18:28 ? 00:00:00 [ksoftirqd/2] root 24 2 0 18:28 ? 00:00:00 [kworker/2:0] root 25 2 0 18:28 ? 00:00:00 [kworker/2:0H] root 26 2 0 18:28 ? 00:00:00 [cpuhp/3] root 27 2 0 18:28 ? 00:00:00 [watchdog/3] root 28 2 0 18:28 ? 00:00:00 [migration/3] root 29 2 0 18:28 ? 00:00:00 [ksoftirqd/3] root 30 2 0 18:28 ? 00:00:00 [kworker/3:0] root 31 2 0 18:28 ? 00:00:00 [kworker/3:0H] root 32 2 0 18:28 ? 00:00:00 [kdevtmpfs] root 33 2 0 18:28 ? 00:00:00 [netns] root 34 2 0 18:28 ? 00:00:00 [kworker/u8:1] root 65 2 0 18:28 ? 00:00:00 [kworker/u8:2] root 557 2 0 18:28 ? 00:00:00 [kworker/u8:3] root 1157 2 0 18:28 ? 00:00:00 [khungtaskd] root 1158 2 0 18:28 ? 00:00:00 [oom_reaper] root 1159 2 0 18:28 ? 00:00:00 [writeback] root 1161 2 0 18:28 ? 00:00:00 [kcompactd0] root 1162 2 0 18:28 ? 00:00:00 [crypto] root 1163 2 0 18:28 ? 00:00:00 [bioset] root 1165 2 0 18:28 ? 00:00:00 [kblockd] root 1200 2 0 18:28 ? 00:00:00 [devfreq_wq] root 1239 2 0 18:28 ? 00:00:00 [kworker/3:1] root 1240 2 0 18:28 ? 00:00:00 [cfg80211] root 1244 2 0 18:28 ? 00:00:00 [watchdogd] root 1331 2 0 18:28 ? 00:00:00 [gp_pll] root 1336 2 0 18:28 ? 00:00:00 [kworker/1:1] root 1339 2 0 18:28 ? 00:00:00 [kworker/2:1] root 1370 2 0 18:28 ? 00:00:00 [kswapd0] root 1371 2 0 18:28 ? 00:00:00 [vmstat] root 1440 2 0 18:28 ? 00:00:00 [kworker/u9:0] root 1513 2 0 18:28 ? 00:00:00 [hwrng] root 1524 2 0 18:28 ? 00:00:00 [kbase_job_fault] root 1525 2 0 18:28 ? 00:00:00 [kworker/u9:1] root 1530 2 0 18:28 ? 00:00:00 [bioset] root 1533 2 0 18:28 ? 00:00:00 [bioset] root 1536 2 0 18:28 ? 00:00:00 [bioset] root 1539 2 0 18:28 ? 00:00:00 [bioset] root 1542 2 0 18:28 ? 00:00:00 [bioset] root 1545 2 0 18:28 ? 00:00:00 [bioset] root 1548 2 0 18:28 ? 00:00:00 [bioset] root 1551 2 0 18:28 ? 00:00:00 [bioset] root 1573 2 0 18:28 ? 00:00:00 [eth_moniter_tx_] root 1603 2 0 18:28 ? 00:00:00 [kworker/0:1] root 1611 2 0 18:28 ? 00:00:00 [cfinteractive] root 1670 2 0 18:28 ? 00:00:00 [null] root 1671 2 0 18:28 ? 00:00:00 [cpu_hogplug_thr] root 1684 2 0 18:28 ? 00:00:00 [vmalloc_ion] root 1685 2 0 18:28 ? 00:00:00 [codec_mm_ion] root 1689 2 0 18:28 ? 00:00:00 [ge2d_monitor] root 1703 2 0 18:28 ? 00:00:00 [kthread_hdcp] root 1711 2 0 18:28 ? 00:00:00 [kthread_di] root 1748 2 0 18:28 ? 00:00:00 [videosync] root 1756 2 0 18:28 ? 00:00:00 [irq/51-meson-am] root 1758 2 0 18:28 ? 00:00:00 [irq/52-meson-am] root 1767 2 0 18:28 ? 00:00:00 [cec_work] root 1795 2 0 18:28 ? 00:00:00 [irq/25-meson-g1] root 1800 2 0 18:28 ? 00:00:00 [cma_task0] root 1801 2 0 18:28 ? 00:00:00 [cma_task1] root 1802 2 0 18:28 ? 00:00:00 [cma_task2] root 1803 2 0 18:28 ? 00:00:00 [cma_task3] root 1875 2 0 18:28 ? 00:00:00 [dwc_otg] root 1893 2 0 18:28 ? 00:00:00 [kworker/0:2] root 1894 2 0 18:28 ? 00:00:00 [kworker/3:2] root 1896 2 0 18:28 ? 00:00:00 [bioset] root 1897 2 0 18:28 ? 00:00:00 [mmcqd/1] root 1905 2 0 18:28 ? 00:00:00 [kworker/1:2] root 1946 2 0 18:28 ? 00:00:00 [jbd2/mmcblk1p2-] root 1947 2 0 18:28 ? 00:00:00 [ext4-rsv-conver] root 1954 2 0 18:28 ? 00:00:00 [loop0] root 1961 2 0 18:28 ? 00:00:00 [kworker/2:1H] root 1989 2 0 18:28 ? 00:00:00 [jbd2/mmcblk1p3-] root 1990 2 0 18:28 ? 00:00:00 [ext4-rsv-conver] root 1992 2 0 18:28 ? 00:00:00 [kworker/0:1H] root 2001 2 0 18:29 ? 00:00:00 [kworker/3:1H] root 2004 2 0 18:29 ? 00:00:00 [kworker/1:1H] root 2011 2 0 18:29 ? 00:00:00 [ipv6_addrconf] root 2085 2 0 18:29 ? 00:00:00 [kauditd] root 2123 2 0 18:29 ? 00:00:00 [kworker/u8:4] root 2140 1 0 18:29 ? 00:00:01 /lib/systemd/systemd-journald root 2160 2 0 18:29 ? 00:00:00 [rpciod] root 2161 2 0 18:29 ? 00:00:00 [xprtiod] root 2219 2 0 18:29 ? 00:00:00 [kworker/2:2] root 2591 2 0 18:29 ? 00:00:00 [kworker/2:3] root 2622 2 0 18:29 ? 00:00:00 [kworker/0:3] root 3063 1 0 18:29 ? 00:00:00 /lib/systemd/systemd-udevd root 3073 2 0 18:29 ? 00:00:00 [kworker/3:3] root 3263 2 0 18:29 ? 00:00:00 [irq/45-vdec-0] root 3264 2 0 18:29 ? 00:00:00 [irq/46-vdec-1] root 3265 2 0 18:29 ? 00:00:00 [vdec-core] root 3266 2 0 18:29 ? 00:00:00 [vdec-work] root 3330 2 0 18:29 ? 00:00:00 [aml-vcodec-dec] _rpc 3479 1 0 18:29 ? 00:00:00 /sbin/rpcbind -f -w root 3496 1 0 18:29 ? 00:00:01 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 root 3571 1 0 18:29 ? 00:00:00 /lib/systemd/systemd-logind root 3583 1 0 18:29 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon message+ 3646 1 0 18:29 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 3660 1 0 18:29 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant nobody 3670 1 0 18:29 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* volumio 3716 1 0 18:29 ? 00:00:00 /usr/local/bin/volumio-remote-updater avahi 3730 1 0 18:29 ? 00:00:00 avahi-daemon: running [volumio.local] root 3737 2 0 18:29 ? 00:00:00 [kworker/u8:5] root 3824 1 0 18:29 ? 00:00:00 /usr/sbin/dhcpcd avahi 3908 3730 0 18:29 ? 00:00:00 avahi-daemon: chroot helper root 4270 1 0 18:29 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 4331 1 0 18:29 ttyS0 00:00:00 /sbin/agetty -o -p -- \u --keep-baud 115200,38400,9600 ttyS0 vt220 root 4333 1 0 18:29 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I root 4336 1 0 18:29 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux root 4449 1 0 18:29 ? 00:00:00 /usr/sbin/sshd -D root 4464 1 0 18:29 ? 00:00:00 /usr/sbin/lircd --nodaemon root 4479 4464 0 18:29 ? 00:00:00 [sh] root 4496 4270 0 18:29 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group ntp 4699 1 0 18:29 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 root 4701 1 0 18:29 ? 00:00:00 /sbin/dhcpcd eth0 root 4749 1 0 18:29 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 4775 4749 0 18:29 ? 00:00:00 winbindd: domain child [VOLUMIO] root 4793 1 0 18:29 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 4808 4793 0 18:29 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 4809 4793 0 18:29 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 4823 4749 0 18:29 ? 00:00:00 winbindd: idmap child root 4824 4749 0 18:29 ? 00:00:00 winbindd: domain child [BUILTIN] root 4825 4793 0 18:29 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group volumio 4865 1 5 18:29 ? 00:00:12 /usr/bin/node /volumio/index.js root 4892 2 0 18:29 ? 00:00:00 [kworker/u8:6] root 4997 2 0 18:29 ? 00:00:00 [kworker/1:3] volumio 5014 4865 0 18:29 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 5025 5014 0 18:29 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 5026 5014 0 18:29 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 5027 5014 0 18:29 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 5212 1 0 18:29 ? 00:00:00 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf volumio 5273 1 0 18:29 ? 00:00:00 /bin/streaming-daemon shairpo+ 5433 1 0 18:30 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf mpd 5455 1 0 18:30 ? 00:00:00 /usr/bin/mpd --no-daemon volumio 5514 4865 0 18:30 ? 00:00:00 /bin/journalctl --output cat -f root 5574 1 0 18:31 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 volumio 5716 4865 0 18:33 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'No info available' volumio 5717 5716 0 18:33 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js No info available volumio 5737 5717 0 18:33 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 5738 5737 0 18:33 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk1p2 2.5G 327M 2.0G 14% /imgpart /dev/loop0 302M 302M 0 100% /static overlay 12G 64M 11G 1% / devtmpfs 1.9G 0 1.9G 0% /dev tmpfs 1.9G 0 1.9G 0% /dev/shm tmpfs 1.9G 8.9M 1.9G 1% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 1.9G 0 1.9G 0% /sys/fs/cgroup tmpfs 1.9G 48K 1.9G 1% /tmp tmpfs 20M 28K 20M 1% /var/log tmpfs 1.9G 0 1.9G 0% /var/spool/cups tmpfs 1.9G 0 1.9G 0% /var/spool/cups/tmp /dev/mmcblk1p1 60M 18M 43M 29% /boot # mount --------------- /dev/mmcblk1p2 on /imgpart type ext4 (rw,relatime,data=ordered) /dev/loop0 on /static type squashfs (ro,relatime) overlay on / type overlay (rw,relatime,lowerdir=/mnt/static,upperdir=/mnt/ext/dyn,workdir=/mnt/ext/work) sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime) proc on /proc type proc (rw,relatime) devtmpfs on /dev type devtmpfs (rw,nosuid,size=1893872k,nr_inodes=473468,mode=755) securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime) selinuxfs on /sys/fs/selinux type selinuxfs (rw,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) cgroup on /sys/fs/cgroup/systemd type cgroup (rw,nosuid,nodev,noexec,relatime,xattr,name=systemd) pstore on /sys/fs/pstore type pstore (rw,nosuid,nodev,noexec,relatime) bpf on /sys/fs/bpf type bpf (rw,nosuid,nodev,noexec,relatime) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/net_cls type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/debug type cgroup (rw,nosuid,nodev,noexec,relatime,debug) cgroup on /sys/fs/cgroup/memory type cgroup (rw,nosuid,nodev,noexec,relatime,memory) 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) fusectl on /sys/fs/fuse/connections type fusectl (rw,relatime) tmpfs on /tmp 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 type tmpfs (rw,noatime,mode=755) tmpfs on /var/spool/cups/tmp type tmpfs (rw,noatime,mode=755) /dev/mmcblk1p1 on /boot type vfat (rw,nosuid,nodev,noexec,relatime,fmask=0111,dmask=0000,allow_utime=0022,codepage=437,iocharset=iso8859-1,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:22 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49149 0.0.0.0:* LISTEN 5212/upmpdcli tcp 0 0 0.0.0.0:445 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49152 0.0.0.0:* LISTEN 5212/upmpdcli 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 127.0.0.1:38826 127.0.0.1:3000 ESTABLISHED 3716/volumio-remote tcp 0 0 127.0.0.1:58752 127.0.0.1:6599 ESTABLISHED 5212/upmpdcli tcp 0 0 192.168.7.70:35098 34.120.160.131:443 ESTABLISHED 4865/node tcp 0 0 127.0.0.1:38838 127.0.0.1:3000 ESTABLISHED 4865/node tcp 0 0 127.0.0.1:58748 127.0.0.1:6599 ESTABLISHED 5212/upmpdcli tcp6 0 0 :::22 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 4865/node tcp6 0 0 :::3001 :::* LISTEN 5014/node tcp6 0 0 :::3005 :::* LISTEN 4865/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::7777 :::* LISTEN 5273/streaming-daem tcp6 0 0 :::6599 :::* LISTEN 4865/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 192.168.7.70:3000 192.168.7.16:57048 ESTABLISHED 4865/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:38838 ESTABLISHED 4865/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:38826 ESTABLISHED 4865/node tcp6 0 0 192.168.7.70:3000 192.168.7.16:46706 ESTABLISHED 4865/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:58752 ESTABLISHED 4865/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:58748 ESTABLISHED 4865/node # cat /etc/asound.conf --------------- pcm.!default { type empty slave.pcm "volumio" } pcm.volumio { 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 "ODROIDDAC" } # cat /var/log/mpd.log --------------- Apr 20 18:28 : zeroconf: No global port, disabling zeroconf Apr 20 18:29 : zeroconf: No global port, disabling zeroconf Apr 20 18:29 : client: [0] opened from local Apr 20 18:29 : exception: No such directory Apr 20 18:29 : client: [1] opened from local Apr 20 18:29 : zeroconf: No global port, disabling zeroconf Apr 20 18:29 : client: [0] opened from local Apr 20 18:29 : client: [1] opened from 127.0.0.1:38498 Apr 20 18:29 : client: [2] opened from 127.0.0.1:38502 Apr 20 18:29 : exception: Failed to read mixer for 'alsa': no such mixer control: PCM Apr 20 18:29 : client: [3] opened from local Apr 20 18:30 : zeroconf: No global port, disabling zeroconf Apr 20 18:30 : client: [0] opened from local Apr 20 18:30 : client: [1] opened from local Apr 20 18:30 : client: [2] opened from local # volumio plugin list --------------- This command will list installed plugins on your device ----------------------- Installed Plugins: [ { prettyName: 'GPIO Buttons', name: 'gpio-buttons', category: 'system_hardware', version: '0.8.3', icon: 'fa-microchip', isManuallyInstalled: false, enabled: false, active: false }, { prettyName: 'System information', name: 'Systeminfo', category: 'user_interface', version: '3.0.0', icon: 'fa-info-circle', isManuallyInstalled: false, enabled: false, active: false } ] ------------------------------------------ # 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": true }, "status": { "type": "string", "value": "STARTED" } }, "streaming_services": { "status": { "type": "string", "value": "STARTING" } } }, "audio_interface": { "outputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "alsa_controller": { "enabled": { "type": "boolean", "value": true }, "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" } } }, "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" } }, "Systeminfo": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } } }, "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" } } }, "system_hardware": { "gpio-buttons": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } } } } # 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 "0" } 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, 646 ms: OK https://www.googleapis.com, 332 ms: OK https://securetoken.googleapis.com, 342 ms: OK https://myvolumio.firebaseio.com, 444 ms: OK https://functions.volumio.cloud, 337 ms: OK https://oauth-performer.dfs.volumio.org, 301 ms: OK https://browsing-performer.dfs.volumio.org, 322 ms: OK http://cddb.volumio.org, 600 ms: OK https://functions.volumio.cloud, 368 ms: OK http://pushupdates.volumio.org, 227 ms: OK http://plugins.volumio.org, 819 ms: OK https://database.volumio.cloud, 673 ms: OK https://radio-directory.firebaseapp.com, 276 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # sudo journalctl -p 7 --------------- -- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Thu 2023-04-20 18:33:35 UTC. -- Feb 14 10:11:58 volumio kernel: Booting Linux on physical CPU 0x0 Feb 14 10:11:58 volumio kernel: Linux version 4.9.241+ (odroidc4@busteros) (gcc version 6.3.1 20170109 (Linaro GCC 6.3-2017.02) ) #15 SMP PREEMPT Thu Feb 10 11:27:37 CET 2022 Feb 14 10:11:58 volumio kernel: Boot CPU: AArch64 Processor [411fd050] Feb 14 10:11:58 volumio kernel: Machine model: Hardkernel ODROID-C4 Feb 14 10:11:58 volumio kernel: efi: Getting EFI parameters from FDT: Feb 14 10:11:58 volumio kernel: efi: UEFI not found. Feb 14 10:11:58 volumio kernel: 07400000 - 07500000, 1024 KB, ramoops@0x07400000 Feb 14 10:11:58 volumio kernel: __reserved_mem_alloc_size, start:0x0000000005000000, end:0x0000000005400000, len:4 MiB Feb 14 10:11:58 volumio kernel: 05000000 - 05400000, 4096 KB, linux,secmon Feb 14 10:11:58 volumio kernel: Reserved memory: created DMA memory pool at 0x00000000ed800000, size 0 MiB Feb 14 10:11:58 volumio kernel: ed800000 - ed800000, 0 KB, linux,ppmgr Feb 14 10:11:58 volumio kernel: __reserved_mem_alloc_size, start:0x000000007f800000, end:0x0000000080000000, len:8 MiB Feb 14 10:11:58 volumio kernel: 7f800000 - 80000000, 8192 KB, linux,meson-fb Feb 14 10:11:58 volumio kernel: e5800000 - ed800000, 131072 KB, linux,ion-dev Feb 14 10:11:58 volumio kernel: e3000000 - e5800000, 40960 KB, linux,di_cma Feb 14 10:11:58 volumio kernel: cfc00000 - e3000000, 315392 KB, linux,codec_mm_cma Feb 14 10:11:58 volumio kernel: cfc00000 - cfc00000, 0 KB, linux,codec_mm_reserved Feb 14 10:11:58 volumio kernel: cma: Reserved 8 MiB at 0x00000000cf400000 Feb 14 10:11:58 volumio kernel: On node 0 totalpages: 972800 Feb 14 10:11:58 volumio kernel: DMA zone: 15200 pages used for memmap Feb 14 10:11:58 volumio kernel: DMA zone: 0 pages reserved Feb 14 10:11:58 volumio kernel: DMA zone: 972800 pages, LIFO batch:31 Feb 14 10:11:58 volumio kernel: psci: probing for conduit method from DT. Feb 14 10:11:58 volumio kernel: psci: PSCIv1.0 detected in firmware. Feb 14 10:11:58 volumio kernel: psci: Using standard PSCI v0.2 function IDs Feb 14 10:11:58 volumio kernel: psci: MIGRATE_INFO_TYPE not supported. Feb 14 10:11:58 volumio kernel: psci: SMC Calling Convention v1.1 Feb 14 10:11:58 volumio kernel: percpu: Embedded 21 pages/cpu s47960 r8192 d29864 u86016 Feb 14 10:11:58 volumio kernel: pcpu-alloc: s47960 r8192 d29864 u86016 alloc=21*4096 Feb 14 10:11:58 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 14 10:11:58 volumio kernel: Detected VIPT I-cache on CPU0 Feb 14 10:11:58 volumio kernel: Built 1 zonelists in Zone order, mobility grouping on. Total pages: 957600 Feb 14 10:11:58 volumio kernel: Kernel command line: imgpart=UUID=633f1037-5802-4dbd-a783-fd204f6760e0 bootpart=UUID=2A58-C332 datapart=UUID=98aebd79-6c4f-46e4-a3a7-3c4b3d8150fe imgfile=/volumio_current.sqsh bootconfig=boot.ini loglevel=0 hwdevice=Odroid-C4 bootdelay=5 console=ttyS0,115200n8 no_console_suspend fsck.repair=yes net.ifnames=0 elevator=noop hdmimode=1080p60hz cvbsmode=576cvbs max_freq_a55=1908 maxcpus=4 voutmode=hdmi disablehpd=false cvbscable=0 overscan=100 monitor_onoff=false logo=osd0,loaded sdrmode=auto enable_wol=0 Feb 14 10:11:58 volumio kernel: vout: get hdmimode: 1080p60hz Feb 14 10:11:58 volumio kernel: vout: get cvbsmode: 576cvbs Feb 14 10:11:58 volumio kernel: max cpufreq of cluster0 : 1908000kHz Feb 14 10:11:58 volumio kernel: hdmitx: voutmode : 1 Feb 14 10:11:58 volumio kernel: fb: osd0 Feb 14 10:11:58 volumio kernel: fb: loaded Feb 14 10:11:58 volumio kernel: PID hash table entries: 4096 (order: 3, 32768 bytes) Feb 14 10:11:58 volumio kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes) Feb 14 10:11:58 volumio kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes) Feb 14 10:11:58 volumio kernel: Memory: 3279840K/3891200K available (11964K kernel code, 1816K rwdata, 5136K rodata, 4992K init, 1421K bss, 103456K reserved, 507904K cma-reserved) Feb 14 10:11:58 volumio kernel: Virtual kernel memory layout: Feb 14 10:11:58 volumio kernel: modules : 0xffffff8000000000 - 0xffffff8008000000 ( 128 MB) Feb 14 10:11:58 volumio kernel: vmalloc : 0xffffff8008000000 - 0xffffffbebfff0000 ( 250 GB) Feb 14 10:11:58 volumio kernel: .text : 0xffffff8009080000 - 0xffffff8009c30000 ( 11968 KB) Feb 14 10:11:58 volumio kernel: .rodata : 0xffffff8009c30000 - 0xffffff800a140000 ( 5184 KB) Feb 14 10:11:58 volumio kernel: .init : 0xffffff800a140000 - 0xffffff800a620000 ( 4992 KB) Feb 14 10:11:58 volumio kernel: .data : 0xffffff800a620000 - 0xffffff800a7e6200 ( 1817 KB) Feb 14 10:11:58 volumio kernel: .bss : 0xffffff800a7e6200 - 0xffffff800a9498bc ( 1422 KB) Feb 14 10:11:58 volumio kernel: fixed : 0xffffffbefe7fd000 - 0xffffffbefec00000 ( 4108 KB) Feb 14 10:11:58 volumio kernel: PCI I/O : 0xffffffbefee00000 - 0xffffffbeffe00000 ( 16 MB) Feb 14 10:11:58 volumio kernel: vmemmap : 0xffffffbf00000000 - 0xffffffc000000000 ( 4 GB maximum) Feb 14 10:11:58 volumio kernel: 0xffffffbf00000000 - 0xffffffbf03b60000 ( 59 MB actual) Feb 14 10:11:58 volumio kernel: memory : 0xffffffc000000000 - 0xffffffc0ed800000 ( 3800 MB) Feb 14 10:11:58 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 14 10:11:58 volumio kernel: Preemptible hierarchical RCU implementation. Feb 14 10:11:58 volumio kernel: Build-time adjustment of leaf fanout to 64. Feb 14 10:11:58 volumio kernel: RCU restricting CPUs from NR_CPUS=8 to nr_cpu_ids=4. Feb 14 10:11:58 volumio kernel: RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=4 Feb 14 10:11:58 volumio kernel: NR_IRQS:64 nr_irqs:64 0 Feb 14 10:11:58 volumio kernel: GPIO-INTC: support to detect double-edge trigger signal Feb 14 10:11:58 volumio kernel: g12a_aoclkc_init: register ao clk ok! Feb 14 10:11:58 volumio kernel: Meson chip version = RevC (2B:C - 10:0) Feb 14 10:11:58 volumio kernel: meson_g12a_sdemmc_init: register amlogic sdemmc clk Feb 14 10:11:58 volumio kernel: meson_g12a_sdemmc_init: register amlogic sdemmc clk Feb 14 10:11:58 volumio kernel: meson_g12a_gpu_init: register meson gpu clk Feb 14 10:11:58 volumio kernel: meson_g12a_media_init: register meson media clk Feb 14 10:11:58 volumio kernel: meson_g12a_misc_init: register amlogic g12a misc clks Feb 14 10:11:58 volumio kernel: meson_g12a_misc_init: done. Feb 14 10:11:58 volumio kernel: g12a_clkc_init initialization complete Feb 14 10:11:58 volumio kernel: sm1 clk probe ok Feb 14 10:11:58 volumio kernel: arm_arch_timer: Architected cp15 timer(s) running at 24.00MHz (phys). Feb 14 10:11:58 volumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns Feb 14 10:11:58 volumio kernel: sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns Feb 14 10:11:58 volumio kernel: meson_bc_timer: mclk->mux_reg =ffffff800800c190,mclk->reg =ffffff800800e194 Feb 14 10:11:58 volumio kernel: Console: colour dummy device 80x25 Feb 14 10:11:58 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=96000) Feb 14 10:11:58 volumio kernel: pid_max: default: 32768 minimum: 301 Feb 14 10:11:58 volumio kernel: Security Framework initialized Feb 14 10:11:58 volumio kernel: SELinux: Initializing. Feb 14 10:11:58 volumio kernel: SELinux: Starting in permissive mode Feb 14 10:11:58 volumio kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes) Feb 14 10:11:58 volumio kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes) Feb 14 10:11:58 volumio kernel: ftrace: allocating 39807 entries in 156 pages Feb 14 10:11:58 volumio kernel: sched-energy: CPU device node has no sched-energy-costs Feb 14 10:11:58 volumio kernel: CPU0: update cpu_capacity 1024 Feb 14 10:11:58 volumio kernel: ASID allocator initialised with 65536 entries Feb 14 10:11:58 volumio kernel: secmon: can't fine clear_range Feb 14 10:11:58 volumio kernel: EFI services will not be available. Feb 14 10:11:58 volumio kernel: Detected VIPT I-cache on CPU1 Feb 14 10:11:58 volumio kernel: CPU1: update cpu_capacity 1024 Feb 14 10:11:58 volumio kernel: CPU1: Booted secondary processor [411fd050] Feb 14 10:11:58 volumio kernel: Detected VIPT I-cache on CPU2 Feb 14 10:11:58 volumio kernel: CPU2: update cpu_capacity 1024 Feb 14 10:11:58 volumio kernel: CPU2: Booted secondary processor [411fd050] Feb 14 10:11:58 volumio kernel: Detected VIPT I-cache on CPU3 Feb 14 10:11:58 volumio kernel: CPU3: update cpu_capacity 1024 Feb 14 10:11:58 volumio kernel: CPU3: Booted secondary processor [411fd050] Feb 14 10:11:58 volumio kernel: Brought up 4 CPUs Feb 14 10:11:58 volumio kernel: SMP: Total of 4 processors activated. Feb 14 10:11:58 volumio kernel: CPU features: detected feature: Privileged Access Never Feb 14 10:11:58 volumio kernel: CPU features: detected feature: User Access Override Feb 14 10:11:58 volumio kernel: CPU features: detected feature: 32-bit EL0 Support Feb 14 10:11:58 volumio kernel: CPU: All CPU(s) started at EL2 Feb 14 10:11:58 volumio kernel: alternatives: patching kernel code Feb 14 10:11:58 volumio kernel: devtmpfs: initialized Feb 14 10:11:58 volumio kernel: DMI not present or invalid. Feb 14 10:11:58 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns Feb 14 10:11:58 volumio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes) Feb 14 10:11:58 volumio kernel: pinctrl core: initialized pinctrl subsystem Feb 14 10:11:58 volumio kernel: NET: Registered protocol family 16 Feb 14 10:11:58 volumio kernel: schedtune: init normalization constants... Feb 14 10:11:58 volumio kernel: schedtune: no energy model data Feb 14 10:11:58 volumio kernel: schedtune: disabled! Feb 14 10:11:58 volumio kernel: cpuidle: using governor menu Feb 14 10:11:58 volumio kernel: register canvas platform driver Feb 14 10:11:58 volumio kernel: register rdma platform driver Feb 14 10:11:58 volumio kernel: vdso: 2 pages (1 code @ ffffff8009c37000, 1 data @ ffffff800a625000) Feb 14 10:11:58 volumio kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Feb 14 10:11:58 volumio kernel: DMA: preallocated 2048 KiB pool for atomic allocations Feb 14 10:11:58 volumio kernel: clkmsr: clkmsr: driver init Feb 14 10:11:58 volumio kernel: codec_mm_module_init Feb 14 10:11:58 volumio kernel: media_configs_system_init Feb 14 10:11:58 volumio kernel: pstore: using zlib compression Feb 14 10:11:58 volumio kernel: console [pstore-1] enabled Feb 14 10:11:58 volumio kernel: pstore: Registered ramoops as persistent store backend Feb 14 10:11:58 volumio kernel: ramoops: attached 0x100000@0x7400000, ecc: 0/0 Feb 14 10:11:58 volumio kernel: aml_iomap: amlogic iomap probe done Feb 14 10:11:58 volumio kernel: vpu: driver version: v20190329(10-sm1) Feb 14 10:11:58 volumio kernel: vpu: load vpu_clk: 666666667Hz(7) Feb 14 10:11:58 volumio kernel: vpu: clktree_init Feb 14 10:11:58 volumio kernel: vpu: vpu_probe OK Feb 14 10:11:58 volumio kernel: clkmsr: msr_clk_reg0=ffffff8008453004,msr_clk_reg2=ffffff800845500c Feb 14 10:11:58 volumio kernel: clkmsr: msr_ring_reg0=ffffff80084575fc Feb 14 10:11:58 volumio kernel: audio_clocks: audio_clocks_probe done Feb 14 10:11:58 volumio kernel: aml_snd_reg_map[0], reg:ff661000, size:400 Feb 14 10:11:58 volumio kernel: aml_snd_reg_map[1], reg:ff660000, size:1000 Feb 14 10:11:58 volumio kernel: aml_snd_reg_map[2], reg:ff661400, size:400 Apr 20 18:28:29 volumio kernel: aml_snd_reg_map[3], reg:ff662000, size:1000 Apr 20 18:28:29 volumio kernel: aml_snd_reg_map[4], reg:ffd01000, size:1000 Apr 20 18:28:29 volumio kernel: aml_snd_reg_map[5], reg:ff661800, size:400 Apr 20 18:28:29 volumio kernel: aml_snd_reg_map[6], reg:ff661c00, size:104 Apr 20 18:28:29 volumio kernel: aml_snd_reg_map[7], reg:ff664000, size:104 Apr 20 18:28:29 volumio kernel: amlogic auge_snd_iomap probe done Apr 20 18:28:29 volumio kernel: aml_vdac_config_probe: cpu_id:6, name:meson-sm1-vdac Apr 20 18:28:29 volumio kernel: aml_vdac_probe: ok Apr 20 18:28:29 volumio kernel: canvas_probe reg=00000000ff638000,size=2000 Apr 20 18:28:29 volumio kernel: canvas maped reg_base =ffffff800846c000 Apr 20 18:28:29 volumio kernel: rdma_probe,cpu_type:1, ver:0, len:8 Apr 20 18:28:29 volumio kernel: rdma_register, rdma_table_addr ffffff80084a1000 rdma_table_addr_phy cf600000 reg_buf ffffffc0ca228000 Apr 20 18:28:29 volumio kernel: rdma_register success, handle 1 table_size 32768 Apr 20 18:28:29 volumio kernel: set_rdma_handle video rdma handle = 1. Apr 20 18:28:29 volumio kernel: classs created ok Apr 20 18:28:29 volumio kernel: classs file created ok Apr 20 18:28:29 volumio kernel: codec_mm codec_mm: assigned reserved memory node linux,codec_mm_cma Apr 20 18:28:29 volumio kernel: codec_mm codec_mm: assigned reserved memory node linux,codec_mm_cma Apr 20 18:28:29 volumio kernel: codec_mm_probe ok Apr 20 18:28:29 volumio kernel: cvbs_out: cvbsout_probe, cpu_id:7,name:meson-sm1-cvbsout Apr 20 18:28:29 volumio kernel: cvbs_out: clk path:0 Apr 20 18:28:29 volumio kernel: cvbs_out: error: failed to get vdac_config Apr 20 18:28:29 volumio kernel: vout: vout1: register server: cvbs_vout_server Apr 20 18:28:29 volumio kernel: cvbs_out: register cvbs module server ok Apr 20 18:28:29 volumio kernel: vout: vout2: register server: cvbs_vout2_server Apr 20 18:28:29 volumio kernel: cvbs_out: register cvbs module vout2 server ok Apr 20 18:28:29 volumio kernel: cvbs_out: chrdev devno 264241152 for disp Apr 20 18:28:29 volumio kernel: cvbs_out: create cdev cvbs Apr 20 18:28:29 volumio kernel: cvbs_out: cvbsout_probe OK Apr 20 18:28:29 volumio kernel: SCSI subsystem initialized Apr 20 18:28:29 volumio kernel: usbcore: registered new interface driver usbfs Apr 20 18:28:29 volumio kernel: usbcore: registered new interface driver hub Apr 20 18:28:29 volumio kernel: usbcore: registered new device driver usb Apr 20 18:28:29 volumio kernel: media: Linux media interface: v0.10 Apr 20 18:28:29 volumio kernel: Linux video capture interface: v2.00 Apr 20 18:28:29 volumio kernel: pps_core: LinuxPPS API ver. 1 registered Apr 20 18:28:29 volumio kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Apr 20 18:28:29 volumio kernel: PTP clock support registered Apr 20 18:28:29 volumio kernel: dmi: Firmware registration failed. Apr 20 18:28:29 volumio kernel: secmon: reserve_mem_size:0x300000 Apr 20 18:28:29 volumio kernel: secmon secmon: assigned reserved memory node linux,secmon Apr 20 18:28:29 volumio kernel: secmon: get page:ffffffbf00140000, 5000 Apr 20 18:28:29 volumio kernel: secmon: share in base: 0xffffffc0050fe000, share out base: 0xffffffc0050ff000 Apr 20 18:28:29 volumio kernel: secmon: phy_in_base: 0x50fe000, phy_out_base: 0x50ff000 Apr 20 18:28:29 volumio kernel: hdmitx: system: amhdmitx_probe start Apr 20 18:28:29 volumio kernel: hdmitx: system: Ver: 20190815 Apr 20 18:28:29 volumio kernel: hdmitx: system: hdmitx_device.chip_type : 12 Apr 20 18:28:29 volumio kernel: hdmitx: system: not find match pwr-ctl Apr 20 18:28:29 volumio kernel: hdmitx: system: not find drm_amhdmitx Apr 20 18:28:29 volumio kernel: hdmitx: system: hpd irq = 37 Apr 20 18:28:29 volumio kernel: hdmitx: system: hdcp22_tx_skp failed to probe Apr 20 18:28:29 volumio kernel: hdmitx: system: hdcp22_tx_esm failed to probe Apr 20 18:28:29 volumio kernel: vout: vout1: register server: hdmitx_vout_server Apr 20 18:28:29 volumio kernel: vout: vout2: register server: hdmitx_vout2_server Apr 20 18:28:29 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xffd00000 Apr 20 18:28:29 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xff634400 Apr 20 18:28:29 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xff900000 Apr 20 18:28:29 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xff800000 Apr 20 18:28:29 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xff63c000 Apr 20 18:28:29 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xffd00000 Apr 20 18:28:29 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xff608000 Apr 20 18:28:29 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xff600000 Apr 20 18:28:29 volumio kernel: hdmitx: hdmitx20: Mapped PHY: 0xffe01000 Apr 20 18:28:29 volumio kernel: hdmitx: hw: alread display in uboot 0x10 Apr 20 18:28:29 volumio kernel: hdmitx: hw: avmute set to 1 Apr 20 18:28:29 volumio kernel: hdmitx: system: fmt_attr 444,8bit Apr 20 18:28:29 volumio kernel: hdmitx: system: fmt_attr 444,8bit Apr 20 18:28:29 volumio kernel: hdmitx: system: amhdmitx_probe end Apr 20 18:28:29 volumio kernel: vout: create vout attribute OK Apr 20 18:28:29 volumio kernel: vout: vout_fops_create OK Apr 20 18:28:29 volumio kernel: vout: vout1: register server: nulldisp_vout_server Apr 20 18:28:29 volumio kernel: vout: tvout monitor interval:500(ms), timeout cnt:20 Apr 20 18:28:29 volumio kernel: hdmitx: hdmitx_set_current_vmode[3877] Apr 20 18:28:29 volumio kernel: hdmitx: system: recalc before 1080p60hz 60 1 Apr 20 18:28:29 volumio kernel: hdmitx: system: recalc after 1080p60hz 2997 50 Apr 20 18:28:29 volumio kernel: hdmitx: alread display in uboot Apr 20 18:28:29 volumio kernel: vout: init mode 1080p60hz set ok Apr 20 18:28:29 volumio kernel: vout: aml_tvout_mode_monitor Apr 20 18:28:29 volumio kernel: vout: aml_vout_probe OK Apr 20 18:28:29 volumio kernel: chip type:0x2b Apr 20 18:28:29 volumio kernel: MEMORY:[0+ed800000] Apr 20 18:28:29 volumio kernel: ramdump_probe, storage device:data Apr 20 18:28:29 volumio kernel: NO valid ramdump args:0 0 Apr 20 18:28:29 volumio kernel: ramdump_probe, set sticky to 8ed8 Apr 20 18:28:29 volumio kernel: Advanced Linux Sound Architecture Driver Initialized. Apr 20 18:28:29 volumio kernel: NetLabel: Initializing Apr 20 18:28:29 volumio kernel: NetLabel: domain hash size = 128 Apr 20 18:28:29 volumio kernel: NetLabel: protocols = UNLABELED CIPSOv4 Apr 20 18:28:29 volumio kernel: NetLabel: unlabeled traffic allowed by default Apr 20 18:28:29 volumio kernel: clocksource: Switched to clocksource arch_sys_counter Apr 20 18:28:29 volumio kernel: VFS: Disk quotas dquot_6.6.0 Apr 20 18:28:29 volumio kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 20 18:28:29 volumio kernel: NET: Registered protocol family 2 Apr 20 18:28:29 volumio kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes) Apr 20 18:28:29 volumio kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes) Apr 20 18:28:29 volumio kernel: TCP: Hash tables configured (established 32768 bind 32768) Apr 20 18:28:29 volumio kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes) Apr 20 18:28:29 volumio kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes) Apr 20 18:28:29 volumio kernel: NET: Registered protocol family 1 Apr 20 18:28:29 volumio kernel: PCI: CLS 0 bytes, default 64 Apr 20 18:28:29 volumio kernel: Unpacking initramfs... Apr 20 18:28:29 volumio kernel: Freeing initrd memory: 8420K Apr 20 18:28:29 volumio kernel: hw perfevents: clusterb_enabled = 0 Apr 20 18:28:29 volumio kernel: hw perfevents: cpumasks 0xf, 0x0 Apr 20 18:28:29 volumio kernel: hw perfevents: cluster A irq = 18 Apr 20 18:28:29 volumio kernel: hw perfevents: enabled with armv8_pmuv3 PMU driver, 7 counters available Apr 20 18:28:29 volumio kernel: audit: initializing netlink subsys (disabled) Apr 20 18:28:29 volumio kernel: audit: type=2000 audit(0.836:1): initialized Apr 20 18:28:29 volumio kernel: workingset: timestamp_bits=46 max_order=20 bucket_order=0 Apr 20 18:28:29 volumio kernel: Registering sdcardfs 0.1 Apr 20 18:28:29 volumio kernel: SELinux: Registering netfilter hooks Apr 20 18:28:29 volumio kernel: NET: Registered protocol family 38 Apr 20 18:28:29 volumio kernel: Key type asymmetric registered Apr 20 18:28:29 volumio kernel: Asymmetric key parser 'x509' registered Apr 20 18:28:29 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 243) Apr 20 18:28:29 volumio kernel: io scheduler noop registered (default) Apr 20 18:28:29 volumio kernel: io scheduler deadline registered Apr 20 18:28:29 volumio kernel: io scheduler cfq registered Apr 20 18:28:29 volumio kernel: meson-pwm ff802000.pwm: pwm pinmux : can't get pinctrl Apr 20 18:28:29 volumio kernel: random: fast init done Apr 20 18:28:29 volumio kernel: random: crng init done Apr 20 18:28:29 volumio kernel: gpiomem-aml ff634000.gpiomem: Initialised: GPIO register area is 2 Apr 20 18:28:29 volumio kernel: gpiomem-aml ff634000.gpiomem: Initialised: Registers at start:0xff634000 end:0xff634fff size:0x00000fff Apr 20 18:28:29 volumio kernel: gpiomem-aml ff634000.gpiomem: Initialised: Registers at start:0xff800000 end:0xff800fff size:0x00000fff Apr 20 18:28:29 volumio kernel: [drm] Initialized Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: Continuing without Mali regulator control Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: max pp is 2 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: set min pp to default 1 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: min pp is 1 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: min clk is 4 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: hiu io source 0xffffff80084d9000 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: hiu io source 0xffffff80084db000 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: num of pp used most of time 1 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: clock dvfs cfg table size is 6 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: max clk set 4 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: max clk is 4 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: turbo clk set to 5 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: turbo clk is 5 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: default clk set to 4 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: default clk is 4 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: ====================0==================== clk_freq= 285714285, clk_parent=fclk_div7, voltage=1150, keep_count=5, threshod=<100 190>, clk_sample=285 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: ====================1==================== clk_freq= 400000000, clk_parent=fclk_div5, voltage=1150, keep_count=5, threshod=<152 207>, clk_sample=400 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: ====================2==================== clk_freq= 500000000, clk_parent=fclk_div4, voltage=1150, keep_count=5, threshod=<180 220>, clk_sample=500 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: ====================3==================== clk_freq= 666666666, clk_parent=fclk_div3, voltage=1150, keep_count=5, threshod=<210 236>, clk_sample=666 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: ====================4==================== clk_freq= 846000000, clk_parent= gp0_pll, voltage=1150, keep_count=5, threshod=<230 255>, clk_sample=846 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: ====================5==================== clk_freq= 846000000, clk_parent= gp0_pll, voltage=1150, keep_count=5, threshod=<230 255>, clk_sample=846 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: clock dvfs table size is 6 Apr 20 18:28:29 volumio kernel: mali_plat=ffffff800a6b8250 Apr 20 18:28:29 volumio kernel: meson_gcooldev is null, no set min status Apr 20 18:28:29 volumio kernel: gpu cooling register okay with err=0 Apr 20 18:28:29 volumio kernel: meson_gcooldev is null, no set min status Apr 20 18:28:29 volumio kernel: gpu core cooling register okay with err=0 Apr 20 18:28:29 volumio kernel: shader_present=1, tiler_present=1, l2_present=1 Apr 20 18:28:29 volumio kernel: Mali_pwr_on:gpu_irq : 200 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: GPU identified as 0x3 arch 7.0.9 r0p0 status 0 Apr 20 18:28:29 volumio kernel: mali ffe40000.bifrost: Probed as mali0 Apr 20 18:28:29 volumio kernel: Unable to detect cache hierarchy for CPU 0 Apr 20 18:28:29 volumio kernel: loop: module loaded Apr 20 18:28:29 volumio kernel: mtdoops: mtd device (mtddev=name/number) must be supplied Apr 20 18:28:29 volumio kernel: libphy: Fixed MDIO Bus: probed Apr 20 18:28:29 volumio kernel: REG0:Addr = ffffff80084f1540 Apr 20 18:28:29 volumio kernel: ee eth reset:Addr = ffffff80084f3008 Apr 20 18:28:29 volumio kernel: read auto_cali_idx fail Apr 20 18:28:29 volumio kernel: Not set cali_val for REG1 Apr 20 18:28:29 volumio kernel: meson6-dwmac ff3f0000.ethernet: no reset control found Apr 20 18:28:29 volumio kernel: stmmac - user ID: 0x11, Synopsys ID: 0x37 Apr 20 18:28:29 volumio kernel: Ring mode enabled Apr 20 18:28:29 volumio kernel: DMA HW capability register supported Apr 20 18:28:29 volumio kernel: Normal descriptors Apr 20 18:28:29 volumio kernel: RX Checksum Offload Engine supported Apr 20 18:28:29 volumio kernel: COE Type 2 Apr 20 18:28:29 volumio kernel: TX Checksum insertion supported Apr 20 18:28:29 volumio kernel: Wake-Up On Lan supported Apr 20 18:28:29 volumio kernel: eth%d: device MAC address 00:1e:06:48:e6:46 Apr 20 18:28:29 volumio kernel: Enable RX Mitigation via HW Watchdog Timer Apr 20 18:28:29 volumio kernel: libphy: stmmac: probed Apr 20 18:28:29 volumio kernel: eth%d: PHY ID 001cc916 at 0 IRQ POLL (stmmac-0:00) active Apr 20 18:28:29 volumio kernel: eth%d: PHY ID 001cc916 at 7 IRQ POLL (stmmac-0:07) Apr 20 18:28:29 volumio kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Apr 20 18:28:29 volumio kernel: ehci-pci: EHCI PCI platform driver Apr 20 18:28:29 volumio kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Apr 20 18:28:29 volumio kernel: usbcore: registered new interface driver cdc_acm Apr 20 18:28:29 volumio kernel: cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters Apr 20 18:28:29 volumio kernel: usbcore: registered new interface driver usb-storage Apr 20 18:28:29 volumio kernel: usbcore: registered new interface driver usbserial Apr 20 18:28:29 volumio kernel: usbcore: registered new interface driver usbserial_generic Apr 20 18:28:29 volumio kernel: usbserial: USB Serial support registered for generic Apr 20 18:28:29 volumio kernel: mousedev: PS/2 mouse device common for all mice Apr 20 18:28:29 volumio kernel: i2c /dev entries driver Apr 20 18:28:29 volumio kernel: lirc_helper: wakeupkey 0xffffffff, protocol 0x1 Apr 20 18:28:29 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Apr 20 18:28:29 volumio kernel: hidraw: raw HID events driver (C) Jiri Kosina Apr 20 18:28:29 volumio kernel: usbcore: registered new interface driver usbhid Apr 20 18:28:29 volumio kernel: usbhid: USB HID core driver Apr 20 18:28:29 volumio kernel: value of voltage_tolerance 0 Apr 20 18:28:29 volumio kernel: meson_cpufreq_init:don't find the node Apr 20 18:28:29 volumio kernel: value of gp1_clk_target 0 Apr 20 18:28:29 volumio kernel: dvfs [meson_cpufreq_init] - cluster 0 freq 2016000 Apr 20 18:28:29 volumio kernel: dvfs [meson_cpufreq_init] - cluster 0 freq 2100000 Apr 20 18:28:29 volumio kernel: cpu cpu0: meson_cpufreq_init: CPU 0 initialized Apr 20 18:28:29 volumio kernel: ff803000.serial: clock gate not found Apr 20 18:28:29 volumio kernel: meson_uart ff803000.serial: ==uart0 reg addr = ffffff80084f9000 Apr 20 18:28:29 volumio kernel: ff803000.serial: ttyS0 at MMIO 0xff803000 (irq = 26, base_baud = 1500000) is a meson_uart Apr 20 18:28:29 volumio kernel: meson_uart ff803000.serial: ttyS0 use xtal(24M) 24000000 change 0 to 115200 Apr 20 18:28:29 volumio kernel: console [ttyS0] enabled Apr 20 18:28:29 volumio kernel: amlogic-new-usb2-v2 ffe09000.usb2phy: USB2 phy probe:phy_mem:0xffe09000, iomap phy_base:0xffffff80084fb000 Apr 20 18:28:29 volumio kernel: amlogic-new-usb3-v2 ffe09080.usb3phy: USB3 phy probe:phy_mem:0xffe09080, iomap phy_base:0xffffff8008629080 Apr 20 18:28:29 volumio kernel: aml_dma ff63e000.aml_dma: Aml dma Apr 20 18:28:29 volumio kernel: aml_aes_dma ff63e000.aml_dma:aml_aes: Aml AES_dma Apr 20 18:28:29 volumio kernel: aml_sha_dma ff63e000.aml_dma:aml_sha: Aml SHA1/SHA224/SHA256 dma Apr 20 18:28:29 volumio kernel: gpio-keypad: probe of ff800000.gpio_keypad failed with error -22 Apr 20 18:28:29 volumio kernel: efusekeynum: 1 Apr 20 18:28:29 volumio kernel: efusekeyname: uuid offset: 0 size: 32 Apr 20 18:28:29 volumio kernel: efuse efuse: probe OK! Apr 20 18:28:29 volumio kernel: ion_dev soc:ion_dev: assigned reserved memory node linux,ion-dev Apr 20 18:28:29 volumio kernel: ge2d: ge2d_init_module Apr 20 18:28:29 volumio kernel: ge2d: ge2d_dev major:236 Apr 20 18:28:29 volumio kernel: ge2d: clock source clk_ge2d_gate ffffffc049086dc0 Apr 20 18:28:29 volumio kernel: ge2d: clock clk_ge2d source ffffffc049086e40 Apr 20 18:28:29 volumio kernel: ge2d: clock source clk_vapb_0 ffffffc049086ec0 Apr 20 18:28:29 volumio kernel: ge2d: ge2d init clock is 500000000 HZ, VPU clock is 666666656 HZ Apr 20 18:28:29 volumio kernel: ge2d: ge2d clock is 499 MHZ Apr 20 18:28:29 volumio kernel: ge2d: find address resource Apr 20 18:28:29 volumio kernel: ge2d: map io source 0x00000000ff940000,size=65536 to 0xffffff8008690000 Apr 20 18:28:29 volumio kernel: ge2d: reserved mem init failed Apr 20 18:28:29 volumio kernel: ge2d: ge2d: pdev=ffffffc0ca1e4c00, irq=41, clk=ffffffc049086dc0 Apr 20 18:28:29 volumio kernel: ge2d: ge2d start monitor Apr 20 18:28:29 volumio kernel: ge2d: ge2d workqueue monitor start Apr 20 18:28:29 volumio kernel: [tsync_pcr_init]init success. Apr 20 18:28:29 volumio kernel: amvideom vsync irq: 42 Apr 20 18:28:29 volumio kernel: create_ge2d_work_queue video task ok Apr 20 18:28:29 volumio kernel: fb: osd_init_module Apr 20 18:28:29 volumio kernel: fb: viu vsync irq: 42 Apr 20 18:28:29 volumio kernel: fb: viu2 vsync irq: 58 Apr 20 18:28:29 volumio kernel: 0x000000db:Y=db,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000dc:Y=dc,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000dd:Y=dd,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000de:Y=de,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000df:Y=df,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000e0:Y=e0,U=0,V=0 Apr 20 18:28:29 volumio kernel: fb: osd_rdma_init: rdma_table p=0xcf608000,op=0xcf608000 , v=0xffffff8008635000 Apr 20 18:28:29 volumio kernel: rdma_register, rdma_table_addr ffffff8008637000 rdma_table_addr_phy cf609000 reg_buf ffffffc0ca325000 Apr 20 18:28:29 volumio kernel: rdma_register success, handle 2 table_size 4096 Apr 20 18:28:29 volumio kernel: fb: osd_rdma_init:osd rdma handle = 2. Apr 20 18:28:29 volumio kernel: fb: mem_size: 0x800000 Apr 20 18:28:29 volumio kernel: fb: mem_size: 0x4b80000 Apr 20 18:28:29 volumio kernel: fb: mem_size: 0x100000 Apr 20 18:28:29 volumio kernel: fb: mem_size: 0x100000 Apr 20 18:28:29 volumio kernel: fb: mem_size: 0x800000 Apr 20 18:28:29 volumio kernel: fb: failed to init reserved memory Apr 20 18:28:29 volumio kernel: fb: fb def : 1920 1080 1920 2160 32 Apr 20 18:28:29 volumio kernel: fb: init fbdev bpp is:32 Apr 20 18:28:29 volumio kernel: fb: malloc_osd_memory, cma:ffffff800a8a1b30 Apr 20 18:28:29 volumio kernel: fb: malloc_osd_memory, 1231, base:0x00000000cf400000, size:8388608 Apr 20 18:28:29 volumio kernel: fb: use ion buffer for fb memory, fb_index=0 Apr 20 18:28:29 volumio kernel: fb: OSD0 as afbcd mode,afbc_type=2 Apr 20 18:28:29 volumio kernel: meson-fb meson-fb: create ion_client ffffffc049170600, handle=ffffffc0c9ea8300 Apr 20 18:28:29 volumio kernel: meson-fb meson-fb: ion memory(0): created fb at 0x00000000e5800000, size 75 MiB Apr 20 18:28:29 volumio kernel: fb: 0, phy: 0x00000000e5800000, vir:0xffffff800a94f000, size=77312K Apr 20 18:28:29 volumio kernel: fb: Frame buffer memory assigned at Apr 20 18:28:29 volumio kernel: fb: 0, phy: 0x00000000e5800000, vir:0xffffff800a94f000, size=77312K Apr 20 18:28:29 volumio kernel: fb: 0, phy: 0x00000000e5800000, vir:0xffffff800a94f000, size=77312K Apr 20 18:28:29 volumio kernel: fb: logo_index=0,fb_index=0 Apr 20 18:28:29 volumio kernel: fb: ---------------clear fb0 memory ffffff800a94f000 Apr 20 18:28:29 volumio kernel: fb: osd[0] canvas.idx =0x40 Apr 20 18:28:29 volumio kernel: fb: osd[0] canvas.addr=0xe5800000 Apr 20 18:28:29 volumio kernel: fb: osd[0] canvas.width=7680 Apr 20 18:28:29 volumio kernel: fb: osd[0] canvas.height=2160 Apr 20 18:28:29 volumio kernel: fb: osd[0] frame.width=1920 Apr 20 18:28:29 volumio kernel: fb: osd[0] frame.height=1080 Apr 20 18:28:29 volumio kernel: fb: osd[0] out_addr_id =0x1 Apr 20 18:28:29 volumio kernel: Console: switching to colour frame buffer device 240x67 Apr 20 18:28:29 volumio kernel: fb: osd[0] enable: 1 (swapper/0) Apr 20 18:28:29 volumio kernel: fb: set osd0 reverse as NONE Apr 20 18:28:29 volumio kernel: fb: osd probe OK Apr 20 18:28:29 volumio kernel: hdmitx: hdcp: hdmitx_hdcp_init Apr 20 18:28:29 volumio kernel: vout: vout2: create vout2 attribute OK Apr 20 18:28:29 volumio kernel: vout: vout2: vout2_fops_create OK Apr 20 18:28:29 volumio kernel: vout: vout2: clktree_init Apr 20 18:28:29 volumio kernel: vout: vout2: register server: nulldisp_vout2_server Apr 20 18:28:29 volumio kernel: vout: vout2: init mode null set ok Apr 20 18:28:29 volumio kernel: vout: vout2: aml_vout2_probe OK Apr 20 18:28:29 volumio kernel: DI: di_module_init ok. Apr 20 18:28:29 volumio kernel: DI: di_probe: Apr 20 18:28:29 volumio kernel: DI: di_probe: major 510 Apr 20 18:28:29 volumio kernel: deinterlace deinterlace: assigned reserved memory node linux,di_cma Apr 20 18:28:29 volumio kernel: di:flag_cma=1 Apr 20 18:28:29 volumio kernel: DI: CMA size 0x2800000. Apr 20 18:28:29 volumio kernel: pre_irq:56 Apr 20 18:28:29 volumio kernel: post_irq:57 Apr 20 18:28:29 volumio kernel: DI: di_probe allocate rdma channel 0. Apr 20 18:28:29 volumio kernel: di_get_vpu_clkb: get clk vpu error. Apr 20 18:28:29 volumio kernel: DI: vpu clkb <334000000, 667000000> Apr 20 18:28:29 volumio kernel: get clkb rate:333333328 Apr 20 18:28:29 volumio kernel: DI:enable vpu clkb. Apr 20 18:28:29 volumio kernel: 0x000000e1:Y=e1,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000e2:Y=e2,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000e3:Y=e3,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000f0:Y=f0,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000f1:Y=f1,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000f2:Y=f2,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000f3:Y=f3,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000f4:Y=f4,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000f5:Y=f5,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000f6:Y=f6,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000f7:Y=f7,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000f8:Y=f8,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000f9:Y=f9,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000fa:Y=fa,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000fb:Y=fb,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000fc:Y=fc,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000fd:Y=fd,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000fe:Y=fe,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x000000ff:Y=ff,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x0000003a:Y=3a,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x0000003b:Y=3b,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x0000003c:Y=3c,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x0000003d:Y=3d,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x0000003e:Y=3e,U=0,V=0 Apr 20 18:28:29 volumio kernel: 0x0000003f:Y=3f,U=0,V=0 Apr 20 18:28:29 volumio kernel: DI: support multi decoding 61~62~63. Apr 20 18:28:29 volumio kernel: DI: di_probe:Di use HRTIMER Apr 20 18:28:29 volumio kernel: DI: di_probe:ok Apr 20 18:28:29 volumio kernel: dim:dim_module_init Apr 20 18:28:29 volumio kernel: clear:c5800000, free:c5800000, tick:670707 us Apr 20 18:28:29 volumio kernel: dim:dim_module_init finish Apr 20 18:28:29 volumio kernel: dil:dil_init. Apr 20 18:28:29 volumio kernel: dil:dil_init ok. Apr 20 18:28:29 volumio kernel: vdin_drv_init: major 509 Apr 20 18:28:29 volumio kernel: vdin_drv_init: vdin driver init done Apr 20 18:28:29 volumio kernel: [viuin..]viuin_init_module viuin module init Apr 20 18:28:29 volumio kernel: [viuin..]viuin_probe probe ok. Apr 20 18:28:29 volumio kernel: [RX]-hdmirx: hdmirx_init. Apr 20 18:28:29 volumio kernel: ESM HLD: Initializing... Apr 20 18:28:29 volumio kernel: amlvid:info: amlvideo_init called Apr 20 18:28:29 volumio kernel: amlvid:info: amlvideo_create_instance called Apr 20 18:28:29 volumio kernel: amlvid:info: v4l2_dev.name=:amlvideo-000 Apr 20 18:28:29 volumio kernel: amlvideo-000: V4L2 device registered as video10 Apr 20 18:28:29 volumio kernel: amlvid:info: amlvideo_create_instance called Apr 20 18:28:29 volumio kernel: amlvid:info: v4l2_dev.name=:amlvideo-001 Apr 20 18:28:29 volumio kernel: amlvideo-001: V4L2 device registered as video23 Apr 20 18:28:29 volumio kernel: PPMGRDRV: warn: ppmgr module init func called Apr 20 18:28:29 volumio kernel: PPMGRDRV: info: ppmgr_driver_probe called Apr 20 18:28:29 volumio kernel: Reserved memory: failed to init DMA memory pool at 0x00000000ed800000, size 0 MiB Apr 20 18:28:29 volumio kernel: PPMGRDRV: info: ppmgr_dev major:507 Apr 20 18:28:29 volumio kernel: ionvideo-000: V4L2 device registered as video13 Apr 20 18:28:29 volumio kernel: ionvideo-001: V4L2 device registered as video14 Apr 20 18:28:29 volumio kernel: ionvideo-002: V4L2 device registered as video15 Apr 20 18:28:29 volumio kernel: ionvideo-003: V4L2 device registered as video16 Apr 20 18:28:29 volumio kernel: ionvideo-004: V4L2 device registered as video17 Apr 20 18:28:29 volumio kernel: ionvideo-005: V4L2 device registered as video18 Apr 20 18:28:29 volumio kernel: ionvideo-006: V4L2 device registered as video19 Apr 20 18:28:29 volumio kernel: ionvideo-007: V4L2 device registered as video20 Apr 20 18:28:29 volumio kernel: ionvideo-008: V4L2 device registered as video21 Apr 20 18:28:29 volumio kernel: ionvid: info: Video Technology Magazine Ion Video Apr 20 18:28:29 volumio kernel: ionvid: info: Capture Board ver 1.0 successfully loaded Apr 20 18:28:29 volumio kernel: videosync_create_instance dev_s ffffffc049248c00,dev_s->dev ffffffc04916ac80 Apr 20 18:28:29 volumio kernel: videosync_create_instance reg videosync.0 Apr 20 18:28:29 volumio kernel: aml_vecm_init:module init Apr 20 18:28:29 volumio kernel: videosync_thread started Apr 20 18:28:29 volumio kernel: VECM probe start Apr 20 18:28:29 volumio kernel: Can't find detect_colorbar. Apr 20 18:28:29 volumio kernel: Can't find detect_face. Apr 20 18:28:29 volumio kernel: Can't find detect_corn. Apr 20 18:28:29 volumio kernel: Can't find wb_sel. Apr 20 18:28:29 volumio kernel: hdr:Can't find cfg_en_osd_100. Apr 20 18:28:29 volumio kernel: amlogic, vecm Apr 20 18:28:29 volumio kernel: vlock dt support: 1 Apr 20 18:28:29 volumio kernel: vlock dt new_fsm: 0 Apr 20 18:28:29 volumio kernel: vlock dt hwver: 0 Apr 20 18:28:29 volumio kernel: vlock dt phlock_en: 0 Apr 20 18:28:29 volumio kernel: Can't find vlock_en. Apr 20 18:28:29 volumio kernel: Can't find vlock_mode. Apr 20 18:28:29 volumio kernel: Can't find vlock_pll_m_limit. Apr 20 18:28:29 volumio kernel: Can't find vlock_line_limit. Apr 20 18:28:29 volumio kernel: param_config vlock_en:1 md=0x4 Apr 20 18:28:29 volumio kernel: vlock: maxLine 524,maxPixel 1715 Apr 20 18:28:29 volumio kernel: vlock_status_init vlock_en:1 Apr 20 18:28:29 volumio kernel: aml_vecm_probe: ok Apr 20 18:28:29 volumio kernel: amdolby_vision_init:module init Apr 20 18:28:29 volumio kernel: amdolby_vision probe start & ver: 20181220 Apr 20 18:28:29 volumio kernel: cpu_id=2 tvmode=0 Apr 20 18:28:29 volumio kernel: dolby_vision_init_receiver(dvel) Apr 20 18:28:29 volumio kernel: dolby_vision_init_receiver: dvel Apr 20 18:28:29 volumio kernel: amdolby_vision_probe: ok Apr 20 18:28:29 volumio kernel: dovi disable in uboot Apr 20 18:28:29 volumio kernel: meson-mmc: mmc driver version: 3.02, 2017-05-15: New Emmc Host Controller Apr 20 18:28:29 volumio kernel: meson-mmc: >>>>>>>>hostbase ffffff80086b7000, dmode Apr 20 18:28:29 volumio kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Apr 20 18:28:29 volumio kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000033c Apr 20 18:28:29 volumio kernel: hdmitx: hdmitx_set_drm_pkt: tf=1, cf=1, colormetry=0 Apr 20 18:28:29 volumio kernel: meson-mmc: meson_mmc_probe() : success! Apr 20 18:28:29 volumio kernel: meson-mmc: emmc: resp_timeout,vstat:0xa1ff2800,virqc:3fff Apr 20 18:28:29 volumio kernel: meson-mmc: emmc: err: wait for irq service, bus_fsm:0x8 Apr 20 18:28:29 volumio kernel: meson-mmc: meson_mmc_irq_thread_v3() 653: set 1st retry! Apr 20 18:28:29 volumio kernel: meson-mmc: retry cmd 1 the 3-th time(s) Apr 20 18:28:29 volumio kernel: meson-mmc: >>>>>>>>hostbase ffffff80086c0000, dmode Apr 20 18:28:29 volumio kernel: meson-mmc: gpio_cd = 1ca Apr 20 18:28:29 volumio kernel: meson-mmc: emmc: resp_timeout,vstat:0xa1ff2800,virqc:3fff Apr 20 18:28:29 volumio kernel: meson-mmc: emmc: err: wait for irq service, bus_fsm:0x8 Apr 20 18:28:29 volumio kernel: meson-mmc: retry cmd 1 the 2-th time(s) Apr 20 18:28:29 volumio kernel: meson-mmc: emmc: resp_timeout,vstat:0x9dff0800,virqc:3fff Apr 20 18:28:29 volumio kernel: meson-mmc: emmc: err: wait for desc write back, bus_fsm:0x7 Apr 20 18:28:29 volumio kernel: meson-mmc: retry cmd 1 the 1-th time(s) Apr 20 18:28:29 volumio kernel: meson-mmc: emmc: resp_timeout,vstat:0x9dff0800,virqc:3fff Apr 20 18:28:29 volumio kernel: meson-mmc: emmc: err: wait for desc write back, bus_fsm:0x7 Apr 20 18:28:29 volumio kernel: meson-mmc: Command retried failed line:675, cmd:1 Apr 20 18:28:29 volumio kernel: meson-mmc: meson_mmc_probe() : success! Apr 20 18:28:29 volumio kernel: cectx ff80023c.aocec: cec driver date:2019/10/22: finetune ARB rising time Apr 20 18:28:29 volumio kernel: cectx ff80023c.aocec: compatible:amlogic, aocec-sm1 Apr 20 18:28:29 volumio kernel: cectx ff80023c.aocec: cecb_ver:0x2 Apr 20 18:28:29 volumio kernel: cectx ff80023c.aocec: line_reg:0x1 Apr 20 18:28:29 volumio kernel: cectx ff80023c.aocec: line_bit:0x3 Apr 20 18:28:29 volumio kernel: cectx ff80023c.aocec: ee_to_ao:0x1 Apr 20 18:28:29 volumio kernel: input: cec_input as /devices/virtual/input/input0 Apr 20 18:28:29 volumio kernel: cectx ff80023c.aocec: not find 'port_num' Apr 20 18:28:29 volumio kernel: cectx ff80023c.aocec: using cec:1 Apr 20 18:28:29 volumio kernel: cectx ff80023c.aocec: no hdmirx regs Apr 20 18:28:29 volumio kernel: cectx ff80023c.aocec: no hhi regs Apr 20 18:28:29 volumio kernel: irq cnt:2, a:40, b39 Apr 20 18:28:29 volumio kernel: cectx ff80023c.aocec: wakeup_reason:0x0 Apr 20 18:28:29 volumio kernel: cectx ff80023c.aocec: cev val1: 0x0;val2: 0x0 Apr 20 18:28:29 volumio kernel: cectx ff80023c.aocec: aml_cec_probe success end Apr 20 18:28:29 volumio kernel: unifykey: storage in base: 0xffffffc005000000 Apr 20 18:28:29 volumio kernel: unifykey: storage out base: 0xffffffc005040000 Apr 20 18:28:29 volumio kernel: unifykey: storage block base: 0xffffffc005080000 Apr 20 18:28:29 volumio kernel: unifykey: probe done! Apr 20 18:28:29 volumio kernel: unifykey: no efuse-version set, use default value: -1 Apr 20 18:28:29 volumio kernel: unifykey: key unify config unifykey-num is 14 Apr 20 18:28:29 volumio kernel: unifykey: key unify fact unifykey-num is 17 Apr 20 18:28:29 volumio kernel: unifykey: unifykey_devno: 1f600000 Apr 20 18:28:29 volumio kernel: unifykey: device unifykeys created ok Apr 20 18:28:29 volumio kernel: unifykey: aml_unifykeys_init done! Apr 20 18:28:29 volumio kernel: meson ts init Apr 20 18:28:29 volumio kernel: tsensor id: 0 Apr 20 18:28:29 volumio kernel: r1p1_tsensor_read valid cnt is 0 Apr 20 18:28:29 volumio kernel: tsensor trim info: 0xfa008005! Apr 20 18:28:29 volumio kernel: tsensor hireboot: 0xc0ff2a80 Apr 20 18:28:29 volumio kernel: meson ts init Apr 20 18:28:29 volumio kernel: tsensor id: 1 Apr 20 18:28:29 volumio kernel: r1p1_tsensor_read valid cnt is 0 Apr 20 18:28:29 volumio kernel: tsensor trim info: 0xfa008003! Apr 20 18:28:29 volumio kernel: tsensor hireboot: 0xc0ff2a80 Apr 20 18:28:29 volumio kernel: audio_dsp: [dsp]register dsp to char divece(257) Apr 20 18:28:29 volumio kernel: amaudio: amaudio: driver amaudio init! Apr 20 18:28:29 volumio kernel: amaudio: amaudio_init - amaudio: driver amaudio succuess! Apr 20 18:28:29 volumio kernel: amlkaraoke init success! Apr 20 18:28:29 volumio kernel: sysled: module init Apr 20 18:28:29 volumio kernel: meson_wdt ffd0f0d0.watchdog: start watchdog Apr 20 18:28:29 volumio kernel: meson_wdt ffd0f0d0.watchdog: creat work queue for watch dog Apr 20 18:28:29 volumio kernel: meson_wdt ffd0f0d0.watchdog: AML Watchdog Timer probed done Apr 20 18:28:29 volumio kernel: meson-saradc ff809000.saradc: set delay per tick to <1ms> by default. Apr 20 18:28:29 volumio kernel: meson-saradc ff809000.saradc: set ticks per period to <1> by default. Apr 20 18:28:29 volumio kernel: dmc_monitor_probe Apr 20 18:28:29 volumio kernel: atv_demod: aml_atvdemod_init: OK, atv demod version: V2.15. Apr 20 18:28:29 volumio kernel: defendkey ff630218.defendkey: Reserved memory is not enough! Apr 20 18:28:29 volumio kernel: defendkey: probe of ff630218.defendkey failed with error -22 Apr 20 18:28:29 volumio kernel: usbcore: registered new interface driver snd-usb-audio Apr 20 18:28:29 volumio kernel: Error: Driver 'spdif-dit' is already registered, aborting... Apr 20 18:28:29 volumio kernel: asoc debug: aml_audio_controller_probe-130 Apr 20 18:28:29 volumio kernel: aml_tdm_platform_probe, tdm ID = 1, lane_cnt = 8 Apr 20 18:28:29 volumio kernel: TDM id 1 i2s2hdmi:0 Apr 20 18:28:29 volumio kernel: snd_tdm ff660000.audiobus:tdm@1: lane_mask_out = 1, lane_oe_mask_out = 0 Apr 20 18:28:29 volumio kernel: TDM id 1 output clk enable:1 Apr 20 18:28:29 volumio kernel: aml_tdm_platform_probe(), share en = 1 Apr 20 18:28:29 volumio kernel: No channel mask node Channel_Mask Apr 20 18:28:29 volumio kernel: TDM id 1 tuning clk enable:1 Apr 20 18:28:29 volumio kernel: aml_spdif_platform_probe, spdif ID = 0 Apr 20 18:28:29 volumio kernel: SPDIF id 0 asrc_id:0 auto_asrc:0 Apr 20 18:28:29 volumio kernel: spdifout_play_with_zerodata, spdif id:0 enable:0 Apr 20 18:28:29 volumio kernel: spdif_a fifo ctrl, frddr:0 type:3, 32 bits, chmask 0x3, swap 0x10 Apr 20 18:28:29 volumio kernel: spdif_info: rate: 48000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0x200, ch1_r:0x200 Apr 20 18:28:29 volumio kernel: hdmitx: audio: aout notify format CT_PCM Apr 20 18:28:29 volumio kernel: spdif_a is set to enable Apr 20 18:28:29 volumio kernel: aml_spdif_platform_probe, register soc platform Apr 20 18:28:29 volumio kernel: audio-ddr-manager ff660000.audiobus:ddr_manager: 0, irqs toddr 27, frddr 31 Apr 20 18:28:29 volumio kernel: audio-ddr-manager ff660000.audiobus:ddr_manager: 1, irqs toddr 28, frddr 32 Apr 20 18:28:29 volumio kernel: audio-ddr-manager ff660000.audiobus:ddr_manager: 2, irqs toddr 29, frddr 33 Apr 20 18:28:29 volumio kernel: audio-ddr-manager ff660000.audiobus:ddr_manager: 3, irqs toddr 30, frddr 34 Apr 20 18:28:29 volumio kernel: audiolocker_platform_probe Apr 20 18:28:29 volumio kernel: Register vad Apr 20 18:28:29 volumio kernel: aml_dai_spdif_probe Apr 20 18:28:29 volumio kernel: set normal 512 fs /4 fs Apr 20 18:28:29 volumio kernel: set spdifout clk:6144000, mpll:24576000 Apr 20 18:28:29 volumio kernel: get spdifout clk:6143997, mpll:24575987 Apr 20 18:28:29 volumio kernel: aml_dai_set_spdif_fmt , fmt 0x4010 Apr 20 18:28:29 volumio kernel: aml_spdif_new spdif_a, clk continuous:1 Apr 20 18:28:29 volumio kernel: spdifout_play_with_zerodata_free, spdif id:0 Apr 20 18:28:29 volumio kernel: asoc-aml-card odroid_hdmi: dit-hifi <-> SPDIF mapping ok Apr 20 18:28:29 volumio kernel: snd_card_add_kcontrols card:ffffffc04915e818 Apr 20 18:28:29 volumio kernel: effect_v2 is not init Apr 20 18:28:29 volumio kernel: Not init vad Apr 20 18:28:29 volumio kernel: Failed to add VAD controls Apr 20 18:28:29 volumio kernel: eq/drc v1 function enable Apr 20 18:28:29 volumio kernel: no node audio_effect for eq/drc info! Apr 20 18:28:29 volumio kernel: Failed to add audio effects v1 controls Apr 20 18:28:29 volumio kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 20 18:28:29 volumio kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 20 18:28:29 volumio kernel: set mclk:12288000, mpll:24576000, get mclk:12287994, mpll:24575987 Apr 20 18:28:29 volumio kernel: aml_dai_set_tdm_slot(), txmask(0x3), rxmask(0x3) Apr 20 18:28:29 volumio kernel: lanes_out_cnt(1), lanes_in_cnt(1) Apr 20 18:28:29 volumio kernel: lanes_lb_cnt(0) Apr 20 18:28:29 volumio kernel: slots(2), slot_width(32) Apr 20 18:28:29 volumio kernel: lanes_oe_out_cnt(0), lanes_oe_in_cnt(0) Apr 20 18:28:29 volumio kernel: asoc aml_dai_set_tdm_fmt, 0x4011, ffffffc0492a2c18, id(1), clksel(1) Apr 20 18:28:29 volumio kernel: master_mode(1), binv(1), finv(1) out_skew(2), in_skew(3) Apr 20 18:28:29 volumio kernel: asoc-aml-card odroid_hifi: multicodec <-> TDM-B mapping ok Apr 20 18:28:29 volumio kernel: snd_card_add_kcontrols card:ffffffc0492f0018 Apr 20 18:28:29 volumio kernel: effect_v2 is not init Apr 20 18:28:29 volumio kernel: Not init vad Apr 20 18:28:29 volumio kernel: eq/drc v1 function enable Apr 20 18:28:29 volumio kernel: no node audio_effect for eq/drc info! Apr 20 18:28:29 volumio kernel: Netfilter messages via NETLINK v0.30. Apr 20 18:28:29 volumio kernel: Initializing XFRM netlink socket Apr 20 18:28:29 volumio kernel: NET: Registered protocol family 17 Apr 20 18:28:29 volumio kernel: Key type dns_resolver registered Apr 20 18:28:29 volumio kernel: Registered swp emulation handler Apr 20 18:28:29 volumio kernel: Registered cp15_barrier emulation handler Apr 20 18:28:29 volumio kernel: Registered setend emulation handler Apr 20 18:28:29 volumio kernel: disable EAS feature Apr 20 18:28:29 volumio kernel: registered taskstats version 1 Apr 20 18:28:29 volumio kernel: dwc3 ff500000.dwc3: Configuration mismatch. dr_mode forced to host Apr 20 18:28:29 volumio kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller Apr 20 18:28:29 volumio kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 1 Apr 20 18:28:29 volumio kernel: xhci-hcd xhci-hcd.0.auto: hcc params 0x0228fe6c hci version 0x110 quirks 0x20010010 Apr 20 18:28:29 volumio kernel: xhci-hcd xhci-hcd.0.auto: irq 22, io mem 0xff500000 Apr 20 18:28:29 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002 Apr 20 18:28:29 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 20 18:28:29 volumio kernel: usb usb1: Product: xHCI Host Controller Apr 20 18:28:29 volumio kernel: usb usb1: Manufacturer: Linux 4.9.241+ xhci-hcd Apr 20 18:28:29 volumio kernel: usb usb1: SerialNumber: xhci-hcd.0.auto Apr 20 18:28:29 volumio kernel: hub 1-0:1.0: USB hub found Apr 20 18:28:29 volumio kernel: hub 1-0:1.0: 2 ports detected Apr 20 18:28:29 volumio kernel: xhci-hcd xhci-hcd.0.auto: xHCI Host Controller Apr 20 18:28:29 volumio kernel: xhci-hcd xhci-hcd.0.auto: new USB bus registered, assigned bus number 2 Apr 20 18:28:29 volumio kernel: usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. Apr 20 18:28:29 volumio kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003 Apr 20 18:28:29 volumio kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Apr 20 18:28:29 volumio kernel: usb usb2: Product: xHCI Host Controller Apr 20 18:28:29 volumio kernel: usb usb2: Manufacturer: Linux 4.9.241+ xhci-hcd Apr 20 18:28:29 volumio kernel: usb usb2: SerialNumber: xhci-hcd.0.auto Apr 20 18:28:29 volumio kernel: hub 2-0:1.0: USB hub found Apr 20 18:28:29 volumio kernel: hub 2-0:1.0: 1 port detected Apr 20 18:28:29 volumio kernel: hctosys: unable to open rtc device (rtc0) Apr 20 18:28:29 volumio kernel: dwc_otg ff400000.dwc2_a: dwc_otg_driver_probe(ffffffc0ca1a8c00) Apr 20 18:28:29 volumio kernel: dwc_otg: usb0: type: 2 speed: 0, config: 0, dma: 0, id: 0, phy: ffe09000, ctrl: 0 Apr 20 18:28:29 volumio kernel: dwc_otg ff400000.dwc2_a: base=0xffffff8008d00000 Apr 20 18:28:29 volumio kernel: dwc_otg ff400000.dwc2_a: dwc_otg_device=0xffffffc0ca0d3200 Apr 20 18:28:29 volumio kernel: dwc_otg: Core Release: 3.30a Apr 20 18:28:29 volumio kernel: dwc_otg: Setting default values for core params Apr 20 18:28:29 volumio kernel: dwc_otg: curmode: 0, host_only: 0 Apr 20 18:28:29 volumio kernel: dwc_otg ff400000.dwc2_a: DMA config: BURST_DEFAULT Apr 20 18:28:29 volumio kernel: dwc_otg: Using Buffer DMA mode Apr 20 18:28:29 volumio kernel: dwc_otg: OTG VER PARAM: 1, OTG VER FLAG: 1 Apr 20 18:28:29 volumio kernel: dwc_otg: Working on port type = SLAVE Apr 20 18:28:29 volumio kernel: dwc_otg: Dedicated Tx FIFOs mode Apr 20 18:28:29 volumio kernel: meson_cdev probe Apr 20 18:28:29 volumio kernel: thermal: read gpupp failed Apr 20 18:28:29 volumio kernel: meson_cdev index: 0 Apr 20 18:28:29 volumio kernel: thermal: read gpupp failed Apr 20 18:28:29 volumio kernel: meson_cdev index: 1 Apr 20 18:28:29 volumio kernel: cpucore_cooling_register, max_cpu_core_num:4 Apr 20 18:28:29 volumio kernel: meson_cdev index: 2 Apr 20 18:28:29 volumio kernel: thermal: read gpupp failed Apr 20 18:28:29 volumio kernel: meson_cdev index: 3 Apr 20 18:28:29 volumio kernel: find tzd id: 0 Apr 20 18:28:29 volumio kernel: find tzd id: 0 Apr 20 18:28:29 volumio kernel: meson_cdev probe done Apr 20 18:28:29 volumio kernel: gxbb_pm: enter meson_pm_probe! Apr 20 18:28:29 volumio kernel: no vddio3v3_en pin Apr 20 18:28:29 volumio kernel: pm-meson aml_pm: Can't get switch_clk81 Apr 20 18:28:29 volumio kernel: gxbb_pm: meson_pm_probe done Apr 20 18:28:29 volumio kernel: ALSA device list: Apr 20 18:28:29 volumio kernel: #0: ODROID-HDMI Apr 20 18:28:29 volumio kernel: #1: ODROID-DAC Apr 20 18:28:29 volumio kernel: meson_uart ff803000.serial: ttyS0 use xtal(24M) 24000000 change 115200 to 115200 Apr 20 18:28:29 volumio kernel: Freeing unused kernel memory: 4992K Apr 20 18:28:29 volumio kernel: meson-mmc: card IN Apr 20 18:28:29 volumio kernel: meson-mmc: normal card in Apr 20 18:28:29 volumio kernel: usb 1-1: new high-speed USB device number 2 using xhci-hcd Apr 20 18:28:29 volumio kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Apr 20 18:28:29 volumio kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000033c Apr 20 18:28:29 volumio kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Apr 20 18:28:29 volumio kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000033c Apr 20 18:28:29 volumio kernel: meson-aml-mmc ffe05000.sd: divider requested rate 100000000 != actual rate 99999999: ret=0 Apr 20 18:28:29 volumio kernel: meson-mmc: actual_clock :99999999, HHI_nand: 0x80 Apr 20 18:28:29 volumio kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000034a Apr 20 18:28:29 volumio kernel: meson-mmc: Data 1 aligned delay is 0 Apr 20 18:28:29 volumio kernel: meson-mmc: sd: clk 99999999 tuning start Apr 20 18:28:29 volumio kernel: meson-mmc: sd: adj_win: < 2 3 4 5 6 7 8 9 > Apr 20 18:28:29 volumio kernel: meson-mmc: sd: best_win_start =2, best_win_size =8 Apr 20 18:28:29 volumio kernel: meson-mmc: sd: sd_emmc_regs->gclock=0x1000034a,sd_emmc_regs->gadjust=0x62000 Apr 20 18:28:29 volumio kernel: meson-mmc: delay1:0x0, delay2:0x0 Apr 20 18:28:29 volumio kernel: sd: new ultra high speed SDR50 SDHC card at address 0007 Apr 20 18:28:29 volumio kernel: sd: clock 99999999, 4-bit-bus-width Apr 20 18:28:29 volumio kernel: mmcblk1: sd:0007 SD16G 14.4 GiB Apr 20 18:28:29 volumio kernel: mmcblk1: p1 p2 p3 Apr 20 18:28:29 volumio kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=2817 Apr 20 18:28:29 volumio kernel: usb 1-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Apr 20 18:28:29 volumio kernel: usb 1-1: Product: USB2.0 Hub Apr 20 18:28:29 volumio kernel: usb 1-1: Manufacturer: VIA Labs, Inc. Apr 20 18:28:29 volumio kernel: hub 1-1:1.0: USB hub found Apr 20 18:28:29 volumio kernel: hub 1-1:1.0: 4 ports detected Apr 20 18:28:29 volumio kernel: usb 2-1: new SuperSpeed USB device number 2 using xhci-hcd Apr 20 18:28:29 volumio kernel: usb 2-1: New USB device found, idVendor=2109, idProduct=0817 Apr 20 18:28:29 volumio kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Apr 20 18:28:29 volumio kernel: usb 2-1: Product: USB3.0 Hub Apr 20 18:28:29 volumio kernel: usb 2-1: Manufacturer: VIA Labs, Inc. Apr 20 18:28:29 volumio kernel: hub 2-1:1.0: USB hub found Apr 20 18:28:29 volumio kernel: hub 2-1:1.0: 4 ports detected Apr 20 18:28:29 volumio kernel: usb 1-1.2: new high-speed USB device number 3 using xhci-hcd Apr 20 18:28:29 volumio kernel: usb 1-1.2: New USB device found, idVendor=2357, idProduct=0109 Apr 20 18:28:29 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 20 18:28:29 volumio kernel: usb 1-1.2: Product: 802.11n NIC Apr 20 18:28:29 volumio kernel: usb 1-1.2: Manufacturer: Realtek Apr 20 18:28:29 volumio kernel: usb 1-1.2: SerialNumber: 00e04c000001 Apr 20 18:28:29 volumio kernel: usb 1-1.2: Unsupported device Apr 20 18:28:29 volumio initramfs: Booting Volumio for Odroid-C4 Apr 20 18:28:29 volumio initramfs: This script mounts rootfs RO with an overlay RW layer. Apr 20 18:28:29 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Apr 20 18:28:29 volumio kernel: fuse init (API version 7.26) Apr 20 18:28:29 volumio initramfs: IMGPART=/dev/mmcblk1p2 Apr 20 18:28:29 volumio initramfs: IMGFILE=/volumio_current.sqsh Apr 20 18:28:29 volumio initramfs: DATAPART=/dev/mmcblk1p3 Apr 20 18:28:29 volumio initramfs: BOOTPART=/dev/mmcblk1p1 Apr 20 18:28:29 volumio initramfs: BOOTCONFIG=/boot/boot.ini Apr 20 18:28:29 volumio kernel: EXT4-fs (mmcblk1p2): recovery complete Apr 20 18:28:29 volumio kernel: EXT4-fs (mmcblk1p2): mounted filesystem with ordered data mode. Opts: (null) Apr 20 18:28:29 volumio initramfs: Checking for USB updates if you did not boot from USB... Apr 20 18:28:29 volumio initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Apr 20 18:28:29 volumio kernel: vout: aml_tvout_mode_work: monitor_timeout Apr 20 18:28:29 volumio kernel: EXT4-fs (mmcblk1p3): recovery complete Apr 20 18:28:29 volumio kernel: EXT4-fs (mmcblk1p3): mounted filesystem with ordered data mode. Opts: (null) Apr 20 18:28:29 volumio initramfs: With Option: Apr 20 18:28:29 volumio initramfs: found fstab entry with UUID=2A58-C332 for /boot Apr 20 18:28:29 volumio initramfs: VOLUMIO_VERSION="3.224" Apr 20 18:28:29 volumio initramfs: Finish initramfs, continue booting Volumio Apr 20 18:28:29 volumio systemd[1]: System time before build time, advancing clock. Apr 20 18:28:29 volumio kernel: NET: Registered protocol family 10 Apr 20 18:28:29 volumio kernel: ip_tables: (C) 2000-2006 Netfilter Core Team Apr 20 18:28:29 volumio kernel: cgroup: cgroup2: unknown option "nsdelegate" Apr 20 18:28:29 volumio 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) Apr 20 18:28:29 volumio systemd[1]: Detected architecture arm64. Apr 20 18:28:29 volumio systemd[1]: Set hostname to . Apr 20 18:28:29 volumio systemd[1]: File /lib/systemd/system/systemd-journald.service:12 configures an IP firewall (IPAddressDeny=any), but the local system does not support BPF/cgroup based firewalling. Apr 20 18:28:29 volumio systemd[1]: Proceeding WITHOUT firewalling in effect! (This warning is only shown for the first loaded unit using IP firewalling.) Apr 20 18:28:29 volumio 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. Apr 20 18:28:29 volumio systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Apr 20 18:28:29 volumio systemd[1]: Created slice system-systemd\x2dfsck.slice. Apr 20 18:28:29 volumio systemd[1]: Started Forward Password Requests to Wall Directory Watch. Apr 20 18:28:29 volumio systemd[1]: Reached target System Time Synchronized. Apr 20 18:28:29 volumio kernel: RPC: Registered named UNIX socket transport module. Apr 20 18:28:29 volumio kernel: RPC: Registered udp transport module. Apr 20 18:28:29 volumio kernel: RPC: Registered tcp transport module. Apr 20 18:28:29 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Apr 20 18:28:29 volumio systemd-journald[2140]: Journal started Apr 20 18:28:29 volumio systemd-journald[2140]: Runtime journal (/run/log/journal/bdff3ba6e5504f94fce4bcf66204f225) is 7.5M, max 30.0M, 22.5M free. Apr 20 18:28:29 volumio fake-hwclock[2100]: Thu Apr 20 18:28:29 UTC 2023 Apr 20 18:28:29 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 20 18:28:29 volumio systemd[1]: Started Load Kernel Modules. Apr 20 18:28:29 volumio systemd[1]: Started Restore / save the current clock. Apr 20 18:28:29 volumio systemd[1]: Started Remount Root and Kernel File Systems. Apr 20 18:28:29 volumio systemd[1]: Mounted POSIX Message Queue File System. Apr 20 18:28:29 volumio systemd[1]: Starting Create System Users... Apr 20 18:28:29 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Apr 20 18:28:29 volumio systemd[1]: Starting Load/Save Random Seed... Apr 20 18:28:29 volumio systemd[1]: Starting Apply Kernel Variables... Apr 20 18:28:29 volumio systemd[1]: Mounting Kernel Configuration File System... Apr 20 18:28:29 volumio systemd[1]: Mounting FUSE Control File System... Apr 20 18:28:29 volumio systemd[1]: Mounted Kernel Configuration File System. Apr 20 18:28:29 volumio kernel: Removed cp15_barrier emulation handler Apr 20 18:28:29 volumio kernel: Enabled cp15_barrier support Apr 20 18:28:29 volumio systemd[1]: Started Create System Users. Apr 20 18:28:29 volumio systemd[1]: Started Load/Save Random Seed. Apr 20 18:28:29 volumio systemd[1]: Started Apply Kernel Variables. Apr 20 18:28:29 volumio systemd[1]: Mounted FUSE Control File System. Apr 20 18:28:29 volumio systemd[1]: Started udev Coldplug all Devices. Apr 20 18:28:29 volumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Apr 20 18:28:29 volumio systemd[1]: Starting Create Static Device Nodes in /dev... Apr 20 18:28:29 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Apr 20 18:28:29 volumio systemd[1]: Started Create Static Device Nodes in /dev. Apr 20 18:28:29 volumio systemd[1]: Starting udev Kernel Device Manager... Apr 20 18:28:29 volumio systemd[1]: Reached target Local File Systems (Pre). Apr 20 18:28:29 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Apr 20 18:28:29 volumio systemd-udevd[3063]: Network interface NamePolicy= disabled on kernel command line, ignoring. Apr 20 18:28:29 volumio systemd[1]: Mounting /tmp... Apr 20 18:28:29 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Apr 20 18:28:29 volumio systemd[1]: Mounting /var/log... Apr 20 18:28:29 volumio systemd[1]: Mounting /var/spool/cups... Apr 20 18:28:29 volumio systemd[1]: Started udev Kernel Device Manager. Apr 20 18:28:29 volumio systemd[1]: Mounted /tmp. Apr 20 18:28:29 volumio systemd[1]: Mounted /var/log. Apr 20 18:28:29 volumio systemd[1]: Mounted /var/spool/cups. Apr 20 18:28:29 volumio systemd[1]: Mounting /var/spool/cups/tmp... Apr 20 18:28:29 volumio systemd[1]: Starting Flush Journal to Persistent Storage... Apr 20 18:28:29 volumio systemd[1]: Starting Show Plymouth Boot Screen... Apr 20 18:28:29 volumio systemd[1]: Mounted /var/spool/cups/tmp. Apr 20 18:28:29 volumio systemd-udevd[3175]: Using default interface naming scheme 'v240'. Apr 20 18:28:29 volumio systemd[1]: Received SIGRTMIN+20 from PID 3204 (plymouthd). Apr 20 18:28:29 volumio systemd-journald[2140]: Runtime journal (/run/log/journal/bdff3ba6e5504f94fce4bcf66204f225) is 7.5M, max 30.0M, 22.5M free. Apr 20 18:28:29 volumio systemd[1]: Started Flush Journal to Persistent Storage. Apr 20 18:28:29 volumio systemd-udevd[3191]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 20 18:28:29 volumio systemd[1]: Found device /dev/ttyS0. Apr 20 18:28:29 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Apr 20 18:28:29 volumio kernel: ionvid: dbg: ionvideo open Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close!!!! Apr 20 18:28:29 volumio kernel: ionvid: dbg: ionvideo open Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close!!!! Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close Apr 20 18:28:29 volumio kernel: ionvid: dbg: ionvideo open Apr 20 18:28:29 volumio kernel: ionvid: dbg: ionvideo open Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close!!!! Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close!!!! Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close Apr 20 18:28:29 volumio kernel: ionvid: dbg: ionvideo open Apr 20 18:28:29 volumio kernel: ionvid: dbg: ionvideo open Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close!!!! Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close!!!! Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close Apr 20 18:28:29 volumio kernel: ionvid: dbg: ionvideo open Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close!!!! Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close Apr 20 18:28:29 volumio kernel: ionvid: dbg: ionvideo open Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close!!!! Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close Apr 20 18:28:29 volumio kernel: ionvid: dbg: ionvideo open Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close!!!! Apr 20 18:28:29 volumio kernel: ionvid: dbg: vidioc_close Apr 20 18:28:29 volumio systemd[1]: Condition check resulted in Huge Pages File System being skipped. Apr 20 18:28:29 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Apr 20 18:28:29 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Apr 20 18:28:29 volumio systemd[1]: Started Show Plymouth Boot Screen. Apr 20 18:28:29 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Apr 20 18:28:29 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 20 18:28:29 volumio systemd[1]: Reached target Local Encrypted Volumes. Apr 20 18:28:29 volumio systemd[1]: Reached target Paths. Apr 20 18:28:29 volumio kernel: register clk_set_setting cpu[43] Apr 20 18:28:30 volumio kernel: Registered firmware driver success. Apr 20 18:28:30 volumio kernel: Try to load video/h264_enc.bin ... Apr 20 18:28:30 volumio kernel: firmware_codec firmware_vdec: Direct firmware load for video/h264_enc.bin failed with error -2 Apr 20 18:28:30 volumio kernel: Error : -2 can't load the video/h264_enc.bin. Apr 20 18:28:30 volumio kernel: get data from fsys fail. Apr 20 18:28:30 volumio kernel: invaild file type. Apr 20 18:28:30 volumio kernel: Try to load video/video_ucode.bin ... Apr 20 18:28:30 volumio kernel: firmware_codec firmware_vdec: Direct firmware load for video/video_ucode.bin failed with error -2 Apr 20 18:28:30 volumio kernel: Error : -2 can't load the video/video_ucode.bin. Apr 20 18:28:30 volumio kernel: get data from fsys fail. Apr 20 18:28:30 volumio kernel: invaild file type. Apr 20 18:28:30 volumio systemd-udevd[3113]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 20 18:28:30 volumio systemd[1]: Found device /dev/disk/by-uuid/2A58-C332. Apr 20 18:28:30 volumio systemd[1]: Starting File System Check on /dev/disk/by-uuid/2A58-C332... Apr 20 18:28:30 volumio kernel: Amlogic A/V streaming port init Apr 20 18:28:30 volumio systemd-udevd[3175]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 20 18:28:30 volumio systemd-udevd[3175]: link_config: could not get ethtool features for eth0 Apr 20 18:28:30 volumio systemd-udevd[3175]: Could not set offload features of eth0: Device or resource busy Apr 20 18:28:30 volumio kernel: get gate demux control ok ffffffc0c92f5980 Apr 20 18:28:30 volumio kernel: get gate parser_top control ok ffffffc0c92f5780 Apr 20 18:28:30 volumio kernel: get gate vdec control ok ffffffc0c7c0a000 Apr 20 18:28:30 volumio kernel: get gate clk_81 control ok ffffffc0c7c0a080 Apr 20 18:28:30 volumio kernel: get gate clk_vdec_mux control ok ffffffc0c7c0a100 Apr 20 18:28:30 volumio kernel: get gate clk_hcodec_mux control ok ffffffc0c7c0a180 Apr 20 18:28:30 volumio kernel: get gate clk_hevc_mux control ok ffffffc0c7c0a200 Apr 20 18:28:30 volumio kernel: get gate clk_hevcb_mux control ok ffffffc0c7c0a280 Apr 20 18:28:30 volumio kernel: get gate ahbarb0 control ok ffffffc0c7c0a300 Apr 20 18:28:30 volumio kernel: get gate asyncfifo control failed (null) Apr 20 18:28:30 volumio systemd[1]: Started File System Check Daemon to report status. Apr 20 18:28:30 volumio kernel: decoder registered as /dev/video26 Apr 20 18:28:30 volumio kernel: [0] vcodec_dec decoder Apr 20 18:28:30 volumio kernel: [0] release decoder Apr 20 18:28:30 volumio kernel: usb 1-1.2: Vendor: Realtek Apr 20 18:28:30 volumio kernel: [31B blob data] Apr 20 18:28:30 volumio kernel: usb 1-1.2: Serial: Apr 20 18:28:30 volumio kernel: usb 1-1.2: rtl8192eu_parse_efuse: dumping efuse (0x200 bytes): Apr 20 18:28:30 volumio kernel: usb 1-1.2: 00: 29 81 00 7c 01 40 03 00 Apr 20 18:28:30 volumio kernel: usb 1-1.2: 08: 40 74 04 50 14 00 00 00 Apr 20 18:28:30 volumio kernel: usb 1-1.2: 10: 2a 2b 2b 2c 2c 2c 2c 2d Apr 20 18:28:30 volumio kernel: usb 1-1.2: 18: 2e 2f 2f f2 ef ef ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 20: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 28: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 30: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 38: ff ff 27 27 27 28 28 28 Apr 20 18:28:30 volumio kernel: usb 1-1.2: 40: 28 28 28 29 29 f2 ef ef Apr 20 18:28:30 volumio kernel: usb 1-1.2: 48: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 50: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 58: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 60: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 68: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 70: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 78: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 80: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 88: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 90: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 98: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: a0: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: a8: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: b0: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: b8: a1 2f 22 00 00 00 ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: c0: ff 01 00 10 00 00 00 ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: c8: 00 00 ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: d0: 57 23 09 01 e7 47 02 30 Apr 20 18:28:30 volumio kernel: usb 1-1.2: d8: de 4b f4 ca 09 0a 03 52 Apr 20 18:28:30 volumio kernel: usb 1-1.2: e0: 65 61 6c 74 65 6b 20 0e Apr 20 18:28:30 volumio kernel: usb 1-1.2: e8: 03 38 30 32 2e 31 31 6e Apr 20 18:28:30 volumio kernel: usb 1-1.2: f0: 20 4e 49 43 20 00 00 ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: f8: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 100: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 108: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 110: ff ff ff ff ff ff ff 0d Apr 20 18:28:30 volumio kernel: usb 1-1.2: 118: 03 00 05 00 30 00 00 00 Apr 20 18:28:30 volumio kernel: usb 1-1.2: 120: 00 93 ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 128: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 130: f6 a8 98 2d 03 92 98 00 Apr 20 18:28:30 volumio kernel: usb 1-1.2: 138: fc 8c 00 11 9b 44 02 0a Apr 20 18:28:30 volumio kernel: usb 1-1.2: 140: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 148: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 150: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 158: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 160: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 168: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 170: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 178: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 180: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 188: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 190: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 198: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 1a0: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 1a8: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 1b0: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 1b8: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 1c0: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 1c8: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 1d0: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 1d8: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 1e0: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 1e8: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 1f0: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: 1f8: ff ff ff ff ff ff ff ff Apr 20 18:28:30 volumio kernel: usb 1-1.2: RTL8192EU rev B (SMIC) 2T2R, TX queues 3, WiFi=1, BT=0, GPS=0, HI PA=0 Apr 20 18:28:30 volumio kernel: usb 1-1.2: RTL8192EU MAC: 30:de:4b:f4:ca:09 Apr 20 18:28:30 volumio kernel: usb 1-1.2: rtl8xxxu: Loading firmware rtlwifi/rtl8192eu_nic.bin Apr 20 18:28:30 volumio kernel: usb 1-1.2: Firmware revision 19.0 (signature 0x92e1) Apr 20 18:28:30 volumio kernel: do the RAM reset Apr 20 18:28:30 volumio systemd-fsck[3270]: fsck.fat 4.1 (2017-01-24) Apr 20 18:28:30 volumio systemd-fsck[3270]: /dev/mmcblk1p1: 43 files, 8735/30651 clusters Apr 20 18:28:30 volumio systemd[1]: Started File System Check on /dev/disk/by-uuid/2A58-C332. Apr 20 18:28:30 volumio systemd[1]: Mounting /boot... Apr 20 18:28:30 volumio systemd[1]: Mounted /boot. Apr 20 18:28:30 volumio systemd[1]: Reached target Local File Systems. Apr 20 18:28:30 volumio systemd[1]: Starting Create Volatile Files and Directories... Apr 20 18:28:30 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Apr 20 18:28:30 volumio systemd[1]: Starting Preprocess NFS configuration... Apr 20 18:28:30 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Apr 20 18:28:30 volumio systemd[1]: Starting Raise network interfaces... Apr 20 18:28:30 volumio systemd[1]: Found device /sys/subsystem/net/devices/eth0. Apr 20 18:28:30 volumio systemd[1]: plymouth-read-write.service: Succeeded. Apr 20 18:28:30 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Apr 20 18:28:30 volumio systemd[1]: Received SIGRTMIN+20 from PID 3204 (plymouthd). Apr 20 18:28:30 volumio systemd[1]: Started ifup for eth0. Apr 20 18:28:30 volumio systemd[1]: Started Create Volatile Files and Directories. Apr 20 18:28:30 volumio sh[3438]: command failed: No such device (-19) Apr 20 18:28:30 volumio ifup[3380]: command failed: No such device (-19) Apr 20 18:28:30 volumio systemd[1]: Starting RPC bind portmap service... Apr 20 18:28:30 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Apr 20 18:28:30 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Apr 20 18:28:30 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Apr 20 18:28:30 volumio systemd[1]: nfs-config.service: Succeeded. Apr 20 18:28:30 volumio systemd[1]: Started Preprocess NFS configuration. Apr 20 18:28:30 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Apr 20 18:28:30 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Apr 20 18:28:30 volumio systemd[1]: Reached target NFS client services. Apr 20 18:28:30 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Apr 20 18:28:30 volumio systemd[1]: Reached target System Initialization. Apr 20 18:28:30 volumio systemd[1]: Listening on lircd.socket. Apr 20 18:28:30 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Apr 20 18:28:30 volumio systemd[1]: Started Daily apt download activities. Apr 20 18:28:30 volumio systemd[1]: Started Daily apt upgrade and clean activities. Apr 20 18:28:30 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Apr 20 18:28:30 volumio systemd[1]: Reached target Timers. Apr 20 18:28:30 volumio systemd[1]: Listening on mpd.socket. Apr 20 18:28:30 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Apr 20 18:28:30 volumio systemd[1]: Listening on triggerhappy.socket. Apr 20 18:28:30 volumio systemd[1]: Reached target Sockets. Apr 20 18:28:30 volumio systemd[1]: Reached target Basic System. Apr 20 18:28:30 volumio ifup[3380]: command failed: No such device (-19) Apr 20 18:28:30 volumio systemd[1]: Starting Login Service... Apr 20 18:28:30 volumio systemd[1]: Started Volumio Iptables Module. Apr 20 18:28:30 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Apr 20 18:28:30 volumio systemd[1]: Starting Volumio SSH enabler... Apr 20 18:28:30 volumio systemd[1]: Started Manage Sound Card State (restore and store). Apr 20 18:28:30 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Apr 20 18:28:30 volumio alsactl[3583]: alsactl 1.1.8 daemon started Apr 20 18:28:30 volumio volumiossh[3582]: SSH file found, enabling SSH Apr 20 18:28:30 volumio systemd[1]: Starting Wireless Services... Apr 20 18:28:30 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Apr 20 18:28:30 volumio systemd[1]: Started D-Bus System Message Bus. Apr 20 18:28:30 volumio systemd[1]: Starting WPA supplicant... Apr 20 18:28:30 volumio systemd[1]: Starting Save/Restore Sound Card State... Apr 20 18:28:30 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Apr 20 18:28:30 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 20 18:28:30 volumio systemd[1]: Started volumio-remote-updater.service. Apr 20 18:28:30 volumio thd[3670]: Unable to parse trigger line: Apr 20 18:28:30 volumio thd[3670]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Apr 20 18:28:30 volumio thd[3670]: Unable to parse trigger line: Apr 20 18:28:30 volumio thd[3670]: Unable to parse trigger line: Apr 20 18:28:30 volumio systemd[1]: Starting lircd(8) initialization helper tool... Apr 20 18:28:30 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Apr 20 18:28:30 volumio systemd[1]: Starting DHCP Client Daemon... Apr 20 18:28:30 volumio volumiossh[3766]: Synchronizing state of ssh.service with SysV service script with /lib/systemd/systemd-sysv-install. Apr 20 18:28:30 volumio volumiossh[3766]: Executing: /lib/systemd/systemd-sysv-install enable ssh Apr 20 18:28:30 volumio thd[3670]: Found socket passed from systemd Apr 20 18:28:30 volumio alsactl[3662]: Found hardware: "ODROID-HDMI" "" "" "" "" Apr 20 18:28:30 volumio alsactl[3662]: Hardware is initialized using a generic method Apr 20 18:28:30 volumio alsactl[3662]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Apr 20 18:28:30 volumio alsactl[3662]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Apr 20 18:28:30 volumio alsactl[3662]: No state is present for card ODROIDDAC Apr 20 18:28:30 volumio alsactl[3662]: Found hardware: "ODROID-DAC" "" "" "" "" Apr 20 18:28:30 volumio alsactl[3662]: Hardware is initialized using a generic method Apr 20 18:28:30 volumio kernel: out of value, fixed it Apr 20 18:28:30 volumio kernel: id=0 set inskew=0 Apr 20 18:28:30 volumio alsactl[3662]: No state is present for card ODROIDDAC Apr 20 18:28:30 volumio alsactl[3583]: Found hardware: "ODROID-HDMI" "" "" "" "" Apr 20 18:28:30 volumio alsactl[3583]: Hardware is initialized using a generic method Apr 20 18:28:31 volumio alsactl[3583]: /usr/sbin/alsactl: set_control:1461Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Apr 20 18:28:31 volumio alsactl[3583]: /usr/sbin/alsactl: set_control:1461Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Apr 20 18:28:31 volumio kernel: out of value, fixed it Apr 20 18:28:31 volumio kernel: id=0 set inskew=0 Apr 20 18:28:31 volumio alsactl[3583]: No state is present for card ODROIDDAC Apr 20 18:28:31 volumio alsactl[3583]: Found hardware: "ODROID-DAC" "" "" "" "" Apr 20 18:28:31 volumio alsactl[3583]: Hardware is initialized using a generic method Apr 20 18:28:31 volumio alsactl[3583]: No state is present for card ODROIDDAC Apr 20 18:28:31 volumio dhcpcd[3759]: no interfaces have a carrier Apr 20 18:28:31 volumio dhcpcd[3759]: no interfaces have a carrier Apr 20 18:28:31 volumio dhcpcd[3759]: forked to background, child pid 3824 Apr 20 18:28:31 volumio dhcpcd[3759]: forked to background, child pid 3824 Apr 20 18:28:31 volumio kernel: meson6-dwmac ff3f0000.ethernet eth0: fail to init PTP. Apr 20 18:28:31 volumio sh[3438]: eth0: waiting for carrier Apr 20 18:28:31 volumio sh[3438]: eth0: carrier acquired Apr 20 18:28:31 volumio dhcpcd[3534]: eth0: waiting for carrier Apr 20 18:28:31 volumio dhcpcd[3534]: eth0: carrier acquired Apr 20 18:28:31 volumio dhcpcd[3824]: eth0: waiting for carrier Apr 20 18:28:31 volumio dhcpcd[3824]: eth0: carrier acquired Apr 20 18:28:31 volumio kernel: usbcore: registered new interface driver rtl8xxxu Apr 20 18:28:31 volumio sh[3438]: DUID 00:01:00:01:29:97:af:20:00:1e:06:48:e6:46 Apr 20 18:28:31 volumio sh[3438]: eth0: IAID 06:48:e6:46 Apr 20 18:28:31 volumio dhcpcd[3824]: DUID 00:01:00:01:29:97:af:20:00:1e:06:48:e6:46 Apr 20 18:28:31 volumio dhcpcd[3534]: DUID 00:01:00:01:29:97:af:20:00:1e:06:48:e6:46 Apr 20 18:28:31 volumio dhcpcd[3824]: eth0: IAID 06:48:e6:46 Apr 20 18:28:31 volumio dhcpcd[3534]: eth0: IAID 06:48:e6:46 Apr 20 18:28:31 volumio sh[3438]: eth0: adding address fe80::1c4f:b744:8719:7fea Apr 20 18:28:31 volumio sh[3438]: ipv6_addaddr1: Permission denied Apr 20 18:28:31 volumio sh[3438]: eth0: carrier lost Apr 20 18:28:31 volumio dhcpcd[3534]: eth0: adding address fe80::1c4f:b744:8719:7fea Apr 20 18:28:31 volumio dhcpcd[3824]: eth0: adding address fe80::1c4f:b744:8719:7fea Apr 20 18:28:31 volumio dhcpcd[3824]: ipv6_addaddr1: Permission denied Apr 20 18:28:31 volumio dhcpcd[3534]: ipv6_addaddr1: Permission denied Apr 20 18:28:31 volumio dhcpcd[3824]: eth0: carrier lost Apr 20 18:28:31 volumio dhcpcd[3534]: eth0: carrier lost Apr 20 18:28:31 volumio haveged[3496]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.3.0 CTV); collect: 128K Apr 20 18:28:31 volumio haveged[3496]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15012/57848 Apr 20 18:28:31 volumio haveged[3496]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00073 Apr 20 18:28:31 volumio haveged[3496]: haveged: fills: 0, generated: 0 Apr 20 18:28:31 volumio dhcpcd[3824]: wlan0: waiting for carrier Apr 20 18:28:31 volumio dhcpcd[3824]: wlan0: carrier acquired Apr 20 18:28:31 volumio dhcpcd[3824]: wlan0: IAID 4b:f4:ca:09 Apr 20 18:28:31 volumio dhcpcd[3824]: wlan0: adding address fe80::4008:9299:1558:cf2b Apr 20 18:28:31 volumio dhcpcd[3824]: ipv6_addaddr1: Permission denied Apr 20 18:28:31 volumio systemd-udevd[3175]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 20 18:28:31 volumio systemd-udevd[3180]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 0' failed with exit code 99. Apr 20 18:28:32 volumio dhcpcd[3824]: wlan0: carrier lost Apr 20 18:28:32 volumio systemd-logind[3571]: Watching system buttons on /dev/input/event0 (cec_input) Apr 20 18:28:32 volumio systemd-logind[3571]: New seat seat0. Apr 20 18:28:32 volumio kernel: nf_tables: (c) 2007-2009 Patrick McHardy Apr 20 18:28:32 volumio systemd[1]: Started triggerhappy global hotkey daemon. Apr 20 18:28:32 volumio systemd[1]: Started RPC bind portmap service. Apr 20 18:28:32 volumio systemd[1]: Started Save/Restore Sound Card State. Apr 20 18:28:32 volumio systemd[1]: Started DHCP Client Daemon. Apr 20 18:28:32 volumio kernel: nf_tables_compat: (c) 2012 Pablo Neira Ayuso Apr 20 18:28:32 volumio avahi-daemon[3730]: Found user 'avahi' (UID 111) and group 'avahi' (GID 113). Apr 20 18:28:32 volumio avahi-daemon[3730]: Successfully dropped root privileges. Apr 20 18:28:32 volumio avahi-daemon[3730]: avahi-daemon 0.7 starting up. Apr 20 18:28:32 volumio avahi-daemon[3730]: Successfully called chroot(). Apr 20 18:28:32 volumio avahi-daemon[3730]: Successfully dropped remaining capabilities. Apr 20 18:28:32 volumio avahi-daemon[3730]: No service file found in /etc/avahi/services. Apr 20 18:28:32 volumio avahi-daemon[3730]: Network interface enumeration completed. Apr 20 18:28:32 volumio avahi-daemon[3730]: Server startup complete. Host name is volumio.local. Local service cookie is 8859996. Apr 20 18:28:32 volumio systemd[1]: Started Login Service. Apr 20 18:28:32 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Apr 20 18:28:32 volumio systemd-udevd[3142]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 1' failed with exit code 99. Apr 20 18:28:32 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Apr 20 18:28:32 volumio systemd[1]: Reached target Sound Card. Apr 20 18:28:32 volumio systemd[1]: Reached target Remote File Systems (Pre). Apr 20 18:28:32 volumio systemd[1]: Reached target Remote File Systems. Apr 20 18:28:32 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Apr 20 18:28:32 volumio systemd[1]: Reached target RPC Port Mapper. Apr 20 18:28:32 volumio systemd[1]: Found device TL WN823N RTL8192EU. Apr 20 18:28:32 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Apr 20 18:28:32 volumio systemd[1]: Started ifup for wlan0. Apr 20 18:28:32 volumio systemd[1]: Started Raise network interfaces. Apr 20 18:28:32 volumio sh[3977]: wlan0=wlan0 Apr 20 18:28:32 volumio kernel: nf_conntrack version 0.5.0 (16384 buckets, 65536 max) Apr 20 18:28:32 volumio loadcpufreq[3925]: Loading cpufreq kernel modules...done (none). Apr 20 18:28:32 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Apr 20 18:28:32 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Apr 20 18:28:32 volumio systemd[1]: Started WPA supplicant. Apr 20 18:28:32 volumio wpa_supplicant[3660]: Successfully initialized wpa_supplicant Apr 20 18:28:32 volumio systemd[1]: Reached target Network. Apr 20 18:28:32 volumio systemd[1]: Starting Network Time Service... Apr 20 18:28:32 volumio systemd[1]: Starting Permit User Sessions... Apr 20 18:28:32 volumio systemd[1]: Starting Music Player Daemon... Apr 20 18:28:32 volumio systemd[1]: Reached target Network is Online. Apr 20 18:28:32 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Apr 20 18:28:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 18:28:32 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Apr 20 18:28:32 volumio systemd[1]: Starting /etc/rc.local Compatibility... Apr 20 18:28:33 volumio systemd[1]: Starting OpenBSD Secure Shell server... Apr 20 18:28:33 volumio systemd[1]: Starting Samba NMB Daemon... Apr 20 18:28:33 volumio systemd[1]: Started Permit User Sessions. Apr 20 18:28:33 volumio rc.local[4167]: Simple mixer control 'Audio hdmi-out mute',0 Apr 20 18:28:33 volumio rc.local[4167]: Capabilities: pswitch pswitch-joined Apr 20 18:28:33 volumio rc.local[4167]: Playback channels: Mono Apr 20 18:28:33 volumio rc.local[4167]: Mono: Playback [off] Apr 20 18:28:33 volumio systemd[1]: Started /etc/rc.local Compatibility. Apr 20 18:28:33 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 20 18:28:33 volumio systemd[1]: Starting Hold until boot process finishes up... Apr 20 18:28:33 volumio systemd[1]: Received SIGRTMIN+21 from PID 3204 (plymouthd). Apr 20 18:28:33 volumio systemd[1]: plymouth-start.service: Succeeded. Apr 20 18:28:33 volumio systemd[1]: plymouth-quit.service: Succeeded. Apr 20 18:28:33 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Apr 20 18:28:33 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Apr 20 18:28:33 volumio systemd[1]: Started Hold until boot process finishes up. Apr 20 18:28:33 volumio systemd[1]: Received SIGRTMIN+21 from PID 3204 (n/a). Apr 20 18:28:33 volumio ifplugd(eth0)[4333]: ifplugd 0.28 initializing. Apr 20 18:28:33 volumio systemd[1]: Started Serial Getty on ttyS0. Apr 20 18:28:33 volumio ifplugd(eth0)[4333]: Using interface eth0/00:1E:06:48:E6:46 with driver (version: Jan_2016) Apr 20 18:28:33 volumio ifplugd(eth0)[4333]: Using detection mode: SIOCETHTOOL Apr 20 18:28:33 volumio ifplugd(eth0)[4333]: Initialization complete, link beat not detected. Apr 20 18:28:33 volumio systemd[1]: Started Getty on tty1. Apr 20 18:28:33 volumio systemd[1]: Reached target Login Prompts. Apr 20 18:28:33 volumio cpufrequtils[4103]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Apr 20 18:28:33 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Apr 20 18:28:33 volumio ifplugd[4153]: Network Interface Plugging Daemon...start eth0...done. Apr 20 18:28:33 volumio systemd[1]: Started LSB: Brings up/down network automatically. Apr 20 18:28:33 volumio systemd[1]: Reloading. Apr 20 18:28:34 volumio 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. Apr 20 18:28:34 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Apr 20 18:28:34 volumio systemd[1]: ifup@eth0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Apr 20 18:28:34 volumio systemd[1]: volumiossh.service: Current command vanished from the unit file, execution of the command list won't be resumed. Apr 20 18:28:34 volumio systemd[1]: serial-getty@ttyS0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Apr 20 18:28:34 volumio systemd[1]: iptables.service: Succeeded. Apr 20 18:28:34 volumio systemd[1]: Reloading. Apr 20 18:28:34 volumio 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. Apr 20 18:28:35 volumio systemd[1]: Reloading. Apr 20 18:28:35 volumio sh[3438]: eth0: carrier acquired Apr 20 18:28:35 volumio dhcpcd[3824]: eth0: carrier acquired Apr 20 18:28:35 volumio dhcpcd[3534]: eth0: carrier acquired Apr 20 18:28:35 volumio kernel: meson6-dwmac ff3f0000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx Apr 20 18:28:35 volumio dhcpcd[3824]: eth0: IAID 06:48:e6:46 Apr 20 18:28:35 volumio sh[3438]: eth0: IAID 06:48:e6:46 Apr 20 18:28:35 volumio dhcpcd[3534]: eth0: IAID 06:48:e6:46 Apr 20 18:28:35 volumio 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. Apr 20 18:28:35 volumio sh[3438]: eth0: soliciting an IPv6 router Apr 20 18:28:35 volumio dhcpcd[3534]: eth0: soliciting an IPv6 router Apr 20 18:28:35 volumio ntpd[4125]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 20 18:28:35 volumio ntpd[4125]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 20 18:28:35 volumio systemd[1]: Started Network Time Service. Apr 20 18:28:35 volumio sshd[4449]: Server listening on 0.0.0.0 port 22. Apr 20 18:28:35 volumio sshd[4449]: Server listening on :: port 22. Apr 20 18:28:35 volumio systemd[1]: Started OpenBSD Secure Shell server. Apr 20 18:28:35 volumio systemd[1]: volumiossh.service: Succeeded. Apr 20 18:28:35 volumio systemd[1]: Started Volumio SSH enabler. Apr 20 18:28:35 volumio volumio-remote-updater[3716]: Error: No active session Apr 20 18:28:35 volumio volumio-remote-updater[3716]: [2023-04-20 18:28:35] [info] asio async_connect error: system:111 (Connection refused) Apr 20 18:28:35 volumio volumio-remote-updater[3716]: [2023-04-20 18:28:35] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 20 18:28:35 volumio volumio-remote-updater[3716]: [2023-04-20 18:28:35] [error] handle_connect error: Underlying Transport Error Apr 20 18:28:35 volumio ntpd[4447]: proto: precision = 0.791 usec (-20) Apr 20 18:28:35 volumio ntpd[4447]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 20 18:28:35 volumio ntpd[4447]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 20 18:28:35 volumio ntpd[4447]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 297 days ago Apr 20 18:28:35 volumio ntpd[4447]: Listen and drop on 0 v6wildcard [::]:123 Apr 20 18:28:35 volumio ntpd[4447]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 20 18:28:35 volumio ntpd[4447]: Listen normally on 2 lo 127.0.0.1:123 Apr 20 18:28:35 volumio ntpd[4447]: Listening on routing socket on fd #19 for interface updates Apr 20 18:28:35 volumio ntpd[4447]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 20 18:28:35 volumio ntpd[4447]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 20 18:28:35 volumio dhcpcd[3824]: eth0: soliciting an IPv6 router Apr 20 18:28:35 volumio ifplugd(eth0)[4333]: Link beat detected. Apr 20 18:28:35 volumio sh[3438]: eth0: soliciting a DHCP lease Apr 20 18:28:35 volumio dhcpcd[3534]: eth0: soliciting a DHCP lease Apr 20 18:28:35 volumio sh[3438]: eth0: offered 192.168.7.70 from 192.168.7.2 Apr 20 18:28:35 volumio dhcpcd[3534]: eth0: offered 192.168.7.70 from 192.168.7.2 Apr 20 18:28:35 volumio sh[3438]: eth0: probing address 192.168.7.70/24 Apr 20 18:28:35 volumio dhcpcd[3534]: eth0: probing address 192.168.7.70/24 Apr 20 18:28:35 volumio ifplugd(eth0)[4333]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 20 18:28:35 volumio ifplugd(eth0)[4333]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Apr 20 18:28:35 volumio dhcpcd[3824]: eth0: soliciting a DHCP lease Apr 20 18:28:35 volumio dhcpcd[3824]: eth0: offered 192.168.7.70 from 192.168.7.2 Apr 20 18:28:35 volumio dhcpcd[3824]: eth0: probing address 192.168.7.70/24 Apr 20 18:28:36 volumio volumio[3684]: Could not open config: /tmp/upmpdcli.conf Apr 20 18:28:36 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 20 18:28:36 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 20 18:28:36 volumio lircd-setup[3718]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Apr 20 18:28:36 volumio lircd-setup[3718]: parser = configparser.SafeConfigParser() Apr 20 18:28:36 volumio systemd[1]: lircd-setup.service: Succeeded. Apr 20 18:28:36 volumio systemd[1]: Started lircd(8) initialization helper tool. Apr 20 18:28:36 volumio systemd[1]: Starting Flexible IR remote input/output application support... Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Info: lircd: Opening log, level: Info Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Version: lircd 0.10.1 Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: System info: Linux volumio 4.9.241+ #15 SMP PREEMPT Thu Feb 10 11:27:37 CET 2022 aarch64 GNU/Linux Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Info: Initial device: auto Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Info: Initial device: auto Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: driver: devinput Apr 20 18:28:36 volumio lircd[4464]: lircd-0.10.1[4464]: Info: lircd: Opening log, level: Info Apr 20 18:28:36 volumio lircd[4464]: lircd-0.10.1[4464]: Notice: Using systemd fd Apr 20 18:28:36 volumio lircd[4464]: lircd-0.10.1[4464]: Warning: Running as root Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: output: /var/run/lirc/lircd Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: nodaemon: 1 Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: logfile: syslog Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: immediate-init: 0 Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: permission: 666 Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: driver-options: Apr 20 18:28:36 volumio lircd[4464]: lircd-0.10.1[4464]: Info: Using remote: odroid. Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Apr 20 18:28:36 volumio lircd[4464]: lircd-0.10.1[4464]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Apr 20 18:28:36 volumio systemd[1]: Started Flexible IR remote input/output application support. Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: listen: 0 Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: connect: (null) Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: userelease: 0 Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: effective_user: (null) Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: release_suffix: _EVUP Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: allow_simulate: 0 Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: repeat_max: 600 Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: configfile: /etc/lirc/lircd.conf Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Options: dynamic_codes: (null) Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Current driver: devinput Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Driver API version: 4 Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Driver version: 0.10.0 Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/devinput.html Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Info: lircd: Opening log, level: Info Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: Using systemd fd Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Warning: Running as root Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Info: Using remote: odroid. Apr 20 18:28:36 volumio lircd-0.10.1[4464]: Notice: lircd(devinput) ready, using /var/run/lirc/lircd Apr 20 18:28:36 volumio ntpd[4447]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 20 18:28:36 volumio nmbd[4270]: [2023/04/20 18:28:36.957193, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 20 18:28:36 volumio nmbd[4270]: started asyncdns process 4496 Apr 20 18:28:36 volumio nmbd[4270]: [2023/04/20 18:28:36.959281, 0] ../lib/util/become_daemon.c:149(daemon_status) Apr 20 18:28:36 volumio nmbd[4270]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Apr 20 18:28:36 volumio nmbd[4270]: [2023/04/20 18:28:36.959465, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Apr 20 18:28:36 volumio nmbd[4270]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Apr 20 18:28:37 volumio systemd[1]: systemd-rfkill.service: Succeeded. Apr 20 18:28:37 volumio ntpd[4447]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 20 18:28:38 volumio wireless.js[3626]: WIRELESS DAEMON: start Apr 20 18:28:38 volumio wireless.js[3626]: WIRELESS: Loaded configuration Apr 20 18:28:38 volumio wireless.js[3626]: Cleaning previous... Apr 20 18:28:38 volumio sudo[4517]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 20 18:28:38 volumio sudo[4517]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:38 volumio sudo[4517]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:38 volumio sudo[4519]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 20 18:28:38 volumio sudo[4519]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:38 volumio sudo[4519]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:38 volumio wireless.js[3626]: Stopped aP Apr 20 18:28:38 volumio wireless.js[3626]: Start wireless flow Apr 20 18:28:38 volumio wireless.js[3626]: Stopped hotspot (if there).. Apr 20 18:28:38 volumio sudo[4526]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 20 18:28:38 volumio sudo[4526]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:38 volumio sudo[4526]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:38 volumio sudo[4528]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 20 18:28:38 volumio sudo[4528]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:38 volumio sudo[4528]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:38 volumio wireless.js[3626]: DHCP IP Apr 20 18:28:38 volumio wireless.js[3626]: Start ap Apr 20 18:28:38 volumio wpa_supplicant[4530]: Successfully initialized wpa_supplicant Apr 20 18:28:38 volumio sudo[4531]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 20 18:28:38 volumio sudo[4531]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:38 volumio dhcpcd[4532]: sending commands to master dhcpcd process Apr 20 18:28:38 volumio dhcpcd[3824]: control command: /sbin/dhcpcd Apr 20 18:28:38 volumio sudo[4531]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:38 volumio ntpd[4447]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 20 18:28:38 volumio mpd[4337]: Apr 20 18:28 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 18:28:38 volumio mpd[4337]: Apr 20 18:28 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 18:28:38 volumio mpd[4337]: Apr 20 18:28 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 18:28:38 volumio systemd[1]: Started Music Player Daemon. Apr 20 18:28:39 volumio wireless.js[3626]: trying... Apr 20 18:28:39 volumio sudo[4571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:28:39 volumio sudo[4571]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:39 volumio sudo[4571]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:39 volumio wpa_supplicant[4534]: wlan0: SME: Trying to authenticate with f4:92:bf:c4:68:3a (SSID='Chateau2_IoT' freq=2462 MHz) Apr 20 18:28:39 volumio kernel: wlan0: authenticate with f4:92:bf:c4:68:3a Apr 20 18:28:39 volumio kernel: wlan0: send auth to f4:92:bf:c4:68:3a (try 1/3) Apr 20 18:28:39 volumio ntpd[4447]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 20 18:28:39 volumio kernel: wlan0: send auth to f4:92:bf:c4:68:3a (try 2/3) Apr 20 18:28:40 volumio kernel: wlan0: send auth to f4:92:bf:c4:68:3a (try 3/3) Apr 20 18:28:40 volumio kernel: wlan0: authentication with f4:92:bf:c4:68:3a timed out Apr 20 18:28:40 volumio wireless.js[3626]: trying... Apr 20 18:28:40 volumio sudo[4574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:28:40 volumio sudo[4574]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:40 volumio sudo[4574]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:40 volumio wpa_supplicant[4534]: wlan0: SME: Trying to authenticate with 94:83:c4:1d:e3:64 (SSID='Chateau2_IoT' freq=2462 MHz) Apr 20 18:28:40 volumio kernel: wlan0: authenticate with 94:83:c4:1d:e3:64 Apr 20 18:28:40 volumio kernel: wlan0: send auth to 94:83:c4:1d:e3:64 (try 1/3) Apr 20 18:28:40 volumio kernel: wlan0: send auth to 94:83:c4:1d:e3:64 (try 2/3) Apr 20 18:28:40 volumio volumio-remote-updater[3716]: [2023-04-20 18:28:40] [info] asio async_connect error: system:111 (Connection refused) Apr 20 18:28:40 volumio volumio-remote-updater[3716]: [2023-04-20 18:28:40] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 20 18:28:40 volumio volumio-remote-updater[3716]: [2023-04-20 18:28:40] [error] handle_connect error: Underlying Transport Error Apr 20 18:28:40 volumio dhcpcd[3824]: eth0: leased 192.168.7.70 for 7200 seconds Apr 20 18:28:40 volumio dhcpcd[3824]: eth0: adding route to 192.168.7.0/24 Apr 20 18:28:40 volumio dhcpcd[3824]: eth0: adding default route via 192.168.7.2 Apr 20 18:28:40 volumio avahi-daemon[3730]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.7.70. Apr 20 18:28:40 volumio avahi-daemon[3730]: New relevant interface eth0.IPv4 for mDNS. Apr 20 18:28:40 volumio avahi-daemon[3730]: Registering new address record for 192.168.7.70 on eth0.IPv4. Apr 20 18:28:40 volumio kernel: wlan0: send auth to 94:83:c4:1d:e3:64 (try 3/3) Apr 20 18:28:41 volumio ntpd[4447]: ntpd exiting on signal 15 (Terminated) Apr 20 18:28:41 volumio systemd[1]: Stopping Network Time Service... Apr 20 18:28:41 volumio systemd[1]: ntp.service: Succeeded. Apr 20 18:28:41 volumio systemd[1]: Stopped Network Time Service. Apr 20 18:28:41 volumio systemd[1]: Starting Network Time Service... Apr 20 18:28:41 volumio ntpd[4614]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 20 18:28:41 volumio ntpd[4614]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 20 18:28:41 volumio systemd[1]: Started Network Time Service. Apr 20 18:28:41 volumio ntpd[4639]: proto: precision = 0.791 usec (-20) Apr 20 18:28:41 volumio ntpd[4639]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 20 18:28:41 volumio ntpd[4639]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 20 18:28:41 volumio ntpd[4639]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 297 days ago Apr 20 18:28:41 volumio ntpd[4639]: Listen and drop on 0 v6wildcard [::]:123 Apr 20 18:28:41 volumio ntpd[4639]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 20 18:28:41 volumio ntpd[4639]: Listen normally on 2 lo 127.0.0.1:123 Apr 20 18:28:41 volumio ntpd[4639]: Listen normally on 3 eth0 192.168.7.70:123 Apr 20 18:28:41 volumio ntpd[4639]: Listening on routing socket on fd #20 for interface updates Apr 20 18:28:41 volumio ntpd[4639]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 20 18:28:41 volumio ntpd[4639]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 20 18:28:41 volumio kernel: wlan0: authentication with 94:83:c4:1d:e3:64 timed out Apr 20 18:28:41 volumio sh[3438]: eth0: leased 192.168.7.70 for 7200 seconds Apr 20 18:28:41 volumio sh[3438]: eth0: adding route to 192.168.7.0/24 Apr 20 18:28:41 volumio sh[3438]: eth0: adding default route via 192.168.7.2 Apr 20 18:28:41 volumio dhcpcd[3534]: eth0: leased 192.168.7.70 for 7200 seconds Apr 20 18:28:41 volumio dhcpcd[3534]: eth0: adding route to 192.168.7.0/24 Apr 20 18:28:41 volumio dhcpcd[3534]: eth0: adding default route via 192.168.7.2 Apr 20 18:28:41 volumio ntpd[4639]: ntpd exiting on signal 15 (Terminated) Apr 20 18:28:41 volumio systemd[1]: Stopping Network Time Service... Apr 20 18:28:41 volumio systemd[1]: ntp.service: Succeeded. Apr 20 18:28:41 volumio systemd[1]: Stopped Network Time Service. Apr 20 18:28:41 volumio systemd[1]: Starting Network Time Service... Apr 20 18:28:41 volumio ntpd[4674]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 20 18:28:41 volumio ntpd[4674]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 20 18:28:41 volumio systemd[1]: Started Network Time Service. Apr 20 18:28:41 volumio ntpd[4699]: proto: precision = 0.792 usec (-20) Apr 20 18:28:41 volumio ntpd[4699]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 20 18:28:41 volumio ntpd[4699]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 20 18:28:41 volumio ntpd[4699]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 297 days ago Apr 20 18:28:41 volumio ntpd[4699]: Listen and drop on 0 v6wildcard [::]:123 Apr 20 18:28:41 volumio ntpd[4699]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 20 18:28:41 volumio ntpd[4699]: Listen normally on 2 lo 127.0.0.1:123 Apr 20 18:28:41 volumio ntpd[4699]: Listen normally on 3 eth0 192.168.7.70:123 Apr 20 18:28:41 volumio sh[3438]: forked to background, child pid 4701 Apr 20 18:28:41 volumio ntpd[4699]: Listening on routing socket on fd #20 for interface updates Apr 20 18:28:41 volumio ntpd[4699]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 20 18:28:41 volumio ntpd[4699]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 20 18:28:41 volumio dhcpcd[3534]: forked to background, child pid 4701 Apr 20 18:28:41 volumio ifplugd(eth0)[4333]: client: ifup: interface eth0 already configured Apr 20 18:28:41 volumio sh[3438]: eth0=eth0 Apr 20 18:28:41 volumio wireless.js[3626]: trying... Apr 20 18:28:41 volumio sudo[4747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:28:41 volumio sudo[4747]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:41 volumio sudo[4747]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:41 volumio ifplugd(eth0)[4333]: Program executed successfully. Apr 20 18:28:41 volumio nmbd[4270]: [2023/04/20 18:28:41.963885, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 20 18:28:41 volumio nmbd[4270]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 20 18:28:41 volumio systemd[1]: Started Samba NMB Daemon. Apr 20 18:28:41 volumio systemd[1]: Starting Samba Winbind Daemon... Apr 20 18:28:42 volumio winbindd[4749]: [2023/04/20 18:28:42.219792, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 20 18:28:42 volumio winbindd[4749]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 20 18:28:42 volumio winbindd[4749]: [2023/04/20 18:28:42.260366, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 20 18:28:42 volumio winbindd[4749]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 20 18:28:42 volumio systemd[1]: Started Samba Winbind Daemon. Apr 20 18:28:42 volumio systemd[1]: Starting Samba SMB Daemon... Apr 20 18:28:42 volumio wpa_supplicant[4534]: wlan0: SME: Trying to authenticate with 18:e8:29:c1:a1:ff (SSID='Chateau2_IoT' freq=2412 MHz) Apr 20 18:28:42 volumio kernel: wlan0: authenticate with 18:e8:29:c1:a1:ff Apr 20 18:28:42 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 1/3) Apr 20 18:28:42 volumio ntpd[4699]: Soliciting pool server 193.203.3.171 Apr 20 18:28:42 volumio wireless.js[3626]: trying... Apr 20 18:28:42 volumio sudo[4795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:28:42 volumio sudo[4795]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:42 volumio sudo[4795]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:42 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 2/3) Apr 20 18:28:42 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 3/3) Apr 20 18:28:42 volumio kernel: wlan0: authentication with 18:e8:29:c1:a1:ff timed out Apr 20 18:28:43 volumio ntpd[4699]: Soliciting pool server 185.248.188.98 Apr 20 18:28:43 volumio ntpd[4699]: Soliciting pool server 185.232.69.65 Apr 20 18:28:43 volumio wireless.js[3626]: trying... Apr 20 18:28:43 volumio sudo[4812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:28:43 volumio sudo[4812]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:43 volumio sudo[4812]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:44 volumio wpa_supplicant[4534]: wlan0: SME: Trying to authenticate with 18:e8:29:c1:a1:ff (SSID='Chateau2_IoT' freq=2412 MHz) Apr 20 18:28:44 volumio kernel: wlan0: authenticate with 18:e8:29:c1:a1:ff Apr 20 18:28:44 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 1/3) Apr 20 18:28:44 volumio ntpd[4699]: Soliciting pool server 176.9.42.91 Apr 20 18:28:44 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 2/3) Apr 20 18:28:44 volumio ntpd[4699]: Soliciting pool server 130.255.77.87 Apr 20 18:28:44 volumio ntpd[4699]: Soliciting pool server 178.215.228.24 Apr 20 18:28:44 volumio wireless.js[3626]: trying... Apr 20 18:28:44 volumio sudo[4815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:28:44 volumio sudo[4815]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:44 volumio sudo[4815]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:44 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 3/3) Apr 20 18:28:44 volumio kernel: wlan0: authentication with 18:e8:29:c1:a1:ff timed out Apr 20 18:28:45 volumio ntpd[4699]: Soliciting pool server 80.190.68.169 Apr 20 18:28:45 volumio ntpd[4699]: Soliciting pool server 80.153.195.191 Apr 20 18:28:45 volumio ntpd[4699]: Soliciting pool server 85.215.93.134 Apr 20 18:28:45 volumio ntpd[4699]: Soliciting pool server 185.11.138.90 Apr 20 18:28:45 volumio wireless.js[3626]: trying... Apr 20 18:28:45 volumio sudo[4818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:28:45 volumio sudo[4818]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:45 volumio sudo[4818]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:45 volumio wpa_supplicant[4534]: wlan0: SME: Trying to authenticate with f4:92:bf:c4:68:3a (SSID='Chateau2_IoT' freq=2462 MHz) Apr 20 18:28:45 volumio kernel: wlan0: authenticate with f4:92:bf:c4:68:3a Apr 20 18:28:45 volumio kernel: wlan0: send auth to f4:92:bf:c4:68:3a (try 1/3) Apr 20 18:28:46 volumio kernel: wlan0: send auth to f4:92:bf:c4:68:3a (try 2/3) Apr 20 18:28:46 volumio kernel: wlan0: send auth to f4:92:bf:c4:68:3a (try 3/3) Apr 20 18:28:46 volumio ntpd[4699]: Soliciting pool server 162.159.200.1 Apr 20 18:28:46 volumio ntpd[4699]: Soliciting pool server 116.203.96.227 Apr 20 18:28:46 volumio wireless.js[3626]: trying... Apr 20 18:28:46 volumio sudo[4821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:28:46 volumio sudo[4821]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:46 volumio sudo[4821]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:46 volumio kernel: wlan0: authentication with f4:92:bf:c4:68:3a timed out Apr 20 18:28:47 volumio smbd[4793]: [2023/04/20 18:28:47.090579, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 20 18:28:47 volumio smbd[4793]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 20 18:28:47 volumio systemd[1]: Started Samba SMB Daemon. Apr 20 18:28:47 volumio ntpd[4699]: Soliciting pool server 85.220.190.246 Apr 20 18:28:47 volumio ntpd[4699]: Soliciting pool server 89.58.51.72 Apr 20 18:28:47 volumio wireless.js[3626]: trying... Apr 20 18:28:47 volumio sudo[4828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:28:47 volumio sudo[4828]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:47 volumio sudo[4828]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:47 volumio wpa_supplicant[4534]: wlan0: SME: Trying to authenticate with 18:e8:29:c1:82:f5 (SSID='Chateau2_IoT' freq=2462 MHz) Apr 20 18:28:47 volumio kernel: wlan0: authenticate with 18:e8:29:c1:82:f5 Apr 20 18:28:47 volumio kernel: wlan0: send auth to 18:e8:29:c1:82:f5 (try 1/3) Apr 20 18:28:47 volumio kernel: wlan0: send auth to 18:e8:29:c1:82:f5 (try 2/3) Apr 20 18:28:48 volumio kernel: wlan0: send auth to 18:e8:29:c1:82:f5 (try 3/3) Apr 20 18:28:48 volumio kernel: wlan0: authentication with 18:e8:29:c1:82:f5 timed out Apr 20 18:28:48 volumio volumio-remote-updater[3716]: [2023-04-20 18:28:48] [info] asio async_connect error: system:111 (Connection refused) Apr 20 18:28:48 volumio volumio-remote-updater[3716]: [2023-04-20 18:28:48] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 20 18:28:48 volumio volumio-remote-updater[3716]: [2023-04-20 18:28:48] [error] handle_connect error: Underlying Transport Error Apr 20 18:28:48 volumio ntpd[4699]: Soliciting pool server 85.10.240.253 Apr 20 18:28:48 volumio ntpd[4699]: Soliciting pool server 2a02:c207:3004:6520::1 Apr 20 18:28:48 volumio wireless.js[3626]: trying... Apr 20 18:28:48 volumio sudo[4831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:28:48 volumio sudo[4831]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:48 volumio sudo[4831]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:49 volumio wpa_supplicant[4534]: wlan0: SME: Trying to authenticate with 94:83:c4:1d:e3:64 (SSID='Chateau2_IoT' freq=2462 MHz) Apr 20 18:28:49 volumio kernel: wlan0: authenticate with 94:83:c4:1d:e3:64 Apr 20 18:28:49 volumio kernel: wlan0: send auth to 94:83:c4:1d:e3:64 (try 1/3) Apr 20 18:28:49 volumio kernel: wlan0: send auth to 94:83:c4:1d:e3:64 (try 2/3) Apr 20 18:28:49 volumio wireless.js[3626]: trying... Apr 20 18:28:49 volumio sudo[4834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:28:49 volumio sudo[4834]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:28:49 volumio sudo[4834]: pam_unix(sudo:session): session closed for user root Apr 20 18:28:49 volumio kernel: wlan0: send auth to 94:83:c4:1d:e3:64 (try 3/3) Apr 20 18:28:49 volumio kernel: wlan0: authentication with 94:83:c4:1d:e3:64 timed out Apr 20 18:29:23 volumio ntpd[4699]: receive: Unexpected origin timestamp 0xe7ec02e2.6a76c39c does not match aorg 0000000000.00000000 from server@116.203.96.227 xmt 0xe7ec0303.62ee3f76 Apr 20 18:29:23 volumio ntpd[4699]: receive: Unexpected origin timestamp 0xe7ec02e2.6a7f37b2 does not match aorg 0000000000.00000000 from server@176.9.42.91 xmt 0xe7ec0303.630597cc Apr 20 18:29:23 volumio ntpd[4699]: receive: Unexpected origin timestamp 0xe7ec02e2.6a7cbca3 does not match aorg 0000000000.00000000 from server@80.190.68.169 xmt 0xe7ec0303.63715e11 Apr 20 18:29:23 volumio ntpd[4699]: receive: Unexpected origin timestamp 0xe7ec02e2.6a7b6c98 does not match aorg 0000000000.00000000 from server@85.215.93.134 xmt 0xe7ec0303.633e1442 Apr 20 18:29:23 volumio ntpd[4699]: receive: Unexpected origin timestamp 0xe7ec02e2.6a79d136 does not match aorg 0000000000.00000000 from server@80.153.195.191 xmt 0xe7ec0303.646cbed2 Apr 20 18:29:23 volumio wireless.js[3626]: trying... Apr 20 18:29:23 volumio sudo[4837]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:29:23 volumio sudo[4837]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:23 volumio sudo[4837]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:24 volumio wireless.js[3626]: trying... Apr 20 18:29:24 volumio sudo[4840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:29:24 volumio sudo[4840]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:24 volumio sudo[4840]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:24 volumio wpa_supplicant[4534]: wlan0: SME: Trying to authenticate with f4:92:bf:c4:68:3a (SSID='Chateau2_IoT' freq=2462 MHz) Apr 20 18:29:24 volumio kernel: wlan0: authenticate with f4:92:bf:c4:68:3a Apr 20 18:29:24 volumio kernel: wlan0: send auth to f4:92:bf:c4:68:3a (try 1/3) Apr 20 18:29:25 volumio kernel: wlan0: send auth to f4:92:bf:c4:68:3a (try 2/3) Apr 20 18:29:25 volumio kernel: wlan0: send auth to f4:92:bf:c4:68:3a (try 3/3) Apr 20 18:29:25 volumio wireless.js[3626]: trying... Apr 20 18:29:25 volumio sudo[4843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:29:25 volumio sudo[4843]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:25 volumio sudo[4843]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:25 volumio kernel: wlan0: authentication with f4:92:bf:c4:68:3a timed out Apr 20 18:29:25 volumio wpa_supplicant[4534]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Chateau2_IoT" auth_failures=1 duration=10 reason=CONN_FAILED Apr 20 18:29:26 volumio wireless.js[3626]: trying... Apr 20 18:29:26 volumio sudo[4846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:29:26 volumio sudo[4846]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:26 volumio sudo[4846]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:27 volumio wireless.js[3626]: trying... Apr 20 18:29:27 volumio sudo[4849]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:29:27 volumio sudo[4849]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:27 volumio sudo[4849]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:28 volumio wireless.js[3626]: trying... Apr 20 18:29:28 volumio sudo[4852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:29:28 volumio sudo[4852]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:28 volumio sudo[4852]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:29 volumio wireless.js[3626]: trying... Apr 20 18:29:29 volumio sudo[4855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:29:29 volumio sudo[4855]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:29 volumio sudo[4855]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:30 volumio wireless.js[3626]: trying... Apr 20 18:29:30 volumio sudo[4858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:29:30 volumio sudo[4858]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:30 volumio sudo[4858]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:31 volumio volumio-remote-updater[3716]: [2023-04-20 18:29:31] [info] asio async_connect error: system:111 (Connection refused) Apr 20 18:29:31 volumio volumio-remote-updater[3716]: [2023-04-20 18:29:31] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 20 18:29:31 volumio volumio-remote-updater[3716]: [2023-04-20 18:29:31] [error] handle_connect error: Underlying Transport Error Apr 20 18:29:31 volumio wireless.js[3626]: trying... Apr 20 18:29:31 volumio sudo[4861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:29:31 volumio sudo[4861]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:31 volumio sudo[4861]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:32 volumio wireless.js[3626]: Overtime, starting plan B Apr 20 18:29:32 volumio systemd[1]: Started Wireless Services. Apr 20 18:29:32 volumio systemd[1]: Started Volumio Backend Module. Apr 20 18:29:32 volumio systemd[1]: Started Volumio Streaming Daemon. Apr 20 18:29:32 volumio systemd[1]: Reached target Multi-User System. Apr 20 18:29:32 volumio systemd[1]: Reached target Graphical Interface. Apr 20 18:29:32 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 20 18:29:32 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 20 18:29:32 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 20 18:29:32 volumio systemd[1]: Startup finished in 15.992s (kernel) + 31.468s (userspace) = 47.460s. Apr 20 18:29:32 volumio volumio-streaming-daemon[4866]: ############################ Apr 20 18:29:32 volumio volumio-streaming-daemon[4866]: # Volumio Streaming Daemon # Apr 20 18:29:32 volumio volumio-streaming-daemon[4866]: # Running on port 7777 # Apr 20 18:29:32 volumio volumio-streaming-daemon[4866]: ############################ Apr 20 18:29:32 volumio volumio-streaming-daemon[4866]: Environment: production Apr 20 18:29:33 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 20 18:29:34 volumio volumio[4865]: info: ------------------------------------------- Apr 20 18:29:34 volumio volumio[4865]: info: ----- Volumio3 ---- Apr 20 18:29:34 volumio volumio[4865]: info: ------------------------------------------- Apr 20 18:29:34 volumio volumio[4865]: info: ----- System startup ---- Apr 20 18:29:34 volumio volumio[4865]: info: ------------------------------------------- Apr 20 18:29:35 volumio wpa_supplicant[4534]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Chateau2_IoT" Apr 20 18:29:35 volumio wpa_supplicant[4534]: wlan0: SME: Trying to authenticate with 18:e8:29:c1:a1:ff (SSID='Chateau2_IoT' freq=2412 MHz) Apr 20 18:29:35 volumio kernel: wlan0: authenticate with 18:e8:29:c1:a1:ff Apr 20 18:29:35 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 1/3) Apr 20 18:29:35 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 2/3) Apr 20 18:29:36 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 3/3) Apr 20 18:29:36 volumio volumio[4865]: info: MYVOLUMIO Environment detected Apr 20 18:29:36 volumio kernel: wlan0: authentication with 18:e8:29:c1:a1:ff timed out Apr 20 18:29:36 volumio wpa_supplicant[4534]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Chateau2_IoT" auth_failures=2 duration=23 reason=CONN_FAILED Apr 20 18:29:36 volumio volumio[4865]: info: Plugin folders cleanup Apr 20 18:29:36 volumio volumio[4865]: info: Scanning into folder /volumio/app/plugins/ Apr 20 18:29:36 volumio volumio[4865]: info: Scanning category audio_interface Apr 20 18:29:36 volumio volumio[4865]: info: Scanning category miscellanea Apr 20 18:29:36 volumio volumio[4865]: info: Scanning category music_service Apr 20 18:29:36 volumio volumio[4865]: info: Scanning category plugins.json Apr 20 18:29:36 volumio volumio[4865]: info: Scanning category system_controller Apr 20 18:29:36 volumio volumio[4865]: info: Scanning category user_interface Apr 20 18:29:36 volumio volumio[4865]: info: Scanning into folder /data/plugins/ Apr 20 18:29:36 volumio volumio[4865]: info: Scanning category system_hardware Apr 20 18:29:36 volumio volumio[4865]: info: Scanning category user_interface Apr 20 18:29:36 volumio volumio[4865]: info: Plugin folders cleanup completed Apr 20 18:29:36 volumio volumio[4865]: info: ------------------------------------------- Apr 20 18:29:36 volumio volumio[4865]: info: ----- Core plugins startup ---- Apr 20 18:29:36 volumio volumio[4865]: info: ------------------------------------------- Apr 20 18:29:36 volumio volumio[4865]: info: Loading plugins from folder /volumio/app/plugins/ Apr 20 18:29:36 volumio volumio[4865]: info: Adding plugin upnp to MyMusic Plugins Apr 20 18:29:36 volumio volumio[4865]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 20 18:29:36 volumio volumio[4865]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 20 18:29:36 volumio volumio[4865]: info: Loading plugins from folder /data/plugins/ Apr 20 18:29:36 volumio volumio[4865]: info: Loading plugin "system"... Apr 20 18:29:36 volumio volumio[4865]: info: Loading plugin "appearance"... Apr 20 18:29:37 volumio volumio[4865]: info: Loading plugin "network"... Apr 20 18:29:37 volumio volumio[4865]: info: Refreshing Cached IP Addresses Apr 20 18:29:37 volumio sudo[4968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 18:29:37 volumio sudo[4968]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:37 volumio sudo[4968]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:37 volumio volumio[4865]: info: Loading plugin "services"... Apr 20 18:29:37 volumio sudo[4970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 18:29:37 volumio sudo[4970]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:37 volumio volumio[4865]: info: Loading plugin "alsa_controller"... Apr 20 18:29:37 volumio sudo[4970]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:37 volumio sudo[4973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 20 18:29:37 volumio sudo[4973]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:37 volumio volumio[4865]: info: Loading plugin "wizard"... Apr 20 18:29:37 volumio volumio[4865]: info: Loading plugin "volumio_command_line_client"... Apr 20 18:29:37 volumio volumio[4865]: info: Loading plugin "upnp"... Apr 20 18:29:37 volumio volumio[4865]: info: [1682015377942] Starting Upmpd Daemon Apr 20 18:29:37 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 18:29:37 volumio volumio[4865]: info: Loading plugin "my_music"... Apr 20 18:29:37 volumio volumio[4865]: info: Loading plugin "mpd"... Apr 20 18:29:38 volumio volumio[4865]: info: Creating MPD Configuration file Apr 20 18:29:38 volumio volumio[4865]: info: Loading plugin "upnp_browser"... Apr 20 18:29:38 volumio sudo[4983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 18:29:38 volumio sudo[4983]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:38 volumio sudo[4983]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:38 volumio sudo[4985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 18:29:38 volumio sudo[4985]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:38 volumio systemd[1]: Stopping Music Player Daemon... Apr 20 18:29:38 volumio systemd[1]: mpd.service: Succeeded. Apr 20 18:29:38 volumio systemd[1]: Stopped Music Player Daemon. Apr 20 18:29:38 volumio systemd[1]: Starting Music Player Daemon... Apr 20 18:29:38 volumio volumio[4865]: info: Loading plugin "networkfs"... Apr 20 18:29:38 volumio sudo[4973]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:39 volumio volumio[4865]: info: Starting Udev Watcher for removable devices Apr 20 18:29:39 volumio volumio[4865]: info: Ignoring mount for partition: boot Apr 20 18:29:39 volumio volumio[4865]: info: Ignoring mount for partition: volumio Apr 20 18:29:39 volumio volumio[4865]: info: Ignoring mount for partition: volumio_data Apr 20 18:29:39 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 18:29:39 volumio volumio[4865]: info: Loading plugin "alarm-clock"... Apr 20 18:29:39 volumio volumio[4865]: info: Loading plugin "airplay_emulation"... Apr 20 18:29:39 volumio volumio[4865]: info: Starting Shairport Sync Apr 20 18:29:39 volumio volumio[4865]: info: Loading plugin "last_100"... Apr 20 18:29:39 volumio volumio[4865]: info: Loading plugin "webradio"... Apr 20 18:29:39 volumio volumio[4865]: info: Loading plugin "i2s_dacs"... Apr 20 18:29:39 volumio volumio[4865]: info: I2S DAC not set, start Auto-detection Apr 20 18:29:39 volumio volumio[4865]: info: Loading plugin "volumiodiscovery"... Apr 20 18:29:39 volumio volumio[4865]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 18:29:39 volumio volumio[4865]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 18:29:39 volumio volumio[4865]: *** WARNING *** For more information see Apr 20 18:29:39 volumio node[4865]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 18:29:39 volumio volumio[4865]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 18:29:39 volumio volumio[4865]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 18:29:39 volumio volumio[4865]: *** WARNING *** For more information see Apr 20 18:29:39 volumio node[4865]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 18:29:39 volumio node[4865]: *** WARNING *** For more information see Apr 20 18:29:39 volumio node[4865]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 18:29:39 volumio node[4865]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 18:29:39 volumio node[4865]: *** WARNING *** For more information see Apr 20 18:29:39 volumio volumio[4865]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 20 18:29:39 volumio volumio[4865]: info: Discovery: Started advertising with name: Volumio Apr 20 18:29:39 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 18:29:39 volumio volumio[4865]: info: Plugin gpio-buttons is not enabled Apr 20 18:29:39 volumio volumio[4865]: info: Loading plugin "outputs"... Apr 20 18:29:39 volumio volumio[4865]: info: Loading plugin "albumart"... Apr 20 18:29:39 volumio mpd[5003]: Apr 20 18:29 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 18:29:39 volumio mpd[5003]: Apr 20 18:29 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 18:29:39 volumio mpd[5003]: Apr 20 18:29 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 18:29:39 volumio systemd[1]: Started Music Player Daemon. Apr 20 18:29:39 volumio sudo[4985]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:40 volumio volumio[4865]: info: Plugin example_plugin is not enabled Apr 20 18:29:40 volumio volumio[4865]: info: Loading plugin "inputs"... Apr 20 18:29:40 volumio volumio[4865]: info: Loading plugin "updater_comm"... Apr 20 18:29:40 volumio volumio[4865]: info: Plugin mpdemulation is not enabled Apr 20 18:29:40 volumio volumio[4865]: info: Loading plugin "rest_api"... Apr 20 18:29:40 volumio volumio[4865]: info: Loading plugin "websocket"... Apr 20 18:29:40 volumio volumio[4865]: info: Plugin Systeminfo is not enabled Apr 20 18:29:40 volumio volumio[4865]: info: Loading i18n strings for locale en Apr 20 18:29:40 volumio volumio[4865]: Updating browse sources language Apr 20 18:29:40 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 18:29:40 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:29:40 volumio volumio[4865]: info: CoreCommandRouter::initPlayerControls Apr 20 18:29:40 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:40 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:40 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:40 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:40 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:40 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:40 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:40 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:40 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:29:40 volumio volumio[4865]: Express server listening on port 3000 Apr 20 18:29:40 volumio volumio[4865]: [Metrics] WebUI: 6s 951.26ms Apr 20 18:29:40 volumio volumio[4865]: info: CoreStateMachine::resetVolumioState Apr 20 18:29:40 volumio volumio[4865]: info: CoreStateMachine::getcurrentVolume Apr 20 18:29:40 volumio volumio[4865]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 18:29:40 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:29:40 volumio volumio[4865]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 20 18:29:40 volumio volumio[4865]: info: Completed loading Core Plugins Apr 20 18:29:40 volumio volumio[4865]: Forking 3 albumart workers Apr 20 18:29:40 volumio volumio[4865]: info: Preparing to generate the ALSA configuration file Apr 20 18:29:40 volumio volumio[4865]: info: Cannot read play queue from file Apr 20 18:29:41 volumio volumio[4865]: info: MPD Permissions set Apr 20 18:29:41 volumio volumio-remote-updater[3716]: [2023-04-20 18:29:41] [connect] Successful connection Apr 20 18:29:41 volumio volumio[4865]: Starting albumart workers Apr 20 18:29:41 volumio volumio[4865]: Starting albumart workers Apr 20 18:29:41 volumio volumio[4865]: Starting albumart workers Apr 20 18:29:42 volumio volumio[4865]: verbose: New Socket.io Connection to 192.168.7.70 from 192.168.7.16 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/108.0.0.0 Safari/537.36 Total Clients: 1 Apr 20 18:29:42 volumio volumio[4865]: verbose: New Socket.io Connection to 192.168.7.70 from 192.168.7.16 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/108.0.0.0 Safari/537.36 Total Clients: 2 Apr 20 18:29:42 volumio volumio[4865]: Unable to parse: Apr 20 18:29:42 volumio volumio[4865]: Simple mixer control 'Audio hdmi-out mute',0 Apr 20 18:29:42 volumio volumio[4865]: Capabilities: pswitch pswitch-joined Apr 20 18:29:42 volumio volumio[4865]: Playback channels: Mono Apr 20 18:29:42 volumio volumio[4865]: Mono: Playback [off] Apr 20 18:29:42 volumio volumio[4865]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 20 18:29:42 volumio volumio[4865]: info: MPD running with PID5003 Apr 20 18:29:42 volumio volumio[4865]: ,establishing connection Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:42 volumio volumio[4865]: error: updateQueue error: null Apr 20 18:29:42 volumio volumio[4865]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 20 18:29:42 volumio volumio-remote-updater[3716]: [2023-04-20 18:29:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1682015381 101 Apr 20 18:29:42 volumio volumio[4865]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Apr 20 18:29:42 volumio volumio[4865]: Unable to parse: Apr 20 18:29:42 volumio volumio[4865]: Simple mixer control 'Audio hdmi-out mute',0 Apr 20 18:29:42 volumio volumio[4865]: Capabilities: pswitch pswitch-joined Apr 20 18:29:42 volumio volumio[4865]: Playback channels: Mono Apr 20 18:29:42 volumio volumio[4865]: Mono: Playback [off] Apr 20 18:29:42 volumio volumio[4865]: info: VolumeController:: Volume=undefined Mute =false Apr 20 18:29:42 volumio volumio[4865]: info: CoreStateMachine::pushState Apr 20 18:29:42 volumio volumio[4865]: info: CorePlayQueue::getTrack 0 Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::volumioPushState Apr 20 18:29:42 volumio volumio[4865]: info: CoreStateMachine::updateTrackBlock Apr 20 18:29:42 volumio volumio[4865]: info: CorePlayQueue::getTrackBlock Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 18:29:42 volumio volumio[4865]: info: Asound.conf file unchanged, so no further update is needed Apr 20 18:29:42 volumio volumio[4865]: info: Output device has changed, restarting MPD Apr 20 18:29:42 volumio volumio[4865]: info: Output device has changed, restarting Shairport Sync Apr 20 18:29:42 volumio sudo[5087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:42 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:29:42 volumio sudo[5087]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:42 volumio sudo[5087]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:42 volumio sudo[5089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 18:29:42 volumio sudo[5089]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:42 volumio systemd[1]: Stopping Music Player Daemon... Apr 20 18:29:42 volumio volumio[4865]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 18:29:42 volumio volumio[4865]: info: ___________ START PLUGINS ___________ Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 18:29:43 volumio volumio[4865]: info: [1682015383005] CoreMusicLibrary::Adding element Media Servers Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 18:29:43 volumio volumio[4865]: Cannot find translation for sourceMedia Servers Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:29:43 volumio systemd[1]: mpd.service: Succeeded. Apr 20 18:29:43 volumio systemd[1]: Stopped Music Player Daemon. Apr 20 18:29:43 volumio systemd[1]: Starting Music Player Daemon... Apr 20 18:29:43 volumio volumio[4865]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:29:43 volumio volumio[4865]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 18:29:43 volumio volumio[4865]: info: [1682015383103] CoreMusicLibrary::Adding element Last_100 Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 18:29:43 volumio volumio[4865]: Cannot find translation for sourceMedia Servers Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 18:29:43 volumio volumio[4865]: info: [1682015383107] CoreMusicLibrary::Adding element Webradio Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 18:29:43 volumio volumio[4865]: Cannot find translation for sourceMedia Servers Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 18:29:43 volumio volumio[4865]: info: Volumio Calling Home Apr 20 18:29:43 volumio volumio[4865]: info: Completed starting Core Plugins Apr 20 18:29:43 volumio volumio[4865]: info: ------------------------------------------- Apr 20 18:29:43 volumio volumio[4865]: info: ----- MyVolumio plugins startup ---- Apr 20 18:29:43 volumio volumio[4865]: info: ------------------------------------------- Apr 20 18:29:43 volumio volumio[4865]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 20 18:29:43 volumio volumio[4865]: info: CoreStateMachine::setRepeat null single undefined Apr 20 18:29:43 volumio volumio[4865]: info: CoreStateMachine::pushState Apr 20 18:29:43 volumio volumio[4865]: info: CorePlayQueue::getTrack 0 Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::volumioPushState Apr 20 18:29:43 volumio volumio[4865]: info: CoreStateMachine::setRandom null Apr 20 18:29:43 volumio volumio[4865]: info: CoreStateMachine::pushState Apr 20 18:29:43 volumio volumio[4865]: info: CorePlayQueue::getTrack 0 Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::volumioPushState Apr 20 18:29:43 volumio volumio[4865]: error: MPD error: Error: write EPIPE Apr 20 18:29:43 volumio volumio[4865]: info: MPD Permissions set Apr 20 18:29:43 volumio volumio[4865]: Unable to parse: Apr 20 18:29:43 volumio volumio[4865]: Simple mixer control 'Audio hdmi-out mute',0 Apr 20 18:29:43 volumio volumio[4865]: Capabilities: pswitch pswitch-joined Apr 20 18:29:43 volumio volumio[4865]: Playback channels: Mono Apr 20 18:29:43 volumio volumio[4865]: Mono: Playback [off] Apr 20 18:29:43 volumio volumio[4865]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 20 18:29:43 volumio volumio[4865]: info: Volumio called home Apr 20 18:29:43 volumio volumio[4865]: info: Discovery: adding c96a0261-0fb2-4c48-82b5-c3fa13e3495c Apr 20 18:29:43 volumio volumio[4865]: info: mDNS: Found device Volumio Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::volumioGetState Apr 20 18:29:43 volumio volumio[4865]: info: CorePlayQueue::getTrack 0 Apr 20 18:29:43 volumio volumio[4865]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Apr 20 18:29:43 volumio volumio[4865]: Unable to parse: Apr 20 18:29:43 volumio volumio[4865]: Simple mixer control 'Audio hdmi-out mute',0 Apr 20 18:29:43 volumio volumio[4865]: Capabilities: pswitch pswitch-joined Apr 20 18:29:43 volumio volumio[4865]: Playback channels: Mono Apr 20 18:29:43 volumio volumio[4865]: Mono: Playback [off] Apr 20 18:29:43 volumio volumio[4865]: info: VolumeController:: Volume=undefined Mute =false Apr 20 18:29:43 volumio volumio[4865]: info: CoreStateMachine::pushState Apr 20 18:29:43 volumio volumio[4865]: info: CorePlayQueue::getTrack 0 Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::volumioPushState Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:29:43 volumio volumio[4865]: info: Starting Shairport Sync Apr 20 18:29:43 volumio volumio[4865]: info: Starting Shairport Sync Apr 20 18:29:43 volumio volumio[4865]: info: Starting Shairport Sync Apr 20 18:29:43 volumio sudo[5146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 18:29:43 volumio sudo[5146]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:43 volumio sudo[5148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 18:29:43 volumio sudo[5148]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 18:29:43 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 20 18:29:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 18:29:43 volumio sudo[5152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 18:29:43 volumio sudo[5152]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 18:29:43 volumio sudo[5146]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:43 volumio sudo[5148]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 18:29:43 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 20 18:29:43 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 20 18:29:43 volumio volumio[4865]: info: Shairport-Sync Started Apr 20 18:29:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 18:29:43 volumio volumio[4865]: Error adding Membership: Error: addMembership EINVAL Apr 20 18:29:43 volumio volumio[4865]: info: Shairport-Sync Started Apr 20 18:29:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 18:29:43 volumio sudo[5152]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:43 volumio volumio[4865]: info: CoreCommandRouter::volumioGetState Apr 20 18:29:43 volumio volumio[4865]: info: CorePlayQueue::getTrack 0 Apr 20 18:29:43 volumio volumio[4865]: info: Shairport-Sync Started Apr 20 18:29:43 volumio sudo[5179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 18:29:43 volumio sudo[5179]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:43 volumio sudo[5179]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:43 volumio sudo[5181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 18:29:43 volumio sudo[5181]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:43 volumio sudo[5181]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:44 volumio volumio[4865]: verbose: New Socket.io Connection to 192.168.7.70 from 192.168.7.16 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/108.0.0.0 Safari/537.36 Total Clients: 5 Apr 20 18:29:44 volumio mpd[5118]: Apr 20 18:29 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 18:29:44 volumio mpd[5118]: Apr 20 18:29 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 18:29:44 volumio mpd[5118]: Apr 20 18:29 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 18:29:44 volumio systemd[1]: Started Music Player Daemon. Apr 20 18:29:44 volumio sudo[5089]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:44 volumio volumio[4865]: error: updateQueue error: null Apr 20 18:29:44 volumio sudo[5192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 18:29:44 volumio sudo[5192]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:44 volumio sudo[5192]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:44 volumio sudo[5194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 18:29:44 volumio sudo[5194]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:44 volumio sudo[5194]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:44 volumio volumio[4865]: verbose: New Socket.io Connection to 192.168.7.70 from 192.168.7.16 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/108.0.0.0 Safari/537.36 Total Clients: 6 Apr 20 18:29:44 volumio volumio[4865]: info: CoreCommandRouter::volumioGetState Apr 20 18:29:44 volumio volumio[4865]: info: CorePlayQueue::getTrack 0 Apr 20 18:29:44 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 18:29:44 volumio volumio[4865]: info: CoreCommandRouter::volumioGetVisibleSources Apr 20 18:29:44 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 18:29:44 volumio volumio[4865]: info: Listing playlists Apr 20 18:29:44 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 20 18:29:44 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 18:29:44 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 18:29:44 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 18:29:45 volumio volumio[4865]: info: VolumeController::SetAlsaVolume0 Apr 20 18:29:45 volumio volumio[4865]: info: CoreStateMachine::pushState Apr 20 18:29:45 volumio volumio[4865]: info: CorePlayQueue::getTrack 0 Apr 20 18:29:45 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 18:29:45 volumio volumio[4865]: info: CoreCommandRouter::volumioPushState Apr 20 18:29:47 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 20 18:29:47 volumio kernel: fb: mem_free_work, free memory: addr:800000 Apr 20 18:29:47 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 20 18:29:47 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 20 18:29:47 volumio sudo[5203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 18:29:47 volumio sudo[5203]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:47 volumio sudo[5203]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:47 volumio sudo[5205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 18:29:47 volumio sudo[5205]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:47 volumio sudo[5205]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:48 volumio sudo[5210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 20 18:29:48 volumio sudo[5210]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:48 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 20 18:29:48 volumio sudo[5210]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:48 volumio volumio[4865]: info: Upmpdcli Daemon Started Apr 20 18:29:48 volumio volumio[5212]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 20 18:29:48 volumio volumio[4865]: error: updateQueue error: null Apr 20 18:29:48 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Apr 20 18:29:49 volumio volumio[5212]: ....................................++++ Apr 20 18:29:50 volumio volumio[5212]: ........++++ Apr 20 18:29:50 volumio volumio[5212]: e is 65537 (0x010001) Apr 20 18:29:50 volumio volumio[5212]: writing RSA key Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 20 18:29:51 volumio volumio[4865]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 20 18:29:51 volumio volumio[4865]: info: Adding plugin bluetooth to MyMusic Plugins Apr 20 18:29:51 volumio volumio[4865]: info: Adding plugin multiroom to MyMusic Plugins Apr 20 18:29:51 volumio volumio[4865]: info: Adding plugin cd_controller to MyMusic Plugins Apr 20 18:29:52 volumio volumio[4865]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 20 18:29:52 volumio volumio[4865]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 20 18:29:52 volumio volumio[4865]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 20 18:29:53 volumio volumio[4865]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 20 18:29:53 volumio volumio[4865]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 20 18:29:53 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:29:53 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:29:53 volumio volumio[4865]: info: Starting MyVolumio Remote Streaming Endpoints Apr 20 18:29:53 volumio volumio[4865]: info: MyVolumio login type: Token Apr 20 18:29:53 volumio volumio[4865]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 20 18:29:53 volumio volumio[4865]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 20 18:29:53 volumio volumio[4865]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 20 18:29:53 volumio volumio[4865]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 20 18:29:53 volumio volumio[4865]: info: Streaming services startup Apr 20 18:29:53 volumio volumio[4865]: info: Starting Streaming Daemon Apr 20 18:29:53 volumio sudo[5266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 20 18:29:53 volumio sudo[5266]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:53 volumio systemd[1]: Stopping Volumio Streaming Daemon... Apr 20 18:29:53 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Apr 20 18:29:53 volumio systemd[1]: volumio-streaming-daemon.service: Succeeded. Apr 20 18:29:53 volumio systemd[1]: Stopped Volumio Streaming Daemon. Apr 20 18:29:53 volumio systemd[1]: Started Volumio Streaming Daemon. Apr 20 18:29:53 volumio sudo[5266]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:53 volumio volumio-streaming-daemon[5273]: ############################ Apr 20 18:29:53 volumio volumio-streaming-daemon[5273]: # Volumio Streaming Daemon # Apr 20 18:29:53 volumio volumio-streaming-daemon[5273]: # Running on port 7777 # Apr 20 18:29:53 volumio volumio-streaming-daemon[5273]: ############################ Apr 20 18:29:53 volumio volumio-streaming-daemon[5273]: Environment: production Apr 20 18:29:53 volumio volumio[4865]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 20 18:29:53 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 20 18:29:53 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:29:53 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 20 18:29:53 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:29:53 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:29:53 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:29:53 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:29:53 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:29:53 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:29:53 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:29:53 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 20 18:29:53 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 20 18:29:54 volumio volumio[4865]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 20 18:29:54 volumio volumio[4865]: info: MYVOLUMIO: Adding device Apr 20 18:29:54 volumio volumio[4865]: info: MYVOLUMIO: Evaluating Server Apr 20 18:29:55 volumio volumio[4865]: info: MyVolumio status changed Apr 20 18:29:55 volumio volumio[4865]: info: Streaming services startup Apr 20 18:29:55 volumio volumio[4865]: info: Re-Starting Streaming Daemon Apr 20 18:29:55 volumio volumio[4865]: info: Removing browser output: myVolumio user plan is not superstar Apr 20 18:29:55 volumio volumio[4865]: info: Removing audio output: Apr 20 18:29:55 volumio volumio[4865]: info: Stoppping Tunnel 1 Apr 20 18:29:55 volumio sudo[5325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 20 18:29:55 volumio sudo[5325]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:29:55 volumio sudo[5325]: pam_unix(sudo:session): session closed for user root Apr 20 18:29:55 volumio volumio[4865]: info: Remote SSH Stopped Apr 20 18:29:59 volumio volumio[4865]: info: Setting Geolocation for MyVolumio to eu7 Apr 20 18:29:59 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:29:59 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:29:59 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:29:59 volumio volumio-streaming-daemon[5273]: Restarting daemon Apr 20 18:29:59 volumio volumio-streaming-daemon[5273]: Environment: production Apr 20 18:29:59 volumio volumio-streaming-daemon[5273]: Environment: production Apr 20 18:29:59 volumio volumio-streaming-daemon[5273]: Environment: production Apr 20 18:30:00 volumio volumio[4865]: info: Updating MyVolumio device info Apr 20 18:30:00 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:30:00 volumio wpa_supplicant[4534]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Chateau2_IoT" Apr 20 18:30:00 volumio wpa_supplicant[4534]: wlan0: SME: Trying to authenticate with f4:92:bf:c4:68:3a (SSID='Chateau2_IoT' freq=2462 MHz) Apr 20 18:30:00 volumio kernel: wlan0: authenticate with f4:92:bf:c4:68:3a Apr 20 18:30:00 volumio kernel: wlan0: send auth to f4:92:bf:c4:68:3a (try 1/3) Apr 20 18:30:00 volumio volumio[4865]: info: Fetching Streaming Services browse cache Apr 20 18:30:00 volumio volumio[4865]: info: Fetching Streaming Services browse cache Apr 20 18:30:00 volumio kernel: wlan0: send auth to f4:92:bf:c4:68:3a (try 2/3) Apr 20 18:30:01 volumio kernel: wlan0: send auth to f4:92:bf:c4:68:3a (try 3/3) Apr 20 18:30:01 volumio kernel: wlan0: authentication with f4:92:bf:c4:68:3a timed out Apr 20 18:30:01 volumio wpa_supplicant[4534]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Chateau2_IoT" auth_failures=3 duration=46 reason=CONN_FAILED Apr 20 18:30:01 volumio volumio[4865]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 20 18:30:01 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 20 18:30:01 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 20 18:30:01 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 20 18:30:01 volumio volumio[4865]: info: Found match in Cards Database: setting mixer for card HiFi Shield/Shield+ Apr 20 18:30:01 volumio volumio[4865]: info: Setting mixer TDM MCLK Fine Setting for card HiFi Shield/Shield+ Apr 20 18:30:01 volumio volumio[4865]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 20 18:30:01 volumio volumio[4865]: info: Updating Volume Controller Parameters: Device: 1 Name: HiFi Shield/Shield+ Mixer: TDM MCLK Fine Setting Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Apr 20 18:30:01 volumio volumio[4865]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 20 18:30:01 volumio volumio[4865]: info: Preparing to generate the ALSA configuration file Apr 20 18:30:01 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 20 18:30:01 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:30:01 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 20 18:30:02 volumio volumio[4865]: Unable to parse: Apr 20 18:30:02 volumio volumio[4865]: Simple mixer control 'TDM MCLK Fine Setting',0 Apr 20 18:30:02 volumio volumio[4865]: Capabilities: volume volume-joined Apr 20 18:30:02 volumio volumio[4865]: Playback channels: Mono Apr 20 18:30:02 volumio volumio[4865]: Capture channels: Mono Apr 20 18:30:02 volumio volumio[4865]: Limits: 0 - 2000000 Apr 20 18:30:02 volumio volumio[4865]: Mono: 12287994 [614%] Apr 20 18:30:02 volumio volumio[4865]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 20 18:30:02 volumio volumio[4865]: info: Asound.conf file written Apr 20 18:30:02 volumio sudo[5348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 20 18:30:02 volumio sudo[5348]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:30:02 volumio sudo[5348]: pam_unix(sudo:session): session closed for user root Apr 20 18:30:02 volumio kernel: out of value, fixed it Apr 20 18:30:02 volumio kernel: id=0 set inskew=0 Apr 20 18:30:02 volumio volumio[4865]: Found hardware: "ODROID-HDMI" "" "" "" "" Apr 20 18:30:02 volumio volumio[4865]: Hardware is initialized using a generic method Apr 20 18:30:02 volumio volumio[4865]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Apr 20 18:30:02 volumio volumio[4865]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Apr 20 18:30:02 volumio volumio[4865]: No state is present for card ODROIDDAC Apr 20 18:30:02 volumio volumio[4865]: Found hardware: "ODROID-DAC" "" "" "" "" Apr 20 18:30:02 volumio volumio[4865]: Hardware is initialized using a generic method Apr 20 18:30:02 volumio volumio[4865]: No state is present for card ODROIDDAC Apr 20 18:30:02 volumio volumio[4865]: info: Output device has changed, restarting MPD Apr 20 18:30:02 volumio volumio[4865]: info: Output device has changed, restarting Shairport Sync Apr 20 18:30:02 volumio sudo[5353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:30:02 volumio sudo[5353]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:30:02 volumio sudo[5353]: pam_unix(sudo:session): session closed for user root Apr 20 18:30:02 volumio sudo[5355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 18:30:02 volumio sudo[5355]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:30:02 volumio systemd[1]: Stopping Music Player Daemon... Apr 20 18:30:02 volumio volumio[4865]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 18:30:02 volumio volumio[4865]: info: Output device has changed, restarting MPD Apr 20 18:30:02 volumio volumio[4865]: info: Output device has changed, restarting Shairport Sync Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio sudo[5362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 18:30:02 volumio sudo[5362]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:30:02 volumio systemd[1]: mpd.service: Succeeded. Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:30:02 volumio systemd[1]: Stopped Music Player Daemon. Apr 20 18:30:02 volumio sudo[5362]: pam_unix(sudo:session): session closed for user root Apr 20 18:30:02 volumio systemd[1]: Starting Music Player Daemon... Apr 20 18:30:02 volumio sudo[5364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 18:30:02 volumio sudo[5364]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:30:02 volumio volumio[4865]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 20 18:30:02 volumio volumio[4865]: info: MPD Permissions set Apr 20 18:30:02 volumio volumio[4865]: info: MPD Permissions set Apr 20 18:30:02 volumio volumio[4865]: Unable to parse: Apr 20 18:30:02 volumio volumio[4865]: Simple mixer control 'TDM MCLK Fine Setting',0 Apr 20 18:30:02 volumio volumio[4865]: Capabilities: volume volume-joined Apr 20 18:30:02 volumio volumio[4865]: Playback channels: Mono Apr 20 18:30:02 volumio volumio[4865]: Capture channels: Mono Apr 20 18:30:02 volumio volumio[4865]: Limits: 0 - 2000000 Apr 20 18:30:02 volumio volumio[4865]: Mono: 12287994 [614%] Apr 20 18:30:02 volumio volumio[4865]: info: VolumeController:: Volume=undefined Mute =false Apr 20 18:30:02 volumio volumio[4865]: info: CoreStateMachine::pushState Apr 20 18:30:02 volumio volumio[4865]: info: CorePlayQueue::getTrack 0 Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::volumioPushState Apr 20 18:30:02 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 20 18:30:02 volumio systemd[1]: mpd.service: Succeeded. Apr 20 18:30:02 volumio systemd[1]: Stopped Music Player Daemon. Apr 20 18:30:02 volumio systemd[1]: Starting Music Player Daemon... Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:02 volumio volumio[4865]: verbose: New Socket.io Connection to 192.168.7.70 from 192.168.7.16 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/108.0.0.0 Safari/537.36 Total Clients: 7 Apr 20 18:30:02 volumio volumio[4865]: info: Starting Shairport Sync Apr 20 18:30:02 volumio volumio[4865]: info: Starting Shairport Sync Apr 20 18:30:02 volumio sudo[5410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 18:30:02 volumio sudo[5410]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:30:02 volumio sudo[5416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 20 18:30:02 volumio sudo[5416]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::volumioGetState Apr 20 18:30:02 volumio volumio[4865]: info: CorePlayQueue::getTrack 0 Apr 20 18:30:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::volumioGetQueue Apr 20 18:30:02 volumio volumio[4865]: info: CoreStateMachine::getQueue Apr 20 18:30:02 volumio volumio[4865]: info: CorePlayQueue::getQueue Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Apr 20 18:30:02 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 20 18:30:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 20 18:30:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 20 18:30:02 volumio sudo[5410]: pam_unix(sudo:session): session closed for user root Apr 20 18:30:02 volumio sudo[5416]: pam_unix(sudo:session): session closed for user root Apr 20 18:30:02 volumio volumio[4865]: info: Shairport-Sync Started Apr 20 18:30:02 volumio volumio[4865]: info: Shairport-Sync Started Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 18:30:02 volumio volumio[4865]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 20 18:30:02 volumio volumio[4865]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Apr 20 18:30:02 volumio volumio[4865]: info: Received Get System Version Apr 20 18:30:02 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 18:30:03 volumio mpd[5455]: Apr 20 18:30 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 18:30:03 volumio mpd[5455]: Apr 20 18:30 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 20 18:30:03 volumio mpd[5455]: Apr 20 18:30 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 20 18:30:03 volumio systemd[1]: Started Music Player Daemon. Apr 20 18:30:03 volumio sudo[5364]: pam_unix(sudo:session): session closed for user root Apr 20 18:30:03 volumio sudo[5355]: pam_unix(sudo:session): session closed for user root Apr 20 18:30:03 volumio volumio[4865]: error: MPD error: The expression evaluated to a falsy value: Apr 20 18:30:03 volumio volumio[4865]: assert.ok(self.idling) Apr 20 18:30:03 volumio volumio[4865]: error: updateQueue error: null Apr 20 18:30:04 volumio volumio[4865]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 20 18:30:04 volumio volumio[4865]: info: CoreStateMachine::getcurrentVolume Apr 20 18:30:04 volumio volumio[4865]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 18:30:04 volumio volumio[4865]: Unable to parse: Apr 20 18:30:04 volumio volumio[4865]: Simple mixer control 'TDM MCLK Fine Setting',0 Apr 20 18:30:04 volumio volumio[4865]: Capabilities: volume volume-joined Apr 20 18:30:04 volumio volumio[4865]: Playback channels: Mono Apr 20 18:30:04 volumio volumio[4865]: Capture channels: Mono Apr 20 18:30:04 volumio volumio[4865]: Limits: 0 - 2000000 Apr 20 18:30:04 volumio volumio[4865]: Mono: 12287994 [614%] Apr 20 18:30:04 volumio volumio[4865]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 20 18:30:04 volumio volumio[4865]: Unable to parse: Apr 20 18:30:04 volumio volumio[4865]: Simple mixer control 'TDM MCLK Fine Setting',0 Apr 20 18:30:04 volumio volumio[4865]: Capabilities: volume volume-joined Apr 20 18:30:04 volumio volumio[4865]: Playback channels: Mono Apr 20 18:30:04 volumio volumio[4865]: Capture channels: Mono Apr 20 18:30:04 volumio volumio[4865]: Limits: 0 - 2000000 Apr 20 18:30:04 volumio volumio[4865]: Mono: 12287994 [614%] Apr 20 18:30:04 volumio volumio[4865]: info: VolumeController:: Volume=undefined Mute =false Apr 20 18:30:04 volumio volumio[4865]: info: CoreStateMachine::pushState Apr 20 18:30:04 volumio volumio[4865]: info: CorePlayQueue::getTrack 0 Apr 20 18:30:04 volumio volumio[4865]: info: CoreCommandRouter::volumioPushState Apr 20 18:30:04 volumio volumio[4865]: info: CoreStateMachine::updateTrackBlock Apr 20 18:30:04 volumio volumio[4865]: info: CorePlayQueue::getTrackBlock Apr 20 18:30:04 volumio volumio[4865]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 18:30:04 volumio volumio[4865]: Unable to parse: Apr 20 18:30:04 volumio volumio[4865]: Simple mixer control 'TDM MCLK Fine Setting',0 Apr 20 18:30:04 volumio volumio[4865]: Capabilities: volume volume-joined Apr 20 18:30:04 volumio volumio[4865]: Playback channels: Mono Apr 20 18:30:04 volumio volumio[4865]: Capture channels: Mono Apr 20 18:30:04 volumio volumio[4865]: Limits: 0 - 2000000 Apr 20 18:30:04 volumio volumio[4865]: Mono: 12287994 [614%] Apr 20 18:30:04 volumio volumio[4865]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Apr 20 18:30:04 volumio volumio[4865]: Unable to parse: Apr 20 18:30:04 volumio volumio[4865]: Simple mixer control 'TDM MCLK Fine Setting',0 Apr 20 18:30:04 volumio volumio[4865]: Capabilities: volume volume-joined Apr 20 18:30:04 volumio volumio[4865]: Playback channels: Mono Apr 20 18:30:04 volumio volumio[4865]: Capture channels: Mono Apr 20 18:30:04 volumio volumio[4865]: Limits: 0 - 2000000 Apr 20 18:30:04 volumio volumio[4865]: Mono: 12287994 [614%] Apr 20 18:30:04 volumio volumio[4865]: info: VolumeController:: Volume=undefined Mute =false Apr 20 18:30:04 volumio volumio[4865]: info: CoreStateMachine::pushState Apr 20 18:30:04 volumio volumio[4865]: info: CorePlayQueue::getTrack 0 Apr 20 18:30:04 volumio volumio[4865]: info: CoreCommandRouter::volumioPushState Apr 20 18:30:08 volumio volumio[4865]: error: updateQueue error: null Apr 20 18:30:13 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 18:30:13 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:30:13 volumio volumio[4865]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 20 18:30:13 volumio volumio[4865]: info: Completed starting MyVolumio Plugin Apr 20 18:30:13 volumio volumio[4865]: info: BOOT COMPLETED Apr 20 18:30:13 volumio volumio[4865]: [Metrics] CommandRouter: 39s 133.51ms Apr 20 18:30:13 volumio volumio[4865]: info: CoreCommandRouter::volumiosetStartupVolume Apr 20 18:30:13 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 18:30:13 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 18:30:13 volumio volumio[4865]: info: CoreCommandRouter::Close All Modals sent Apr 20 18:30:13 volumio volumio[4865]: info: CoreCommandRouter::Close All Modals sent Apr 20 18:30:13 volumio kernel: aml_tdm_open Apr 20 18:30:13 volumio kernel: Not init audio effects Apr 20 18:30:13 volumio kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Apr 20 18:30:13 volumio kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 20 18:30:13 volumio kernel: asoc aml_dai_set_tdm_fmt, 0x4011, ffffffc0492a2c18, id(1), clksel(1) Apr 20 18:30:13 volumio kernel: aml_dai_set_tdm_fmt(), fmt not change Apr 20 18:30:13 volumio kernel: dump_pcm_setting(ffffffc0492a2c18) Apr 20 18:30:13 volumio kernel: pcm_mode(1) Apr 20 18:30:13 volumio kernel: sysclk(11289600) Apr 20 18:30:13 volumio kernel: sysclk_bclk_ratio(4) Apr 20 18:30:13 volumio kernel: bclk(2822400) Apr 20 18:30:13 volumio kernel: bclk_lrclk_ratio(64) Apr 20 18:30:13 volumio kernel: lrclk(44100) Apr 20 18:30:13 volumio kernel: tx_mask(0x3) Apr 20 18:30:13 volumio kernel: rx_mask(0x3) Apr 20 18:30:13 volumio kernel: slots(2) Apr 20 18:30:13 volumio kernel: slot_width(32) Apr 20 18:30:13 volumio kernel: lane_mask_in(0x2) Apr 20 18:30:13 volumio kernel: lane_mask_out(0x1) Apr 20 18:30:13 volumio kernel: lane_oe_mask_in(0x0) Apr 20 18:30:13 volumio kernel: lane_oe_mask_out(0x0) Apr 20 18:30:13 volumio kernel: lane_lb_mask_in(0x0) Apr 20 18:30:13 volumio kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Apr 20 18:30:13 volumio kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Apr 20 18:30:13 volumio kernel: set mclk:11289600, mpll:22579200, get mclk:11289593, mpll:22579186 Apr 20 18:30:13 volumio kernel: aml_dai_set_clkdiv, div 4, clksel(1) Apr 20 18:30:13 volumio kernel: aml_dai_set_bclk_ratio, select I2S mode Apr 20 18:30:13 volumio kernel: aml_tdm_prepare(), reset fddr Apr 20 18:30:13 volumio kernel: tdm playback mute: 0, lane_cnt = 8 Apr 20 18:30:13 volumio kernel: asoc-aml-card odroid_hifi: tdm playback enable Apr 20 18:30:14 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 20 18:30:14 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 20 18:30:14 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 20 18:30:19 volumio kernel: asoc-aml-card odroid_hifi: tdm playback stop Apr 20 18:30:19 volumio kernel: tdm playback mute: 1, lane_cnt = 8 Apr 20 18:30:19 volumio kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Apr 20 18:30:23 volumio volumio[4865]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 20 18:30:23 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 20 18:30:23 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 20 18:30:23 volumio sudo[5477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 20 18:30:23 volumio sudo[5477]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:30:23 volumio sudo[5482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 20 18:30:23 volumio sudo[5482]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:30:23 volumio sudo[5477]: pam_unix(sudo:session): session closed for user root Apr 20 18:30:23 volumio sudo[5482]: pam_unix(sudo:session): session closed for user root Apr 20 18:30:23 volumio sudo[5489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 20 18:30:23 volumio sudo[5489]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:30:23 volumio sudo[5489]: pam_unix(sudo:session): session closed for user root Apr 20 18:30:23 volumio sudo[5498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 20 18:30:23 volumio sudo[5498]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:30:23 volumio sudo[5498]: pam_unix(sudo:session): session closed for user root Apr 20 18:30:23 volumio sudo[5505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 18:30:23 volumio sudo[5505]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:30:23 volumio sudo[5505]: pam_unix(sudo:session): session closed for user root Apr 20 18:30:23 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 20 18:30:23 volumio sudo[5509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 18:30:23 volumio sudo[5509]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:30:23 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 20 18:30:23 volumio sudo[5509]: pam_unix(sudo:session): session closed for user root Apr 20 18:30:23 volumio sudo[5512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 20 18:30:23 volumio sudo[5512]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:30:24 volumio sudo[5512]: pam_unix(sudo:session): session closed for user root Apr 20 18:30:46 volumio volumio[4865]: verbose: New Socket.io Connection to 192.168.7.70 from 192.168.7.16 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/108.0.0.0 Safari/537.36 Total Clients: 7 Apr 20 18:30:46 volumio volumio[4865]: info: CoreCommandRouter::volumioGetState Apr 20 18:30:46 volumio volumio[4865]: info: CorePlayQueue::getTrack 0 Apr 20 18:30:46 volumio volumio[4865]: info: CoreCommandRouter::volumioGetQueue Apr 20 18:30:46 volumio volumio[4865]: info: CoreStateMachine::getQueue Apr 20 18:30:46 volumio volumio[4865]: info: CorePlayQueue::getQueue Apr 20 18:30:46 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Apr 20 18:30:48 volumio volumio[4865]: info: CALLMETHOD: system_controller system enableLiveLog true Apr 20 18:30:48 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Apr 20 18:30:48 volumio volumio[4865]: info: Launching a new LiveLog session Apr 20 18:30:48 volumio wpa_supplicant[4534]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Chateau2_IoT" Apr 20 18:30:48 volumio wpa_supplicant[4534]: wlan0: SME: Trying to authenticate with 18:e8:29:c1:a1:ff (SSID='Chateau2_IoT' freq=2412 MHz) Apr 20 18:30:48 volumio kernel: wlan0: authenticate with 18:e8:29:c1:a1:ff Apr 20 18:30:48 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 1/3) Apr 20 18:30:48 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 2/3) Apr 20 18:30:48 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 3/3) Apr 20 18:30:49 volumio kernel: wlan0: authentication with 18:e8:29:c1:a1:ff timed out Apr 20 18:30:49 volumio wpa_supplicant[4534]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Chateau2_IoT" auth_failures=4 duration=77 reason=CONN_FAILED Apr 20 18:31:13 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Apr 20 18:31:13 volumio volumio[4865]: info: Saving new wireless network Apr 20 18:31:13 volumio sudo[5516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Apr 20 18:31:13 volumio sudo[5516]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:13 volumio sudo[5516]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:14 volumio sudo[5519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 20 18:31:14 volumio sudo[5519]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:14 volumio systemd[1]: Stopping Wireless Services... Apr 20 18:31:14 volumio wpa_supplicant[4534]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Apr 20 18:31:14 volumio wpa_supplicant[4534]: wlan0: CTRL-EVENT-TERMINATING Apr 20 18:31:14 volumio systemd[1]: wireless.service: Succeeded. Apr 20 18:31:14 volumio systemd[1]: Stopped Wireless Services. Apr 20 18:31:14 volumio systemd[1]: Starting Wireless Services... Apr 20 18:31:14 volumio wireless.js[5521]: WIRELESS DAEMON: start Apr 20 18:31:14 volumio wireless.js[5521]: WIRELESS: Loaded configuration Apr 20 18:31:14 volumio wireless.js[5521]: Cleaning previous... Apr 20 18:31:14 volumio sudo[5557]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 20 18:31:14 volumio sudo[5557]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:14 volumio sudo[5557]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:14 volumio sudo[5559]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 20 18:31:14 volumio sudo[5559]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:14 volumio sudo[5559]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:14 volumio wireless.js[5521]: Stopped aP Apr 20 18:31:14 volumio wireless.js[5521]: Start wireless flow Apr 20 18:31:14 volumio wireless.js[5521]: Stopped hotspot (if there).. Apr 20 18:31:14 volumio sudo[5566]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 20 18:31:14 volumio sudo[5566]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:14 volumio sudo[5566]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:14 volumio sudo[5568]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 20 18:31:14 volumio sudo[5568]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:14 volumio sudo[5568]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:14 volumio wireless.js[5521]: DHCP IP Apr 20 18:31:14 volumio wireless.js[5521]: Start ap Apr 20 18:31:14 volumio wpa_supplicant[5570]: Successfully initialized wpa_supplicant Apr 20 18:31:14 volumio sudo[5571]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 20 18:31:14 volumio sudo[5571]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:14 volumio dhcpcd[5572]: sending commands to master dhcpcd process Apr 20 18:31:14 volumio dhcpcd[3824]: control command: /sbin/dhcpcd Apr 20 18:31:14 volumio sudo[5571]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:15 volumio wireless.js[5521]: trying... Apr 20 18:31:15 volumio sudo[5576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:15 volumio sudo[5576]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:15 volumio sudo[5576]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:15 volumio wpa_supplicant[5574]: wlan0: SME: Trying to authenticate with 18:e8:29:c1:a1:ff (SSID='Chateau2_IoT' freq=2412 MHz) Apr 20 18:31:15 volumio kernel: wlan0: authenticate with 18:e8:29:c1:a1:ff Apr 20 18:31:15 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 1/3) Apr 20 18:31:16 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 2/3) Apr 20 18:31:16 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 3/3) Apr 20 18:31:16 volumio kernel: wlan0: authentication with 18:e8:29:c1:a1:ff timed out Apr 20 18:31:16 volumio wireless.js[5521]: trying... Apr 20 18:31:16 volumio sudo[5579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:16 volumio sudo[5579]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:16 volumio sudo[5579]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:16 volumio wpa_supplicant[5574]: wlan0: SME: Trying to authenticate with 94:83:c4:1d:e3:64 (SSID='Chateau2_IoT' freq=2462 MHz) Apr 20 18:31:16 volumio kernel: wlan0: authenticate with 94:83:c4:1d:e3:64 Apr 20 18:31:16 volumio kernel: wlan0: send auth to 94:83:c4:1d:e3:64 (try 1/3) Apr 20 18:31:16 volumio kernel: wlan0: send auth to 94:83:c4:1d:e3:64 (try 2/3) Apr 20 18:31:17 volumio kernel: wlan0: send auth to 94:83:c4:1d:e3:64 (try 3/3) Apr 20 18:31:17 volumio kernel: wlan0: authentication with 94:83:c4:1d:e3:64 timed out Apr 20 18:31:17 volumio wireless.js[5521]: trying... Apr 20 18:31:17 volumio sudo[5582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:17 volumio sudo[5582]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:17 volumio sudo[5582]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:18 volumio wpa_supplicant[5574]: wlan0: SME: Trying to authenticate with 18:e8:29:c1:a1:ff (SSID='Chateau2_IoT' freq=2412 MHz) Apr 20 18:31:18 volumio kernel: wlan0: authenticate with 18:e8:29:c1:a1:ff Apr 20 18:31:18 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 1/3) Apr 20 18:31:18 volumio wireless.js[5521]: trying... Apr 20 18:31:18 volumio sudo[5585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:18 volumio sudo[5585]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:18 volumio sudo[5585]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:18 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 2/3) Apr 20 18:31:19 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 3/3) Apr 20 18:31:19 volumio kernel: wlan0: authentication with 18:e8:29:c1:a1:ff timed out Apr 20 18:31:19 volumio wireless.js[5521]: trying... Apr 20 18:31:19 volumio sudo[5588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:19 volumio sudo[5588]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:19 volumio sudo[5588]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:20 volumio wireless.js[5521]: trying... Apr 20 18:31:20 volumio sudo[5591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:20 volumio sudo[5591]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:20 volumio sudo[5591]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:21 volumio wireless.js[5521]: trying... Apr 20 18:31:21 volumio sudo[5594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:21 volumio sudo[5594]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:21 volumio sudo[5594]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:22 volumio wireless.js[5521]: trying... Apr 20 18:31:22 volumio sudo[5597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:22 volumio sudo[5597]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:22 volumio sudo[5597]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:23 volumio wireless.js[5521]: trying... Apr 20 18:31:23 volumio sudo[5600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:23 volumio sudo[5600]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:23 volumio sudo[5600]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:24 volumio wireless.js[5521]: trying... Apr 20 18:31:24 volumio sudo[5603]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:24 volumio sudo[5603]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:24 volumio sudo[5603]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:25 volumio wireless.js[5521]: trying... Apr 20 18:31:25 volumio sudo[5606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:25 volumio sudo[5606]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:25 volumio sudo[5606]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:26 volumio wpa_supplicant[5574]: wlan0: SME: Trying to authenticate with 18:e8:29:c1:a1:ff (SSID='Chateau2_IoT' freq=2412 MHz) Apr 20 18:31:26 volumio kernel: wlan0: authenticate with 18:e8:29:c1:a1:ff Apr 20 18:31:26 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 1/3) Apr 20 18:31:26 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 2/3) Apr 20 18:31:26 volumio wireless.js[5521]: trying... Apr 20 18:31:26 volumio sudo[5609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:26 volumio sudo[5609]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:26 volumio sudo[5609]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:26 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 3/3) Apr 20 18:31:27 volumio kernel: wlan0: authentication with 18:e8:29:c1:a1:ff timed out Apr 20 18:31:27 volumio wpa_supplicant[5574]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Chateau2_IoT" auth_failures=1 duration=10 reason=CONN_FAILED Apr 20 18:31:27 volumio wireless.js[5521]: trying... Apr 20 18:31:27 volumio sudo[5612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:27 volumio sudo[5612]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:27 volumio sudo[5612]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:28 volumio wireless.js[5521]: trying... Apr 20 18:31:28 volumio sudo[5615]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:28 volumio sudo[5615]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:28 volumio sudo[5615]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:29 volumio wireless.js[5521]: trying... Apr 20 18:31:29 volumio sudo[5618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:29 volumio sudo[5618]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:29 volumio sudo[5618]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:30 volumio wireless.js[5521]: trying... Apr 20 18:31:30 volumio sudo[5621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:30 volumio sudo[5621]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:30 volumio sudo[5621]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:31 volumio wireless.js[5521]: trying... Apr 20 18:31:31 volumio sudo[5624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:31 volumio sudo[5624]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:31 volumio sudo[5624]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:32 volumio wireless.js[5521]: trying... Apr 20 18:31:32 volumio sudo[5627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:32 volumio sudo[5627]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:32 volumio sudo[5627]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:33 volumio wireless.js[5521]: trying... Apr 20 18:31:33 volumio sudo[5630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:33 volumio sudo[5630]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:33 volumio sudo[5630]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:34 volumio wireless.js[5521]: trying... Apr 20 18:31:34 volumio sudo[5633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 20 18:31:34 volumio sudo[5633]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:34 volumio sudo[5633]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:35 volumio wireless.js[5521]: Overtime, starting plan B Apr 20 18:31:35 volumio systemd[1]: Started Wireless Services. Apr 20 18:31:35 volumio sudo[5519]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:38 volumio wpa_supplicant[5574]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Chateau2_IoT" Apr 20 18:31:38 volumio wpa_supplicant[5574]: wlan0: SME: Trying to authenticate with f4:92:bf:c4:68:3a (SSID='Chateau2_IoT' freq=2462 MHz) Apr 20 18:31:38 volumio kernel: wlan0: authenticate with f4:92:bf:c4:68:3a Apr 20 18:31:38 volumio kernel: wlan0: send auth to f4:92:bf:c4:68:3a (try 1/3) Apr 20 18:31:38 volumio kernel: wlan0: send auth to f4:92:bf:c4:68:3a (try 2/3) Apr 20 18:31:38 volumio kernel: wlan0: send auth to f4:92:bf:c4:68:3a (try 3/3) Apr 20 18:31:38 volumio kernel: wlan0: authentication with f4:92:bf:c4:68:3a timed out Apr 20 18:31:38 volumio wpa_supplicant[5574]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Chateau2_IoT" auth_failures=2 duration=23 reason=CONN_FAILED Apr 20 18:31:40 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Apr 20 18:31:40 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Apr 20 18:31:40 volumio sudo[5638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 18:31:40 volumio sudo[5638]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:40 volumio sudo[5638]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:45 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Apr 20 18:31:45 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Apr 20 18:31:45 volumio volumio[4865]: info: Refreshing Cached IP Addresses Apr 20 18:31:45 volumio sudo[5642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Apr 20 18:31:45 volumio sudo[5642]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:45 volumio sudo[5644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 18:31:45 volumio sudo[5644]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:45 volumio sudo[5644]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:45 volumio sudo[5647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 18:31:45 volumio sudo[5642]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:45 volumio volumio[4865]: error: Upnp client error: Error: This socket has been ended by the other party Apr 20 18:31:45 volumio sudo[5647]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:45 volumio sudo[5647]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:46 volumio sudo[5651]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 18:31:46 volumio sudo[5651]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:46 volumio sudo[5651]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:46 volumio sudo[5653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 18:31:46 volumio sudo[5653]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:46 volumio sudo[5653]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:46 volumio volumio[4865]: verbose: New Socket.io Connection to 192.168.7.70 from 192.168.7.16 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/108.0.0.0 Safari/537.36 Total Clients: 4 Apr 20 18:31:46 volumio volumio[4865]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 20 18:31:46 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 20 18:31:46 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 20 18:31:46 volumio volumio[4865]: info: CoreCommandRouter::volumioGetState Apr 20 18:31:46 volumio volumio[4865]: info: CorePlayQueue::getTrack 0 Apr 20 18:31:46 volumio volumio[4865]: info: CoreCommandRouter::volumioGetVisibleSources Apr 20 18:31:46 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 18:31:46 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 20 18:31:46 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 20 18:31:46 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 20 18:31:46 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 18:31:46 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 20 18:31:46 volumio sudo[5663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 20 18:31:46 volumio sudo[5663]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:46 volumio sudo[5663]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:46 volumio sudo[5668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 20 18:31:46 volumio sudo[5668]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:46 volumio sudo[5668]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:46 volumio sudo[5676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 20 18:31:46 volumio sudo[5676]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:46 volumio sudo[5676]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:46 volumio sudo[5682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 20 18:31:46 volumio sudo[5682]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:46 volumio sudo[5682]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:46 volumio sudo[5689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 18:31:46 volumio sudo[5689]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:46 volumio sudo[5689]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:46 volumio sudo[5692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 18:31:46 volumio sudo[5692]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:46 volumio sudo[5692]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:46 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 20 18:31:46 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 20 18:31:46 volumio sudo[5696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 20 18:31:46 volumio sudo[5696]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:47 volumio sudo[5696]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:49 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 20 18:31:49 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 20 18:31:55 volumio sudo[5699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 18:31:55 volumio sudo[5699]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:55 volumio sudo[5699]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:55 volumio sudo[5701]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 18:31:55 volumio sudo[5701]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:55 volumio sudo[5701]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:55 volumio sudo[5704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 20 18:31:55 volumio sudo[5704]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:31:55 volumio sudo[5704]: pam_unix(sudo:session): session closed for user root Apr 20 18:31:55 volumio volumio[4865]: info: Upmpdcli Daemon Started Apr 20 18:32:03 volumio wpa_supplicant[5574]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Chateau2_IoT" Apr 20 18:32:03 volumio wpa_supplicant[5574]: wlan0: SME: Trying to authenticate with 94:83:c4:1d:e3:64 (SSID='Chateau2_IoT' freq=2462 MHz) Apr 20 18:32:03 volumio kernel: wlan0: authenticate with 94:83:c4:1d:e3:64 Apr 20 18:32:03 volumio kernel: wlan0: send auth to 94:83:c4:1d:e3:64 (try 1/3) Apr 20 18:32:03 volumio kernel: wlan0: send auth to 94:83:c4:1d:e3:64 (try 2/3) Apr 20 18:32:03 volumio kernel: wlan0: send auth to 94:83:c4:1d:e3:64 (try 3/3) Apr 20 18:32:03 volumio kernel: wlan0: authentication with 94:83:c4:1d:e3:64 timed out Apr 20 18:32:03 volumio wpa_supplicant[5574]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Chateau2_IoT" auth_failures=3 duration=46 reason=CONN_FAILED Apr 20 18:32:05 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 20 18:32:05 volumio volumio[4865]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 20 18:32:05 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 20 18:32:05 volumio volumio[4865]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Apr 20 18:32:05 volumio volumio[4865]: info: Received Get System Version Apr 20 18:32:05 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 18:32:50 volumio wpa_supplicant[5574]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="Chateau2_IoT" Apr 20 18:32:50 volumio wpa_supplicant[5574]: wlan0: SME: Trying to authenticate with 18:e8:29:c1:a1:ff (SSID='Chateau2_IoT' freq=2412 MHz) Apr 20 18:32:50 volumio kernel: wlan0: authenticate with 18:e8:29:c1:a1:ff Apr 20 18:32:50 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 1/3) Apr 20 18:32:51 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 2/3) Apr 20 18:32:51 volumio kernel: wlan0: send auth to 18:e8:29:c1:a1:ff (try 3/3) Apr 20 18:32:51 volumio kernel: wlan0: authentication with 18:e8:29:c1:a1:ff timed out Apr 20 18:32:51 volumio wpa_supplicant[5574]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="Chateau2_IoT" auth_failures=4 duration=77 reason=CONN_FAILED Apr 20 18:33:30 volumio volumio[4865]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Apr 20 18:33:30 volumio volumio[4865]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Apr 20 18:33:30 volumio sudo[5733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Apr 20 18:33:30 volumio sudo[5733]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 18:33:30 volumio sudo[5733]: pam_unix(sudo:session): session closed for user root Apr 20 18:33:35 volumio sudo[5778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Apr 20 18:33:35 volumio sudo[5778]: pam_unix(sudo:session): session opened for user root by (uid=0)