Mon Jul 10 12:46:19 UTC 2023 Description="'Supersearch is still returning No Items'" # cat /proc/version --------------- Linux version 5.10.92-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1514 SMP Mon Jan 17 17:38:03 GMT 2022 # cat /etc/os-release --------------- PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62" # ifconfig --------------- eth0: flags=4099 mtu 1500 ether dc:a6:32:b1:3a:ff 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 lo: flags=73 mtu 65536 inet 127.0.0.1 netmask 255.0.0.0 loop txqueuelen 1000 (Local Loopback) RX packets 785 bytes 94720 (92.5 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 785 bytes 94720 (92.5 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 wlan0: flags=4163 mtu 1500 inet 192.168.68.108 netmask 255.255.255.0 broadcast 192.168.68.255 ether dc:a6:32:b1:3b:00 txqueuelen 1000 (Ethernet) RX packets 84828 bytes 24559500 (23.4 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 557060 bytes 821059314 (783.0 MiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- lo no wireless extensions. eth0 no wireless extensions. wlan0 IEEE 802.11 ESSID:"Home Network" Mode:Managed Frequency:5.18 GHz Access Point: 60:A4:B7:A6:9D:F7 Bit Rate=433.3 Mb/s Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off Link Quality=70/70 Signal level=-8 dBm Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0 Tx excessive retries:1309 Invalid misc:0 Missed beacon:0 # aplay -l --------------- xcb_connection_has_error() returned true **** List of PLAYBACK Hardware Devices **** card 0: b1 [bcm2835 HDMI 1], device 0: bcm2835 HDMI 1 [bcm2835 HDMI 1] Subdevices: 4/4 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 card 1: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones] Subdevices: 4/4 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 card 2: sndrpihifiberry [snd_rpi_hifiberry_dacplushd], device 0: HiFiBerry DAC+ HD HiFi pcm179x-hifi-0 [HiFiBerry DAC+ HD HiFi pcm179x-hifi-0] Subdevices: 1/1 Subdevice #0: subdevice #0 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 1 12:36 ? 00:00:07 /sbin/init root 2 0 0 12:36 ? 00:00:00 [kthreadd] root 3 2 0 12:36 ? 00:00:00 [rcu_gp] root 4 2 0 12:36 ? 00:00:00 [rcu_par_gp] root 7 2 0 12:36 ? 00:00:03 [kworker/u8:0-ext4-rsv-conversion] root 8 2 0 12:36 ? 00:00:00 [mm_percpu_wq] root 9 2 0 12:36 ? 00:00:00 [rcu_tasks_rude_] root 10 2 0 12:36 ? 00:00:00 [rcu_tasks_trace] root 11 2 0 12:36 ? 00:00:00 [ksoftirqd/0] root 12 2 0 12:36 ? 00:00:00 [rcu_sched] root 13 2 0 12:36 ? 00:00:00 [migration/0] root 14 2 0 12:36 ? 00:00:00 [cpuhp/0] root 15 2 0 12:36 ? 00:00:00 [cpuhp/1] root 16 2 0 12:36 ? 00:00:00 [migration/1] root 17 2 0 12:36 ? 00:00:00 [ksoftirqd/1] root 20 2 0 12:36 ? 00:00:00 [cpuhp/2] root 21 2 0 12:36 ? 00:00:00 [migration/2] root 22 2 0 12:36 ? 00:00:00 [ksoftirqd/2] root 25 2 0 12:36 ? 00:00:00 [cpuhp/3] root 26 2 0 12:36 ? 00:00:00 [migration/3] root 27 2 0 12:36 ? 00:00:00 [ksoftirqd/3] root 30 2 0 12:36 ? 00:00:00 [kdevtmpfs] root 31 2 0 12:36 ? 00:00:00 [netns] root 32 2 0 12:36 ? 00:00:00 [kworker/0:1-events] root 33 2 0 12:36 ? 00:00:00 [kworker/1:1-mm_percpu_wq] root 34 2 0 12:36 ? 00:00:00 [kauditd] root 35 2 0 12:36 ? 00:00:03 [kworker/0:2-events_power_efficient] root 36 2 0 12:36 ? 00:00:00 [khungtaskd] root 37 2 0 12:36 ? 00:00:00 [oom_reaper] root 38 2 0 12:36 ? 00:00:00 [writeback] root 39 2 0 12:36 ? 00:00:00 [kcompactd0] root 59 2 0 12:36 ? 00:00:00 [kblockd] root 60 2 0 12:36 ? 00:00:00 [blkcg_punt_bio] root 61 2 0 12:36 ? 00:00:00 [watchdogd] root 62 2 0 12:36 ? 00:00:00 [kworker/1:1H-kblockd] root 63 2 0 12:36 ? 00:00:00 [rpciod] root 65 2 0 12:36 ? 00:00:00 [xprtiod] root 66 2 0 12:36 ? 00:00:00 [kswapd0] root 68 2 0 12:36 ? 00:00:00 [nfsiod] root 69 2 0 12:36 ? 00:00:00 [kthrotld] root 71 2 0 12:36 ? 00:00:00 [kworker/3:2-events] root 72 2 0 12:36 ? 00:00:00 [iscsi_eh] root 73 2 0 12:36 ? 00:00:00 [iscsi_destroy] root 74 2 0 12:36 ? 00:00:00 [nvme-wq] root 75 2 0 12:36 ? 00:00:00 [nvme-reset-wq] root 76 2 0 12:36 ? 00:00:00 [nvme-delete-wq] root 79 2 0 12:36 ? 00:00:00 [DWC Notificatio] root 80 2 0 12:36 ? 00:00:00 [uas] root 82 2 0 12:36 ? 00:00:00 [vchiq-slot/0] root 83 2 0 12:36 ? 00:00:00 [vchiq-recy/0] root 84 2 0 12:36 ? 00:00:00 [vchiq-sync/0] root 85 2 0 12:36 ? 00:00:00 [zswap-shrink] root 88 2 0 12:36 ? 00:00:00 [kworker/3:5-mm_percpu_wq] root 89 2 0 12:36 ? 00:00:00 [sdhci] root 90 2 0 12:36 ? 00:00:00 [irq/48-mmc0] root 93 2 0 12:36 ? 00:00:00 [kworker/2:4-mm_percpu_wq] root 94 2 0 12:36 ? 00:00:00 [kworker/2:5-mm_percpu_wq] root 102 2 0 12:36 ? 00:00:00 [mmc_complete] root 103 2 0 12:36 ? 00:00:00 [kworker/2:1H-kblockd] root 104 2 0 12:36 ? 00:00:00 [kworker/0:1H-kblockd] root 124 2 0 12:36 ? 00:00:00 [kworker/1:2H-kblockd] root 128 2 0 12:36 ? 00:00:00 [kworker/2:2H-kblockd] root 129 2 0 12:36 ? 00:00:00 [jbd2/mmcblk0p2-] root 130 2 0 12:36 ? 00:00:00 [ext4-rsv-conver] root 137 2 0 12:36 ? 00:00:01 [loop0] root 143 2 0 12:36 ? 00:00:00 [kworker/3:1H-kblockd] root 164 2 0 12:36 ? 00:00:00 [kworker/3:2H-kblockd] root 165 2 0 12:36 ? 00:00:01 [kworker/0:2H-kblockd] root 166 2 0 12:36 ? 00:00:00 [jbd2/mmcblk0p3-] root 167 2 0 12:36 ? 00:00:00 [ext4-rsv-conver] root 174 2 0 12:36 ? 00:00:00 [ipv6_addrconf] root 197 1 0 12:36 ? 00:00:01 /lib/systemd/systemd-journald root 222 1 0 12:36 ? 00:00:00 /lib/systemd/systemd-udevd root 270 2 0 12:36 ? 00:00:00 [vchiq-keep/0] root 272 2 0 12:36 ? 00:00:00 [SMIO] root 349 2 0 12:36 ? 00:00:00 [mmal-vchiq] root 350 2 0 12:36 ? 00:00:00 [mmal-vchiq] root 353 2 0 12:36 ? 00:00:00 [mmal-vchiq] root 354 2 0 12:36 ? 00:00:00 [mmal-vchiq] root 355 2 0 12:36 ? 00:00:00 [mmal-vchiq] root 362 2 0 12:36 ? 00:00:00 [mmal-vchiq] root 395 2 0 12:36 ? 00:00:00 [kworker/1:3-cgroup_destroy] root 412 2 0 12:36 ? 00:00:00 [cfg80211] root 414 2 0 12:36 ? 00:00:00 [brcmf_wq/mmc1:0] root 415 2 0 12:36 ? 00:00:00 [brcmf_wdog/mmc1] root 487 1 0 12:36 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 _rpc 493 1 0 12:36 ? 00:00:00 /sbin/rpcbind -f -w root 505 2 0 12:36 ? 00:00:00 [kworker/u8:4-brcmf_wq/mmc1:0001:1] root 525 1 0 12:36 ? 00:00:00 /lib/systemd/systemd-logind volumio 542 1 0 12:36 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 545 1 0 12:36 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon avahi 550 1 0 12:36 ? 00:00:00 avahi-daemon: running [volumio1.local] message+ 553 1 0 12:36 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 557 1 0 12:36 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant nobody 558 1 0 12:36 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* avahi 594 550 0 12:36 ? 00:00:00 avahi-daemon: chroot helper root 668 1 0 12:36 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group ntp 674 1 0 12:36 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 683 1 0 12:36 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I root 686 1 0 12:36 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux root 688 1 0 12:36 ? 00:00:00 /usr/sbin/sshd -D root 728 668 0 12:36 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 783 1 0 12:36 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 3000000 flow - root 785 2 0 12:36 ? 00:00:00 [kworker/u9:1-hci0] root 787 2 0 12:36 ? 00:00:00 [kworker/u9:2-hci0] root 798 1 0 12:36 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap root 830 1 0 12:36 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 842 1 0 12:36 ? 00:00:00 /sbin/dhcpcd volumio 925 1 14 12:36 ? 00:01:20 /usr/bin/node /volumio/index.js root 942 1 0 12:36 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 946 942 0 12:36 ? 00:00:00 winbindd: domain child [VOLUMIO1] root 947 1 0 12:36 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 949 947 0 12:36 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 950 947 0 12:36 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 951 942 0 12:36 ? 00:00:00 winbindd: idmap child root 952 942 0 12:36 ? 00:00:00 winbindd: domain child [BUILTIN] root 959 947 0 12:36 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 986 2 0 12:36 ? 00:00:00 [cifsiod] root 987 2 0 12:36 ? 00:00:00 [smb3decryptd] root 988 2 0 12:36 ? 00:00:00 [cifsfileinfoput] root 989 2 0 12:36 ? 00:00:00 [cifsoplockd] root 990 2 0 12:36 ? 00:00:00 [cifs-dfscache] root 992 2 0 12:36 ? 00:00:00 [cifsd] volumio 1009 925 0 12:36 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1018 1009 0 12:36 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1019 1009 0 12:36 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1020 1009 0 12:36 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1138 1 0 12:37 ? 00:00:00 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf volumio 1142 925 0 12:37 ? 00:00:00 /usr/bin/node /myvolumio/plugins/music_service/streaming_services/streamingProxy.js mpd 1247 1 3 12:37 ? 00:00:18 /usr/bin/mpd --no-daemon shairpo+ 1261 1 0 12:37 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf volumio 1273 1 0 12:37 ? 00:00:00 /bin/sh /tmp/sshtunnel.sh volumio 1274 1273 0 12:37 ? 00:00:00 /usr/lib/autossh/autossh -M 0 -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R I36CETbEfKgpsQUAkxhJNsXewVX2-58303af3ae8d3244f3c123a9ae33c392.eu7.myvolumio.org:8091:localhost:3000 eu7.myvolumio.org volumio 1277 1274 0 12:37 ? 00:00:00 /usr/bin/ssh -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R I36CETbEfKgpsQUAkxhJNsXewVX2-58303af3ae8d3244f3c123a9ae33c392.eu7.myvolumio.org:8091:localhost:3000 eu7.myvolumio.org volumio 1281 1 0 12:37 ? 00:00:00 /bin/sh /bin/bt/btstart.sh start volumio 1296 1 0 12:37 ? 00:00:00 /bin/sh -c /usr/bin/vtcs volumio 1298 1296 0 12:37 ? 00:00:01 /usr/bin/vtcs volumio 1318 1 0 12:37 ? 00:00:00 /usr/bin/pulseaudio --high-priority=true rtkit 1319 1 0 12:37 ? 00:00:00 /usr/lib/rtkit/rtkit-daemon root 1322 1 0 12:37 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 1330 2 0 12:37 ? 00:00:00 [krfcommd] volumio 1332 1281 0 12:37 ? 00:00:00 /usr/bin/python /bin/bt/simple-agent -c NoInputNoOutput root 1360 2 0 12:41 ? 00:00:00 [kworker/2:0-events_freezable] root 1361 2 0 12:41 ? 00:00:02 [kworker/0:0-mm_percpu_wq] root 1364 2 0 12:42 ? 00:00:00 [kworker/2:0H] root 1365 2 0 12:42 ? 00:00:00 [kworker/0:0H-kblockd] root 1366 2 0 12:42 ? 00:00:00 [kworker/3:0H] root 1367 2 3 12:42 ? 00:00:08 [kworker/u8:1-brcmf_wq/mmc1:0001:1] root 1369 2 0 12:42 ? 00:00:00 [kworker/1:0H] root 1374 2 0 12:43 ? 00:00:00 [kworker/u8:2] root 1379 2 0 12:45 ? 00:00:00 [kworker/0:3-events] volumio 1383 925 0 12:46 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'Supersearch is still returning No Items' volumio 1384 1383 0 12:46 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js Supersearch is still returning No Items volumio 1404 1384 0 12:46 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1405 1404 0 12:46 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.2G 964M 1.2G 47% /imgpart /dev/loop0 443M 443M 0 100% /static overlay 55G 631M 52G 2% / devtmpfs 3.9G 0 3.9G 0% /dev tmpfs 3.9G 0 3.9G 0% /dev/shm tmpfs 3.9G 9.0M 3.9G 1% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 3.9G 0 3.9G 0% /sys/fs/cgroup tmpfs 3.9G 68K 3.9G 1% /tmp tmpfs 3.9G 0 3.9G 0% /var/spool/cups tmpfs 20M 28K 20M 1% /var/log tmpfs 3.9G 0 3.9G 0% /var/spool/cups/tmp /dev/mmcblk0p1 61M 54M 6.6M 90% /boot //192.168.68.107/Music 7.0T 628G 6.4T 9% /mnt/NAS/Synology # mount --------------- /dev/mmcblk0p2 on /imgpart type ext4 (rw,relatime,stripe=1024) /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=4026428k,nr_inodes=137247,mode=755) securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime) tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev,noexec) devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000) tmpfs on /run type tmpfs (rw,nosuid,nodev,mode=755) tmpfs on /run/lock type tmpfs (rw,nosuid,nodev,noexec,relatime,size=5120k) tmpfs on /sys/fs/cgroup type tmpfs (ro,nosuid,nodev,noexec,mode=755) cgroup2 on /sys/fs/cgroup/unified type cgroup2 (rw,nosuid,nodev,noexec,relatime,nsdelegate) cgroup on /sys/fs/cgroup/systemd type cgroup (rw,nosuid,nodev,noexec,relatime,xattr,name=systemd) none on /sys/fs/bpf type bpf (rw,nosuid,nodev,noexec,relatime,mode=700) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/net_cls,net_prio type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls,net_prio) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=27,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) mqueue on /dev/mqueue type mqueue (rw,relatime) sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) debugfs on /sys/kernel/debug type debugfs (rw,relatime) configfs on /sys/kernel/config type configfs (rw,relatime) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) tmpfs on /var/spool/cups/tmp type tmpfs (rw,noatime,mode=755) /dev/mmcblk0p1 on /boot type vfat (rw,nosuid,nodev,noexec,relatime,fmask=0111,dmask=0000,allow_utime=0022,codepage=437,iocharset=ascii,shortname=mixed,utf8,errors=remount-ro,user) //192.168.68.107/Music on /mnt/NAS/Synology type cifs (ro,relatime,vers=3.1.1,cache=strict,username=johncann,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.68.107,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,echo_interval=60,actimeo=1) # 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:445 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:5000 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:4713 0.0.0.0:* LISTEN 1318/pulseaudio tcp 0 0 0.0.0.0:139 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:111 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:22 0.0.0.0:* LISTEN - tcp 0 0 127.0.0.1:46934 127.0.0.1:3000 ESTABLISHED 925/node tcp 0 0 192.168.68.108:45342 130.211.19.189:80 TIME_WAIT - tcp 0 0 127.0.0.1:46990 127.0.0.1:3000 ESTABLISHED 925/node tcp 0 0 127.0.0.1:48410 127.0.0.1:6599 ESTABLISHED 1138/upmpdcli tcp 0 0 127.0.0.1:46924 127.0.0.1:3000 ESTABLISHED 542/volumio-remote- tcp 0 0 192.168.68.108:52054 167.71.43.69:2222 ESTABLISHED 1277/ssh tcp 0 0 192.168.68.108:35134 84.247.14.230:80 ESTABLISHED 925/node tcp 0 0 127.0.0.1:47602 127.0.0.1:3000 TIME_WAIT - tcp 0 0 192.168.68.108:43592 192.168.68.107:445 ESTABLISHED - tcp 0 0 192.168.68.108:33788 34.120.160.131:443 ESTABLISHED 925/node tcp 0 0 127.0.0.1:47692 127.0.0.1:3000 TIME_WAIT - tcp 0 0 127.0.0.1:47008 127.0.0.1:3000 ESTABLISHED 925/node tcp6 0 0 :::3005 :::* LISTEN 925/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::2019 :::* LISTEN 1298/vtcs tcp6 0 0 :::6599 :::* LISTEN 925/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::4713 :::* LISTEN 1318/pulseaudio tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::3245 :::* LISTEN 1142/node tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::22 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 925/node tcp6 0 0 :::3001 :::* LISTEN 1009/node tcp6 0 0 192.168.68.108:3000 192.168.68.112:60646 TIME_WAIT - tcp6 0 0 127.0.0.1:6599 127.0.0.1:48410 ESTABLISHED 925/node tcp6 0 0 192.168.68.108:3000 192.168.68.112:60647 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:46924 ESTABLISHED 925/node tcp6 0 0 192.168.68.108:3000 192.168.68.112:60780 FIN_WAIT2 - tcp6 0 0 192.168.68.108:3000 192.168.68.112:60672 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:46990 ESTABLISHED 925/node tcp6 0 0 192.168.68.108:3000 192.168.68.112:60455 ESTABLISHED 925/node tcp6 0 0 192.168.68.108:3000 192.168.68.112:60648 TIME_WAIT - tcp6 0 0 192.168.68.108:3000 192.168.68.112:60650 TIME_WAIT - tcp6 0 0 192.168.68.108:3000 192.168.68.112:60777 FIN_WAIT2 - tcp6 0 0 192.168.68.108:3000 192.168.68.112:60435 ESTABLISHED 925/node tcp6 0 0 192.168.68.108:3000 192.168.68.112:60779 FIN_WAIT2 - tcp6 0 0 192.168.68.108:3000 192.168.68.112:60649 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:47008 ESTABLISHED 925/node tcp6 0 0 192.168.68.108:3000 192.168.68.112:60776 FIN_WAIT2 - tcp6 0 0 192.168.68.108:3000 192.168.68.112:60781 FIN_WAIT2 - tcp6 0 0 127.0.0.1:3000 127.0.0.1:46934 ESTABLISHED 925/node tcp6 0 0 192.168.68.108:3000 192.168.68.112:60778 FIN_WAIT2 - # cat /etc/asound.conf --------------- pcm.!default { type empty slave.pcm "volumio" } pcm.volumio { type empty slave.pcm "volumioMultiRoomServer" } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/server/switch.target pcm.volumioMultiRoomServer { type volumioswitch target_file "/tmp/multiroom/server/switch.target" control_fifo "/tmp/multiroom/server/switch.fifo" } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/client/switch.target pcm.volumioMultiRoomClient { type volumioswitch target_file "/tmp/multiroom/client/switch.target" control_fifo "/tmp/multiroom/client/switch.fifo" } # This PCM performs any necessary rate/format/channel conversion before sending on to the Server FIFO pcm.volumioMultiRoom { type plug slave { pcm "volumioMultiRoomFIFO" rate 48000 format "S16_LE" channels 2 } rate_converter "speexrate_medium" } # Write to the FIFO watched by the Server pcm.volumioMultiRoomFIFO { type volumiofifo fifo "/tmp/multiroom/server/fifo" clear_on_drop false lead_in_frames 16384 } # This pcm is used by pcm.volumioMultiRoomServer and pcm.volumioMultiRoomClient to play audio on the local output pcm.volumioLocalPlayback { type empty slave.pcm "postMultiRoom" } # This pcm is used by pcm.volumioMultiRoomClient to discard audio and allow for faster switching pcm.volumioDiscard { type null } pcm.postMultiRoom { type empty slave.pcm "volumioOutput" } # There is always a plug before the hardware to be safe pcm.volumioOutput { type plug slave.pcm "volumioHw" } pcm.volumioHw { type hw card "sndrpihifiberry" } # cat /var/log/mpd.log --------------- Jul 10 12:36 : zeroconf: No global port, disabling zeroconf Jul 10 12:36 : zeroconf: No global port, disabling zeroconf Jul 10 12:36 : client: [0] opened from local Jul 10 12:36 : client: [1] opened from local Jul 10 12:36 : zeroconf: No global port, disabling zeroconf Jul 10 12:36 : client: [0] opened from local Jul 10 12:36 : exception: No such directory Jul 10 12:36 : client: [1] opened from 127.0.0.1:52330 Jul 10 12:36 : client: [2] opened from 127.0.0.1:52334 Jul 10 12:36 : exception: Failed to read mixer for 'alsa': no such mixer control: PCM Jul 10 12:36 : client: [3] opened from 127.0.0.1:52340 Jul 10 12:36 : zeroconf: No global port, disabling zeroconf Jul 10 12:36 : client: [0] opened from local # volumio plugin list --------------- This command will list installed plugins on your device ----------------------- Installed Plugins: [] ------------------------------------------ # 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": "STARTED" } }, "cd_controller": { "status": { "type": "string", "value": "STARTED" } }, "smart_inputs": { "status": { "type": "string", "value": "STARTED" } }, "hi_res_audio": { "status": { "type": "string", "value": "STARTED" } }, "tidal": { "status": { "type": "string", "value": "STARTED" } }, "qobuz": { "status": { "type": "string", "value": "STARTED" } }, "tidalconnect": { "status": { "type": "string", "value": "STARTED" } } }, "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" } }, "bluetooth": { "status": { "type": "string", "value": "STARTED" } }, "multiroom": { "status": { "type": "string", "value": "STARTED" } } }, "miscellanea": { "alarm-clock": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "albumart": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "appearance": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "wizard": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_music": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "metavolumio": { "status": { "type": "string", "value": "STARTED" } }, "manifestui": { "status": { "type": "string", "value": "STARTED" } } }, "user_interface": { "websocket": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpdemulation": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STARTED" } }, "rest_api": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "system_controller": { "updater_comm": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "network": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "networkfs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "services": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "system": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "i2s_dacs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumiodiscovery": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumio_command_line_client": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_volumio": { "status": { "type": "string", "value": "STARTED" } } } } # cat /etc/mpd.conf --------------- # Volumio MPD Configuration File # Files and directories ####################################################### music_directory "/var/lib/mpd/music" playlist_directory "/var/lib/mpd/playlists" db_file "/var/lib/mpd/tag_cache" log_file "/var/log/mpd.log" #pid_file "/var/run/mpd/pid" #state_file "/var/lib/mpd/state" #sticker_file "/var/lib/mpd/sticker.sql" ############################################################################### # General music daemon options ################################################ user "mpd" group "audio" bind_to_address "any" #port "6600" #log_level "default" #save_absolute_paths_in_playlists "no" #metadata_to_use "artist,album,title,track,name,genre,date,composer,performer,disc" auto_update "no" #auto_update_depth "3" ############################################################################### # Symbolic link behavior ###################################################### follow_outside_symlinks "yes" follow_inside_symlinks "yes" ############################################################################### # Input ####################################################################### # input { plugin "curl" # proxy "proxy.isp.com:8080" # proxy_user "user" # proxy_password "password" } ############################################################################### # Decoder ################################################################ decoder { plugin "faad" enabled "no" } ############################################################################### # Audio Output ################################################################ resampler { plugin "soxr" quality "high" threads "1" } audio_output { type "alsa" name "alsa" device "volumio" dop "no" } audio_output { type "fifo" enabled "no" name "multiroom" path "/tmp/snapfifo" format "44100:16:2" } #replaygain "album" #replaygain_preamp "0" volume_normalization "no" ############################################################################### # MPD Internal Buffering ###################################################### audio_buffer_size "2048" ############################################################################### # Resource Limitations ######################################################## #connection_timeout "60" max_connections "20" max_playlist_length "81920" max_command_list_size "81920" max_output_buffer_size "81920" ############################################################################### # Character Encoding ########################################################## filesystem_charset "UTF-8" ############################################################################### # volumio endpointstest --------------- TESTING REMOTE ENDPOINTS https://google.com, 1059 ms: OK https://www.googleapis.com, 376 ms: OK https://securetoken.googleapis.com, 410 ms: OK https://myvolumio.firebaseio.com, 576 ms: OK https://functions.volumio.cloud, 495 ms: OK https://oauth-performer.dfs.volumio.org, 410 ms: OK https://browsing-performer.dfs.volumio.org, 428 ms: OK http://cddb.volumio.org, 971 ms: OK https://functions.volumio.cloud, 644 ms: OK http://pushupdates.volumio.org, 535 ms: OK http://plugins.volumio.org, 4976 ms: OK https://database.volumio.cloud, 960 ms: OK https://radio-directory.firebaseapp.com, 764 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # cat /data/updatesHistoryFile --------------- --- Mon Mar 06 2023 09:48:17 GMT+0000 (Coordinated Universal Time) {"systemversion":"3.435","builddate":"Mon 06 Mar 2023 10:42:18 AM CET","variant":"volumio","hardware":"pi"} ------ Tue Apr 04 2023 03:23:03 GMT+0000 (Coordinated Universal Time) {"systemversion":"3.449","builddate":"Thu 30 Mar 2023 05:45:03 PM CEST","variant":"volumio","hardware":"pi"} ------ Fri Jun 30 2023 12:46:26 GMT+0000 (Coordinated Universal Time) {"systemversion":"3.512","builddate":"Sun 25 Jun 2023 07:20:58 PM CEST","variant":"volumio","hardware":"pi"} --- # sudo journalctl -p 7 --------------- -- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2023-07-10 12:46:27 UTC. -- Feb 14 10:11:59 volumio1 kernel: Booting Linux on physical CPU 0x0 Feb 14 10:11:59 volumio1 kernel: Linux version 5.10.92-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1514 SMP Mon Jan 17 17:38:03 GMT 2022 Feb 14 10:11:59 volumio1 kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Feb 14 10:11:59 volumio1 kernel: CPU: div instructions available: patching division code Feb 14 10:11:59 volumio1 kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Feb 14 10:11:59 volumio1 kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.4 Feb 14 10:11:59 volumio1 kernel: random: fast init done Feb 14 10:11:59 volumio1 kernel: Memory policy: Data cache writealloc Feb 14 10:11:59 volumio1 kernel: Reserved memory: created CMA memory pool at 0x000000002a000000, size 64 MiB Feb 14 10:11:59 volumio1 kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 14 10:11:59 volumio1 kernel: Zone ranges: Feb 14 10:11:59 volumio1 kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Feb 14 10:11:59 volumio1 kernel: Normal empty Feb 14 10:11:59 volumio1 kernel: HighMem [mem 0x0000000030000000-0x00000001ffffffff] Feb 14 10:11:59 volumio1 kernel: Movable zone start for each node Feb 14 10:11:59 volumio1 kernel: Early memory node ranges Feb 14 10:11:59 volumio1 kernel: node 0: [mem 0x0000000000000000-0x000000003dffffff] Feb 14 10:11:59 volumio1 kernel: node 0: [mem 0x0000000040000000-0x00000000fbffffff] Feb 14 10:11:59 volumio1 kernel: node 0: [mem 0x0000000100000000-0x00000001ffffffff] Feb 14 10:11:59 volumio1 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff] Feb 14 10:11:59 volumio1 kernel: On node 0 totalpages: 2072576 Feb 14 10:11:59 volumio1 kernel: DMA zone: 1728 pages used for memmap Feb 14 10:11:59 volumio1 kernel: DMA zone: 0 pages reserved Feb 14 10:11:59 volumio1 kernel: DMA zone: 196608 pages, LIFO batch:63 Feb 14 10:11:59 volumio1 kernel: HighMem zone: 1875968 pages, LIFO batch:63 Feb 14 10:11:59 volumio1 kernel: percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920 Feb 14 10:11:59 volumio1 kernel: pcpu-alloc: s50828 r8192 d22900 u81920 alloc=20*4096 Feb 14 10:11:59 volumio1 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 14 10:11:59 volumio1 kernel: Built 1 zonelists, mobility grouping on. Total pages: 2070848 Feb 14 10:11:59 volumio1 kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:B1:3A:FF vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Feb 14 10:11:59 volumio1 kernel: Kernel parameter elevator= does not have any effect anymore. Please use sysfs to set IO scheduler for individual devices. Feb 14 10:11:59 volumio1 kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Feb 14 10:11:59 volumio1 kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 10:11:59 volumio1 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 14 10:11:59 volumio1 kernel: software IO TLB: mapped [mem 0x0000000021800000-0x0000000025800000] (64MB) Feb 14 10:11:59 volumio1 kernel: Memory: 8052860K/8290304K available (10240K kernel code, 1366K rwdata, 3196K rodata, 2048K init, 882K bss, 171908K reserved, 65536K cma-reserved, 7503872K highmem) Feb 14 10:11:59 volumio1 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 14 10:11:59 volumio1 kernel: ftrace: allocating 34360 entries in 101 pages Feb 14 10:11:59 volumio1 kernel: ftrace: allocated 101 pages with 4 groups Feb 14 10:11:59 volumio1 kernel: rcu: Hierarchical RCU implementation. Feb 14 10:11:59 volumio1 kernel: Rude variant of Tasks RCU enabled. Feb 14 10:11:59 volumio1 kernel: Tracing variant of Tasks RCU enabled. Feb 14 10:11:59 volumio1 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Feb 14 10:11:59 volumio1 kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Feb 14 10:11:59 volumio1 kernel: GIC: Using split EOI/Deactivate mode Feb 14 10:11:59 volumio1 kernel: random: get_random_bytes called from start_kernel+0x3c8/0x59c with crng_init=1 Feb 14 10:11:59 volumio1 kernel: sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns Feb 14 10:11:59 volumio1 kernel: clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns Feb 14 10:11:59 volumio1 kernel: bcm2835: system timer (irq = 25) Feb 14 10:11:59 volumio1 kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Feb 14 10:11:59 volumio1 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Feb 14 10:11:59 volumio1 kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Feb 14 10:11:59 volumio1 kernel: Switching to timer-based delay loop, resolution 18ns Feb 14 10:11:59 volumio1 kernel: Console: colour dummy device 80x30 Feb 14 10:11:59 volumio1 kernel: printk: console [tty1] enabled Feb 14 10:11:59 volumio1 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Feb 14 10:11:59 volumio1 kernel: pid_max: default: 32768 minimum: 301 Feb 14 10:11:59 volumio1 kernel: LSM: Security Framework initializing Feb 14 10:11:59 volumio1 kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 volumio1 kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 volumio1 kernel: cgroup: Disabling memory control group subsystem Feb 14 10:11:59 volumio1 kernel: CPU: Testing write buffer coherency: ok Feb 14 10:11:59 volumio1 kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Feb 14 10:11:59 volumio1 kernel: Setting up static identity map for 0x200000 - 0x20003c Feb 14 10:11:59 volumio1 kernel: rcu: Hierarchical SRCU implementation. Feb 14 10:11:59 volumio1 kernel: smp: Bringing up secondary CPUs ... Feb 14 10:11:59 volumio1 kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Feb 14 10:11:59 volumio1 kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Feb 14 10:11:59 volumio1 kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Feb 14 10:11:59 volumio1 kernel: smp: Brought up 1 node, 4 CPUs Feb 14 10:11:59 volumio1 kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Feb 14 10:11:59 volumio1 kernel: CPU: All CPU(s) started in HYP mode. Feb 14 10:11:59 volumio1 kernel: CPU: Virtualization extensions available. Feb 14 10:11:59 volumio1 kernel: devtmpfs: initialized Feb 14 10:11:59 volumio1 kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Feb 14 10:11:59 volumio1 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 14 10:11:59 volumio1 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 14 10:11:59 volumio1 kernel: pinctrl core: initialized pinctrl subsystem Feb 14 10:11:59 volumio1 kernel: NET: Registered protocol family 16 Feb 14 10:11:59 volumio1 kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 14 10:11:59 volumio1 kernel: audit: initializing netlink subsys (disabled) Feb 14 10:11:59 volumio1 kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Feb 14 10:11:59 volumio1 kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 14 10:11:59 volumio1 kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 14 10:11:59 volumio1 kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 14 10:11:59 volumio1 kernel: Serial: AMBA PL011 UART driver Feb 14 10:11:59 volumio1 kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Feb 14 10:11:59 volumio1 kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2022-01-20T13:56:48, variant start Feb 14 10:11:59 volumio1 kernel: raspberrypi-firmware soc:firmware: Firmware hash is bd88f66f8952d34e4e0613a85c7a6d3da49e13e2 Feb 14 10:11:59 volumio1 kernel: Kprobes globally optimized Feb 14 10:11:59 volumio1 kernel: bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 Feb 14 10:11:59 volumio1 kernel: vgaarb: loaded Feb 14 10:11:59 volumio1 kernel: SCSI subsystem initialized Feb 14 10:11:59 volumio1 kernel: usbcore: registered new interface driver usbfs Feb 14 10:11:59 volumio1 kernel: usbcore: registered new interface driver hub Feb 14 10:11:59 volumio1 kernel: usbcore: registered new device driver usb Feb 14 10:11:59 volumio1 kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Feb 14 10:11:59 volumio1 kernel: clocksource: Switched to clocksource arch_sys_counter Feb 14 10:11:59 volumio1 kernel: VFS: Disk quotas dquot_6.6.0 Feb 14 10:11:59 volumio1 kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 14 10:11:59 volumio1 kernel: FS-Cache: Loaded Feb 14 10:11:59 volumio1 kernel: CacheFiles: Loaded Feb 14 10:11:59 volumio1 kernel: NET: Registered protocol family 2 Feb 14 10:11:59 volumio1 kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Feb 14 10:11:59 volumio1 kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Feb 14 10:11:59 volumio1 kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Feb 14 10:11:59 volumio1 kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 14 10:11:59 volumio1 kernel: TCP: Hash tables configured (established 8192 bind 8192) Feb 14 10:11:59 volumio1 kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:11:59 volumio1 kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:11:59 volumio1 kernel: NET: Registered protocol family 1 Feb 14 10:11:59 volumio1 kernel: RPC: Registered named UNIX socket transport module. Feb 14 10:11:59 volumio1 kernel: RPC: Registered udp transport module. Feb 14 10:11:59 volumio1 kernel: RPC: Registered tcp transport module. Feb 14 10:11:59 volumio1 kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 14 10:11:59 volumio1 kernel: PCI: CLS 0 bytes, default 64 Feb 14 10:11:59 volumio1 kernel: Trying to unpack rootfs image as initramfs... Feb 14 10:11:59 volumio1 kernel: Freeing initrd memory: 13028K Feb 14 10:11:59 volumio1 kernel: Initialise system trusted keyrings Feb 14 10:11:59 volumio1 kernel: workingset: timestamp_bits=14 max_order=21 bucket_order=7 Feb 14 10:11:59 volumio1 kernel: zbud: loaded Feb 14 10:11:59 volumio1 kernel: FS-Cache: Netfs 'nfs' registered for caching Feb 14 10:11:59 volumio1 kernel: NFS: Registering the id_resolver key type Feb 14 10:11:59 volumio1 kernel: Key type id_resolver registered Feb 14 10:11:59 volumio1 kernel: Key type id_legacy registered Feb 14 10:11:59 volumio1 kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 14 10:11:59 volumio1 kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Feb 14 10:11:59 volumio1 kernel: Key type asymmetric registered Feb 14 10:11:59 volumio1 kernel: Asymmetric key parser 'x509' registered Feb 14 10:11:59 volumio1 kernel: bounce: pool size: 64 pages Feb 14 10:11:59 volumio1 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Feb 14 10:11:59 volumio1 kernel: io scheduler mq-deadline registered Feb 14 10:11:59 volumio1 kernel: io scheduler kyber registered Feb 14 10:11:59 volumio1 kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Feb 14 10:11:59 volumio1 kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Feb 14 10:11:59 volumio1 kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Feb 14 10:11:59 volumio1 kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000 Feb 14 10:11:59 volumio1 kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Feb 14 10:11:59 volumio1 kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Feb 14 10:11:59 volumio1 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Feb 14 10:11:59 volumio1 kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Feb 14 10:11:59 volumio1 kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Feb 14 10:11:59 volumio1 kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Feb 14 10:11:59 volumio1 kernel: PCI: bus0: Fast back to back transfers disabled Feb 14 10:11:59 volumio1 kernel: pci 0000:00:00.0: bridge configuration invalid ([bus 00-00]), reconfiguring Feb 14 10:11:59 volumio1 kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Feb 14 10:11:59 volumio1 kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Feb 14 10:11:59 volumio1 kernel: pci 0000:01:00.0: PME# supported from D0 D3hot Feb 14 10:11:59 volumio1 kernel: PCI: bus1: Fast back to back transfers disabled Feb 14 10:11:59 volumio1 kernel: pci_bus 0000:01: busn_res: [bus 01-ff] end is updated to 01 Feb 14 10:11:59 volumio1 kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Feb 14 10:11:59 volumio1 kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Feb 14 10:11:59 volumio1 kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Feb 14 10:11:59 volumio1 kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Feb 14 10:11:59 volumio1 kernel: pcieport 0000:00:00.0: enabling device (0140 -> 0142) Feb 14 10:11:59 volumio1 kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 62 Feb 14 10:11:59 volumio1 kernel: bcm2708_fb soc:fb: FB found 1 display(s) Feb 14 10:11:59 volumio1 kernel: Console: switching to colour frame buffer device 80x30 Feb 14 10:11:59 volumio1 kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Feb 14 10:11:59 volumio1 kernel: iproc-rng200 fe104000.rng: hwrng registered Feb 14 10:11:59 volumio1 kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Feb 14 10:11:59 volumio1 kernel: gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000 Feb 14 10:11:59 volumio1 kernel: brd: module loaded Feb 14 10:11:59 volumio1 kernel: loop: module loaded Feb 14 10:11:59 volumio1 kernel: Loading iSCSI transport class v2.0-870. Feb 14 10:11:59 volumio1 kernel: libphy: Fixed MDIO Bus: probed Feb 14 10:11:59 volumio1 kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Feb 14 10:11:59 volumio1 kernel: libphy: bcmgenet MII bus: probed Feb 14 10:11:59 volumio1 kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Feb 14 10:11:59 volumio1 kernel: usbcore: registered new interface driver r8152 Feb 14 10:11:59 volumio1 kernel: usbcore: registered new interface driver lan78xx Feb 14 10:11:59 volumio1 kernel: usbcore: registered new interface driver smsc95xx Feb 14 10:11:59 volumio1 kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Feb 14 10:11:59 volumio1 kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Feb 14 10:11:59 volumio1 kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00000e0000000890 Feb 14 10:11:59 volumio1 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Jul 10 12:35:59 volumio1 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jul 10 12:35:59 volumio1 kernel: usb usb1: Product: xHCI Host Controller Jul 10 12:35:59 volumio1 kernel: usb usb1: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Jul 10 12:35:59 volumio1 kernel: usb usb1: SerialNumber: 0000:01:00.0 Jul 10 12:35:59 volumio1 kernel: hub 1-0:1.0: USB hub found Jul 10 12:35:59 volumio1 kernel: hub 1-0:1.0: 1 port detected Jul 10 12:35:59 volumio1 kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Jul 10 12:35:59 volumio1 kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Jul 10 12:35:59 volumio1 kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Jul 10 12:35:59 volumio1 kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 Jul 10 12:35:59 volumio1 kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jul 10 12:35:59 volumio1 kernel: usb usb2: Product: xHCI Host Controller Jul 10 12:35:59 volumio1 kernel: usb usb2: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Jul 10 12:35:59 volumio1 kernel: usb usb2: SerialNumber: 0000:01:00.0 Jul 10 12:35:59 volumio1 kernel: hub 2-0:1.0: USB hub found Jul 10 12:35:59 volumio1 kernel: hub 2-0:1.0: 4 ports detected Jul 10 12:35:59 volumio1 kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Jul 10 12:35:59 volumio1 kernel: dwc_otg: FIQ enabled Jul 10 12:35:59 volumio1 kernel: dwc_otg: NAK holdoff enabled Jul 10 12:35:59 volumio1 kernel: dwc_otg: FIQ split-transaction FSM enabled Jul 10 12:35:59 volumio1 kernel: Module dwc_common_port init Jul 10 12:35:59 volumio1 kernel: usbcore: registered new interface driver uas Jul 10 12:35:59 volumio1 kernel: usbcore: registered new interface driver usb-storage Jul 10 12:35:59 volumio1 kernel: mousedev: PS/2 mouse device common for all mice Jul 10 12:35:59 volumio1 kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Jul 10 12:35:59 volumio1 kernel: sdhci: Secure Digital Host Controller Interface driver Jul 10 12:35:59 volumio1 kernel: sdhci: Copyright(c) Pierre Ossman Jul 10 12:35:59 volumio1 kernel: mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe Jul 10 12:35:59 volumio1 kernel: sdhci-pltfm: SDHCI platform and OF driver helper Jul 10 12:35:59 volumio1 kernel: ledtrig-cpu: registered to indicate activity on CPUs Jul 10 12:35:59 volumio1 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 10 12:35:59 volumio1 kernel: usbcore: registered new interface driver usbhid Jul 10 12:35:59 volumio1 kernel: usbhid: USB HID core driver Jul 10 12:35:59 volumio1 kernel: Initializing XFRM netlink socket Jul 10 12:35:59 volumio1 kernel: NET: Registered protocol family 17 Jul 10 12:35:59 volumio1 kernel: Key type dns_resolver registered Jul 10 12:35:59 volumio1 kernel: Registering SWP/SWPB emulation handler Jul 10 12:35:59 volumio1 kernel: registered taskstats version 1 Jul 10 12:35:59 volumio1 kernel: Loading compiled-in X.509 certificates Jul 10 12:35:59 volumio1 kernel: Key type ._fscrypt registered Jul 10 12:35:59 volumio1 kernel: Key type .fscrypt registered Jul 10 12:35:59 volumio1 kernel: Key type fscrypt-provisioning registered Jul 10 12:35:59 volumio1 kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Jul 10 12:35:59 volumio1 kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Jul 10 12:35:59 volumio1 kernel: fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2 Jul 10 12:35:59 volumio1 kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Jul 10 12:35:59 volumio1 kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Jul 10 12:35:59 volumio1 kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Jul 10 12:35:59 volumio1 kernel: of_cfs_init Jul 10 12:35:59 volumio1 kernel: of_cfs_init: OK Jul 10 12:35:59 volumio1 kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Jul 10 12:35:59 volumio1 kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Jul 10 12:35:59 volumio1 kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Jul 10 12:35:59 volumio1 kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Jul 10 12:35:59 volumio1 kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Jul 10 12:35:59 volumio1 kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Jul 10 12:35:59 volumio1 kernel: Freeing unused kernel memory: 2048K Jul 10 12:35:59 volumio1 kernel: Run /init as init process Jul 10 12:35:59 volumio1 kernel: with arguments: Jul 10 12:35:59 volumio1 kernel: /init Jul 10 12:35:59 volumio1 kernel: splash Jul 10 12:35:59 volumio1 kernel: with environment: Jul 10 12:35:59 volumio1 kernel: HOME=/ Jul 10 12:35:59 volumio1 kernel: TERM=linux Jul 10 12:35:59 volumio1 kernel: imgpart=/dev/mmcblk0p2 Jul 10 12:35:59 volumio1 kernel: imgfile=/volumio_current.sqsh Jul 10 12:35:59 volumio1 kernel: bootdelay=5 Jul 10 12:35:59 volumio1 kernel: mmc1: new high speed SDIO card at address 0001 Jul 10 12:35:59 volumio1 kernel: mmc0: new ultra high speed DDR50 SDXC card at address 0007 Jul 10 12:35:59 volumio1 kernel: mmcblk0: mmc0:0007 SD64G 58.0 GiB Jul 10 12:35:59 volumio1 kernel: mmcblk0: p1 p2 p3 Jul 10 12:35:59 volumio1 kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Jul 10 12:35:59 volumio1 initramfs: Booting Volumio for BCM2711 Jul 10 12:35:59 volumio1 initramfs: This script mounts rootfs RO with an overlay RW layer. Jul 10 12:35:59 volumio1 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 10 12:35:59 volumio1 initramfs: IMGPART=/dev/mmcblk0p2 Jul 10 12:35:59 volumio1 initramfs: IMGFILE=/volumio_current.sqsh Jul 10 12:35:59 volumio1 initramfs: Boot delay (except first time) will be 5 seconds Jul 10 12:35:59 volumio1 kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Jul 10 12:35:59 volumio1 kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Jul 10 12:35:59 volumio1 kernel: usb 1-1: Product: USB2.0 Hub Jul 10 12:35:59 volumio1 kernel: hub 1-1:1.0: USB hub found Jul 10 12:35:59 volumio1 kernel: hub 1-1:1.0: 4 ports detected Jul 10 12:35:59 volumio1 initramfs: /dev/mmcblk0p2: Jul 10 12:35:59 volumio1 kernel: EXT4-fs (mmcblk0p2): recovery complete Jul 10 12:35:59 volumio1 kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Jul 10 12:35:59 volumio1 initramfs: Doing a 5 second delay here to give kernel load a headstart Jul 10 12:35:59 volumio1 initramfs: Checking for USB updates Jul 10 12:35:59 volumio1 initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Jul 10 12:35:59 volumio1 kernel: EXT4-fs (mmcblk0p3): recovery complete Jul 10 12:35:59 volumio1 kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Jul 10 12:35:59 volumio1 initramfs: With Option: Jul 10 12:35:59 volumio1 initramfs: VOLUMIO_VERSION="3.512" Jul 10 12:35:59 volumio1 initramfs: Finish initramfs, continue booting Volumio Jul 10 12:35:59 volumio1 systemd[1]: System time before build time, advancing clock. Jul 10 12:35:59 volumio1 kernel: NET: Registered protocol family 10 Jul 10 12:35:59 volumio1 kernel: Segment Routing with IPv6 Jul 10 12:35:59 volumio1 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) Jul 10 12:35:59 volumio1 systemd[1]: Detected architecture arm. Jul 10 12:35:59 volumio1 systemd[1]: Set hostname to . Jul 10 12:35:59 volumio1 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. Jul 10 12:35:59 volumio1 kernel: random: systemd: uninitialized urandom read (16 bytes read) Jul 10 12:35:59 volumio1 kernel: random: systemd: uninitialized urandom read (16 bytes read) Jul 10 12:35:59 volumio1 kernel: random: systemd: uninitialized urandom read (16 bytes read) Jul 10 12:35:59 volumio1 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Jul 10 12:35:59 volumio1 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Jul 10 12:35:59 volumio1 systemd[1]: Listening on udev Kernel Socket. Jul 10 12:35:59 volumio1 systemd[1]: Created slice User and Session Slice. Jul 10 12:35:59 volumio1 systemd[1]: Listening on RPCbind Server Activation Socket. Jul 10 12:35:59 volumio1 systemd[1]: Listening on initctl Compatibility Named Pipe. Jul 10 12:35:59 volumio1 kernel: i2c /dev entries driver Jul 10 12:35:59 volumio1 systemd-journald[197]: Journal started Jul 10 12:35:59 volumio1 systemd-journald[197]: Runtime journal (/run/log/journal/b09207998001defae5f73bf26405b501) is 7.5M, max 30.0M, 22.5M free. Jul 10 12:35:59 volumio1 systemd-modules-load[204]: Inserted module 'i2c_dev' Jul 10 12:35:59 volumio1 fake-hwclock[193]: Mon Jul 10 12:35:59 UTC 2023 Jul 10 12:35:59 volumio1 systemd[1]: Mounted Kernel Configuration File System. Jul 10 12:35:59 volumio1 systemd[1]: Started Load/Save Random Seed. Jul 10 12:35:59 volumio1 systemd[1]: Started Create System Users. Jul 10 12:35:59 volumio1 systemd[1]: Starting Create Static Device Nodes in /dev... Jul 10 12:35:59 volumio1 systemd[1]: Started Apply Kernel Variables. Jul 10 12:35:59 volumio1 systemd[1]: dynamicswap.service: Succeeded. Jul 10 12:35:59 volumio1 systemd[1]: Started Create Static Device Nodes in /dev. Jul 10 12:35:59 volumio1 systemd[1]: Starting udev Kernel Device Manager... Jul 10 12:35:59 volumio1 systemd[1]: Reached target Local File Systems (Pre). Jul 10 12:35:59 volumio1 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jul 10 12:35:59 volumio1 systemd[1]: Mounting /tmp... Jul 10 12:35:59 volumio1 systemd[1]: Mounting /var/spool/cups... Jul 10 12:35:59 volumio1 systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jul 10 12:35:59 volumio1 systemd[1]: Mounting /var/log... Jul 10 12:35:59 volumio1 systemd[1]: Mounted /tmp. Jul 10 12:35:59 volumio1 systemd[1]: Mounted /var/spool/cups. Jul 10 12:35:59 volumio1 systemd[1]: Mounted /var/log. Jul 10 12:35:59 volumio1 systemd[1]: Starting Flush Journal to Persistent Storage... Jul 10 12:35:59 volumio1 systemd-udevd[222]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jul 10 12:35:59 volumio1 systemd[1]: Mounting /var/spool/cups/tmp... Jul 10 12:35:59 volumio1 systemd[1]: Mounted /var/spool/cups/tmp. Jul 10 12:35:59 volumio1 systemd[1]: Started udev Kernel Device Manager. Jul 10 12:35:59 volumio1 systemd[1]: Started udev Coldplug all Devices. Jul 10 12:35:59 volumio1 systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jul 10 12:35:59 volumio1 systemd-journald[197]: Runtime journal (/run/log/journal/b09207998001defae5f73bf26405b501) is 7.5M, max 30.0M, 22.5M free. Jul 10 12:35:59 volumio1 systemd[1]: Starting Show Plymouth Boot Screen... Jul 10 12:35:59 volumio1 systemd[1]: Started Flush Journal to Persistent Storage. Jul 10 12:35:59 volumio1 systemd[1]: Received SIGRTMIN+20 from PID 245 (plymouthd). Jul 10 12:35:59 volumio1 systemd[1]: Started Helper to synchronize boot up for ifupdown. Jul 10 12:35:59 volumio1 systemd[1]: Started Show Plymouth Boot Screen. Jul 10 12:35:59 volumio1 systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jul 10 12:35:59 volumio1 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jul 10 12:35:59 volumio1 systemd[1]: Reached target Local Encrypted Volumes. Jul 10 12:35:59 volumio1 systemd[1]: Reached target Paths. Jul 10 12:35:59 volumio1 kernel: rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000 Jul 10 12:35:59 volumio1 kernel: rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000 Jul 10 12:35:59 volumio1 kernel: rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000 Jul 10 12:35:59 volumio1 kernel: rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000 Jul 10 12:35:59 volumio1 systemd-udevd[249]: Using default interface naming scheme 'v240'. Jul 10 12:35:59 volumio1 kernel: mc: Linux media interface: v0.10 Jul 10 12:35:59 volumio1 systemd-udevd[249]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 10 12:35:59 volumio1 kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 12:35:59 volumio1 kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jul 10 12:35:59 volumio1 kernel: [vc_sm_connected_init]: start Jul 10 12:35:59 volumio1 kernel: [vc_sm_connected_init]: installed successfully Jul 10 12:36:00 volumio1 systemd-udevd[260]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 10 12:36:00 volumio1 kernel: videodev: Linux video capture interface: v2.00 Jul 10 12:36:00 volumio1 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 12:36:00 volumio1 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 12:36:00 volumio1 kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 12:36:00 volumio1 kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 12:36:00 volumio1 kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jul 10 12:36:00 volumio1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jul 10 12:36:00 volumio1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jul 10 12:36:00 volumio1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jul 10 12:36:00 volumio1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jul 10 12:36:00 volumio1 kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jul 10 12:36:00 volumio1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jul 10 12:36:00 volumio1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jul 10 12:36:00 volumio1 kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jul 10 12:36:00 volumio1 kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jul 10 12:36:01 volumio1 kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 12:36:01 volumio1 kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jul 10 12:36:01 volumio1 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jul 10 12:36:01 volumio1 kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jul 10 12:36:02 volumio1 systemd[1]: Found device /dev/mmcblk0p1. Jul 10 12:36:02 volumio1 kernel: pcm179x 1-004c: No cache used with register defaults set! Jul 10 12:36:02 volumio1 kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jul 10 12:36:02 volumio1 kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jul 10 12:36:02 volumio1 kernel: cfg80211: loaded regulatory.db is malformed or signature is missing/invalid Jul 10 12:36:02 volumio1 kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Jul 10 12:36:03 volumio1 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jul 10 12:36:03 volumio1 kernel: usbcore: registered new interface driver brcmfmac Jul 10 12:36:03 volumio1 kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Jul 10 12:36:03 volumio1 kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov 1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60 Jul 10 12:36:03 volumio1 systemd-udevd[283]: Using default interface naming scheme 'v240'. Jul 10 12:36:03 volumio1 systemd-udevd[283]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jul 10 12:36:03 volumio1 systemd[1]: Condition check resulted in FUSE Control File System being skipped. Jul 10 12:36:03 volumio1 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jul 10 12:36:03 volumio1 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jul 10 12:36:03 volumio1 systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jul 10 12:36:03 volumio1 systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jul 10 12:36:03 volumio1 systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jul 10 12:36:03 volumio1 systemd[1]: Starting File System Check on /dev/mmcblk0p1... Jul 10 12:36:03 volumio1 systemd[1]: Starting Load/Save RF Kill Switch Status... Jul 10 12:36:04 volumio1 systemd[1]: Found device /sys/subsystem/net/devices/eth0. Jul 10 12:36:04 volumio1 systemd[1]: Started Load/Save RF Kill Switch Status. Jul 10 12:36:04 volumio1 systemd[1]: Started File System Check Daemon to report status. Jul 10 12:36:04 volumio1 systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jul 10 12:36:04 volumio1 systemd-fsck[424]: fsck.fat 4.1 (2017-01-24) Jul 10 12:36:04 volumio1 systemd-fsck[424]: /dev/mmcblk0p1: 309 files, 109538/123044 clusters Jul 10 12:36:04 volumio1 systemd[1]: Started File System Check on /dev/mmcblk0p1. Jul 10 12:36:04 volumio1 systemd[1]: Mounting /boot... Jul 10 12:36:04 volumio1 systemd[1]: Mounted /boot. Jul 10 12:36:04 volumio1 systemd[1]: Reached target Local File Systems. Jul 10 12:36:04 volumio1 systemd[1]: Starting Preprocess NFS configuration... Jul 10 12:36:04 volumio1 systemd[1]: Starting Raise network interfaces... Jul 10 12:36:04 volumio1 systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jul 10 12:36:04 volumio1 systemd[1]: Starting Create Volatile Files and Directories... Jul 10 12:36:04 volumio1 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jul 10 12:36:04 volumio1 systemd[1]: Started ifup for wlan0. Jul 10 12:36:04 volumio1 systemd[1]: Started ifup for eth0. Jul 10 12:36:04 volumio1 systemd[1]: nfs-config.service: Succeeded. Jul 10 12:36:04 volumio1 systemd[1]: Started Preprocess NFS configuration. Jul 10 12:36:04 volumio1 systemd[1]: plymouth-read-write.service: Succeeded. Jul 10 12:36:04 volumio1 systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jul 10 12:36:04 volumio1 systemd[1]: Received SIGRTMIN+20 from PID 245 (plymouthd). Jul 10 12:36:04 volumio1 systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jul 10 12:36:04 volumio1 systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jul 10 12:36:04 volumio1 systemd[1]: Reached target NFS client services. Jul 10 12:36:04 volumio1 systemd[1]: Started Create Volatile Files and Directories. Jul 10 12:36:04 volumio1 systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jul 10 12:36:04 volumio1 systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jul 10 12:36:04 volumio1 systemd[1]: Starting RPC bind portmap service... Jul 10 12:36:04 volumio1 systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jul 10 12:36:04 volumio1 systemd[1]: Started Update UTMP about System Boot/Shutdown. Jul 10 12:36:04 volumio1 systemd[1]: Reached target System Initialization. Jul 10 12:36:04 volumio1 systemd[1]: Started Daily apt download activities. Jul 10 12:36:04 volumio1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 10 12:36:04 volumio1 systemd[1]: Listening on D-Bus System Message Bus Socket. Jul 10 12:36:04 volumio1 kernel: 8021q: 802.1Q VLAN Support v1.8 Jul 10 12:36:04 volumio1 systemd[1]: Listening on mpd.socket. Jul 10 12:36:04 volumio1 systemd[1]: Started Daily Cleanup of Temporary Directories. Jul 10 12:36:04 volumio1 systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jul 10 12:36:04 volumio1 systemd[1]: Listening on triggerhappy.socket. Jul 10 12:36:04 volumio1 systemd[1]: Reached target Sockets. Jul 10 12:36:04 volumio1 systemd[1]: Reached target Basic System. Jul 10 12:36:04 volumio1 systemd[1]: Starting Login Service... Jul 10 12:36:04 volumio1 systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jul 10 12:36:04 volumio1 systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jul 10 12:36:04 volumio1 systemd[1]: Starting Wireless Services... Jul 10 12:36:04 volumio1 systemd[1]: Started UPnP Renderer front-end to MPD. Jul 10 12:36:05 volumio1 systemd[1]: Started volumio-remote-updater.service. Jul 10 12:36:05 volumio1 systemd[1]: Started Volumio Iptables Module. Jul 10 12:36:05 volumio1 systemd[1]: Started Manage Sound Card State (restore and store). Jul 10 12:36:05 volumio1 alsactl[545]: alsactl 1.1.8 daemon started Jul 10 12:36:05 volumio1 systemd[1]: Starting Save/Restore Sound Card State... Jul 10 12:36:05 volumio1 systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jul 10 12:36:05 volumio1 systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jul 10 12:36:05 volumio1 systemd[1]: Starting dhcpcd on all interfaces... Jul 10 12:36:05 volumio1 systemd[1]: Started D-Bus System Message Bus. Jul 10 12:36:05 volumio1 systemd[1]: Starting WPA supplicant... Jul 10 12:36:05 volumio1 systemd[1]: Starting triggerhappy global hotkey daemon... Jul 10 12:36:05 volumio1 dhcpcd[551]: Not running dhcpcd because /etc/network/interfaces Jul 10 12:36:05 volumio1 dhcpcd[551]: defines some interfaces that will use a Jul 10 12:36:05 volumio1 dhcpcd[551]: DHCP client or static address Jul 10 12:36:05 volumio1 systemd[1]: Started Daily apt upgrade and clean activities. Jul 10 12:36:05 volumio1 systemd[1]: Reached target Timers. Jul 10 12:36:05 volumio1 systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jul 10 12:36:05 volumio1 systemd[1]: Started Save/Restore Sound Card State. Jul 10 12:36:05 volumio1 systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jul 10 12:36:05 volumio1 systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jul 10 12:36:05 volumio1 systemd[1]: Failed to start dhcpcd on all interfaces. Jul 10 12:36:05 volumio1 thd[558]: Unable to parse trigger line: Jul 10 12:36:05 volumio1 thd[558]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jul 10 12:36:05 volumio1 thd[558]: Unable to parse trigger line: Jul 10 12:36:05 volumio1 thd[558]: Unable to parse trigger line: Jul 10 12:36:05 volumio1 systemd-logind[525]: New seat seat0. Jul 10 12:36:05 volumio1 thd[558]: Unable to parse trigger line: Jul 10 12:36:05 volumio1 kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Jul 10 12:36:05 volumio1 kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jul 10 12:36:05 volumio1 sh[443]: eth0: waiting for carrier Jul 10 12:36:05 volumio1 dhcpcd[484]: eth0: waiting for carrier Jul 10 12:36:05 volumio1 systemd[1]: Reached target Sound Card. Jul 10 12:36:05 volumio1 thd[558]: Found socket passed from systemd Jul 10 12:36:05 volumio1 sh[442]: wlan0=wlan0 Jul 10 12:36:05 volumio1 avahi-daemon[550]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jul 10 12:36:05 volumio1 avahi-daemon[550]: Successfully dropped root privileges. Jul 10 12:36:05 volumio1 avahi-daemon[550]: avahi-daemon 0.7 starting up. Jul 10 12:36:05 volumio1 kernel: uart-pl011 fe201000.serial: no DMA platform data Jul 10 12:36:06 volumio1 systemd[1]: Started triggerhappy global hotkey daemon. Jul 10 12:36:06 volumio1 systemd[1]: Started RPC bind portmap service. Jul 10 12:36:06 volumio1 avahi-daemon[550]: Successfully called chroot(). Jul 10 12:36:06 volumio1 avahi-daemon[550]: Successfully dropped remaining capabilities. Jul 10 12:36:06 volumio1 avahi-daemon[550]: Loading service file /services/volumio.service. Jul 10 12:36:06 volumio1 systemd[1]: Started Login Service. Jul 10 12:36:06 volumio1 avahi-daemon[550]: Network interface enumeration completed. Jul 10 12:36:06 volumio1 systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jul 10 12:36:06 volumio1 avahi-daemon[550]: Server startup complete. Host name is volumio1.local. Local service cookie is 1234530156. Jul 10 12:36:06 volumio1 avahi-daemon[550]: Service "Volumio1" (/services/volumio.service) successfully established. Jul 10 12:36:06 volumio1 systemd[1]: Reached target RPC Port Mapper. Jul 10 12:36:06 volumio1 systemd[1]: Reached target Remote File Systems (Pre). Jul 10 12:36:06 volumio1 systemd[1]: Reached target Remote File Systems. Jul 10 12:36:06 volumio1 systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jul 10 12:36:06 volumio1 systemd[1]: Started Raise network interfaces. Jul 10 12:36:06 volumio1 loadcpufreq[600]: Loading cpufreq kernel modules...done (none). Jul 10 12:36:06 volumio1 systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jul 10 12:36:06 volumio1 systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jul 10 12:36:07 volumio1 systemd[1]: iptables.service: Succeeded. Jul 10 12:36:07 volumio1 cpufrequtils[634]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jul 10 12:36:07 volumio1 systemd[1]: Started LSB: set CPUFreq kernel parameters. Jul 10 12:36:07 volumio1 systemd[1]: Started WPA supplicant. Jul 10 12:36:07 volumio1 wpa_supplicant[557]: Successfully initialized wpa_supplicant Jul 10 12:36:07 volumio1 systemd[1]: Reached target Network. Jul 10 12:36:07 volumio1 systemd[1]: Starting Network Time Service... Jul 10 12:36:07 volumio1 systemd[1]: Starting Music Player Daemon... Jul 10 12:36:07 volumio1 systemd[1]: Reached target Network is Online. Jul 10 12:36:07 volumio1 systemd[1]: Starting LSB: Brings up/down network automatically... Jul 10 12:36:07 volumio1 systemd[1]: Starting Daily apt download activities... Jul 10 12:36:07 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 12:36:07 volumio1 systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jul 10 12:36:07 volumio1 systemd[1]: Starting Samba NMB Daemon... Jul 10 12:36:07 volumio1 systemd[1]: Starting OpenBSD Secure Shell server... Jul 10 12:36:07 volumio1 systemd[1]: Starting Permit User Sessions... Jul 10 12:36:07 volumio1 systemd[1]: Starting /etc/rc.local Compatibility... Jul 10 12:36:07 volumio1 systemd[1]: Started /etc/rc.local Compatibility. Jul 10 12:36:07 volumio1 systemd[1]: Started Permit User Sessions. Jul 10 12:36:07 volumio1 systemd[1]: Starting Terminate Plymouth Boot Screen... Jul 10 12:36:07 volumio1 ntpd[651]: ntpd 4.2.8p12@1.3728-o (1): Starting Jul 10 12:36:07 volumio1 ntpd[651]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jul 10 12:36:07 volumio1 systemd[1]: Starting Hold until boot process finishes up... Jul 10 12:36:07 volumio1 systemd[1]: Started Network Time Service. Jul 10 12:36:07 volumio1 systemd[1]: Received SIGRTMIN+21 from PID 245 (plymouthd). Jul 10 12:36:07 volumio1 ifplugd(eth0)[683]: ifplugd 0.28 initializing. Jul 10 12:36:07 volumio1 ifplugd(eth0)[683]: Using interface eth0/DC:A6:32:B1:3A:FF with driver (version: 5.10.92-v7l+) Jul 10 12:36:07 volumio1 ifplugd(eth0)[683]: Using detection mode: SIOCETHTOOL Jul 10 12:36:07 volumio1 ifplugd(eth0)[683]: Initialization complete, link beat not detected. Jul 10 12:36:07 volumio1 systemd[1]: plymouth-quit.service: Succeeded. Jul 10 12:36:07 volumio1 systemd[1]: Started Terminate Plymouth Boot Screen. Jul 10 12:36:07 volumio1 systemd[1]: plymouth-start.service: Succeeded. Jul 10 12:36:07 volumio1 systemd[1]: plymouth-quit-wait.service: Succeeded. Jul 10 12:36:07 volumio1 systemd[1]: Started Hold until boot process finishes up. Jul 10 12:36:07 volumio1 systemd[1]: Received SIGRTMIN+21 from PID 245 (n/a). Jul 10 12:36:07 volumio1 systemd[1]: Started Getty on tty1. Jul 10 12:36:07 volumio1 systemd[1]: Reached target Login Prompts. Jul 10 12:36:07 volumio1 ifplugd[656]: Network Interface Plugging Daemon...start eth0...done. Jul 10 12:36:07 volumio1 systemd[1]: Started LSB: Brings up/down network automatically. Jul 10 12:36:07 volumio1 ntpd[674]: proto: precision = 0.796 usec (-20) Jul 10 12:36:07 volumio1 ntpd[674]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jul 10 12:36:07 volumio1 ntpd[674]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jul 10 12:36:07 volumio1 ntpd[674]: Listen and drop on 0 v6wildcard [::]:123 Jul 10 12:36:07 volumio1 ntpd[674]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jul 10 12:36:07 volumio1 ntpd[674]: Listen normally on 2 lo 127.0.0.1:123 Jul 10 12:36:07 volumio1 ntpd[674]: Listening on routing socket on fd #19 for interface updates Jul 10 12:36:07 volumio1 ntpd[674]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 10 12:36:07 volumio1 ntpd[674]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 10 12:36:07 volumio1 haveged[487]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jul 10 12:36:07 volumio1 haveged[487]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jul 10 12:36:07 volumio1 haveged[487]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99881 Jul 10 12:36:07 volumio1 haveged[487]: haveged: fills: 0, generated: 0 Jul 10 12:36:07 volumio1 kernel: random: crng init done Jul 10 12:36:07 volumio1 kernel: random: 7 urandom warning(s) missed due to ratelimiting Jul 10 12:36:08 volumio1 sshd[688]: Server listening on 0.0.0.0 port 22. Jul 10 12:36:08 volumio1 sshd[688]: Server listening on :: port 22. Jul 10 12:36:08 volumio1 systemd[1]: Started OpenBSD Secure Shell server. Jul 10 12:36:08 volumio1 volumio-remote-updater[542]: Error: No active session Jul 10 12:36:08 volumio1 volumio-remote-updater[542]: [2023-07-10 12:36:08] [info] asio async_connect error: system:111 (Connection refused) Jul 10 12:36:08 volumio1 volumio-remote-updater[542]: [2023-07-10 12:36:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 10 12:36:08 volumio1 volumio-remote-updater[542]: [2023-07-10 12:36:08] [error] handle_connect error: Underlying Transport Error Jul 10 12:36:08 volumio1 volumio[535]: Could not open config: /tmp/upmpdcli.conf Jul 10 12:36:08 volumio1 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jul 10 12:36:08 volumio1 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jul 10 12:36:08 volumio1 ntpd[674]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jul 10 12:36:09 volumio1 nmbd[668]: [2023/07/10 12:36:09.043789, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jul 10 12:36:09 volumio1 nmbd[668]: started asyncdns process 728 Jul 10 12:36:09 volumio1 nmbd[668]: [2023/07/10 12:36:09.045742, 0] ../lib/util/become_daemon.c:149(daemon_status) Jul 10 12:36:09 volumio1 nmbd[668]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jul 10 12:36:09 volumio1 nmbd[668]: [2023/07/10 12:36:09.045885, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jul 10 12:36:09 volumio1 nmbd[668]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jul 10 12:36:09 volumio1 systemd[1]: systemd-rfkill.service: Succeeded. Jul 10 12:36:09 volumio1 systemd[1]: apt-daily.service: Succeeded. Jul 10 12:36:09 volumio1 systemd[1]: Started Daily apt download activities. Jul 10 12:36:09 volumio1 ntpd[674]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jul 10 12:36:09 volumio1 wireless.js[531]: Cleaning previous... Jul 10 12:36:10 volumio1 sudo[752]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 10 12:36:10 volumio1 sudo[752]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:10 volumio1 sudo[752]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:10 volumio1 sudo[754]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 10 12:36:10 volumio1 sudo[754]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:10 volumio1 mpd[681]: Jul 10 12:36 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 12:36:10 volumio1 ntpd[674]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jul 10 12:36:10 volumio1 sudo[754]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:10 volumio1 wireless.js[531]: Stopped aP Jul 10 12:36:10 volumio1 sudo[761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 10 12:36:10 volumio1 sudo[761]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:10 volumio1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 10 12:36:10 volumio1 sudo[761]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:10 volumio1 sudo[763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jul 10 12:36:10 volumio1 sudo[763]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:10 volumio1 sudo[763]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:10 volumio1 sudo[770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jul 10 12:36:10 volumio1 sudo[770]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:10 volumio1 sudo[770]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:10 volumio1 sudo[772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jul 10 12:36:10 volumio1 sudo[772]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:10 volumio1 mpd[681]: Jul 10 12:36 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 10 12:36:10 volumio1 mpd[681]: Jul 10 12:36 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 10 12:36:10 volumio1 systemd[1]: Started Music Player Daemon. Jul 10 12:36:11 volumio1 ntpd[674]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jul 10 12:36:12 volumio1 kernel: Bluetooth: Core ver 2.22 Jul 10 12:36:12 volumio1 kernel: NET: Registered protocol family 31 Jul 10 12:36:12 volumio1 kernel: Bluetooth: HCI device and connection manager initialized Jul 10 12:36:12 volumio1 kernel: Bluetooth: HCI socket layer initialized Jul 10 12:36:12 volumio1 kernel: Bluetooth: L2CAP socket layer initialized Jul 10 12:36:12 volumio1 kernel: Bluetooth: SCO socket layer initialized Jul 10 12:36:12 volumio1 btuart[527]: bcm43xx_init Jul 10 12:36:12 volumio1 btuart[527]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Jul 10 12:36:12 volumio1 btuart[527]: Set Controller UART speed to 3000000 bit/s Jul 10 12:36:12 volumio1 btuart[527]: Device setup complete Jul 10 12:36:12 volumio1 kernel: Bluetooth: HCI UART driver ver 2.3 Jul 10 12:36:12 volumio1 kernel: Bluetooth: HCI UART protocol H4 registered Jul 10 12:36:12 volumio1 kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jul 10 12:36:12 volumio1 kernel: Bluetooth: HCI UART protocol Broadcom registered Jul 10 12:36:12 volumio1 systemd[1]: Starting Load/Save RF Kill Switch Status... Jul 10 12:36:12 volumio1 systemd[1]: Started Configure Bluetooth Modems connected by UART. Jul 10 12:36:12 volumio1 systemd[1]: Started Load/Save RF Kill Switch Status. Jul 10 12:36:12 volumio1 systemd[1]: Created slice system-bthelper.slice. Jul 10 12:36:12 volumio1 systemd[1]: Starting Raspberry Pi bluetooth helper... Jul 10 12:36:12 volumio1 bthelper[788]: Raspberry Pi BDADDR already set Jul 10 12:36:12 volumio1 systemd[1]: Started Raspberry Pi bluetooth helper. Jul 10 12:36:12 volumio1 systemd[1]: Starting Bluetooth service... Jul 10 12:36:12 volumio1 bluetoothd[798]: Bluetooth daemon 5.50 Jul 10 12:36:12 volumio1 bluetoothd[798]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jul 10 12:36:12 volumio1 systemd[1]: Started Bluetooth service. Jul 10 12:36:12 volumio1 systemd[1]: Reached target Bluetooth. Jul 10 12:36:12 volumio1 bluetoothd[798]: Starting SDP server Jul 10 12:36:12 volumio1 bluetoothd[798]: Excluding (cli) sap Jul 10 12:36:12 volumio1 bluetoothd[798]: Bluetooth management interface 1.18 initialized Jul 10 12:36:12 volumio1 dbus-daemon[553]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=798 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jul 10 12:36:12 volumio1 kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jul 10 12:36:12 volumio1 kernel: Bluetooth: BNEP filters: protocol multicast Jul 10 12:36:12 volumio1 kernel: Bluetooth: BNEP socket layer initialized Jul 10 12:36:12 volumio1 systemd[1]: Starting Hostname Service... Jul 10 12:36:12 volumio1 dbus-daemon[553]: [system] Successfully activated service 'org.freedesktop.hostname1' Jul 10 12:36:12 volumio1 systemd[1]: Started Hostname Service. Jul 10 12:36:12 volumio1 bluetoothd[798]: Failed to set privacy: Rejected (0x0b) Jul 10 12:36:13 volumio1 volumio-remote-updater[542]: [2023-07-10 12:36:13] [info] asio async_connect error: system:111 (Connection refused) Jul 10 12:36:13 volumio1 volumio-remote-updater[542]: [2023-07-10 12:36:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 10 12:36:13 volumio1 volumio-remote-updater[542]: [2023-07-10 12:36:13] [error] handle_connect error: Underlying Transport Error Jul 10 12:36:13 volumio1 sudo[772]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:13 volumio1 wireless.js[531]: Start wireless flow Jul 10 12:36:13 volumio1 wireless.js[531]: Stopped hotspot (if there).. Jul 10 12:36:13 volumio1 sudo[802]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jul 10 12:36:13 volumio1 sudo[802]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:13 volumio1 sudo[802]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:13 volumio1 sudo[804]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jul 10 12:36:13 volumio1 sudo[804]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:13 volumio1 sudo[804]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:13 volumio1 wireless.js[531]: DHCP IP Jul 10 12:36:13 volumio1 wireless.js[531]: Start ap Jul 10 12:36:13 volumio1 wpa_supplicant[806]: Successfully initialized wpa_supplicant Jul 10 12:36:13 volumio1 sudo[807]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jul 10 12:36:13 volumio1 sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:13 volumio1 dhcpcd[808]: dev: loaded udev Jul 10 12:36:13 volumio1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 10 12:36:14 volumio1 dhcpcd-run-hooks[826]: wlan0: starting wpa_supplicant Jul 10 12:36:14 volumio1 dhcpcd-run-hooks[831]: wlan0: failed to start wpa_supplicant Jul 10 12:36:14 volumio1 dhcpcd-run-hooks[832]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Jul 10 12:36:14 volumio1 dhcpcd[808]: wlan0: connected to Access Point `' Jul 10 12:36:14 volumio1 dhcpcd[808]: no interfaces have a carrier Jul 10 12:36:14 volumio1 dhcpcd[808]: forked to background, child pid 842 Jul 10 12:36:14 volumio1 dhcpcd[842]: eth0: waiting for carrier Jul 10 12:36:14 volumio1 dhcpcd[842]: wlan0: waiting for carrier Jul 10 12:36:14 volumio1 sudo[807]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:14 volumio1 wireless.js[531]: trying... Jul 10 12:36:14 volumio1 sudo[844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 12:36:14 volumio1 sudo[844]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:14 volumio1 sudo[844]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:15 volumio1 wireless.js[531]: trying... Jul 10 12:36:15 volumio1 sudo[847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 12:36:15 volumio1 sudo[847]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:15 volumio1 sudo[847]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:16 volumio1 wpa_supplicant[830]: wlan0: Trying to associate with SSID 'Home Network' Jul 10 12:36:16 volumio1 wireless.js[531]: trying... Jul 10 12:36:16 volumio1 sudo[850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 12:36:16 volumio1 sudo[850]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:16 volumio1 sudo[850]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:17 volumio1 systemd[1]: systemd-rfkill.service: Succeeded. Jul 10 12:36:17 volumio1 bthelper[788]: Changing power off succeeded Jul 10 12:36:17 volumio1 bthelper[788]: [CHG] Controller DC:A6:32:B1:3B:01 Class: 0x0000041c Jul 10 12:36:17 volumio1 bthelper[788]: Changing power on succeeded Jul 10 12:36:17 volumio1 wireless.js[531]: trying... Jul 10 12:36:17 volumio1 sudo[860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 12:36:17 volumio1 sudo[860]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:17 volumio1 sudo[860]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:18 volumio1 wireless.js[531]: trying... Jul 10 12:36:18 volumio1 sudo[863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 12:36:18 volumio1 sudo[863]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:18 volumio1 sudo[863]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:19 volumio1 kernel: cam-dummy-reg: disabling Jul 10 12:36:19 volumio1 kernel: cam1-reg: disabling Jul 10 12:36:19 volumio1 dhcpcd[842]: wlan0: carrier acquired Jul 10 12:36:19 volumio1 dhcpcd[842]: wlan0: connected to Access Point `Home Network' Jul 10 12:36:19 volumio1 wpa_supplicant[830]: wlan0: Associated with 60:a4:b7:a6:9d:f7 Jul 10 12:36:19 volumio1 wpa_supplicant[830]: wlan0: CTRL-EVENT-CONNECTED - Connection to 60:a4:b7:a6:9d:f7 completed [id=0 id_str=] Jul 10 12:36:19 volumio1 wpa_supplicant[830]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jul 10 12:36:19 volumio1 dhcpcd[842]: DUID 00:01:00:01:2b:98:74:d4:dc:a6:32:b1:3b:00 Jul 10 12:36:19 volumio1 dhcpcd[842]: wlan0: IAID 32:b1:3b:00 Jul 10 12:36:19 volumio1 dhcpcd[842]: wlan0: adding address fe80::14f4:b005:51fb:9d94 Jul 10 12:36:19 volumio1 wpa_supplicant[830]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jul 10 12:36:19 volumio1 dhcpcd[842]: ipv6_addaddr1: Permission denied Jul 10 12:36:19 volumio1 dhcpcd[842]: wlan0: carrier lost Jul 10 12:36:19 volumio1 dhcpcd[842]: wlan0: carrier acquired Jul 10 12:36:19 volumio1 dhcpcd[842]: wlan0: IAID 32:b1:3b:00 Jul 10 12:36:19 volumio1 dhcpcd[842]: wlan0: soliciting an IPv6 router Jul 10 12:36:19 volumio1 wireless.js[531]: trying... Jul 10 12:36:19 volumio1 sudo[879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 12:36:19 volumio1 sudo[879]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:19 volumio1 sudo[879]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:20 volumio1 dhcpcd[842]: wlan0: rebinding lease of 192.168.68.108 Jul 10 12:36:20 volumio1 dhcpcd[842]: wlan0: probing address 192.168.68.108/24 Jul 10 12:36:20 volumio1 volumio-remote-updater[542]: [2023-07-10 12:36:20] [info] asio async_connect error: system:111 (Connection refused) Jul 10 12:36:20 volumio1 volumio-remote-updater[542]: [2023-07-10 12:36:20] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 10 12:36:20 volumio1 volumio-remote-updater[542]: [2023-07-10 12:36:20] [error] handle_connect error: Underlying Transport Error Jul 10 12:36:20 volumio1 wireless.js[531]: trying... Jul 10 12:36:20 volumio1 sudo[882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 12:36:20 volumio1 sudo[882]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:20 volumio1 sudo[882]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:21 volumio1 wireless.js[531]: trying... Jul 10 12:36:21 volumio1 sudo[885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 12:36:21 volumio1 sudo[885]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:21 volumio1 sudo[885]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:22 volumio1 wireless.js[531]: trying... Jul 10 12:36:22 volumio1 sudo[888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 12:36:22 volumio1 sudo[888]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:22 volumio1 sudo[888]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:23 volumio1 wireless.js[531]: trying... Jul 10 12:36:23 volumio1 sudo[891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 12:36:23 volumio1 sudo[891]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:23 volumio1 sudo[891]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:24 volumio1 wireless.js[531]: trying... Jul 10 12:36:24 volumio1 sudo[894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 12:36:24 volumio1 sudo[894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:24 volumio1 sudo[894]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:25 volumio1 dhcpcd[842]: wlan0: leased 192.168.68.108 for 7200 seconds Jul 10 12:36:25 volumio1 avahi-daemon[550]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.68.108. Jul 10 12:36:25 volumio1 dhcpcd[842]: wlan0: adding route to 192.168.68.0/24 Jul 10 12:36:25 volumio1 dhcpcd[842]: wlan0: adding default route via 192.168.68.1 Jul 10 12:36:25 volumio1 avahi-daemon[550]: New relevant interface wlan0.IPv4 for mDNS. Jul 10 12:36:25 volumio1 avahi-daemon[550]: Registering new address record for 192.168.68.108 on wlan0.IPv4. Jul 10 12:36:25 volumio1 wireless.js[531]: trying... Jul 10 12:36:25 volumio1 sudo[917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 10 12:36:25 volumio1 sudo[917]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:25 volumio1 sudo[917]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:26 volumio1 wireless.js[531]: Connected to: ----Home Network Jul 10 12:36:26 volumio1 wireless.js[531]: ---- Jul 10 12:36:26 volumio1 sudo[920]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 12:36:26 volumio1 sudo[920]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:26 volumio1 sudo[920]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:26 volumio1 wireless.js[531]: ... joined AP, wlan0 IPv4 is 192.168.68.108, ipV6 is undefined Jul 10 12:36:26 volumio1 wireless.js[531]: It's done! AP Jul 10 12:36:26 volumio1 systemd[1]: Started Wireless Services. Jul 10 12:36:26 volumio1 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jul 10 12:36:26 volumio1 systemd[1]: Started Volumio Backend Module. Jul 10 12:36:26 volumio1 ntpd[674]: Listen normally on 3 wlan0 192.168.68.108:123 Jul 10 12:36:26 volumio1 ntpd[674]: new interface(s) found: waking up resolver Jul 10 12:36:27 volumio1 volumio[925]: info: ------------------------------------------- Jul 10 12:36:27 volumio1 volumio[925]: info: ----- Volumio3 ---- Jul 10 12:36:27 volumio1 volumio[925]: info: ------------------------------------------- Jul 10 12:36:27 volumio1 volumio[925]: info: ----- System startup ---- Jul 10 12:36:27 volumio1 volumio[925]: info: ------------------------------------------- Jul 10 12:36:28 volumio1 volumio[925]: info: MYVOLUMIO Environment detected Jul 10 12:36:28 volumio1 volumio[925]: info: Plugin folders cleanup Jul 10 12:36:28 volumio1 volumio[925]: info: Scanning into folder /volumio/app/plugins/ Jul 10 12:36:28 volumio1 volumio[925]: info: Scanning category audio_interface Jul 10 12:36:28 volumio1 volumio[925]: info: Scanning category miscellanea Jul 10 12:36:28 volumio1 volumio[925]: info: Scanning category music_service Jul 10 12:36:28 volumio1 volumio[925]: info: Scanning category plugins.json Jul 10 12:36:28 volumio1 volumio[925]: info: Scanning category system_controller Jul 10 12:36:28 volumio1 volumio[925]: info: Scanning category user_interface Jul 10 12:36:28 volumio1 volumio[925]: info: Scanning into folder /data/plugins/ Jul 10 12:36:28 volumio1 volumio[925]: info: Plugin folders cleanup completed Jul 10 12:36:28 volumio1 volumio[925]: info: ------------------------------------------- Jul 10 12:36:28 volumio1 volumio[925]: info: ----- Core plugins startup ---- Jul 10 12:36:28 volumio1 volumio[925]: info: ------------------------------------------- Jul 10 12:36:28 volumio1 volumio[925]: info: Loading plugins from folder /volumio/app/plugins/ Jul 10 12:36:28 volumio1 volumio[925]: info: Adding plugin upnp to MyMusic Plugins Jul 10 12:36:28 volumio1 volumio[925]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 10 12:36:28 volumio1 volumio[925]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 10 12:36:28 volumio1 volumio[925]: info: Loading plugins from folder /data/plugins/ Jul 10 12:36:28 volumio1 volumio[925]: info: Loading plugin "system"... Jul 10 12:36:28 volumio1 volumio[925]: info: Loading plugin "appearance"... Jul 10 12:36:29 volumio1 systemd[1]: Started Samba NMB Daemon. Jul 10 12:36:29 volumio1 nmbd[668]: [2023/07/10 12:36:29.075425, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 10 12:36:29 volumio1 nmbd[668]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jul 10 12:36:29 volumio1 systemd[1]: Starting Samba Winbind Daemon... Jul 10 12:36:29 volumio1 winbindd[942]: [2023/07/10 12:36:29.371466, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jul 10 12:36:29 volumio1 winbindd[942]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jul 10 12:36:29 volumio1 winbindd[942]: [2023/07/10 12:36:29.388712, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 10 12:36:29 volumio1 systemd[1]: Started Samba Winbind Daemon. Jul 10 12:36:29 volumio1 winbindd[942]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jul 10 12:36:29 volumio1 systemd[1]: Starting Samba SMB Daemon... Jul 10 12:36:29 volumio1 volumio[925]: info: Loading plugin "network"... Jul 10 12:36:29 volumio1 volumio[925]: info: Refreshing Cached IP Addresses Jul 10 12:36:29 volumio1 systemd[1]: Started Samba SMB Daemon. Jul 10 12:36:29 volumio1 systemd[1]: Reached target Multi-User System. Jul 10 12:36:29 volumio1 systemd[1]: Reached target Graphical Interface. Jul 10 12:36:29 volumio1 sudo[954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 10 12:36:29 volumio1 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 10 12:36:29 volumio1 smbd[947]: [2023/07/10 12:36:29.864842, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jul 10 12:36:29 volumio1 volumio[925]: info: Loading plugin "services"... Jul 10 12:36:29 volumio1 smbd[947]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jul 10 12:36:29 volumio1 volumio[925]: info: Loading plugin "alsa_controller"... Jul 10 12:36:29 volumio1 sudo[954]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:29 volumio1 sudo[956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 12:36:29 volumio1 sudo[956]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:29 volumio1 sudo[954]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:29 volumio1 sudo[956]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:29 volumio1 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 10 12:36:29 volumio1 systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 10 12:36:29 volumio1 systemd[1]: Startup finished in 11.359s (kernel) + 32.504s (userspace) = 43.864s. Jul 10 12:36:29 volumio1 sudo[960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 10 12:36:29 volumio1 sudo[960]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:29 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 12:36:29 volumio1 volumio[925]: info: Loading plugin "wizard"... Jul 10 12:36:30 volumio1 volumio[925]: info: Loading plugin "volumio_command_line_client"... Jul 10 12:36:30 volumio1 volumio[925]: info: Loading plugin "upnp"... Jul 10 12:36:30 volumio1 volumio[925]: info: [1688992590016] Starting Upmpd Daemon Jul 10 12:36:30 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 12:36:30 volumio1 volumio[925]: info: Loading plugin "my_music"... Jul 10 12:36:30 volumio1 volumio[925]: info: Loading plugin "mpd"... Jul 10 12:36:30 volumio1 volumio[925]: info: Creating MPD Configuration file Jul 10 12:36:30 volumio1 volumio[925]: info: Loading plugin "upnp_browser"... Jul 10 12:36:30 volumio1 sudo[970]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 12:36:30 volumio1 sudo[970]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:30 volumio1 sudo[970]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:30 volumio1 sudo[972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 12:36:30 volumio1 sudo[972]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:30 volumio1 systemd[1]: Stopping Music Player Daemon... Jul 10 12:36:30 volumio1 systemd[1]: mpd.service: Succeeded. Jul 10 12:36:30 volumio1 systemd[1]: Stopped Music Player Daemon. Jul 10 12:36:30 volumio1 systemd[1]: Starting Music Player Daemon... Jul 10 12:36:30 volumio1 volumio-remote-updater[542]: [2023-07-10 12:36:30] [connect] Successful connection Jul 10 12:36:30 volumio1 volumio[925]: info: Loading plugin "networkfs"... Jul 10 12:36:31 volumio1 volumio[925]: info: Starting Udev Watcher for removable devices Jul 10 12:36:31 volumio1 sudo[980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.107/Music /mnt/NAS/Synology Jul 10 12:36:31 volumio1 sudo[980]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:31 volumio1 kernel: FS-Cache: Netfs 'cifs' registered for caching Jul 10 12:36:31 volumio1 kernel: Key type cifs.spnego registered Jul 10 12:36:31 volumio1 kernel: Key type cifs.idmap registered Jul 10 12:36:31 volumio1 kernel: CIFS: Attempting to mount //192.168.68.107/Music Jul 10 12:36:31 volumio1 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Jul 10 12:36:31 volumio1 mpd[977]: Jul 10 12:36 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 12:36:31 volumio1 volumio[925]: info: Ignoring mount for partition: boot Jul 10 12:36:31 volumio1 volumio[925]: info: Ignoring mount for partition: volumio Jul 10 12:36:31 volumio1 volumio[925]: info: Ignoring mount for partition: volumio_data Jul 10 12:36:31 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 12:36:31 volumio1 volumio[925]: info: Loading plugin "alarm-clock"... Jul 10 12:36:31 volumio1 volumio[925]: info: Loading plugin "airplay_emulation"... Jul 10 12:36:31 volumio1 volumio[925]: info: Starting Shairport Sync Jul 10 12:36:31 volumio1 volumio[925]: info: Loading plugin "last_100"... Jul 10 12:36:31 volumio1 volumio[925]: info: Loading plugin "webradio"... Jul 10 12:36:31 volumio1 volumio[925]: info: Loading plugin "i2s_dacs"... Jul 10 12:36:31 volumio1 volumio[925]: info: I2S DAC not set, start Auto-detection Jul 10 12:36:31 volumio1 volumio[925]: info: Loading plugin "volumiodiscovery"... Jul 10 12:36:31 volumio1 volumio[925]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 10 12:36:31 volumio1 volumio[925]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 12:36:31 volumio1 volumio[925]: *** WARNING *** For more information see Jul 10 12:36:31 volumio1 node[925]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 10 12:36:31 volumio1 volumio[925]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 10 12:36:31 volumio1 volumio[925]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 12:36:31 volumio1 volumio[925]: *** WARNING *** For more information see Jul 10 12:36:31 volumio1 node[925]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 12:36:31 volumio1 node[925]: *** WARNING *** For more information see Jul 10 12:36:31 volumio1 node[925]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 10 12:36:31 volumio1 node[925]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 10 12:36:31 volumio1 node[925]: *** WARNING *** For more information see Jul 10 12:36:31 volumio1 volumio[925]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 10 12:36:31 volumio1 volumio[925]: info: Discovery: Started advertising with name: Volumio1 Jul 10 12:36:31 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 12:36:31 volumio1 volumio[925]: info: Loading plugin "outputs"... Jul 10 12:36:31 volumio1 volumio[925]: info: Loading plugin "albumart"... Jul 10 12:36:31 volumio1 volumio[925]: info: Plugin example_plugin is not enabled Jul 10 12:36:31 volumio1 volumio[925]: info: Loading plugin "inputs"... Jul 10 12:36:31 volumio1 volumio[925]: info: Loading plugin "updater_comm"... Jul 10 12:36:31 volumio1 sudo[980]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:31 volumio1 volumio[925]: info: Plugin mpdemulation is not enabled Jul 10 12:36:31 volumio1 volumio[925]: info: Loading plugin "rest_api"... Jul 10 12:36:31 volumio1 volumio[925]: info: Loading plugin "websocket"... Jul 10 12:36:31 volumio1 volumio[925]: info: Loading i18n strings for locale en Jul 10 12:36:31 volumio1 volumio[925]: Updating browse sources language Jul 10 12:36:31 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:36:31 volumio1 mpd[977]: Jul 10 12:36 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 10 12:36:31 volumio1 mpd[977]: Jul 10 12:36 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 10 12:36:31 volumio1 systemd[1]: Started Music Player Daemon. Jul 10 12:36:31 volumio1 sudo[972]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:32 volumio1 volumio[925]: Forking 3 albumart workers Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::initPlayerControls Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: Express server listening on port 3000 Jul 10 12:36:32 volumio1 volumio[925]: [Metrics] WebUI: 4s 980.95ms Jul 10 12:36:32 volumio1 volumio[925]: info: CoreStateMachine::resetVolumioState Jul 10 12:36:32 volumio1 volumio[925]: info: CoreStateMachine::getcurrentVolume Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::volumioRetrievevolume Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: MPD Permissions set Jul 10 12:36:32 volumio1 volumio-remote-updater[542]: [2023-07-10 12:36:32] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1688992590 101 Jul 10 12:36:32 volumio1 volumio[925]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Jul 10 12:36:32 volumio1 volumio[925]: info: MPD running with PID977 Jul 10 12:36:32 volumio1 volumio[925]: ,establishing connection Jul 10 12:36:32 volumio1 volumio[925]: info: Reloading queue from file Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: error: updateQueue error: null Jul 10 12:36:32 volumio1 volumio[925]: info: CoreStateMachine::setRepeat null single undefined Jul 10 12:36:32 volumio1 volumio[925]: info: CoreStateMachine::pushState Jul 10 12:36:32 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 12:36:32 volumio1 volumio[925]: info: CoreStateMachine::setRandom false Jul 10 12:36:32 volumio1 volumio[925]: info: CoreStateMachine::pushState Jul 10 12:36:32 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 12:36:32 volumio1 volumio[925]: info: Setting Device type: Raspberry PI Jul 10 12:36:32 volumio1 volumio[925]: info: Completed loading Core Plugins Jul 10 12:36:32 volumio1 volumio[925]: info: Preparing to generate the ALSA configuration file Jul 10 12:36:32 volumio1 volumio[925]: info: VolumeController:: Volume=47 Mute =false Jul 10 12:36:32 volumio1 volumio[925]: info: CoreStateMachine::pushState Jul 10 12:36:32 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 12:36:32 volumio1 volumio[925]: info: CoreStateMachine::updateTrackBlock Jul 10 12:36:32 volumio1 volumio[925]: info: CorePlayQueue::getTrackBlock Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::volumioRetrievevolume Jul 10 12:36:32 volumio1 volumio[925]: error: updateQueue error: null Jul 10 12:36:32 volumio1 volumio[925]: info: Asound.conf file written Jul 10 12:36:32 volumio1 volumio[925]: Starting albumart workers Jul 10 12:36:32 volumio1 volumio[925]: Starting albumart workers Jul 10 12:36:32 volumio1 sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 10 12:36:32 volumio1 sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:32 volumio1 volumio[925]: Starting albumart workers Jul 10 12:36:32 volumio1 sudo[1057]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:32 volumio1 volumio[925]: info: Output device has changed, restarting MPD Jul 10 12:36:32 volumio1 volumio[925]: info: Output device has changed, restarting Shairport Sync Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:32 volumio1 sudo[1065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 12:36:32 volumio1 sudo[1065]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:32 volumio1 sudo[1063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 12:36:32 volumio1 sudo[1063]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:32 volumio1 systemd[1]: Stopping Music Player Daemon... Jul 10 12:36:32 volumio1 sudo[1063]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:32 volumio1 volumio[925]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 12:36:32 volumio1 volumio[925]: info: ___________ START PLUGINS ___________ Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 12:36:32 volumio1 volumio[925]: info: [1688992592826] CoreMusicLibrary::Adding element Media Servers Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:32 volumio1 systemd[1]: mpd.service: Succeeded. Jul 10 12:36:32 volumio1 systemd[1]: Stopped Music Player Daemon. Jul 10 12:36:32 volumio1 systemd[1]: Starting Music Player Daemon... Jul 10 12:36:32 volumio1 volumio[925]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:32 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:33 volumio1 volumio[925]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 12:36:33 volumio1 volumio[925]: info: [1688992593062] CoreMusicLibrary::Adding element Last_100 Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 10 12:36:33 volumio1 volumio[925]: info: [1688992593065] CoreMusicLibrary::Adding element Webradio Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 12:36:33 volumio1 volumio[925]: info: Volumio Calling Home Jul 10 12:36:33 volumio1 volumio[925]: info: Completed starting Core Plugins Jul 10 12:36:33 volumio1 volumio[925]: info: ------------------------------------------- Jul 10 12:36:33 volumio1 volumio[925]: info: ----- MyVolumio plugins startup ---- Jul 10 12:36:33 volumio1 volumio[925]: info: ------------------------------------------- Jul 10 12:36:33 volumio1 volumio[925]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 10 12:36:33 volumio1 volumio[925]: info: Discovery: adding 03f05545-bcc2-417c-bc9f-fc1582357b66 Jul 10 12:36:33 volumio1 volumio[925]: info: Discovery: Found device Volumio1 Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetState Jul 10 12:36:33 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:36:33 volumio1 volumio[925]: info: MPD Permissions set Jul 10 12:36:33 volumio1 volumio[925]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:33 volumio1 volumio[925]: info: VolumeController:: Volume=47 Mute =false Jul 10 12:36:33 volumio1 volumio[925]: info: CoreStateMachine::pushState Jul 10 12:36:33 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 12:36:33 volumio1 volumio[925]: info: Starting Shairport Sync Jul 10 12:36:33 volumio1 volumio[925]: info: Starting Shairport Sync Jul 10 12:36:33 volumio1 volumio[925]: info: Starting Shairport Sync Jul 10 12:36:33 volumio1 sudo[1112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 12:36:33 volumio1 sudo[1112]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:33 volumio1 sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 12:36:33 volumio1 sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:33 volumio1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 12:36:33 volumio1 systemd[1]: shairport-sync.service: Succeeded. Jul 10 12:36:33 volumio1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 12:36:33 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 12:36:33 volumio1 sudo[1112]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:33 volumio1 sudo[1118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 12:36:33 volumio1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 12:36:33 volumio1 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 10 12:36:33 volumio1 systemd[1]: shairport-sync.service: Succeeded. Jul 10 12:36:33 volumio1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 12:36:33 volumio1 sudo[1118]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:33 volumio1 volumio[925]: info: Shairport-Sync Started Jul 10 12:36:33 volumio1 volumio[925]: Error adding Membership: Error: addMembership EINVAL Jul 10 12:36:33 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 12:36:33 volumio1 sudo[1114]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:33 volumio1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 12:36:33 volumio1 systemd[1]: shairport-sync.service: Succeeded. Jul 10 12:36:33 volumio1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 12:36:33 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 12:36:33 volumio1 volumio[925]: info: Shairport-Sync Started Jul 10 12:36:33 volumio1 sudo[1118]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:33 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetState Jul 10 12:36:33 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:36:33 volumio1 volumio[925]: info: Shairport-Sync Started Jul 10 12:36:33 volumio1 sudo[960]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:33 volumio1 volumio[925]: info: Volumio called home Jul 10 12:36:33 volumio1 mpd[1082]: Jul 10 12:36 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 12:36:34 volumio1 mpd[1082]: Jul 10 12:36 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 10 12:36:34 volumio1 mpd[1082]: Jul 10 12:36 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 10 12:36:34 volumio1 systemd[1]: Started Music Player Daemon. Jul 10 12:36:34 volumio1 sudo[1065]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:34 volumio1 volumio[925]: error: updateQueue error: null Jul 10 12:36:34 volumio1 systemd[1]: systemd-fsckd.service: Succeeded. Jul 10 12:36:35 volumio1 sh[443]: timed out Jul 10 12:36:35 volumio1 dhcpcd[484]: timed out Jul 10 12:36:35 volumio1 sh[443]: dhcpcd exited Jul 10 12:36:35 volumio1 dhcpcd[484]: dhcpcd exited Jul 10 12:36:35 volumio1 sh[443]: ifup: failed to bring up eth0 Jul 10 12:36:35 volumio1 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 10 12:36:35 volumio1 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 10 12:36:38 volumio1 volumio[925]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 10 12:36:40 volumio1 sudo[1130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 10 12:36:40 volumio1 sudo[1130]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:40 volumio1 sudo[1130]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:40 volumio1 sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 12:36:40 volumio1 sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:40 volumio1 sudo[1133]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:41 volumio1 volumio[925]: verbose: New Socket.io Connection to volumio1.local from 192.168.68.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/114.0.0.0 Safari/537.36 Edg/114.0.1823.43 Total Clients: 3 Jul 10 12:36:41 volumio1 sudo[1136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 10 12:36:41 volumio1 sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:41 volumio1 systemd[1]: Started UPnP Renderer front-end to MPD. Jul 10 12:36:41 volumio1 sudo[1136]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:42 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetState Jul 10 12:36:42 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:36:42 volumio1 volumio[925]: info: Upmpdcli Daemon Started Jul 10 12:36:42 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetQueue Jul 10 12:36:42 volumio1 volumio[925]: info: CoreStateMachine::getQueue Jul 10 12:36:42 volumio1 volumio[925]: info: CorePlayQueue::getQueue Jul 10 12:36:42 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jul 10 12:36:42 volumio1 systemd[1]: systemd-hostnamed.service: Succeeded. Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 10 12:36:43 volumio1 volumio[925]: info: Adding plugin bluetooth to MyMusic Plugins Jul 10 12:36:43 volumio1 volumio[925]: info: Adding plugin multiroom to MyMusic Plugins Jul 10 12:36:43 volumio1 volumio[925]: info: Adding plugin metavolumio to MyMusic Plugins Jul 10 12:36:43 volumio1 volumio[925]: info: Adding plugin cd_controller to MyMusic Plugins Jul 10 12:36:43 volumio1 volumio[925]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 10 12:36:43 volumio1 volumio[925]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 10 12:36:43 volumio1 volumio[925]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 10 12:36:44 volumio1 volumio[925]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 10 12:36:44 volumio1 volumio[925]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 10 12:36:44 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:44 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:44 volumio1 volumio[925]: info: Starting MyVolumio Remote Streaming Endpoints Jul 10 12:36:44 volumio1 volumio[925]: info: MyVolumio login type: Token Jul 10 12:36:44 volumio1 volumio[925]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 10 12:36:44 volumio1 volumio[925]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 10 12:36:45 volumio1 volumio[925]: info: Starting Streaming Service Transparent Proxy Jul 10 12:36:45 volumio1 volumio[925]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 10 12:36:45 volumio1 volumio[925]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 10 12:36:45 volumio1 volumio[925]: info: Streaming services startup Jul 10 12:36:45 volumio1 volumio[925]: info: Starting Streaming Daemon Jul 10 12:36:45 volumio1 sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 10 12:36:45 volumio1 sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:45 volumio1 volumio[925]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 10 12:36:45 volumio1 sudo[1149]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:45 volumio1 volumio[925]: error: Cannot start Volumio Streaming Daemon Jul 10 12:36:45 volumio1 volumio[925]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 10 12:36:45 volumio1 volumio[925]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 10 12:36:45 volumio1 volumio[925]: Upnp client error: Error: This socket has been ended by the other party Jul 10 12:36:45 volumio1 volumio[925]: STREAMING PROXY: Starting server on port 3245 Jul 10 12:36:45 volumio1 volumio[925]: Node JS runtime: 14 Jul 10 12:36:47 volumio1 volumio[925]: Cannot compose Albumart path Jul 10 12:36:47 volumio1 volumio[925]: error: MyVolumio Custom Token format not valid, refreshing it Jul 10 12:36:48 volumio1 volumio[925]: info: MyVolumio login type: Token Jul 10 12:36:49 volumio1 volumio[925]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 10 12:36:49 volumio1 volumio[925]: info: MYVOLUMIO: Adding device Jul 10 12:36:49 volumio1 volumio[925]: info: MYVOLUMIO: Evaluating Server Jul 10 12:36:50 volumio1 volumio[925]: info: MyVolumio Plan changed: superstar Jul 10 12:36:50 volumio1 volumio[925]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Jul 10 12:36:50 volumio1 volumio[925]: info: Adding audio output: Jul 10 12:36:50 volumio1 volumio[925]: info: MYVOLUMIO: Adding device Jul 10 12:36:50 volumio1 volumio[925]: info: MYVOLUMIO: Evaluating Server Jul 10 12:36:50 volumio1 volumio[925]: info: Remote config written successfully Jul 10 12:36:50 volumio1 volumio[925]: info: Starting Tunnel 1 Jul 10 12:36:50 volumio1 volumio[925]: info: Starting Tunnel Connection Checker Jul 10 12:36:50 volumio1 volumio[925]: info: Completed starting MyVolumio Plugin Jul 10 12:36:50 volumio1 volumio[925]: info: MYVolumio Device enabled Jul 10 12:36:50 volumio1 volumio[925]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jul 10 12:36:50 volumio1 volumio[925]: info: MyVolumio status changed Jul 10 12:36:50 volumio1 volumio[925]: info: Streaming services startup Jul 10 12:36:50 volumio1 volumio[925]: info: Starting Streaming Daemon Jul 10 12:36:50 volumio1 sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 10 12:36:50 volumio1 sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:50 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jul 10 12:36:50 volumio1 sudo[1189]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:50 volumio1 volumio[925]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jul 10 12:36:50 volumio1 volumio[925]: info: [1688992610781] Starting BluetoothController Jul 10 12:36:50 volumio1 volumio[925]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jul 10 12:36:51 volumio1 volumio[925]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jul 10 12:36:51 volumio1 sudo[1192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast Jul 10 12:36:51 volumio1 sudo[1192]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:51 volumio1 sudo[1192]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:51 volumio1 sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jul 10 12:36:51 volumio1 sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:51 volumio1 sudo[1195]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:52 volumio1 volumio[925]: info: MRS: MultiRoom plugin initialized Jul 10 12:36:52 volumio1 volumio[925]: info: MRS: STOPPING SNAPCLIENT Jul 10 12:36:52 volumio1 volumio[925]: info: MRS: Snap server stop Jul 10 12:36:52 volumio1 sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jul 10 12:36:52 volumio1 sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:52 volumio1 volumio[925]: info: MRS: STOPPING volumioStreaming Jul 10 12:36:52 volumio1 sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jul 10 12:36:52 volumio1 sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:52 volumio1 sudo[1212]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:52 volumio1 sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jul 10 12:36:52 volumio1 sudo[1215]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:52 volumio1 sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:52 volumio1 volumio[925]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jul 10 12:36:52 volumio1 sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jul 10 12:36:52 volumio1 sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:52 volumio1 sudo[1221]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:52 volumio1 sudo[1218]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:52 volumio1 volumio[925]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jul 10 12:36:52 volumio1 volumio[925]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jul 10 12:36:52 volumio1 volumio[925]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jul 10 12:36:52 volumio1 volumio[925]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jul 10 12:36:52 volumio1 volumio[925]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jul 10 12:36:52 volumio1 volumio[925]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jul 10 12:36:52 volumio1 volumio[925]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jul 10 12:36:52 volumio1 volumio[925]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jul 10 12:36:52 volumio1 volumio[925]: info: Preparing to generate the ALSA configuration file Jul 10 12:36:52 volumio1 volumio[925]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 10 12:36:52 volumio1 volumio[925]: info: Reading ALSA contributions from plugins. Jul 10 12:36:52 volumio1 volumio[925]: info: Setting Geolocation for MyVolumio to eu4 Jul 10 12:36:52 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:52 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:52 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:52 volumio1 volumio[925]: info: Setting Geolocation for MyVolumio to eu7 Jul 10 12:36:52 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:52 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:52 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:52 volumio1 volumio[925]: info: MRS: Removed streaming files Jul 10 12:36:52 volumio1 volumio[925]: info: MRS: volumioStreaming STOPPED Jul 10 12:36:52 volumio1 volumio[925]: info: MRS: SNAPSERVER STOPPED Jul 10 12:36:52 volumio1 volumio[925]: info: MRS: SNAPCLIENT STOPPED Jul 10 12:36:52 volumio1 volumio[925]: info: Snap server and client installed Jul 10 12:36:52 volumio1 volumio[925]: error: Cannot start Volumio Streaming Daemon Jul 10 12:36:52 volumio1 volumio[925]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 10 12:36:52 volumio1 volumio[925]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 10 12:36:52 volumio1 volumio[925]: info: Asound.conf file written Jul 10 12:36:52 volumio1 sudo[1224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 10 12:36:52 volumio1 sudo[1224]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:52 volumio1 sudo[1224]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:52 volumio1 volumio[925]: info: Output device has changed, restarting MPD Jul 10 12:36:52 volumio1 sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 12:36:52 volumio1 sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:52 volumio1 volumio[925]: info: Output device has changed, restarting Shairport Sync Jul 10 12:36:52 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:52 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:52 volumio1 sudo[1229]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:52 volumio1 sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 12:36:52 volumio1 sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:52 volumio1 systemd[1]: Stopping Music Player Daemon... Jul 10 12:36:52 volumio1 volumio[925]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 12:36:52 volumio1 volumio[925]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 10 12:36:52 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:52 volumio1 volumio[925]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jul 10 12:36:52 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 10 12:36:52 volumio1 systemd[1]: mpd.service: Succeeded. Jul 10 12:36:52 volumio1 systemd[1]: Stopped Music Player Daemon. Jul 10 12:36:52 volumio1 systemd[1]: Starting Music Player Daemon... Jul 10 12:36:52 volumio1 volumio[925]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jul 10 12:36:52 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jul 10 12:36:52 volumio1 volumio[925]: info: MRS: Pushing multiroomSync output for this device Jul 10 12:36:52 volumio1 volumio[925]: info: MRS: Pushing multiroomSync output Jul 10 12:36:52 volumio1 volumio[925]: info: Adding audio output: Jul 10 12:36:52 volumio1 sudo[1241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio1 Jul 10 12:36:52 volumio1 volumio[925]: info: Adding audio output: Jul 10 12:36:52 volumio1 sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:52 volumio1 sudo[1241]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:52 volumio1 volumio[925]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 10 12:36:52 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:52 volumio1 volumio[925]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jul 10 12:36:52 volumio1 volumio[925]: info: Adding METAVOLUMIO REST API Endpoints Jul 10 12:36:52 volumio1 volumio[925]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 10 12:36:52 volumio1 volumio[925]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jul 10 12:36:52 volumio1 volumio[925]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jul 10 12:36:52 volumio1 volumio[925]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jul 10 12:36:52 volumio1 volumio[925]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jul 10 12:36:52 volumio1 volumio[925]: info: Adding Manifest REST API Endpoints Jul 10 12:36:52 volumio1 volumio[925]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Jul 10 12:36:52 volumio1 volumio[925]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jul 10 12:36:52 volumio1 volumio[925]: info: Preparing CD Folders Jul 10 12:36:52 volumio1 volumio[925]: info: Adding CD REST API Endpoints Jul 10 12:36:52 volumio1 volumio[925]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jul 10 12:36:52 volumio1 volumio[925]: info: Starting UDEV Watcher for CD Jul 10 12:36:52 volumio1 volumio[925]: info: Detecting CD presence with UDEV Jul 10 12:36:52 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jul 10 12:36:53 volumio1 volumio[925]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jul 10 12:36:53 volumio1 volumio[925]: info: Adding inputs REST Endpoints Jul 10 12:36:53 volumio1 volumio[925]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jul 10 12:36:53 volumio1 volumio[925]: info: Scanning Audio Inputs Jul 10 12:36:53 volumio1 volumio[925]: info: Checking against Known Cards name Jul 10 12:36:53 volumio1 volumio[925]: info: Adding Server instance for streaming Jul 10 12:36:53 volumio1 volumio[925]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jul 10 12:36:53 volumio1 volumio[925]: error: Hi Res Audio Failed Login: Missing Login Data Jul 10 12:36:53 volumio1 volumio[925]: info: Adding HIGHRESAUDIO REST API Endpoints Jul 10 12:36:53 volumio1 volumio[925]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jul 10 12:36:53 volumio1 volumio[925]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jul 10 12:36:53 volumio1 volumio[925]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jul 10 12:36:53 volumio1 volumio[925]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jul 10 12:36:53 volumio1 volumio[925]: info: Stopping AccessToken refresher cron Jul 10 12:36:53 volumio1 sudo[1251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 10 12:36:53 volumio1 sudo[1251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:53 volumio1 volumio[925]: info: AccessToken refresher cron started Jul 10 12:36:53 volumio1 volumio[925]: info: Stopping AccessToken refresher cron for QOBUZ Jul 10 12:36:53 volumio1 sudo[1251]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:53 volumio1 volumio[925]: info: AccessToken refresher cron started for QOBUZ Jul 10 12:36:53 volumio1 volumio[925]: info: Adding TIDAL REST API Endpoints Jul 10 12:36:53 volumio1 volumio[925]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jul 10 12:36:53 volumio1 volumio[925]: info: Adding QOBUZ REST API Endpoints Jul 10 12:36:53 volumio1 volumio[925]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jul 10 12:36:53 volumio1 volumio[925]: info: Bluetooth name changed to Volumio1 Jul 10 12:36:53 volumio1 volumio[925]: info: MPD Permissions set Jul 10 12:36:53 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:53 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:53 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:53 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:53 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:53 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:53 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:53 volumio1 volumio[925]: info: Updating MyVolumio device info Jul 10 12:36:53 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:53 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:53 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:53 volumio1 volumio[925]: info: Updating MyVolumio device info Jul 10 12:36:53 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:53 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:53 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:53 volumio1 mpd[1247]: Jul 10 12:36 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 10 12:36:53 volumio1 volumio[925]: info: Starting Shairport Sync Jul 10 12:36:53 volumio1 sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 12:36:53 volumio1 sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:53 volumio1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 10 12:36:53 volumio1 systemd[1]: shairport-sync.service: Succeeded. Jul 10 12:36:53 volumio1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 10 12:36:53 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 10 12:36:53 volumio1 sudo[1259]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:53 volumio1 volumio[925]: info: Shairport-Sync Started Jul 10 12:36:53 volumio1 volumio[925]: info: Successfully Updated MyVolumio device Jul 10 12:36:53 volumio1 volumio[925]: info: Successfully Updated MyVolumio device Jul 10 12:36:53 volumio1 volumio[925]: info: Successfully Added MyVolumio device Jul 10 12:36:54 volumio1 mpd[1247]: Jul 10 12:36 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 10 12:36:54 volumio1 mpd[1247]: Jul 10 12:36 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 10 12:36:54 volumio1 systemd[1]: Started Music Player Daemon. Jul 10 12:36:54 volumio1 sudo[1232]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:54 volumio1 volumio[925]: error: updateQueue error: null Jul 10 12:36:54 volumio1 volumio[925]: info: Successfully Added MyVolumio device Jul 10 12:36:55 volumio1 sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jul 10 12:36:55 volumio1 sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:55 volumio1 systemd[1]: Started MyVolumio SSH Tunnel. Jul 10 12:36:55 volumio1 sudo[1271]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:55 volumio1 volumio[925]: info: Remote SSH Started Jul 10 12:36:55 volumio1 autossh[1274]: port set to 0, monitoring disabled Jul 10 12:36:55 volumio1 autossh[1274]: starting ssh (count 1) Jul 10 12:36:55 volumio1 autossh[1274]: ssh child pid is 1277 Jul 10 12:36:55 volumio1 volumio[925]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Jul 10 12:36:55 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetState Jul 10 12:36:55 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:36:55 volumio1 sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jul 10 12:36:55 volumio1 sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:55 volumio1 volumiossh-tunnel[1273]: Warning: Permanently added '[eu7.myvolumio.org]:2222,[167.71.43.69]:2222' (RSA) to the list of known hosts. Jul 10 12:36:55 volumio1 systemd[1]: Started Volumio Bluetooth Module. Jul 10 12:36:55 volumio1 sudo[1279]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:55 volumio1 volumio[925]: info: Volumio BT Module successfully started Jul 10 12:36:55 volumio1 sudo[1282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jul 10 12:36:55 volumio1 sudo[1282]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:55 volumio1 volumiobt[1281]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jul 10 12:36:55 volumio1 sudo[1282]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:56 volumio1 volumio[925]: info: TidalConnect service stoped! Jul 10 12:36:56 volumio1 volumio[925]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 10 12:36:56 volumio1 volumio[925]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 10 12:36:56 volumio1 sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 10 12:36:56 volumio1 sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:56 volumio1 systemd[1]: Started Volumio Tidal Connect Service. Jul 10 12:36:56 volumio1 sudo[1294]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:56 volumio1 volumio[925]: info: Executing endpoint tc_getconfig Jul 10 12:36:56 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 10 12:36:56 volumio1 vtcs[1296]: STARTING TidalConnect services, version: 1.1.7.5 Jul 10 12:36:56 volumio1 vtcs[1296]: STARTED TidalConnect services. Jul 10 12:36:56 volumio1 volumio[925]: info: Executing endpoint tc_connect Jul 10 12:36:56 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 10 12:36:56 volumio1 volumio[925]: info: Connecting to TidalConnect Jul 10 12:36:56 volumio1 volumio[925]: info: CoreCommandRouter::servicePushState Jul 10 12:36:56 volumio1 volumio[925]: info: CoreStateMachine::pushState Jul 10 12:36:56 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:36:56 volumio1 volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 12:36:56 volumio1 volumio[925]: info: MRS: Pushing multiroomSync output update for this device Jul 10 12:36:56 volumio1 volumio[925]: info: MRS: Pushing multiroomSync output Jul 10 12:36:56 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:36:56 volumio1 volumio[925]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jul 10 12:36:56 volumio1 volumio[925]: info: CoreCommandRouter::servicePushState Jul 10 12:36:56 volumio1 volumio[925]: info: CoreStateMachine::pushState Jul 10 12:36:56 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:36:56 volumio1 volumio[925]: info: CoreCommandRouter::volumioPushState Jul 10 12:36:56 volumio1 volumio[925]: info: MRS: Pushing multiroomSync output update for this device Jul 10 12:36:56 volumio1 volumio[925]: info: MRS: Pushing multiroomSync output Jul 10 12:36:56 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:36:56 volumio1 volumio[925]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Jul 10 12:36:56 volumio1 sudo[1314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jul 10 12:36:56 volumio1 sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:36:56 volumio1 volumiobt[1281]: pulseaudio: no process found Jul 10 12:36:56 volumio1 sudo[1314]: pam_unix(sudo:session): session closed for user root Jul 10 12:36:56 volumio1 dbus-daemon[553]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.20' (uid=1000 pid=1318 comm="/usr/bin/pulseaudio --high-priority=true ") Jul 10 12:36:56 volumio1 systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jul 10 12:36:56 volumio1 volumio[925]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 12:36:56 volumio1 dbus-daemon[553]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jul 10 12:36:56 volumio1 rtkit-daemon[1319]: Successfully called chroot. Jul 10 12:36:56 volumio1 rtkit-daemon[1319]: Successfully dropped privileges. Jul 10 12:36:56 volumio1 systemd[1]: Started RealtimeKit Scheduling Policy Service. Jul 10 12:36:56 volumio1 rtkit-daemon[1319]: Successfully limited resources. Jul 10 12:36:56 volumio1 rtkit-daemon[1319]: Running. Jul 10 12:36:56 volumio1 rtkit-daemon[1319]: Canary thread running. Jul 10 12:36:56 volumio1 rtkit-daemon[1319]: Watchdog thread running. Jul 10 12:36:56 volumio1 dbus-daemon[553]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.21' (uid=0 pid=1319 comm="/usr/lib/rtkit/rtkit-daemon ") Jul 10 12:36:56 volumio1 systemd[1]: Starting Authorization Manager... Jul 10 12:36:56 volumio1 volumio[925]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 10 12:36:56 volumio1 polkitd[1322]: started daemon version 0.105 using authority implementation `local' version `0.105' Jul 10 12:36:56 volumio1 dbus-daemon[553]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jul 10 12:36:56 volumio1 systemd[1]: Started Authorization Manager. Jul 10 12:36:57 volumio1 pulseaudio[1318]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 10 12:36:57 volumio1 pulseaudio[1318]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 10 12:36:57 volumio1 volumiobt[1281]: Applying permissions Jul 10 12:36:57 volumio1 volumiobt[1281]: Setting BT discoverable and pairable Jul 10 12:36:57 volumio1 bluetoothd[798]: Endpoint registered: sender=:1.41 path=/MediaEndpoint/A2DPSource Jul 10 12:36:57 volumio1 bluetoothd[798]: Endpoint registered: sender=:1.41 path=/MediaEndpoint/A2DPSink Jul 10 12:36:57 volumio1 pulseaudio[1318]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Jul 10 12:36:57 volumio1 kernel: Bluetooth: RFCOMM TTY layer initialized Jul 10 12:36:57 volumio1 kernel: Bluetooth: RFCOMM socket layer initialized Jul 10 12:36:57 volumio1 kernel: Bluetooth: RFCOMM ver 1.11 Jul 10 12:36:57 volumio1 volumiobt[1281]: [CHG] Controller DC:A6:32:B1:3B:01 UUIDs: 00001112-0000-1000-8000-00805f9b34fb Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [55B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [117B blob data] Jul 10 12:36:57 volumio1 volumiobt[1281]: [bluetooth]# -e power on Jul 10 12:36:57 volumio1 volumiobt[1281]: Invalid command in menu main: -e Jul 10 12:36:57 volumio1 volumiobt[1281]: Jul 10 12:36:57 volumio1 volumiobt[1281]: Use "help" for a list of available commands in a menu. Jul 10 12:36:57 volumio1 volumiobt[1281]: Use "menu " if you want to enter any submenu. Jul 10 12:36:57 volumio1 volumiobt[1281]: Use "back" if you want to return to menu main. Jul 10 12:36:57 volumio1 volumiobt[1281]: [bluetooth]# agent on Jul 10 12:36:57 volumio1 volumiobt[1281]: Agent is already registered Jul 10 12:36:57 volumio1 volumiobt[1281]: [bluetooth]# discoverable on Jul 10 12:36:57 volumio1 volumiobt[1281]: [bluetooth]# pairable on Jul 10 12:36:57 volumio1 volumiobt[1281]: [bluetooth]# agent NoInputNoOutput Jul 10 12:36:57 volumio1 volumiobt[1281]: Agent is already registered Jul 10 12:36:57 volumio1 volumiobt[1281]: [bluetooth]# default-agent Jul 10 12:36:57 volumio1 volumiobt[1281]: [bluetooth]# quit Jul 10 12:36:57 volumio1 volumiobt[1281]: [59B blob data] Jul 10 12:36:57 volumio1 volumio[925]: info: MRS: Getting audio outputs on start Jul 10 12:36:57 volumio1 volumio[925]: info: MRS: Requesting all other devices output Jul 10 12:36:59 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 10 12:36:59 volumio1 volumio[925]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Jul 10 12:36:59 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetState Jul 10 12:36:59 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:36:59 volumio1 volumio[925]: info: TidalConnect service started! Jul 10 12:36:59 volumio1 volumio[925]: info: BOOT COMPLETED Jul 10 12:36:59 volumio1 volumio[925]: [Metrics] CommandRouter: 31s 497.52ms Jul 10 12:36:59 volumio1 volumio[925]: info: CoreCommandRouter::volumiosetStartupVolume Jul 10 12:36:59 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:36:59 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:36:59 volumio1 volumio[925]: info: CoreCommandRouter::Close All Modals sent Jul 10 12:36:59 volumio1 volumio[925]: info: CoreCommandRouter::Close All Modals sent Jul 10 12:37:00 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 10 12:37:00 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 12:37:00 volumio1 volumio[925]: xcb_connection_has_error() returned true Jul 10 12:37:00 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 10 12:37:03 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 12:37:03 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 12:37:03 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 10 12:37:03 volumio1 volumio-remote-updater[542]: No test mode Jul 10 12:37:03 volumio1 volumio-remote-updater[542]: No alpha test mode Jul 10 12:37:03 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 10 12:37:03 volumio1 volumio[925]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 10 12:37:03 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 10 12:37:04 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:37:04 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 12:37:04 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 10 12:37:04 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 10 12:37:04 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 10 12:37:04 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetBrowseSources Jul 10 12:37:04 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 12:37:09 volumio1 volumio[925]: verbose: New Socket.io Connection to volumio1.local from 192.168.68.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/114.0.0.0 Safari/537.36 Edg/114.0.1823.43 Total Clients: 5 Jul 10 12:37:09 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetState Jul 10 12:37:09 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:37:09 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetQueue Jul 10 12:37:09 volumio1 volumio[925]: info: CoreStateMachine::getQueue Jul 10 12:37:09 volumio1 volumio[925]: info: CorePlayQueue::getQueue Jul 10 12:37:09 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jul 10 12:37:12 volumio1 ntpd[674]: Soliciting pool server 95.216.154.135 Jul 10 12:37:13 volumio1 ntpd[674]: Soliciting pool server 65.21.58.9 Jul 10 12:37:14 volumio1 ntpd[674]: Soliciting pool server 65.108.121.104 Jul 10 12:37:14 volumio1 ntpd[674]: Soliciting pool server 62.241.198.253 Jul 10 12:37:14 volumio1 ntpd[674]: Soliciting pool server 195.148.70.12 Jul 10 12:37:15 volumio1 ntpd[674]: Soliciting pool server 95.216.138.141 Jul 10 12:37:15 volumio1 ntpd[674]: Soliciting pool server 95.217.93.235 Jul 10 12:37:15 volumio1 ntpd[674]: Soliciting pool server 65.109.226.153 Jul 10 12:37:16 volumio1 ntpd[674]: Soliciting pool server 95.217.188.206 Jul 10 12:37:17 volumio1 ntpd[674]: Soliciting pool server 2a01:4f9:6b:10d9:0:900d:ca4:500 Jul 10 12:37:17 volumio1 ntpd[674]: Soliciting pool server 162.159.200.1 Jul 10 12:37:18 volumio1 ntpd[674]: Soliciting pool server 95.216.157.194 Jul 10 12:37:19 volumio1 ntpd[674]: Soliciting pool server 95.217.187.44 Jul 10 12:37:48 volumio1 ntpd[674]: receive: Unexpected origin timestamp 0xe8567a00.96bf53f0 does not match aorg 0000000000.00000000 from server@195.148.70.12 xmt 0xe8567a1c.a08dafbc Jul 10 12:37:53 volumio1 sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 10 12:37:53 volumio1 sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:37:53 volumio1 sudo[1341]: pam_unix(sudo:session): session closed for user root Jul 10 12:37:53 volumio1 sudo[1344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 12:37:53 volumio1 sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:37:53 volumio1 sudo[1344]: pam_unix(sudo:session): session closed for user root Jul 10 12:37:53 volumio1 volumio[925]: verbose: New Socket.io Connection to 192.168.68.108 from 192.168.68.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/114.0.0.0 Safari/537.36 Edg/114.0.1823.43 Total Clients: 6 Jul 10 12:37:54 volumio1 sudo[1347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 10 12:37:54 volumio1 sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:37:54 volumio1 sudo[1347]: pam_unix(sudo:session): session closed for user root Jul 10 12:37:54 volumio1 sudo[1350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 12:37:54 volumio1 sudo[1350]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:37:54 volumio1 sudo[1350]: pam_unix(sudo:session): session closed for user root Jul 10 12:37:55 volumio1 volumio[925]: verbose: New Socket.io Connection to 192.168.68.108 from 192.168.68.112 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/114.0.0.0 Safari/537.36 Edg/114.0.1823.43 Total Clients: 7 Jul 10 12:37:55 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 10 12:37:55 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 10 12:37:55 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetVisibleSources Jul 10 12:37:55 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:37:55 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetState Jul 10 12:37:55 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:37:55 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 10 12:37:55 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 10 12:37:55 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 10 12:37:55 volumio1 volumio[925]: info: Received Get System Info Jul 10 12:37:55 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 12:37:55 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 12:37:55 volumio1 volumio[925]: info: Discovery: Getting this device information Jul 10 12:37:55 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetState Jul 10 12:37:55 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:37:55 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 12:37:55 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetState Jul 10 12:37:55 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:37:55 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 10 12:37:55 volumio1 volumio[925]: info: Listing playlists Jul 10 12:37:55 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 10 12:37:55 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 10 12:37:56 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetState Jul 10 12:37:56 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:37:56 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 10 12:37:56 volumio1 volumio[925]: info: Received Get System Info Jul 10 12:37:56 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 12:37:56 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 12:37:56 volumio1 volumio[925]: info: Discovery: Getting this device information Jul 10 12:37:56 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetState Jul 10 12:37:56 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:37:56 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 12:37:58 volumio1 volumio[925]: info: Executing endpoint metavolumio Jul 10 12:37:58 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 10 12:37:58 volumio1 volumio[925]: info: Executing endpoint metavolumio Jul 10 12:37:58 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 10 12:37:58 volumio1 volumio[925]: info: Executing endpoint metavolumio Jul 10 12:37:58 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 10 12:37:58 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 10 12:37:58 volumio1 volumio[925]: info: Received Get System Info Jul 10 12:37:58 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 12:37:58 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 12:37:58 volumio1 volumio[925]: info: Discovery: Getting this device information Jul 10 12:37:58 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetState Jul 10 12:37:58 volumio1 volumio[925]: info: CorePlayQueue::getTrack 0 Jul 10 12:37:58 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 12:38:13 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 10 12:38:13 volumio1 volumio[925]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 10 12:38:13 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 10 12:38:13 volumio1 volumio[925]: info: Received Get System Version Jul 10 12:38:13 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 12:42:12 volumio1 volumio[925]: info: CoreCommandRouter::volumioGetQueue Jul 10 12:42:12 volumio1 volumio[925]: info: CoreStateMachine::getQueue Jul 10 12:42:12 volumio1 volumio[925]: info: CorePlayQueue::getQueue Jul 10 12:42:19 volumio1 volumio[925]: Searching plugin music_service/mpd Jul 10 12:42:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:42:20 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:42:21 volumio1 volumio[925]: Searching plugin music_service/mpd Jul 10 12:42:21 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:42:22 volumio1 volumio[925]: Searching plugin music_service/mpd Jul 10 12:42:22 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:42:23 volumio1 volumio[925]: Searching plugin music_service/mpd Jul 10 12:42:23 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:42:27 volumio1 volumio[925]: Cannot compose Albumart path Jul 10 12:42:27 volumio1 volumio[925]: Searching plugin music_service/mpd Jul 10 12:42:27 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:42:27 volumio1 volumio[925]: Searching plugin music_service/mpd Jul 10 12:42:27 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:42:31 volumio1 volumio[925]: Cannot compose Albumart path Jul 10 12:42:31 volumio1 volumio[925]: Cannot compose Albumart path Jul 10 12:42:31 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:42:31 volumio1 volumio[925]: Searching plugin music_service/mpd Jul 10 12:42:31 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:42:32 volumio1 volumio[925]: Cannot compose Albumart path Jul 10 12:42:32 volumio1 volumio[925]: Cannot compose Albumart path Jul 10 12:42:32 volumio1 volumio[925]: Cannot compose Albumart path Jul 10 12:42:32 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:42:32 volumio1 volumio[925]: Cannot compose Albumart path Jul 10 12:42:32 volumio1 volumio[925]: Cannot compose Albumart path Jul 10 12:42:32 volumio1 volumio[925]: Cannot compose Albumart path Jul 10 12:42:32 volumio1 volumio[925]: Cannot compose Albumart path Jul 10 12:42:32 volumio1 volumio[925]: Cannot compose Albumart path Jul 10 12:42:32 volumio1 volumio[925]: Cannot compose Albumart path Jul 10 12:42:32 volumio1 volumio[925]: Cannot compose Albumart path Jul 10 12:42:32 volumio1 volumio[925]: Cannot compose Albumart path Jul 10 12:42:32 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:42:32 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:42:32 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:42:32 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:42:33 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:44:00 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:44:01 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:44:05 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:44:11 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:44:16 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: metavolumio , superSearch Jul 10 12:44:19 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: All cached search sources collected Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , search Jul 10 12:44:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: last_100 , search Jul 10 12:44:19 volumio1 volumio[925]: info: Error : CoreCommandRouter::executeOnPlugin: No method [search] in plugin last_100 Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:29 volumio1 volumio[925]: error: Search in plugin mpd timed out Jul 10 12:44:29 volumio1 volumio[925]: info: All search sources collected, pushing search results Jul 10 12:44:29 volumio1 volumio[925]: info: CoreCommandRouter::emitMessageToSpecificClient pushBrowseLibrary Jul 10 12:44:31 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:32 volumio1 volumio[925]: error: Failed search in plugin mpd: Error: Unable to resolve or reject the same promise twice Jul 10 12:44:39 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:00 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:00 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:00 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:00 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:02 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:25 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:26 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:26 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:29 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:30 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:31 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:33 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:33 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:34 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:35 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:35 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:39 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:39 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:39 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:42 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:43 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:43 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:43 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:43 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:44 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:44 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:45:44 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:05 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:06 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:07 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:09 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:09 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:10 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:10 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:10 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:10 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:10 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:10 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:11 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:11 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:11 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:11 volumio1 volumio[925]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jul 10 12:46:19 volumio1 volumio[925]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Jul 10 12:46:19 volumio1 volumio[925]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Jul 10 12:46:19 volumio1 sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Jul 10 12:46:19 volumio1 sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 12:46:19 volumio1 sudo[1400]: pam_unix(sudo:session): session closed for user root Jul 10 12:46:27 volumio1 sudo[1445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Jul 10 12:46:27 volumio1 sudo[1445]: pam_unix(sudo:session): session opened for user root by (uid=0)