Wed Mar 8 20:09:30 CET 2023 Description="'Bad OAuth, API rate limit'" # 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="1d63a568b7d5e4a317f16be7d0961cbb2d82e282" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="4f8978e0148ab5e1610548b80abf1b4b5e03ea0a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 07 Feb 2023 02:43:13 PM CET" VOLUMIO_VERSION="3.429" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3223bbb74b9427313a173f7908c19cfa" # ifconfig --------------- eth0: flags=4099 mtu 1500 ether e4:5f:01:11:28:5e 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 1432 bytes 178479 (174.2 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 1432 bytes 178479 (174.2 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 wlan0: flags=4163 mtu 1500 inet 172.16.0.49 netmask 255.255.255.0 broadcast 172.16.0.255 ether e4:5f:01:11:28:5f txqueuelen 1000 (Ethernet) RX packets 3691 bytes 721923 (705.0 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 2744 bytes 838763 (819.1 KiB) 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:"Isengard" Mode:Managed Frequency:2.412 GHz Access Point: 74:42:7F:01:19:AB Bit Rate=72.2 Mb/s Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off Link Quality=67/70 Signal level=-43 dBm Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0 Tx excessive retries:9 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 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 1 20:01 ? 00:00:07 /sbin/init root 2 0 0 20:01 ? 00:00:00 [kthreadd] root 3 2 0 20:01 ? 00:00:00 [rcu_gp] root 4 2 0 20:01 ? 00:00:00 [rcu_par_gp] root 6 2 0 20:01 ? 00:00:00 [kworker/0:0H-kblockd] root 7 2 0 20:01 ? 00:00:00 [kworker/u8:0-brcmf_wq/mmc1:0001:1] root 8 2 0 20:01 ? 00:00:00 [mm_percpu_wq] root 9 2 0 20:01 ? 00:00:00 [rcu_tasks_rude_] root 10 2 0 20:01 ? 00:00:00 [rcu_tasks_trace] root 11 2 0 20:01 ? 00:00:00 [ksoftirqd/0] root 12 2 0 20:01 ? 00:00:00 [rcu_sched] root 13 2 0 20:01 ? 00:00:00 [migration/0] root 14 2 0 20:01 ? 00:00:00 [cpuhp/0] root 15 2 0 20:01 ? 00:00:00 [cpuhp/1] root 16 2 0 20:01 ? 00:00:00 [migration/1] root 17 2 0 20:01 ? 00:00:00 [ksoftirqd/1] root 20 2 0 20:01 ? 00:00:00 [cpuhp/2] root 21 2 0 20:01 ? 00:00:00 [migration/2] root 22 2 0 20:01 ? 00:00:00 [ksoftirqd/2] root 25 2 0 20:01 ? 00:00:00 [cpuhp/3] root 26 2 0 20:01 ? 00:00:00 [migration/3] root 27 2 0 20:01 ? 00:00:00 [ksoftirqd/3] root 30 2 0 20:01 ? 00:00:00 [kdevtmpfs] root 31 2 0 20:01 ? 00:00:00 [netns] root 33 2 0 20:01 ? 00:00:00 [kworker/1:1-mm_percpu_wq] root 34 2 0 20:01 ? 00:00:00 [kauditd] root 35 2 0 20:01 ? 00:00:00 [kworker/0:2-events] root 36 2 0 20:01 ? 00:00:00 [khungtaskd] root 37 2 0 20:01 ? 00:00:00 [oom_reaper] root 38 2 0 20:01 ? 00:00:00 [writeback] root 39 2 0 20:01 ? 00:00:00 [kcompactd0] root 59 2 0 20:01 ? 00:00:00 [kblockd] root 60 2 0 20:01 ? 00:00:00 [blkcg_punt_bio] root 61 2 0 20:01 ? 00:00:00 [watchdogd] root 62 2 0 20:01 ? 00:00:00 [kworker/3:1-mm_percpu_wq] root 63 2 0 20:01 ? 00:00:00 [kworker/3:1H-kblockd] root 64 2 0 20:01 ? 00:00:00 [rpciod] root 65 2 0 20:01 ? 00:00:00 [kworker/u9:0-hci0] root 66 2 0 20:01 ? 00:00:00 [xprtiod] root 68 2 0 20:01 ? 00:00:00 [kswapd0] root 69 2 0 20:01 ? 00:00:00 [nfsiod] root 70 2 0 20:01 ? 00:00:00 [kthrotld] root 71 2 0 20:01 ? 00:00:00 [kworker/2:2-events] root 72 2 0 20:01 ? 00:00:00 [iscsi_eh] root 73 2 0 20:01 ? 00:00:00 [iscsi_destroy] root 74 2 0 20:01 ? 00:00:00 [nvme-wq] root 75 2 0 20:01 ? 00:00:00 [nvme-reset-wq] root 76 2 0 20:01 ? 00:00:00 [nvme-delete-wq] root 79 2 0 20:01 ? 00:00:00 [DWC Notificatio] root 80 2 0 20:01 ? 00:00:00 [uas] root 82 2 0 20:01 ? 00:00:00 [vchiq-slot/0] root 83 2 0 20:01 ? 00:00:00 [vchiq-recy/0] root 84 2 0 20:01 ? 00:00:00 [vchiq-sync/0] root 85 2 0 20:01 ? 00:00:00 [zswap-shrink] root 88 2 0 20:01 ? 00:00:00 [kworker/2:5-mm_percpu_wq] root 89 2 0 20:01 ? 00:00:00 [sdhci] root 90 2 0 20:01 ? 00:00:00 [irq/48-mmc0] root 92 2 0 20:01 ? 00:00:00 [kworker/3:3-events_power_efficient] root 102 2 0 20:01 ? 00:00:00 [mmc_complete] root 123 2 0 20:01 ? 00:00:00 [kworker/1:1H-kblockd] root 124 2 0 20:01 ? 00:00:00 [kworker/1:2H-kblockd] root 128 2 0 20:01 ? 00:00:00 [jbd2/mmcblk0p2-] root 129 2 0 20:01 ? 00:00:00 [ext4-rsv-conver] root 136 2 0 20:01 ? 00:00:01 [loop0] root 148 2 0 20:01 ? 00:00:00 [kworker/2:1H-kblockd] root 163 2 0 20:01 ? 00:00:00 [kworker/2:2H-kblockd] root 164 2 0 20:01 ? 00:00:00 [jbd2/mmcblk0p3-] root 165 2 0 20:01 ? 00:00:00 [ext4-rsv-conver] root 172 2 0 20:01 ? 00:00:00 [kworker/3:2H-kblockd] root 173 2 0 20:01 ? 00:00:00 [ipv6_addrconf] root 198 1 0 20:01 ? 00:00:00 /lib/systemd/systemd-journald root 214 2 0 20:01 ? 00:00:00 [kworker/1:2-mm_percpu_wq] root 220 1 0 20:01 ? 00:00:00 /lib/systemd/systemd-udevd root 273 2 0 20:01 ? 00:00:00 [vchiq-keep/0] root 274 2 0 20:01 ? 00:00:00 [SMIO] root 331 2 0 20:01 ? 00:00:00 [kworker/0:2H-mmc_complete] root 341 2 0 20:01 ? 00:00:00 [mmal-vchiq] root 343 2 0 20:01 ? 00:00:00 [mmal-vchiq] root 344 2 0 20:01 ? 00:00:00 [mmal-vchiq] root 345 2 0 20:01 ? 00:00:00 [mmal-vchiq] root 346 2 0 20:01 ? 00:00:00 [mmal-vchiq] root 354 2 0 20:01 ? 00:00:00 [mmal-vchiq] root 366 2 0 20:01 ? 00:00:00 [cfg80211] root 367 2 0 20:01 ? 00:00:00 [brcmf_wq/mmc1:0] root 368 2 0 20:01 ? 00:00:00 [brcmf_wdog/mmc1] _rpc 430 1 0 20:01 ? 00:00:00 /sbin/rpcbind -f -w root 440 1 0 20:01 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 avahi 457 1 0 20:01 ? 00:00:00 avahi-daemon: running [musicliving.local] volumio 459 1 0 20:01 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 460 1 0 20:01 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon message+ 464 1 0 20:01 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 465 2 0 20:01 ? 00:00:00 [kworker/u8:3-brcmf_wq/mmc1:0001:1] root 485 1 0 20:01 ? 00:00:00 /lib/systemd/systemd-logind root 487 1 0 20:01 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant nobody 488 1 0 20:01 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* avahi 498 457 0 20:01 ? 00:00:00 avahi-daemon: chroot helper shairpo+ 609 1 0 20:01 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf root 616 1 0 20:01 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 624 1 0 20:01 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux root 628 1 0 20:01 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I ntp 634 1 0 20:01 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 643 616 0 20:01 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 665 2 0 20:01 ? 00:00:00 [kworker/0:3-events] root 683 2 0 20:01 ? 00:00:00 [kworker/u9:1-hci0] root 684 1 0 20:01 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 3000000 flow - root 698 1 0 20:01 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap root 731 1 0 20:01 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 743 1 0 20:01 ? 00:00:00 /sbin/dhcpcd volumio 838 1 4 20:02 ? 00:00:19 /usr/bin/node /volumio/index.js root 910 1 0 20:02 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 916 910 0 20:02 ? 00:00:00 winbindd: domain child [MUSICLIVING] root 918 1 0 20:02 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 920 918 0 20:02 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 921 918 0 20:02 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 923 910 0 20:02 ? 00:00:00 winbindd: idmap child root 924 910 0 20:02 ? 00:00:00 winbindd: domain child [BUILTIN] root 927 918 0 20:02 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 934 2 0 20:02 ? 00:00:00 [cifsiod] root 935 2 0 20:02 ? 00:00:00 [smb3decryptd] root 936 2 0 20:02 ? 00:00:00 [cifsfileinfoput] root 937 2 0 20:02 ? 00:00:00 [cifsoplockd] root 938 2 0 20:02 ? 00:00:00 [cifs-dfscache] root 943 2 0 20:02 ? 00:00:00 [cifsd] volumio 961 838 0 20:02 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 968 961 0 20:02 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 969 961 0 20:02 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 970 961 0 20:02 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1078 838 0 20:02 ? 00:00:00 /usr/bin/node /myvolumio/plugins/music_service/streaming_services/streamingProxy.js mpd 1181 1 0 20:02 ? 00:00:00 /usr/bin/mpd --no-daemon volumio 1183 1 0 20:02 ? 00:00:00 /bin/bash /usr/lib/startconnect.sh volumio 1187 1183 0 20:02 ? 00:00:00 ./usr/bin/vollibrespot -c /tmp/volspotify.toml - volumio 1207 1 0 20:02 ? 00:00:00 /bin/sh /tmp/sshtunnel.sh volumio 1208 1207 0 20:02 ? 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 DKAI6nUhVgc9FOd9idaRHF4IkVF2-3f4194277429bc07bf17f8e9bfd59f2d.eu7.myvolumio.org:8091:localhost:3000 eu7.myvolumio.org volumio 1211 1208 0 20:02 ? 00:00:00 /usr/bin/ssh -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R DKAI6nUhVgc9FOd9idaRHF4IkVF2-3f4194277429bc07bf17f8e9bfd59f2d.eu7.myvolumio.org:8091:localhost:3000 eu7.myvolumio.org volumio 1215 1 0 20:02 ? 00:00:00 /bin/sh /bin/bt/btstart.sh start volumio 1223 1 0 20:02 ? 00:00:00 /usr/bin/pulseaudio --high-priority=true rtkit 1224 1 0 20:02 ? 00:00:00 /usr/lib/rtkit/rtkit-daemon root 1227 1 0 20:02 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 1235 2 0 20:02 ? 00:00:00 [krfcommd] volumio 1237 1215 0 20:02 ? 00:00:00 /usr/bin/python /bin/bt/simple-agent -c NoInputNoOutput volumio 1238 838 0 20:02 ? 00:00:00 /bin/journalctl --output cat -f root 1250 2 0 20:06 ? 00:00:00 [kworker/3:0-events_power_efficient] root 1251 2 0 20:06 ? 00:00:00 [kworker/1:0-events_power_efficient] volumio 1259 838 0 20:09 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'Bad OAuth, API rate limit' volumio 1260 1259 0 20:09 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js Bad OAuth, API rate limit volumio 1280 1260 0 20:09 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1281 1280 0 20:09 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.5G 939M 1.4G 41% /imgpart /dev/loop0 410M 410M 0 100% /static overlay 27G 72M 25G 1% / devtmpfs 3.9G 0 3.9G 0% /dev tmpfs 3.9G 0 3.9G 0% /dev/shm tmpfs 3.9G 5.2M 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 60K 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 92M 54M 38M 59% /boot //nas02.fritz.box/Multimedia\Musik 2.0T 1.6T 433G 79% /mnt/NAS/NAS02 # mount --------------- /dev/mmcblk0p2 on /imgpart type ext4 (rw,relatime) /dev/loop0 on /static type squashfs (ro,relatime) overlay on / type overlay (rw,relatime,lowerdir=/mnt/static,upperdir=/mnt/ext/dyn,workdir=/mnt/ext/work) sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime) proc on /proc type proc (rw,relatime) devtmpfs on /dev type devtmpfs (rw,nosuid,size=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/net_cls,net_prio type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls,net_prio) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) mqueue on /dev/mqueue type mqueue (rw,relatime) debugfs on /sys/kernel/debug type debugfs (rw,relatime) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=44,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) sunrpc on /run/rpc_pipefs type rpc_pipefs (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) //nas02.fritz.box/Multimedia\Musik on /mnt/NAS/NAS02 type cifs (ro,relatime,vers=3.1.1,cache=strict,username=tiggr,uid=0,noforceuid,gid=0,noforcegid,addr=172.16.0.25,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:42781 0.0.0.0:* LISTEN 1187/./usr/bin/voll 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 1223/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 127.0.0.1:53752 127.0.0.1:3000 ESTABLISHED 838/node tcp 0 0 127.0.0.1:53756 127.0.0.1:3000 ESTABLISHED 838/node tcp 0 0 127.0.0.1:53808 127.0.0.1:3000 ESTABLISHED 838/node tcp 0 0 127.0.0.1:53818 127.0.0.1:3000 ESTABLISHED 838/node tcp 0 0 172.16.0.49:38332 34.120.160.131:443 ESTABLISHED 838/node tcp 0 0 172.16.0.49:47144 84.247.14.230:80 ESTABLISHED 838/node tcp 0 0 172.16.0.49:50128 167.71.43.69:2222 ESTABLISHED 1211/ssh tcp 0 0 172.16.0.49:37076 172.16.0.25:445 ESTABLISHED - tcp 0 0 172.16.0.49:52066 172.16.0.24:49154 TIME_WAIT - tcp 0 0 172.16.0.49:49372 104.199.65.124:443 ESTABLISHED 1187/./usr/bin/voll tcp 0 0 172.16.0.49:45464 172.16.0.39:3000 ESTABLISHED 838/node tcp 0 0 127.0.0.1:53926 127.0.0.1:3000 TIME_WAIT - tcp 0 0 127.0.0.1:53736 127.0.0.1:3000 ESTABLISHED 459/volumio-remote- tcp6 0 0 :::3005 :::* LISTEN 838/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::8098 :::* LISTEN 838/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::4713 :::* LISTEN 1223/pulseaudio tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::3245 :::* LISTEN 1078/node tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 838/node tcp6 0 0 :::3001 :::* LISTEN 961/node tcp6 0 0 172.16.0.49:3000 172.16.0.20:45024 ESTABLISHED 838/node tcp6 0 0 172.16.0.49:3000 172.16.0.52:51680 TIME_WAIT - tcp6 0 0 172.16.0.49:3000 172.16.0.20:45058 ESTABLISHED 838/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:53736 ESTABLISHED 838/node tcp6 0 0 172.16.0.49:3000 172.16.0.52:34442 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:53818 ESTABLISHED 838/node tcp6 0 0 172.16.0.49:3000 172.16.0.52:55756 TIME_WAIT - tcp6 0 0 172.16.0.49:3000 172.16.0.52:45206 ESTABLISHED 838/node tcp6 0 0 172.16.0.49:3000 172.16.0.52:59796 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:53752 ESTABLISHED 838/node tcp6 0 0 172.16.0.49:3000 172.16.0.52:51542 TIME_WAIT - tcp6 0 0 172.16.0.49:3000 172.16.0.39:54774 ESTABLISHED 838/node tcp6 0 0 172.16.0.49:3000 172.16.0.52:55728 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:53756 ESTABLISHED 838/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:53808 ESTABLISHED 838/node # 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 "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 "softvolume" } # Convert to 24 bit to avoid unnecessary quality loss for 16 bit audio pcm.softvolume { type plug slave { pcm "volumioSoftVol" format "S24_3LE" } } pcm.volumioSoftVol { type softvol slave { pcm "postVolume" } control { name "SoftMaster" card 1 device 0 } max_dB 0.0 min_dB -50.0 resolution 100 } pcm.postVolume { 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 "Headphones" } # cat /var/log/mpd.log --------------- Mar 08 20:01 : zeroconf: No global port, disabling zeroconf Mar 08 20:01 : zeroconf: No global port, disabling zeroconf Mar 08 20:01 : client: [0] opened from local Mar 08 20:01 : exception: No such directory Mar 08 20:01 : client: [1] opened from local Mar 08 20:01 : zeroconf: No global port, disabling zeroconf Mar 08 20:01 : client: [0] opened from 127.0.0.1:59548 Mar 08 20:01 : client: [1] opened from local Mar 08 20:02 : zeroconf: No global port, disabling zeroconf Mar 08 20:02 : client: [0] opened from local # volumio plugin list --------------- This command will list installed plugins on your device ----------------------- Installed Plugins: [ { prettyName: 'Podcast', name: 'podcast', category: 'music_service', version: '2.1.0', icon: 'fa-podcast', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'Spotify', name: 'spop', category: 'music_service', version: '3.0.8', icon: 'fa-spotify', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'YouTube Cast Receiver', name: 'ytcr', category: 'music_service', version: '0.1.2', icon: 'fa-youtube', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'System information', name: 'Systeminfo', category: 'user_interface', version: '3.0.0', icon: 'fa-info-circle', isManuallyInstalled: false, enabled: true, active: true } ] ------------------------------------------ # cat /data/configuration/plugins.json --------------- { "music_service": { "airplay_emulation": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "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" } }, "podcast": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "ytcr": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "cd_controller": { "status": { "type": "string", "value": "STOPPED" }, "enabled": { "type": "boolean", "value": false } }, "smart_inputs": { "status": { "type": "string", "value": "STOPPED" }, "enabled": { "type": "boolean", "value": false } }, "hi_res_audio": { "status": { "type": "string", "value": "STARTED" } }, "tidal": { "status": { "type": "string", "value": "STARTED" } }, "qobuz": { "status": { "type": "string", "value": "STARTED" } }, "tidalconnect": { "status": { "type": "string", "value": "STOPPED" }, "enabled": { "type": "boolean", "value": false } }, "spop": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "audio_interface": { "outputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "alsa_controller": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "bluetooth": { "status": { "type": "string", "value": "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" } }, "Systeminfo": { "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 "12288" ############################################################################### # 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, 319 ms: OK https://www.googleapis.com, 253 ms: OK https://securetoken.googleapis.com, 259 ms: OK https://myvolumio.firebaseio.com, 363 ms: OK https://functions.volumio.cloud, 825 ms: OK https://oauth-performer.dfs.volumio.org, 252 ms: OK https://browsing-performer.dfs.volumio.org, 268 ms: OK http://cddb.volumio.org, 743 ms: OK https://functions.volumio.cloud, 892 ms: OK http://pushupdates.volumio.org, 176 ms: OK http://plugins.volumio.org, 1381 ms: OK https://database.volumio.cloud, 553 ms: OK https://radio-directory.firebaseapp.com, 263 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # sudo journalctl -p 7 --------------- -- Logs begin at Wed 2023-03-08 20:01:11 CET, end at Wed 2023-03-08 20:09:34 CET. -- Mar 08 20:01:11 musicliving kernel: Booting Linux on physical CPU 0x0 Mar 08 20:01:11 musicliving 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 Mar 08 20:01:11 musicliving kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Mar 08 20:01:11 musicliving kernel: CPU: div instructions available: patching division code Mar 08 20:01:11 musicliving kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Mar 08 20:01:11 musicliving kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.4 Mar 08 20:01:11 musicliving kernel: random: fast init done Mar 08 20:01:11 musicliving kernel: Memory policy: Data cache writealloc Mar 08 20:01:11 musicliving kernel: Reserved memory: created CMA memory pool at 0x000000002a000000, size 64 MiB Mar 08 20:01:11 musicliving kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Mar 08 20:01:11 musicliving kernel: Zone ranges: Mar 08 20:01:11 musicliving kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Mar 08 20:01:11 musicliving kernel: Normal empty Mar 08 20:01:11 musicliving kernel: HighMem [mem 0x0000000030000000-0x00000001ffffffff] Mar 08 20:01:11 musicliving kernel: Movable zone start for each node Mar 08 20:01:11 musicliving kernel: Early memory node ranges Mar 08 20:01:11 musicliving kernel: node 0: [mem 0x0000000000000000-0x000000003dffffff] Mar 08 20:01:11 musicliving kernel: node 0: [mem 0x0000000040000000-0x00000000fbffffff] Mar 08 20:01:11 musicliving kernel: node 0: [mem 0x0000000100000000-0x00000001ffffffff] Mar 08 20:01:11 musicliving kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff] Mar 08 20:01:11 musicliving kernel: On node 0 totalpages: 2072576 Mar 08 20:01:11 musicliving kernel: DMA zone: 1728 pages used for memmap Mar 08 20:01:11 musicliving kernel: DMA zone: 0 pages reserved Mar 08 20:01:11 musicliving kernel: DMA zone: 196608 pages, LIFO batch:63 Mar 08 20:01:11 musicliving kernel: HighMem zone: 1875968 pages, LIFO batch:63 Mar 08 20:01:11 musicliving kernel: percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920 Mar 08 20:01:11 musicliving kernel: pcpu-alloc: s50828 r8192 d22900 u81920 alloc=20*4096 Mar 08 20:01:11 musicliving kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Mar 08 20:01:11 musicliving kernel: Built 1 zonelists, mobility grouping on. Total pages: 2070848 Mar 08 20:01:11 musicliving 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=E4:5F:01:11:28:5E 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 Mar 08 20:01:11 musicliving kernel: Kernel parameter elevator= does not have any effect anymore. Please use sysfs to set IO scheduler for individual devices. Mar 08 20:01:11 musicliving kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Mar 08 20:01:11 musicliving kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 08 20:01:11 musicliving kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 08 20:01:11 musicliving kernel: software IO TLB: mapped [mem 0x0000000021800000-0x0000000025800000] (64MB) Mar 08 20:01:11 musicliving kernel: Memory: 8052860K/8290304K available (10240K kernel code, 1366K rwdata, 3196K rodata, 2048K init, 882K bss, 171908K reserved, 65536K cma-reserved, 7503872K highmem) Mar 08 20:01:11 musicliving kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Mar 08 20:01:11 musicliving kernel: ftrace: allocating 34360 entries in 101 pages Mar 08 20:01:11 musicliving kernel: ftrace: allocated 101 pages with 4 groups Mar 08 20:01:11 musicliving kernel: rcu: Hierarchical RCU implementation. Mar 08 20:01:11 musicliving kernel: Rude variant of Tasks RCU enabled. Mar 08 20:01:11 musicliving kernel: Tracing variant of Tasks RCU enabled. Mar 08 20:01:11 musicliving kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Mar 08 20:01:11 musicliving kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Mar 08 20:01:11 musicliving kernel: GIC: Using split EOI/Deactivate mode Mar 08 20:01:11 musicliving kernel: random: get_random_bytes called from start_kernel+0x3c8/0x59c with crng_init=1 Mar 08 20:01:11 musicliving kernel: sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns Mar 08 20:01:11 musicliving kernel: clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns Mar 08 20:01:11 musicliving kernel: bcm2835: system timer (irq = 25) Mar 08 20:01:11 musicliving kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Mar 08 20:01:11 musicliving kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Mar 08 20:01:11 musicliving kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Mar 08 20:01:11 musicliving kernel: Switching to timer-based delay loop, resolution 18ns Mar 08 20:01:11 musicliving kernel: Console: colour dummy device 80x30 Mar 08 20:01:11 musicliving kernel: printk: console [tty1] enabled Mar 08 20:01:11 musicliving kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Mar 08 20:01:11 musicliving kernel: pid_max: default: 32768 minimum: 301 Mar 08 20:01:11 musicliving kernel: LSM: Security Framework initializing Mar 08 20:01:11 musicliving kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Mar 08 20:01:11 musicliving kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Mar 08 20:01:11 musicliving kernel: cgroup: Disabling memory control group subsystem Mar 08 20:01:11 musicliving kernel: CPU: Testing write buffer coherency: ok Mar 08 20:01:11 musicliving kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Mar 08 20:01:11 musicliving kernel: Setting up static identity map for 0x200000 - 0x20003c Mar 08 20:01:11 musicliving kernel: rcu: Hierarchical SRCU implementation. Mar 08 20:01:11 musicliving kernel: smp: Bringing up secondary CPUs ... Mar 08 20:01:11 musicliving kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Mar 08 20:01:11 musicliving kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Mar 08 20:01:11 musicliving kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Mar 08 20:01:11 musicliving kernel: smp: Brought up 1 node, 4 CPUs Mar 08 20:01:11 musicliving kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Mar 08 20:01:11 musicliving kernel: CPU: All CPU(s) started in HYP mode. Mar 08 20:01:11 musicliving kernel: CPU: Virtualization extensions available. Mar 08 20:01:11 musicliving kernel: devtmpfs: initialized Mar 08 20:01:11 musicliving kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Mar 08 20:01:11 musicliving kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Mar 08 20:01:11 musicliving kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Mar 08 20:01:11 musicliving kernel: pinctrl core: initialized pinctrl subsystem Mar 08 20:01:11 musicliving kernel: NET: Registered protocol family 16 Mar 08 20:01:11 musicliving kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Mar 08 20:01:11 musicliving kernel: audit: initializing netlink subsys (disabled) Mar 08 20:01:11 musicliving kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Mar 08 20:01:11 musicliving kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 08 20:01:11 musicliving kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Mar 08 20:01:11 musicliving kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Mar 08 20:01:11 musicliving kernel: Serial: AMBA PL011 UART driver Mar 08 20:01:11 musicliving kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Mar 08 20:01:11 musicliving kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2022-01-20T13:56:48, variant start Mar 08 20:01:11 musicliving kernel: raspberrypi-firmware soc:firmware: Firmware hash is bd88f66f8952d34e4e0613a85c7a6d3da49e13e2 Mar 08 20:01:11 musicliving kernel: Kprobes globally optimized Mar 08 20:01:11 musicliving kernel: bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 Mar 08 20:01:11 musicliving kernel: vgaarb: loaded Mar 08 20:01:11 musicliving kernel: SCSI subsystem initialized Mar 08 20:01:11 musicliving kernel: usbcore: registered new interface driver usbfs Mar 08 20:01:11 musicliving kernel: usbcore: registered new interface driver hub Mar 08 20:01:11 musicliving kernel: usbcore: registered new device driver usb Mar 08 20:01:11 musicliving kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Mar 08 20:01:11 musicliving kernel: clocksource: Switched to clocksource arch_sys_counter Mar 08 20:01:11 musicliving kernel: VFS: Disk quotas dquot_6.6.0 Mar 08 20:01:11 musicliving kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Mar 08 20:01:11 musicliving kernel: FS-Cache: Loaded Mar 08 20:01:11 musicliving kernel: CacheFiles: Loaded Mar 08 20:01:11 musicliving kernel: NET: Registered protocol family 2 Mar 08 20:01:11 musicliving kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Mar 08 20:01:11 musicliving kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Mar 08 20:01:11 musicliving kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Mar 08 20:01:11 musicliving kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 08 20:01:11 musicliving kernel: TCP: Hash tables configured (established 8192 bind 8192) Mar 08 20:01:11 musicliving kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Mar 08 20:01:11 musicliving kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Mar 08 20:01:11 musicliving kernel: NET: Registered protocol family 1 Mar 08 20:01:11 musicliving kernel: RPC: Registered named UNIX socket transport module. Mar 08 20:01:11 musicliving kernel: RPC: Registered udp transport module. Mar 08 20:01:11 musicliving kernel: RPC: Registered tcp transport module. Mar 08 20:01:11 musicliving kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Mar 08 20:01:11 musicliving kernel: PCI: CLS 0 bytes, default 64 Mar 08 20:01:11 musicliving kernel: Trying to unpack rootfs image as initramfs... Mar 08 20:01:11 musicliving kernel: Freeing initrd memory: 13028K Mar 08 20:01:11 musicliving kernel: Initialise system trusted keyrings Mar 08 20:01:11 musicliving kernel: workingset: timestamp_bits=14 max_order=21 bucket_order=7 Mar 08 20:01:11 musicliving kernel: zbud: loaded Mar 08 20:01:11 musicliving kernel: FS-Cache: Netfs 'nfs' registered for caching Mar 08 20:01:11 musicliving kernel: NFS: Registering the id_resolver key type Mar 08 20:01:11 musicliving kernel: Key type id_resolver registered Mar 08 20:01:11 musicliving kernel: Key type id_legacy registered Mar 08 20:01:11 musicliving kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Mar 08 20:01:11 musicliving kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Mar 08 20:01:11 musicliving kernel: Key type asymmetric registered Mar 08 20:01:11 musicliving kernel: Asymmetric key parser 'x509' registered Mar 08 20:01:11 musicliving kernel: bounce: pool size: 64 pages Mar 08 20:01:11 musicliving kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Mar 08 20:01:11 musicliving kernel: io scheduler mq-deadline registered Mar 08 20:01:11 musicliving kernel: io scheduler kyber registered Mar 08 20:01:11 musicliving kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Mar 08 20:01:11 musicliving kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Mar 08 20:01:11 musicliving kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Mar 08 20:01:11 musicliving kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000 Mar 08 20:01:11 musicliving kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Mar 08 20:01:11 musicliving kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Mar 08 20:01:11 musicliving kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Mar 08 20:01:11 musicliving kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Mar 08 20:01:11 musicliving kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Mar 08 20:01:11 musicliving kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Mar 08 20:01:11 musicliving kernel: PCI: bus0: Fast back to back transfers disabled Mar 08 20:01:11 musicliving kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Mar 08 20:01:11 musicliving kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Mar 08 20:01:11 musicliving kernel: pci 0000:01:00.0: PME# supported from D0 D3hot Mar 08 20:01:11 musicliving kernel: PCI: bus1: Fast back to back transfers disabled Mar 08 20:01:11 musicliving kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Mar 08 20:01:11 musicliving kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Mar 08 20:01:11 musicliving kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Mar 08 20:01:11 musicliving kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Mar 08 20:01:11 musicliving kernel: pcieport 0000:00:00.0: enabling device (0140 -> 0142) Mar 08 20:01:11 musicliving kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 62 Mar 08 20:01:11 musicliving kernel: bcm2708_fb soc:fb: FB found 1 display(s) Mar 08 20:01:11 musicliving kernel: Console: switching to colour frame buffer device 80x30 Mar 08 20:01:11 musicliving kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Mar 08 20:01:11 musicliving kernel: iproc-rng200 fe104000.rng: hwrng registered Mar 08 20:01:11 musicliving kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Mar 08 20:01:11 musicliving kernel: gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000 Mar 08 20:01:11 musicliving kernel: brd: module loaded Mar 08 20:01:11 musicliving kernel: loop: module loaded Mar 08 20:01:11 musicliving kernel: Loading iSCSI transport class v2.0-870. Mar 08 20:01:11 musicliving kernel: libphy: Fixed MDIO Bus: probed Mar 08 20:01:11 musicliving kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Mar 08 20:01:11 musicliving kernel: libphy: bcmgenet MII bus: probed Mar 08 20:01:11 musicliving kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Mar 08 20:01:11 musicliving kernel: usbcore: registered new interface driver r8152 Mar 08 20:01:11 musicliving kernel: usbcore: registered new interface driver lan78xx Mar 08 20:01:11 musicliving kernel: usbcore: registered new interface driver smsc95xx Mar 08 20:01:11 musicliving kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Mar 08 20:01:11 musicliving kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Mar 08 20:01:11 musicliving kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00000e0000000890 Mar 08 20:01:11 musicliving kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Mar 08 20:01:11 musicliving kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Mar 08 20:01:11 musicliving kernel: usb usb1: Product: xHCI Host Controller Mar 08 20:01:11 musicliving kernel: usb usb1: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Mar 08 20:01:11 musicliving kernel: usb usb1: SerialNumber: 0000:01:00.0 Mar 08 20:01:11 musicliving kernel: hub 1-0:1.0: USB hub found Mar 08 20:01:11 musicliving kernel: hub 1-0:1.0: 1 port detected Mar 08 20:01:11 musicliving kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Mar 08 20:01:11 musicliving kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Mar 08 20:01:11 musicliving kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Mar 08 20:01:11 musicliving kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 Mar 08 20:01:11 musicliving kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Mar 08 20:01:11 musicliving kernel: usb usb2: Product: xHCI Host Controller Mar 08 20:01:11 musicliving kernel: usb usb2: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Mar 08 20:01:11 musicliving kernel: usb usb2: SerialNumber: 0000:01:00.0 Mar 08 20:01:11 musicliving kernel: hub 2-0:1.0: USB hub found Mar 08 20:01:11 musicliving kernel: hub 2-0:1.0: 4 ports detected Mar 08 20:01:11 musicliving kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Mar 08 20:01:11 musicliving kernel: dwc_otg: FIQ enabled Mar 08 20:01:11 musicliving kernel: dwc_otg: NAK holdoff enabled Mar 08 20:01:11 musicliving kernel: dwc_otg: FIQ split-transaction FSM enabled Mar 08 20:01:11 musicliving kernel: Module dwc_common_port init Mar 08 20:01:11 musicliving kernel: usbcore: registered new interface driver uas Mar 08 20:01:11 musicliving kernel: usbcore: registered new interface driver usb-storage Mar 08 20:01:11 musicliving kernel: mousedev: PS/2 mouse device common for all mice Mar 08 20:01:11 musicliving kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Mar 08 20:01:11 musicliving kernel: sdhci: Secure Digital Host Controller Interface driver Mar 08 20:01:11 musicliving kernel: sdhci: Copyright(c) Pierre Ossman Mar 08 20:01:11 musicliving kernel: mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe Mar 08 20:01:11 musicliving kernel: sdhci-pltfm: SDHCI platform and OF driver helper Mar 08 20:01:11 musicliving kernel: ledtrig-cpu: registered to indicate activity on CPUs Mar 08 20:01:11 musicliving kernel: hid: raw HID events driver (C) Jiri Kosina Mar 08 20:01:11 musicliving kernel: usbcore: registered new interface driver usbhid Mar 08 20:01:11 musicliving kernel: usbhid: USB HID core driver Mar 08 20:01:11 musicliving kernel: Initializing XFRM netlink socket Mar 08 20:01:11 musicliving kernel: NET: Registered protocol family 17 Mar 08 20:01:11 musicliving kernel: Key type dns_resolver registered Mar 08 20:01:11 musicliving kernel: Registering SWP/SWPB emulation handler Mar 08 20:01:11 musicliving kernel: registered taskstats version 1 Mar 08 20:01:11 musicliving kernel: Loading compiled-in X.509 certificates Mar 08 20:01:11 musicliving kernel: Key type ._fscrypt registered Mar 08 20:01:11 musicliving kernel: Key type .fscrypt registered Mar 08 20:01:11 musicliving kernel: Key type fscrypt-provisioning registered Mar 08 20:01:11 musicliving kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Mar 08 20:01:11 musicliving kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Mar 08 20:01:11 musicliving kernel: fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2 Mar 08 20:01:11 musicliving kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Mar 08 20:01:11 musicliving kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Mar 08 20:01:11 musicliving kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Mar 08 20:01:11 musicliving kernel: of_cfs_init Mar 08 20:01:11 musicliving kernel: of_cfs_init: OK Mar 08 20:01:11 musicliving kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Mar 08 20:01:11 musicliving kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Mar 08 20:01:11 musicliving kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Mar 08 20:01:11 musicliving kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Mar 08 20:01:11 musicliving kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Mar 08 20:01:11 musicliving kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Mar 08 20:01:11 musicliving kernel: Freeing unused kernel memory: 2048K Mar 08 20:01:11 musicliving kernel: Run /init as init process Mar 08 20:01:11 musicliving kernel: with arguments: Mar 08 20:01:11 musicliving kernel: /init Mar 08 20:01:11 musicliving kernel: splash Mar 08 20:01:11 musicliving kernel: with environment: Mar 08 20:01:11 musicliving kernel: HOME=/ Mar 08 20:01:11 musicliving kernel: TERM=linux Mar 08 20:01:11 musicliving kernel: imgpart=/dev/mmcblk0p2 Mar 08 20:01:11 musicliving kernel: imgfile=/volumio_current.sqsh Mar 08 20:01:11 musicliving kernel: bootdelay=5 Mar 08 20:01:11 musicliving kernel: mmc1: new high speed SDIO card at address 0001 Mar 08 20:01:11 musicliving kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Mar 08 20:01:11 musicliving kernel: mmcblk0: mmc0:aaaa SE32G 29.7 GiB Mar 08 20:01:11 musicliving kernel: mmcblk0: p1 p2 p3 Mar 08 20:01:11 musicliving kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Mar 08 20:01:11 musicliving initramfs: Booting Volumio for BCM2711 Mar 08 20:01:11 musicliving initramfs: This script mounts rootfs RO with an overlay RW layer. Mar 08 20:01:11 musicliving kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 08 20:01:11 musicliving initramfs: IMGPART=/dev/mmcblk0p2 Mar 08 20:01:11 musicliving initramfs: IMGFILE=/volumio_current.sqsh Mar 08 20:01:11 musicliving initramfs: Boot delay (except first time) will be 5 seconds Mar 08 20:01:11 musicliving initramfs: /dev/mmcblk0p2: Mar 08 20:01:11 musicliving kernel: EXT4-fs (mmcblk0p2): recovery complete Mar 08 20:01:11 musicliving kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Mar 08 20:01:11 musicliving initramfs: Doing a 5 second delay here to give kernel load a headstart Mar 08 20:01:11 musicliving kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Mar 08 20:01:11 musicliving kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Mar 08 20:01:11 musicliving kernel: usb 1-1: Product: USB2.0 Hub Mar 08 20:01:11 musicliving kernel: hub 1-1:1.0: USB hub found Mar 08 20:01:11 musicliving kernel: hub 1-1:1.0: 4 ports detected Mar 08 20:01:11 musicliving initramfs: Checking for USB updates Mar 08 20:01:11 musicliving initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Mar 08 20:01:11 musicliving kernel: EXT4-fs (mmcblk0p3): recovery complete Mar 08 20:01:11 musicliving kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Mar 08 20:01:11 musicliving initramfs: With Option: Mar 08 20:01:11 musicliving initramfs: VOLUMIO_VERSION="3.429" Mar 08 20:01:11 musicliving initramfs: Finish initramfs, continue booting Volumio Mar 08 20:01:11 musicliving systemd[1]: System time before build time, advancing clock. Mar 08 20:01:11 musicliving kernel: NET: Registered protocol family 10 Mar 08 20:01:11 musicliving kernel: Segment Routing with IPv6 Mar 08 20:01:11 musicliving 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) Mar 08 20:01:11 musicliving systemd[1]: Detected architecture arm. Mar 08 20:01:11 musicliving systemd[1]: Set hostname to . Mar 08 20:01:11 musicliving 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. Mar 08 20:01:11 musicliving kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 08 20:01:11 musicliving kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 08 20:01:11 musicliving kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 08 20:01:11 musicliving systemd[1]: Listening on initctl Compatibility Named Pipe. Mar 08 20:01:11 musicliving systemd[1]: Listening on Journal Audit Socket. Mar 08 20:01:11 musicliving systemd[1]: Listening on Journal Socket. Mar 08 20:01:11 musicliving systemd[1]: Condition check resulted in Kernel Module supporting RPCSEC_GSS being skipped. Mar 08 20:01:11 musicliving systemd[1]: Mounting Kernel Debug File System... Mar 08 20:01:11 musicliving systemd[1]: Starting Restore / save the current clock... Mar 08 20:01:11 musicliving kernel: i2c /dev entries driver Mar 08 20:01:11 musicliving systemd-journald[198]: Journal started Mar 08 20:01:11 musicliving systemd-journald[198]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 3.7M, max 30.0M, 26.2M free. Mar 08 20:01:11 musicliving systemd-modules-load[191]: Inserted module 'i2c_dev' Mar 08 20:01:11 musicliving fake-hwclock[189]: Wed Mar 8 19:01:11 UTC 2023 Mar 08 20:01:11 musicliving systemd[1]: Started Load/Save Random Seed. Mar 08 20:01:11 musicliving systemd[1]: dynamicswap.service: Succeeded. Mar 08 20:01:11 musicliving systemd[1]: Started Create System Users. Mar 08 20:01:11 musicliving systemd[1]: Starting Create Static Device Nodes in /dev... Mar 08 20:01:11 musicliving systemd[1]: Started Create Static Device Nodes in /dev. Mar 08 20:01:11 musicliving systemd[1]: Starting udev Kernel Device Manager... Mar 08 20:01:11 musicliving systemd[1]: Reached target Local File Systems (Pre). Mar 08 20:01:11 musicliving systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Mar 08 20:01:11 musicliving systemd[1]: Mounting /tmp... Mar 08 20:01:11 musicliving systemd[1]: Mounting /var/spool/cups... Mar 08 20:01:11 musicliving systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Mar 08 20:01:11 musicliving systemd[1]: Mounting /var/log... Mar 08 20:01:11 musicliving systemd[1]: Mounted /tmp. Mar 08 20:01:11 musicliving systemd-udevd[220]: Network interface NamePolicy= disabled on kernel command line, ignoring. Mar 08 20:01:11 musicliving systemd[1]: Mounted /var/spool/cups. Mar 08 20:01:11 musicliving systemd[1]: Mounted /var/log. Mar 08 20:01:11 musicliving systemd[1]: Starting Flush Journal to Persistent Storage... Mar 08 20:01:11 musicliving systemd[1]: Mounting /var/spool/cups/tmp... Mar 08 20:01:11 musicliving systemd[1]: Mounted /var/spool/cups/tmp. Mar 08 20:01:11 musicliving systemd[1]: Started udev Kernel Device Manager. Mar 08 20:01:11 musicliving systemd-journald[198]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 3.7M, max 30.0M, 26.2M free. Mar 08 20:01:11 musicliving systemd[1]: Started udev Coldplug all Devices. Mar 08 20:01:11 musicliving systemd[1]: Started Flush Journal to Persistent Storage. Mar 08 20:01:11 musicliving systemd[1]: Starting Helper to synchronize boot up for ifupdown... Mar 08 20:01:11 musicliving systemd[1]: Starting Show Plymouth Boot Screen... Mar 08 20:01:11 musicliving systemd[1]: Started Helper to synchronize boot up for ifupdown. Mar 08 20:01:11 musicliving systemd[1]: Received SIGRTMIN+20 from PID 269 (plymouthd). Mar 08 20:01:11 musicliving systemd[1]: Started Show Plymouth Boot Screen. Mar 08 20:01:11 musicliving systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Mar 08 20:01:11 musicliving systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 08 20:01:11 musicliving systemd[1]: Reached target Paths. Mar 08 20:01:11 musicliving systemd[1]: Reached target Local Encrypted Volumes. Mar 08 20:01:12 musicliving kernel: rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000 Mar 08 20:01:12 musicliving kernel: rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000 Mar 08 20:01:12 musicliving kernel: rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000 Mar 08 20:01:12 musicliving kernel: rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000 Mar 08 20:01:12 musicliving systemd-udevd[234]: Using default interface naming scheme 'v240'. Mar 08 20:01:12 musicliving kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 20:01:12 musicliving kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Mar 08 20:01:12 musicliving kernel: [vc_sm_connected_init]: start Mar 08 20:01:12 musicliving kernel: [vc_sm_connected_init]: installed successfully Mar 08 20:01:12 musicliving kernel: mc: Linux media interface: v0.10 Mar 08 20:01:12 musicliving systemd-udevd[254]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 08 20:01:12 musicliving systemd-udevd[234]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 08 20:01:12 musicliving kernel: videodev: Linux video capture interface: v2.00 Mar 08 20:01:12 musicliving kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 20:01:12 musicliving kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 20:01:12 musicliving kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 20:01:12 musicliving kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 20:01:12 musicliving kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 20:01:12 musicliving kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Mar 08 20:01:12 musicliving kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 20:01:12 musicliving kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 08 20:01:12 musicliving kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Mar 08 20:01:12 musicliving kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Mar 08 20:01:12 musicliving kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 08 20:01:12 musicliving kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Mar 08 20:01:12 musicliving kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Mar 08 20:01:12 musicliving kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Mar 08 20:01:12 musicliving kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Mar 08 20:01:12 musicliving kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Mar 08 20:01:12 musicliving kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Mar 08 20:01:12 musicliving kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Mar 08 20:01:13 musicliving kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Mar 08 20:01:13 musicliving kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Mar 08 20:01:13 musicliving kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Mar 08 20:01:13 musicliving kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Mar 08 20:01:13 musicliving kernel: usbcore: registered new interface driver brcmfmac Mar 08 20:01:14 musicliving kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Mar 08 20:01:14 musicliving kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Mar 08 20:01:14 musicliving kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan 4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e Mar 08 20:01:14 musicliving systemd-udevd[246]: Using default interface naming scheme 'v240'. Mar 08 20:01:14 musicliving systemd-udevd[246]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 08 20:01:14 musicliving systemd[1]: Found device /dev/mmcblk0p1. Mar 08 20:01:14 musicliving systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Mar 08 20:01:14 musicliving systemd[1]: Condition check resulted in Huge Pages File System being skipped. Mar 08 20:01:14 musicliving systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 08 20:01:14 musicliving systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 08 20:01:14 musicliving systemd[1]: Condition check resulted in FUSE Control File System being skipped. Mar 08 20:01:14 musicliving systemd[1]: Starting File System Check on /dev/mmcblk0p1... Mar 08 20:01:14 musicliving systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Mar 08 20:01:14 musicliving systemd[1]: Found device /sys/subsystem/net/devices/eth0. Mar 08 20:01:14 musicliving systemd[1]: Started File System Check Daemon to report status. Mar 08 20:01:15 musicliving systemd[1]: Starting Load/Save RF Kill Switch Status... Mar 08 20:01:15 musicliving systemd-fsck[379]: fsck.fat 4.1 (2017-01-24) Mar 08 20:01:15 musicliving systemd-fsck[379]: /dev/mmcblk0p1: 294 files, 27483/46774 clusters Mar 08 20:01:15 musicliving systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Mar 08 20:01:15 musicliving systemd[1]: Started Load/Save RF Kill Switch Status. Mar 08 20:01:15 musicliving systemd[1]: Started File System Check on /dev/mmcblk0p1. Mar 08 20:01:15 musicliving systemd[1]: Mounting /boot... Mar 08 20:01:15 musicliving systemd[1]: Mounted /boot. Mar 08 20:01:15 musicliving systemd[1]: Reached target Local File Systems. Mar 08 20:01:15 musicliving systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Mar 08 20:01:15 musicliving systemd[1]: Started ifup for wlan0. Mar 08 20:01:15 musicliving systemd[1]: Starting Create Volatile Files and Directories... Mar 08 20:01:15 musicliving systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Mar 08 20:01:15 musicliving systemd[1]: Starting Preprocess NFS configuration... Mar 08 20:01:15 musicliving systemd[1]: Starting Raise network interfaces... Mar 08 20:01:15 musicliving systemd[1]: Started ifup for eth0. Mar 08 20:01:15 musicliving systemd[1]: plymouth-read-write.service: Succeeded. Mar 08 20:01:15 musicliving systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Mar 08 20:01:15 musicliving systemd[1]: Received SIGRTMIN+20 from PID 269 (plymouthd). Mar 08 20:01:15 musicliving systemd[1]: nfs-config.service: Succeeded. Mar 08 20:01:15 musicliving systemd[1]: Started Preprocess NFS configuration. Mar 08 20:01:15 musicliving systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Mar 08 20:01:15 musicliving systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Mar 08 20:01:15 musicliving systemd[1]: Reached target NFS client services. Mar 08 20:01:15 musicliving systemd[1]: Started Create Volatile Files and Directories. Mar 08 20:01:15 musicliving systemd[1]: Starting RPC bind portmap service... Mar 08 20:01:15 musicliving systemd[1]: Starting Update UTMP about System Boot/Shutdown... Mar 08 20:01:15 musicliving systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Mar 08 20:01:15 musicliving systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Mar 08 20:01:15 musicliving systemd[1]: Started Update UTMP about System Boot/Shutdown. Mar 08 20:01:15 musicliving systemd[1]: Reached target System Initialization. Mar 08 20:01:15 musicliving systemd[1]: Listening on D-Bus System Message Bus Socket. Mar 08 20:01:15 musicliving systemd[1]: Started Daily apt download activities. Mar 08 20:01:15 musicliving systemd[1]: Listening on mpd.socket. Mar 08 20:01:15 musicliving systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Mar 08 20:01:15 musicliving systemd[1]: Started Daily Cleanup of Temporary Directories. Mar 08 20:01:15 musicliving systemd[1]: Listening on triggerhappy.socket. Mar 08 20:01:15 musicliving systemd[1]: Reached target Sockets. Mar 08 20:01:15 musicliving systemd[1]: Reached target Basic System. Mar 08 20:01:15 musicliving systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Mar 08 20:01:15 musicliving systemd[1]: Started volumio-remote-updater.service. Mar 08 20:01:15 musicliving systemd[1]: Started Manage Sound Card State (restore and store). Mar 08 20:01:15 musicliving systemd[1]: Starting Save/Restore Sound Card State... Mar 08 20:01:15 musicliving alsactl[460]: alsactl 1.1.8 daemon started Mar 08 20:01:15 musicliving systemd[1]: Starting Configure Bluetooth Modems connected by UART... Mar 08 20:01:15 musicliving systemd[1]: Started D-Bus System Message Bus. Mar 08 20:01:15 musicliving kernel: 8021q: 802.1Q VLAN Support v1.8 Mar 08 20:01:15 musicliving kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 08 20:01:15 musicliving systemd[1]: Started Volumio Iptables Module. Mar 08 20:01:15 musicliving systemd[1]: Started UPnP Renderer front-end to MPD. Mar 08 20:01:15 musicliving systemd[1]: Starting Login Service... Mar 08 20:01:15 musicliving systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Mar 08 20:01:15 musicliving systemd[1]: Starting dhcpcd on all interfaces... Mar 08 20:01:15 musicliving systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Mar 08 20:01:15 musicliving systemd[1]: Starting WPA supplicant... Mar 08 20:01:15 musicliving systemd[1]: Starting triggerhappy global hotkey daemon... Mar 08 20:01:15 musicliving systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Mar 08 20:01:15 musicliving systemd[1]: Started Daily apt upgrade and clean activities. Mar 08 20:01:15 musicliving systemd[1]: Reached target Timers. Mar 08 20:01:15 musicliving systemd[1]: Starting Wireless Services... Mar 08 20:01:16 musicliving dhcpcd[486]: Not running dhcpcd because /etc/network/interfaces Mar 08 20:01:16 musicliving dhcpcd[486]: defines some interfaces that will use a Mar 08 20:01:16 musicliving dhcpcd[486]: DHCP client or static address Mar 08 20:01:16 musicliving systemd[1]: Started Save/Restore Sound Card State. Mar 08 20:01:16 musicliving systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Mar 08 20:01:16 musicliving systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Mar 08 20:01:16 musicliving systemd[1]: Failed to start dhcpcd on all interfaces. Mar 08 20:01:16 musicliving avahi-daemon[457]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Mar 08 20:01:16 musicliving avahi-daemon[457]: Successfully dropped root privileges. Mar 08 20:01:16 musicliving avahi-daemon[457]: avahi-daemon 0.7 starting up. Mar 08 20:01:16 musicliving thd[488]: Unable to parse trigger line: Mar 08 20:01:16 musicliving thd[488]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Mar 08 20:01:16 musicliving thd[488]: Unable to parse trigger line: Mar 08 20:01:16 musicliving systemd[1]: Reached target Sound Card. Mar 08 20:01:16 musicliving sh[387]: wlan0=wlan0 Mar 08 20:01:16 musicliving kernel: uart-pl011 fe201000.serial: no DMA platform data Mar 08 20:01:16 musicliving sh[395]: eth0: waiting for carrier Mar 08 20:01:16 musicliving dhcpcd[437]: eth0: waiting for carrier Mar 08 20:01:16 musicliving kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Mar 08 20:01:16 musicliving kernel: bcmgenet fd580000.ethernet eth0: Link is Down Mar 08 20:01:16 musicliving thd[488]: Found socket passed from systemd Mar 08 20:01:16 musicliving systemd-logind[485]: New seat seat0. Mar 08 20:01:16 musicliving systemd[1]: Started triggerhappy global hotkey daemon. Mar 08 20:01:16 musicliving systemd[1]: Started RPC bind portmap service. Mar 08 20:01:16 musicliving avahi-daemon[457]: Successfully called chroot(). Mar 08 20:01:16 musicliving avahi-daemon[457]: Successfully dropped remaining capabilities. Mar 08 20:01:16 musicliving avahi-daemon[457]: Loading service file /services/volumio.service. Mar 08 20:01:16 musicliving systemd[1]: Started Login Service. Mar 08 20:01:16 musicliving systemd[1]: Started Avahi mDNS/DNS-SD Stack. Mar 08 20:01:16 musicliving avahi-daemon[457]: Network interface enumeration completed. Mar 08 20:01:16 musicliving systemd[1]: Reached target RPC Port Mapper. Mar 08 20:01:16 musicliving systemd[1]: Reached target Remote File Systems (Pre). Mar 08 20:01:16 musicliving avahi-daemon[457]: Server startup complete. Host name is musicliving.local. Local service cookie is 3837399776. Mar 08 20:01:16 musicliving avahi-daemon[457]: Service "musicliving" (/services/volumio.service) successfully established. Mar 08 20:01:16 musicliving systemd[1]: Reached target Remote File Systems. Mar 08 20:01:16 musicliving systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Mar 08 20:01:16 musicliving systemd[1]: Started Raise network interfaces. Mar 08 20:01:17 musicliving loadcpufreq[551]: Loading cpufreq kernel modules...done (none). Mar 08 20:01:17 musicliving systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Mar 08 20:01:17 musicliving systemd[1]: Starting LSB: set CPUFreq kernel parameters... Mar 08 20:01:17 musicliving systemd[1]: iptables.service: Succeeded. Mar 08 20:01:18 musicliving cpufrequtils[585]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Mar 08 20:01:18 musicliving systemd[1]: Started LSB: set CPUFreq kernel parameters. Mar 08 20:01:18 musicliving systemd[1]: Started WPA supplicant. Mar 08 20:01:18 musicliving systemd[1]: Reached target Network. Mar 08 20:01:18 musicliving wpa_supplicant[487]: Successfully initialized wpa_supplicant Mar 08 20:01:18 musicliving systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Mar 08 20:01:18 musicliving systemd[1]: Reached target Network is Online. Mar 08 20:01:18 musicliving systemd[1]: Starting LSB: Brings up/down network automatically... Mar 08 20:01:18 musicliving systemd[1]: Starting Samba NMB Daemon... Mar 08 20:01:18 musicliving systemd[1]: Starting Permit User Sessions... Mar 08 20:01:18 musicliving systemd[1]: Starting Network Time Service... Mar 08 20:01:18 musicliving systemd[1]: Starting Music Player Daemon... Mar 08 20:01:18 musicliving systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 08 20:01:18 musicliving systemd[1]: Starting /etc/rc.local Compatibility... Mar 08 20:01:18 musicliving systemd[1]: Started Permit User Sessions. Mar 08 20:01:18 musicliving systemd[1]: Started /etc/rc.local Compatibility. Mar 08 20:01:18 musicliving systemd[1]: Starting Hold until boot process finishes up... Mar 08 20:01:18 musicliving systemd[1]: Starting Terminate Plymouth Boot Screen... Mar 08 20:01:18 musicliving systemd[1]: Received SIGRTMIN+21 from PID 269 (plymouthd). Mar 08 20:01:18 musicliving systemd[1]: Received SIGRTMIN+21 from PID 269 (plymouthd). Mar 08 20:01:18 musicliving systemd[1]: plymouth-quit.service: Succeeded. Mar 08 20:01:18 musicliving systemd[1]: Started Terminate Plymouth Boot Screen. Mar 08 20:01:18 musicliving systemd[1]: plymouth-start.service: Succeeded. Mar 08 20:01:18 musicliving systemd[1]: plymouth-quit-wait.service: Succeeded. Mar 08 20:01:18 musicliving systemd[1]: Started Hold until boot process finishes up. Mar 08 20:01:18 musicliving systemd[1]: Started Getty on tty1. Mar 08 20:01:18 musicliving ifplugd(eth0)[628]: ifplugd 0.28 initializing. Mar 08 20:01:18 musicliving ifplugd(eth0)[628]: Using interface eth0/E4:5F:01:11:28:5E with driver (version: 5.10.92-v7l+) Mar 08 20:01:18 musicliving ifplugd(eth0)[628]: Using detection mode: SIOCETHTOOL Mar 08 20:01:18 musicliving ifplugd(eth0)[628]: Initialization complete, link beat not detected. Mar 08 20:01:18 musicliving systemd[1]: Reached target Login Prompts. Mar 08 20:01:18 musicliving ifplugd[602]: Network Interface Plugging Daemon...start eth0...done. Mar 08 20:01:18 musicliving systemd[1]: Started LSB: Brings up/down network automatically. Mar 08 20:01:18 musicliving ntpd[607]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 08 20:01:18 musicliving ntpd[607]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Mar 08 20:01:18 musicliving systemd[1]: Started Network Time Service. Mar 08 20:01:18 musicliving ntpd[634]: proto: precision = 0.814 usec (-20) Mar 08 20:01:18 musicliving ntpd[634]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 08 20:01:18 musicliving ntpd[634]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 08 20:01:18 musicliving ntpd[634]: Listen and drop on 0 v6wildcard [::]:123 Mar 08 20:01:18 musicliving ntpd[634]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 08 20:01:18 musicliving ntpd[634]: Listen normally on 2 lo 127.0.0.1:123 Mar 08 20:01:18 musicliving ntpd[634]: Listening on routing socket on fd #19 for interface updates Mar 08 20:01:18 musicliving ntpd[634]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 08 20:01:18 musicliving ntpd[634]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 08 20:01:18 musicliving volumio-remote-updater[459]: Error: No active session Mar 08 20:01:18 musicliving volumio-remote-updater[459]: [2023-03-08 20:01:18] [info] asio async_connect error: system:111 (Connection refused) Mar 08 20:01:18 musicliving volumio-remote-updater[459]: [2023-03-08 20:01:18] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 08 20:01:18 musicliving volumio-remote-updater[459]: [2023-03-08 20:01:18] [error] handle_connect error: Underlying Transport Error Mar 08 20:01:18 musicliving haveged[440]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Mar 08 20:01:18 musicliving haveged[440]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Mar 08 20:01:18 musicliving haveged[440]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99738 Mar 08 20:01:18 musicliving haveged[440]: haveged: fills: 0, generated: 0 Mar 08 20:01:18 musicliving kernel: random: crng init done Mar 08 20:01:18 musicliving kernel: random: 7 urandom warning(s) missed due to ratelimiting Mar 08 20:01:18 musicliving volumio[477]: Could not open config: /tmp/upmpdcli.conf Mar 08 20:01:18 musicliving systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 08 20:01:18 musicliving systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 08 20:01:19 musicliving ntpd[634]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 08 20:01:19 musicliving nmbd[616]: [2023/03/08 20:01:19.839473, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 08 20:01:19 musicliving nmbd[616]: started asyncdns process 643 Mar 08 20:01:19 musicliving nmbd[616]: [2023/03/08 20:01:19.841396, 0] ../lib/util/become_daemon.c:149(daemon_status) Mar 08 20:01:19 musicliving nmbd[616]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 08 20:01:19 musicliving nmbd[616]: [2023/03/08 20:01:19.841538, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Mar 08 20:01:19 musicliving nmbd[616]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 08 20:01:20 musicliving systemd[1]: systemd-rfkill.service: Succeeded. Mar 08 20:01:20 musicliving wireless.js[490]: Cleaning previous... Mar 08 20:01:20 musicliving ntpd[634]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 08 20:01:20 musicliving sudo[652]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 08 20:01:20 musicliving sudo[652]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:20 musicliving sudo[652]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:20 musicliving sudo[654]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 08 20:01:20 musicliving sudo[654]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:20 musicliving mpd[630]: Mar 08 20:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 08 20:01:21 musicliving mpd[630]: Mar 08 20:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 08 20:01:21 musicliving mpd[630]: Mar 08 20:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 08 20:01:21 musicliving sudo[654]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:21 musicliving systemd[1]: Started Music Player Daemon. Mar 08 20:01:21 musicliving wireless.js[490]: Stopped aP Mar 08 20:01:21 musicliving sudo[663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 08 20:01:21 musicliving sudo[663]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:21 musicliving kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 08 20:01:21 musicliving sudo[663]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:21 musicliving sudo[666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 08 20:01:21 musicliving sudo[666]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:21 musicliving sudo[666]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:21 musicliving sudo[673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 08 20:01:21 musicliving sudo[673]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:21 musicliving sudo[673]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:21 musicliving sudo[675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 08 20:01:21 musicliving sudo[675]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:21 musicliving ntpd[634]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 08 20:01:22 musicliving ntpd[634]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 08 20:01:23 musicliving kernel: Bluetooth: Core ver 2.22 Mar 08 20:01:23 musicliving kernel: NET: Registered protocol family 31 Mar 08 20:01:23 musicliving kernel: Bluetooth: HCI device and connection manager initialized Mar 08 20:01:23 musicliving kernel: Bluetooth: HCI socket layer initialized Mar 08 20:01:23 musicliving kernel: Bluetooth: L2CAP socket layer initialized Mar 08 20:01:23 musicliving kernel: Bluetooth: SCO socket layer initialized Mar 08 20:01:23 musicliving btuart[462]: bcm43xx_init Mar 08 20:01:23 musicliving btuart[462]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Mar 08 20:01:23 musicliving btuart[462]: Set Controller UART speed to 3000000 bit/s Mar 08 20:01:23 musicliving btuart[462]: Device setup complete Mar 08 20:01:23 musicliving systemd[1]: Started Configure Bluetooth Modems connected by UART. Mar 08 20:01:23 musicliving kernel: Bluetooth: HCI UART driver ver 2.3 Mar 08 20:01:23 musicliving kernel: Bluetooth: HCI UART protocol H4 registered Mar 08 20:01:23 musicliving kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Mar 08 20:01:23 musicliving kernel: Bluetooth: HCI UART protocol Broadcom registered Mar 08 20:01:23 musicliving systemd[1]: Starting Load/Save RF Kill Switch Status... Mar 08 20:01:23 musicliving systemd[1]: Started Load/Save RF Kill Switch Status. Mar 08 20:01:23 musicliving systemd[1]: Created slice system-bthelper.slice. Mar 08 20:01:23 musicliving systemd[1]: Starting Raspberry Pi bluetooth helper... Mar 08 20:01:23 musicliving bthelper[689]: Raspberry Pi BDADDR already set Mar 08 20:01:23 musicliving systemd[1]: Started Raspberry Pi bluetooth helper. Mar 08 20:01:23 musicliving systemd[1]: Starting Bluetooth service... Mar 08 20:01:23 musicliving bluetoothd[698]: Bluetooth daemon 5.50 Mar 08 20:01:23 musicliving bluetoothd[698]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Mar 08 20:01:23 musicliving systemd[1]: Started Bluetooth service. Mar 08 20:01:23 musicliving systemd[1]: Reached target Bluetooth. Mar 08 20:01:23 musicliving bluetoothd[698]: Starting SDP server Mar 08 20:01:23 musicliving bluetoothd[698]: Excluding (cli) sap Mar 08 20:01:23 musicliving dbus-daemon[464]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=698 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Mar 08 20:01:23 musicliving bluetoothd[698]: Bluetooth management interface 1.18 initialized Mar 08 20:01:23 musicliving kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Mar 08 20:01:23 musicliving kernel: Bluetooth: BNEP filters: protocol multicast Mar 08 20:01:23 musicliving kernel: Bluetooth: BNEP socket layer initialized Mar 08 20:01:23 musicliving systemd[1]: Starting Hostname Service... Mar 08 20:01:23 musicliving dbus-daemon[464]: [system] Successfully activated service 'org.freedesktop.hostname1' Mar 08 20:01:23 musicliving systemd[1]: Started Hostname Service. Mar 08 20:01:23 musicliving bluetoothd[698]: Failed to set privacy: Rejected (0x0b) Mar 08 20:01:23 musicliving volumio-remote-updater[459]: [2023-03-08 20:01:23] [info] asio async_connect error: system:111 (Connection refused) Mar 08 20:01:23 musicliving volumio-remote-updater[459]: [2023-03-08 20:01:23] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 08 20:01:23 musicliving volumio-remote-updater[459]: [2023-03-08 20:01:23] [error] handle_connect error: Underlying Transport Error Mar 08 20:01:23 musicliving sudo[675]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:23 musicliving wireless.js[490]: Start wireless flow Mar 08 20:01:23 musicliving wireless.js[490]: Stopped hotspot (if there).. Mar 08 20:01:23 musicliving sudo[703]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 08 20:01:23 musicliving sudo[703]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:23 musicliving sudo[703]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:23 musicliving sudo[705]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 08 20:01:23 musicliving sudo[705]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:24 musicliving sudo[705]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:24 musicliving wireless.js[490]: DHCP IP Mar 08 20:01:24 musicliving wireless.js[490]: Start ap Mar 08 20:01:24 musicliving wpa_supplicant[707]: Successfully initialized wpa_supplicant Mar 08 20:01:24 musicliving sudo[708]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 08 20:01:24 musicliving sudo[708]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:24 musicliving dhcpcd[709]: dev: loaded udev Mar 08 20:01:24 musicliving kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 08 20:01:24 musicliving dhcpcd-run-hooks[727]: wlan0: starting wpa_supplicant Mar 08 20:01:24 musicliving dhcpcd-run-hooks[732]: wlan0: failed to start wpa_supplicant Mar 08 20:01:24 musicliving dhcpcd-run-hooks[733]: 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 Mar 08 20:01:24 musicliving dhcpcd[709]: wlan0: connected to Access Point `' Mar 08 20:01:24 musicliving dhcpcd[709]: no interfaces have a carrier Mar 08 20:01:24 musicliving dhcpcd[709]: forked to background, child pid 743 Mar 08 20:01:24 musicliving dhcpcd[743]: eth0: waiting for carrier Mar 08 20:01:24 musicliving dhcpcd[743]: wlan0: waiting for carrier Mar 08 20:01:24 musicliving sudo[708]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:25 musicliving wireless.js[490]: trying... Mar 08 20:01:25 musicliving sudo[745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:25 musicliving sudo[745]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:25 musicliving sudo[745]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:26 musicliving wireless.js[490]: trying... Mar 08 20:01:26 musicliving sudo[748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:26 musicliving sudo[748]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:26 musicliving sudo[748]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:27 musicliving wpa_supplicant[731]: wlan0: Trying to associate with SSID 'Isengard' Mar 08 20:01:27 musicliving wireless.js[490]: trying... Mar 08 20:01:27 musicliving sudo[751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:27 musicliving sudo[751]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:27 musicliving sudo[751]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:28 musicliving systemd[1]: systemd-rfkill.service: Succeeded. Mar 08 20:01:28 musicliving bthelper[689]: Changing power off succeeded Mar 08 20:01:28 musicliving wireless.js[490]: trying... Mar 08 20:01:28 musicliving bthelper[689]: [CHG] Controller E4:5F:01:11:28:60 Class: 0x0000041c Mar 08 20:01:28 musicliving bthelper[689]: Changing power on succeeded Mar 08 20:01:28 musicliving sudo[759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:28 musicliving sudo[759]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:28 musicliving sudo[759]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:29 musicliving wireless.js[490]: trying... Mar 08 20:01:29 musicliving sudo[764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:29 musicliving sudo[764]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:29 musicliving sudo[764]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:29 musicliving wpa_supplicant[731]: RRM: Ignoring radio measurement request: Not associated Mar 08 20:01:29 musicliving dhcpcd[743]: wlan0: carrier acquired Mar 08 20:01:29 musicliving dhcpcd[743]: wlan0: connected to Access Point `Isengard' Mar 08 20:01:29 musicliving wpa_supplicant[731]: wlan0: Associated with 74:42:7f:01:19:ab Mar 08 20:01:29 musicliving wpa_supplicant[731]: wlan0: CTRL-EVENT-CONNECTED - Connection to 74:42:7f:01:19:ab completed [id=0 id_str=] Mar 08 20:01:29 musicliving wpa_supplicant[731]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 08 20:01:29 musicliving wpa_supplicant[731]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Mar 08 20:01:29 musicliving dhcpcd[743]: DUID 00:01:00:01:2b:1f:b7:4a:e4:5f:01:11:28:5f Mar 08 20:01:29 musicliving dhcpcd[743]: wlan0: IAID 01:11:28:5f Mar 08 20:01:29 musicliving dhcpcd[743]: wlan0: adding address fe80::9a0f:54b0:1f8e:8539 Mar 08 20:01:29 musicliving dhcpcd[743]: ipv6_addaddr1: Permission denied Mar 08 20:01:29 musicliving dhcpcd[743]: wlan0: carrier lost Mar 08 20:01:30 musicliving dhcpcd[743]: wlan0: carrier acquired Mar 08 20:01:30 musicliving dhcpcd[743]: wlan0: IAID 01:11:28:5f Mar 08 20:01:30 musicliving dhcpcd[743]: wlan0: soliciting an IPv6 router Mar 08 20:01:30 musicliving wpa_supplicant[731]: RRM: Ignoring radio measurement request: Not RRM network Mar 08 20:01:30 musicliving wpa_supplicant[731]: RRM: Ignoring radio measurement request: Not RRM network Mar 08 20:01:30 musicliving wireless.js[490]: trying... Mar 08 20:01:30 musicliving sudo[779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:30 musicliving sudo[779]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:30 musicliving dhcpcd[743]: wlan0: rebinding lease of 172.16.0.49 Mar 08 20:01:30 musicliving sudo[779]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:30 musicliving wpa_supplicant[731]: RRM: Ignoring radio measurement request: Not RRM network Mar 08 20:01:30 musicliving wpa_supplicant[731]: RRM: Ignoring radio measurement request: Not RRM network Mar 08 20:01:31 musicliving volumio-remote-updater[459]: [2023-03-08 20:01:31] [info] asio async_connect error: system:111 (Connection refused) Mar 08 20:01:31 musicliving volumio-remote-updater[459]: [2023-03-08 20:01:31] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 08 20:01:31 musicliving volumio-remote-updater[459]: [2023-03-08 20:01:31] [error] handle_connect error: Underlying Transport Error Mar 08 20:01:31 musicliving wireless.js[490]: trying... Mar 08 20:01:31 musicliving sudo[782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:31 musicliving sudo[782]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:31 musicliving sudo[782]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:32 musicliving wireless.js[490]: trying... Mar 08 20:01:32 musicliving sudo[785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:32 musicliving sudo[785]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:32 musicliving sudo[785]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:33 musicliving kernel: cam-dummy-reg: disabling Mar 08 20:01:33 musicliving kernel: cam1-reg: disabling Mar 08 20:01:33 musicliving wireless.js[490]: trying... Mar 08 20:01:33 musicliving sudo[788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:33 musicliving sudo[788]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:33 musicliving sudo[788]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:34 musicliving wireless.js[490]: trying... Mar 08 20:01:34 musicliving sudo[791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:34 musicliving sudo[791]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:34 musicliving sudo[791]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:35 musicliving wireless.js[490]: trying... Mar 08 20:01:35 musicliving dhcpcd[743]: wlan0: probing for an IPv4LL address Mar 08 20:01:35 musicliving sudo[794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:35 musicliving sudo[794]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:35 musicliving sudo[794]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:35 musicliving dhcpcd[743]: wlan0: DHCP lease expired Mar 08 20:01:35 musicliving dhcpcd[743]: wlan0: soliciting a DHCP lease Mar 08 20:01:35 musicliving dhcpcd[743]: wlan0: offered 172.16.0.49 from 172.16.0.1 Mar 08 20:01:35 musicliving dhcpcd[743]: wlan0: probing address 172.16.0.49/24 Mar 08 20:01:36 musicliving wireless.js[490]: trying... Mar 08 20:01:36 musicliving sudo[806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:36 musicliving sudo[806]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:36 musicliving sudo[806]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:37 musicliving wireless.js[490]: trying... Mar 08 20:01:37 musicliving sudo[809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:37 musicliving sudo[809]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:37 musicliving sudo[809]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:38 musicliving wireless.js[490]: trying... Mar 08 20:01:38 musicliving sudo[812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:38 musicliving sudo[812]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:38 musicliving sudo[812]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:39 musicliving wireless.js[490]: trying... Mar 08 20:01:39 musicliving sudo[815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:39 musicliving sudo[815]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:39 musicliving sudo[815]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:40 musicliving dhcpcd[743]: wlan0: using IPv4LL address 169.254.242.134 Mar 08 20:01:40 musicliving avahi-daemon[457]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.242.134. Mar 08 20:01:40 musicliving dhcpcd[743]: wlan0: adding route to 169.254.0.0/16 Mar 08 20:01:40 musicliving dhcpcd[743]: wlan0: adding default route Mar 08 20:01:40 musicliving avahi-daemon[457]: New relevant interface wlan0.IPv4 for mDNS. Mar 08 20:01:40 musicliving avahi-daemon[457]: Registering new address record for 169.254.242.134 on wlan0.IPv4. Mar 08 20:01:40 musicliving wireless.js[490]: trying... Mar 08 20:01:40 musicliving sudo[829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 20:01:40 musicliving sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:40 musicliving sudo[829]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:40 musicliving wireless.js[490]: Connected to: ----Isengard Mar 08 20:01:40 musicliving wireless.js[490]: ---- Mar 08 20:01:40 musicliving sudo[832]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 20:01:40 musicliving sudo[832]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:40 musicliving sudo[832]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:40 musicliving wireless.js[490]: ... joined AP, wlan0 IPv4 is 169.254.242.134, ipV6 is undefined Mar 08 20:01:40 musicliving wireless.js[490]: It's done! AP Mar 08 20:01:40 musicliving dhcpcd[743]: wlan0: leased 172.16.0.49 for 864000 seconds Mar 08 20:01:40 musicliving avahi-daemon[457]: Registering new address record for 172.16.0.49 on wlan0.IPv4. Mar 08 20:01:40 musicliving dhcpcd[743]: wlan0: adding route to 172.16.0.0/24 Mar 08 20:01:40 musicliving dhcpcd[743]: wlan0: changing default route via 172.16.0.1 Mar 08 20:01:40 musicliving systemd[1]: Started Wireless Services. Mar 08 20:01:40 musicliving systemd[1]: Started Volumio Backend Module. Mar 08 20:01:40 musicliving avahi-daemon[457]: Withdrawing address record for 169.254.242.134 on wlan0. Mar 08 20:01:40 musicliving avahi-daemon[457]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.242.134. Mar 08 20:01:40 musicliving dhcpcd[743]: wlan0: deleting route to 169.254.0.0/16 Mar 08 20:01:40 musicliving avahi-daemon[457]: Joining mDNS multicast group on interface wlan0.IPv4 with address 172.16.0.49. Mar 08 20:01:40 musicliving dhcpcd[743]: wlan0: pid 743 deleted default route via 172.16.0.1 Mar 08 20:01:41 musicliving volumio-remote-updater[459]: [2023-03-08 20:01:41] [info] asio async_connect error: system:111 (Connection refused) Mar 08 20:01:41 musicliving volumio-remote-updater[459]: [2023-03-08 20:01:41] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 08 20:01:41 musicliving volumio-remote-updater[459]: [2023-03-08 20:01:41] [error] handle_connect error: Underlying Transport Error Mar 08 20:01:42 musicliving volumio[838]: info: ------------------------------------------- Mar 08 20:01:42 musicliving volumio[838]: info: ----- Volumio3 ---- Mar 08 20:01:42 musicliving volumio[838]: info: ------------------------------------------- Mar 08 20:01:42 musicliving volumio[838]: info: ----- System startup ---- Mar 08 20:01:42 musicliving volumio[838]: info: ------------------------------------------- Mar 08 20:01:42 musicliving ntpd[634]: Listen normally on 3 wlan0 172.16.0.49:123 Mar 08 20:01:42 musicliving ntpd[634]: new interface(s) found: waking up resolver Mar 08 20:01:43 musicliving volumio[838]: info: MYVOLUMIO Environment detected Mar 08 20:01:43 musicliving volumio[838]: info: Plugin folders cleanup Mar 08 20:01:43 musicliving volumio[838]: info: Scanning into folder /volumio/app/plugins/ Mar 08 20:01:43 musicliving volumio[838]: info: Scanning category audio_interface Mar 08 20:01:43 musicliving volumio[838]: info: Scanning category miscellanea Mar 08 20:01:43 musicliving volumio[838]: info: Scanning category music_service Mar 08 20:01:43 musicliving volumio[838]: info: Scanning category plugins.json Mar 08 20:01:43 musicliving volumio[838]: info: Scanning category system_controller Mar 08 20:01:43 musicliving volumio[838]: info: Scanning category user_interface Mar 08 20:01:43 musicliving volumio[838]: info: Scanning into folder /data/plugins/ Mar 08 20:01:43 musicliving volumio[838]: info: Scanning category music_service Mar 08 20:01:43 musicliving volumio[838]: info: Scanning category user_interface Mar 08 20:01:43 musicliving volumio[838]: info: Plugin folders cleanup completed Mar 08 20:01:43 musicliving volumio[838]: info: ------------------------------------------- Mar 08 20:01:43 musicliving volumio[838]: info: ----- Core plugins startup ---- Mar 08 20:01:43 musicliving volumio[838]: info: ------------------------------------------- Mar 08 20:01:43 musicliving volumio[838]: info: Loading plugins from folder /volumio/app/plugins/ Mar 08 20:01:43 musicliving volumio[838]: info: Adding plugin upnp to MyMusic Plugins Mar 08 20:01:43 musicliving volumio[838]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 08 20:01:43 musicliving volumio[838]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 08 20:01:43 musicliving volumio[838]: info: Loading plugins from folder /data/plugins/ Mar 08 20:01:43 musicliving volumio[838]: info: Loading plugin "system"... Mar 08 20:01:43 musicliving volumio[838]: info: Loading plugin "appearance"... Mar 08 20:01:44 musicliving volumio[838]: info: Loading plugin "network"... Mar 08 20:01:44 musicliving volumio[838]: info: Refreshing Cached IP Addresses Mar 08 20:01:44 musicliving sudo[894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 08 20:01:44 musicliving sudo[894]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:44 musicliving sudo[894]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:44 musicliving volumio[838]: info: Loading plugin "services"... Mar 08 20:01:44 musicliving sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 20:01:44 musicliving sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:44 musicliving volumio[838]: info: Loading plugin "alsa_controller"... Mar 08 20:01:44 musicliving sudo[896]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:44 musicliving sudo[899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 08 20:01:44 musicliving sudo[899]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:44 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 20:01:44 musicliving volumio[838]: info: Loading plugin "wizard"... Mar 08 20:01:44 musicliving volumio[838]: info: Loading plugin "volumio_command_line_client"... Mar 08 20:01:44 musicliving volumio[838]: info: Plugin upnp is not enabled Mar 08 20:01:44 musicliving volumio[838]: info: Loading plugin "my_music"... Mar 08 20:01:44 musicliving volumio[838]: info: Loading plugin "mpd"... Mar 08 20:01:44 musicliving volumio[838]: info: Creating MPD Configuration file Mar 08 20:01:44 musicliving volumio[838]: info: Loading plugin "upnp_browser"... Mar 08 20:01:44 musicliving sudo[905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 08 20:01:44 musicliving sudo[905]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:44 musicliving sudo[905]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:44 musicliving sudo[907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 08 20:01:44 musicliving sudo[907]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:44 musicliving systemd[1]: Stopping Music Player Daemon... Mar 08 20:01:44 musicliving systemd[1]: Started Samba NMB Daemon. Mar 08 20:01:44 musicliving systemd[1]: Starting Samba Winbind Daemon... Mar 08 20:01:44 musicliving nmbd[616]: [2023/03/08 20:01:44.865834, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 08 20:01:44 musicliving nmbd[616]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 08 20:01:44 musicliving systemd[1]: mpd.service: Succeeded. Mar 08 20:01:44 musicliving systemd[1]: Stopped Music Player Daemon. Mar 08 20:01:44 musicliving systemd[1]: Starting Music Player Daemon... Mar 08 20:01:44 musicliving nmbd[616]: [2023/03/08 20:01:44.994618, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 08 20:01:44 musicliving nmbd[616]: query_name_response: Multiple (2) responses received for a query on subnet 172.16.0.49 for name WORKGROUP<1d>. Mar 08 20:01:44 musicliving nmbd[616]: This response was from IP 172.16.0.20, reporting an IP address of 172.16.0.20. Mar 08 20:01:45 musicliving winbindd[910]: [2023/03/08 20:01:45.099232, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 08 20:01:45 musicliving winbindd[910]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 08 20:01:45 musicliving systemd[1]: systemd-fsckd.service: Succeeded. Mar 08 20:01:45 musicliving winbindd[910]: [2023/03/08 20:01:45.119907, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 08 20:01:45 musicliving systemd[1]: Started Samba Winbind Daemon. Mar 08 20:01:45 musicliving winbindd[910]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 08 20:01:45 musicliving systemd[1]: Starting Samba SMB Daemon... Mar 08 20:01:45 musicliving volumio[838]: info: Loading plugin "networkfs"... Mar 08 20:01:45 musicliving volumio[838]: info: Starting Udev Watcher for removable devices Mar 08 20:01:45 musicliving systemd[1]: Started Samba SMB Daemon. Mar 08 20:01:45 musicliving smbd[918]: [2023/03/08 20:01:45.550715, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 08 20:01:45 musicliving smbd[918]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 08 20:01:45 musicliving sudo[926]: 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 //nas02.fritz.box/Multimedia\Musik /mnt/NAS/NAS02 Mar 08 20:01:45 musicliving sudo[926]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:45 musicliving mpd[914]: Mar 08 20:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 08 20:01:45 musicliving kernel: FS-Cache: Netfs 'cifs' registered for caching Mar 08 20:01:45 musicliving kernel: Key type cifs.spnego registered Mar 08 20:01:45 musicliving kernel: Key type cifs.idmap registered Mar 08 20:01:45 musicliving kernel: CIFS: Attempting to mount //nas02.fritz.box/Multimedia\Musik Mar 08 20:01:45 musicliving 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. Mar 08 20:01:45 musicliving volumio[838]: info: Ignoring mount for partition: boot Mar 08 20:01:45 musicliving volumio[838]: info: Ignoring mount for partition: volumio Mar 08 20:01:45 musicliving volumio[838]: info: Ignoring mount for partition: volumio_data Mar 08 20:01:45 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 20:01:45 musicliving volumio[838]: info: Loading plugin "alarm-clock"... Mar 08 20:01:45 musicliving mpd[914]: Mar 08 20:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 08 20:01:45 musicliving mpd[914]: Mar 08 20:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 08 20:01:45 musicliving systemd[1]: Started Music Player Daemon. Mar 08 20:01:45 musicliving systemd[1]: Reached target Multi-User System. Mar 08 20:01:45 musicliving systemd[1]: Reached target Graphical Interface. Mar 08 20:01:45 musicliving sudo[907]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:45 musicliving systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 08 20:01:45 musicliving systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 08 20:01:45 musicliving systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 08 20:01:45 musicliving systemd[1]: Startup finished in 9.330s (kernel) + 36.328s (userspace) = 45.658s. Mar 08 20:01:45 musicliving volumio[838]: info: Plugin airplay_emulation is not enabled Mar 08 20:01:45 musicliving volumio[838]: info: Loading plugin "last_100"... Mar 08 20:01:45 musicliving volumio[838]: info: Loading plugin "webradio"... Mar 08 20:01:46 musicliving volumio[838]: info: Loading plugin "i2s_dacs"... Mar 08 20:01:46 musicliving volumio[838]: info: I2S DAC not set, start Auto-detection Mar 08 20:01:46 musicliving volumio[838]: info: Loading plugin "volumiodiscovery"... Mar 08 20:01:46 musicliving volumio[838]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 08 20:01:46 musicliving volumio[838]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 20:01:46 musicliving node[838]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 08 20:01:46 musicliving volumio[838]: *** WARNING *** For more information see Mar 08 20:01:46 musicliving volumio[838]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 08 20:01:46 musicliving volumio[838]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 20:01:46 musicliving volumio[838]: *** WARNING *** For more information see Mar 08 20:01:46 musicliving node[838]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 20:01:46 musicliving node[838]: *** WARNING *** For more information see Mar 08 20:01:46 musicliving node[838]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 08 20:01:46 musicliving node[838]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 20:01:46 musicliving node[838]: *** WARNING *** For more information see Mar 08 20:01:46 musicliving volumio[838]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 08 20:01:46 musicliving volumio[838]: info: Discovery: Started advertising with name: musicliving Mar 08 20:01:46 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 20:01:46 musicliving volumio[838]: info: Loading plugin "spop"... Mar 08 20:01:46 musicliving sh[395]: timed out Mar 08 20:01:46 musicliving dhcpcd[437]: timed out Mar 08 20:01:46 musicliving sh[395]: dhcpcd exited Mar 08 20:01:46 musicliving dhcpcd[437]: dhcpcd exited Mar 08 20:01:46 musicliving sh[395]: ifup: failed to bring up eth0 Mar 08 20:01:46 musicliving systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 08 20:01:46 musicliving systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 08 20:01:46 musicliving sudo[926]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:47 musicliving volumio[838]: info: Loading plugin "ytcr"... Mar 08 20:01:47 musicliving sudo[899]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:48 musicliving volumio[838]: info: Loading plugin "outputs"... Mar 08 20:01:48 musicliving volumio[838]: info: Loading plugin "albumart"... Mar 08 20:01:48 musicliving volumio[838]: info: Plugin example_plugin is not enabled Mar 08 20:01:48 musicliving volumio[838]: info: Loading plugin "inputs"... Mar 08 20:01:48 musicliving volumio[838]: info: Loading plugin "updater_comm"... Mar 08 20:01:48 musicliving volumio[838]: info: Plugin mpdemulation is not enabled Mar 08 20:01:48 musicliving volumio[838]: info: Loading plugin "rest_api"... Mar 08 20:01:48 musicliving volumio[838]: info: Loading plugin "websocket"... Mar 08 20:01:48 musicliving volumio[838]: info: Loading plugin "podcast"... Mar 08 20:01:48 musicliving volumio[838]: Forking 3 albumart workers Mar 08 20:01:48 musicliving volumio[838]: info: ControllerPodcast::constructor Mar 08 20:01:48 musicliving volumio[838]: info: Loading plugin "Systeminfo"... Mar 08 20:01:49 musicliving volumio[838]: Starting albumart workers Mar 08 20:01:49 musicliving volumio[838]: Starting albumart workers Mar 08 20:01:49 musicliving volumio[838]: Starting albumart workers Mar 08 20:01:49 musicliving volumio[838]: info: Loading i18n strings for locale de Mar 08 20:01:49 musicliving volumio[838]: Updating browse sources language Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::initPlayerControls Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: Express server listening on port 3000 Mar 08 20:01:49 musicliving volumio[838]: [Metrics] WebUI: 7s 749.90ms Mar 08 20:01:49 musicliving volumio[838]: info: CoreStateMachine::resetVolumioState Mar 08 20:01:49 musicliving volumio[838]: info: CoreStateMachine::getcurrentVolume Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::volumioRetrievevolume Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: MPD Permissions set Mar 08 20:01:49 musicliving volumio[838]: verbose: New Socket.io Connection to 172.16.0.49 from 172.16.0.20 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/110.0 Total Clients: 1 Mar 08 20:01:49 musicliving volumio[838]: verbose: New Socket.io Connection to 172.16.0.49 from 172.16.0.20 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/110.0 Total Clients: 2 Mar 08 20:01:49 musicliving volumio[838]: verbose: New Socket.io Connection to 172.16.0.49 from 172.16.0.20 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/110.0 Total Clients: 3 Mar 08 20:01:49 musicliving volumio[838]: verbose: New Socket.io Connection to 172.16.0.49:3000 from 172.16.0.39 UA: node-XMLHttpRequest Total Clients: 4 Mar 08 20:01:49 musicliving volumio[838]: info: CoreStateMachine::pushState Mar 08 20:01:49 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::volumioPushState Mar 08 20:01:49 musicliving volumio[838]: info: CoreStateMachine::updateTrackBlock Mar 08 20:01:49 musicliving volumio[838]: info: CorePlayQueue::getTrackBlock Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::volumioRetrievevolume Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:49 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:49 musicliving volumio[838]: info: MPD running with PID914 Mar 08 20:01:49 musicliving volumio[838]: ,establishing connection Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:49 musicliving volumio[838]: info: Reloading queue from file Mar 08 20:01:49 musicliving volumio[838]: error: updateQueue error: null Mar 08 20:01:49 musicliving volumio[838]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 08 20:01:49 musicliving volumio[838]: verbose: New Socket.io Connection to 172.16.0.49 from 172.16.0.20 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/110.0 Total Clients: 5 Mar 08 20:01:49 musicliving volumio[838]: verbose: New Socket.io Connection to 172.16.0.49 from 172.16.0.20 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/110.0 Total Clients: 6 Mar 08 20:01:49 musicliving volumio[838]: verbose: New Socket.io Connection to 172.16.0.49 from 172.16.0.20 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/110.0 Total Clients: 7 Mar 08 20:01:49 musicliving volumio[838]: info: CoreStateMachine::pushState Mar 08 20:01:49 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::volumioPushState Mar 08 20:01:49 musicliving volumio[838]: info: Setting Device type: Raspberry PI Mar 08 20:01:49 musicliving volumio[838]: info: CoreStateMachine::setRepeat null single undefined Mar 08 20:01:49 musicliving volumio[838]: info: CoreStateMachine::pushState Mar 08 20:01:49 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::volumioPushState Mar 08 20:01:49 musicliving volumio[838]: info: CoreStateMachine::setRandom false Mar 08 20:01:49 musicliving volumio[838]: info: CoreStateMachine::pushState Mar 08 20:01:49 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::volumioPushState Mar 08 20:01:49 musicliving volumio[838]: info: Listing playlists Mar 08 20:01:49 musicliving volumio[838]: info: Listing playlists Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 08 20:01:49 musicliving volumio[838]: info: Completed loading Core Plugins Mar 08 20:01:49 musicliving volumio[838]: info: Preparing to generate the ALSA configuration file Mar 08 20:01:49 musicliving volumio[838]: info: Discovery: adding 8a8eaf5c-8893-482f-b7b1-91391bd03f86 Mar 08 20:01:49 musicliving volumio[838]: info: Discovery: Found device musicliving Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:49 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:49 musicliving volumio[838]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 08 20:01:49 musicliving volumio[838]: info: Reading ALSA contributions from plugins. Mar 08 20:01:49 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:49 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:49 musicliving volumio[838]: info: Discovery: adding 70428f1a-7827-4e3f-9bb4-5b3f016572b3 Mar 08 20:01:49 musicliving volumio[838]: info: Discovery: Found device musicsewing Mar 08 20:01:50 musicliving volumio[838]: info: Discovery: Connecting to remote: 172.16.0.39 Mar 08 20:01:50 musicliving volumio[838]: error: updateQueue error: null Mar 08 20:01:50 musicliving volumio[838]: info: Discovery: Connected to remote: 172.16.0.39 Mar 08 20:01:50 musicliving volumio[838]: info: Asound.conf file written Mar 08 20:01:50 musicliving sudo[1041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 08 20:01:50 musicliving sudo[1041]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:50 musicliving sudo[1041]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:50 musicliving volumio[838]: info: Output device has changed, restarting MPD Mar 08 20:01:50 musicliving sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 08 20:01:50 musicliving sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:50 musicliving sudo[1046]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:50 musicliving volumio[838]: info: ___________ START PLUGINS ___________ Mar 08 20:01:50 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 20:01:50 musicliving volumio[838]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 08 20:01:50 musicliving volumio[838]: info: [1678302110849] CoreMusicLibrary::Adding element Media Servers Mar 08 20:01:50 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 20:01:50 musicliving volumio[838]: Cannot find translation for sourceMedia Servers Mar 08 20:01:50 musicliving sudo[1049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 08 20:01:50 musicliving sudo[1049]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:50 musicliving systemd[1]: Stopping Music Player Daemon... Mar 08 20:01:50 musicliving volumio[838]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 08 20:01:50 musicliving volumio[838]: info: [1678302110874] CoreMusicLibrary::Adding element Last_100 Mar 08 20:01:50 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 20:01:50 musicliving volumio[838]: Cannot find translation for sourceMedia Servers Mar 08 20:01:50 musicliving volumio[838]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 08 20:01:50 musicliving volumio[838]: info: [1678302110877] CoreMusicLibrary::Adding element Webradio Mar 08 20:01:50 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 20:01:50 musicliving volumio[838]: Cannot find translation for sourceMedia Servers Mar 08 20:01:50 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 20:01:50 musicliving volumio[838]: info: Checking for old spotify connect plugin installed Mar 08 20:01:50 musicliving systemd[1]: mpd.service: Succeeded. Mar 08 20:01:50 musicliving systemd[1]: Stopped Music Player Daemon. Mar 08 20:01:50 musicliving systemd[1]: Starting Music Player Daemon... Mar 08 20:01:50 musicliving volumio[838]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 08 20:01:50 musicliving volumio[838]: info: [1678302110910] CoreMusicLibrary::Adding element Podcast Mar 08 20:01:50 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 20:01:50 musicliving volumio[838]: Cannot find translation for sourceMedia Servers Mar 08 20:01:50 musicliving volumio[838]: Cannot find translation for sourcePodcast Mar 08 20:01:50 musicliving volumio[838]: info: Volumio Calling Home Mar 08 20:01:50 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 20:01:50 musicliving volumio[838]: [SpotifyConnect] Creating VLS config file Mar 08 20:01:50 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:50 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:50 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:50 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:50 musicliving volumio[838]: [SpotifyConnect] Starting metadata listener Mar 08 20:01:51 musicliving sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Mar 08 20:01:51 musicliving sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:01:51 musicliving volumio-remote-updater[459]: [2023-03-08 20:01:51] [connect] Successful connection Mar 08 20:01:51 musicliving systemd[1]: Started Volspotconnect2 Daemon. Mar 08 20:01:51 musicliving sudo[1057]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:51 musicliving volumio[1060]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Mar 08 20:01:51 musicliving volumio[1060]: Reading Config from "/tmp/volspotify.toml" Mar 08 20:01:51 musicliving volumio[1060]: Malformed config key: missing field `Authentication` Mar 08 20:01:51 musicliving systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE Mar 08 20:01:51 musicliving systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Mar 08 20:01:51 musicliving volumio[838]: [SpotifyConnect] Vollibrespot Daemon service started! Mar 08 20:01:51 musicliving volumio[838]: [Metrics] SpotifyConnect: 0s 207.10ms Mar 08 20:01:51 musicliving volumio[838]: info: MPD Permissions set Mar 08 20:01:51 musicliving volumio-remote-updater[459]: [2023-03-08 20:01:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1678302111 101 Mar 08 20:01:51 musicliving volumio[838]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 8 Mar 08 20:01:51 musicliving volumio[838]: info: Volumio called home Mar 08 20:01:51 musicliving volumio[838]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Mar 08 20:01:51 musicliving volumio[838]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Mar 08 20:01:51 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:51 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:51 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:51 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:51 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:51 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:51 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:51 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:51 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:01:51 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:01:51 musicliving volumio[838]: verbose: New Socket.io Connection to 172.16.0.49:3000 from 172.16.0.39 UA: node-XMLHttpRequest Total Clients: 11 Mar 08 20:01:51 musicliving volumio[838]: verbose: New Socket.io Connection to musicliving.fritz.box from 172.16.0.20 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/111.0.0.0 Safari/537.36 Total Clients: 12 Mar 08 20:01:51 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:51 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:51 musicliving mpd[1059]: Mar 08 20:01 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 08 20:01:51 musicliving mpd[1059]: Mar 08 20:01 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 08 20:01:51 musicliving mpd[1059]: Mar 08 20:01 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 08 20:01:51 musicliving systemd[1]: Started Music Player Daemon. Mar 08 20:01:51 musicliving sudo[1049]: pam_unix(sudo:session): session closed for user root Mar 08 20:01:51 musicliving volumio[838]: error: updateQueue error: null Mar 08 20:01:51 musicliving volumio[838]: [yt-cast-receiver] Listening on port 8098 Mar 08 20:01:51 musicliving volumio[838]: info: Completed starting Core Plugins Mar 08 20:01:51 musicliving volumio[838]: info: ------------------------------------------- Mar 08 20:01:51 musicliving volumio[838]: info: ----- MyVolumio plugins startup ---- Mar 08 20:01:51 musicliving volumio[838]: info: ------------------------------------------- Mar 08 20:01:51 musicliving volumio[838]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 08 20:01:53 musicliving volumio[838]: verbose: New Socket.io Connection to 172.16.0.49 from 172.16.0.20 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/110.0 Total Clients: 13 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: Received Get System Info Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 20:01:53 musicliving volumio[838]: info: Discovery: Getting this device information Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 08 20:01:53 musicliving volumio[838]: info: Received Get System Info Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 20:01:53 musicliving volumio[838]: info: Discovery: Getting this device information Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: Listing playlists Mar 08 20:01:53 musicliving volumio[838]: info: Received Get System Info Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 20:01:53 musicliving volumio[838]: info: Discovery: Getting this device information Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 08 20:01:53 musicliving volumio[838]: info: Received Get System Info Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 20:01:53 musicliving volumio[838]: info: Discovery: Getting this device information Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: Listing playlists Mar 08 20:01:53 musicliving volumio[838]: info: Received Get System Info Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 20:01:53 musicliving volumio[838]: info: Discovery: Getting this device information Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 08 20:01:53 musicliving volumio[838]: info: Received Get System Info Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 20:01:53 musicliving volumio[838]: info: Discovery: Getting this device information Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: Listing playlists Mar 08 20:01:53 musicliving volumio[838]: info: Received Get System Info Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 20:01:53 musicliving volumio[838]: info: Discovery: Getting this device information Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:53 musicliving systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Mar 08 20:01:53 musicliving systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 1. Mar 08 20:01:53 musicliving systemd[1]: Stopped Volspotconnect2 Daemon. Mar 08 20:01:53 musicliving systemd[1]: Started Volspotconnect2 Daemon. Mar 08 20:01:53 musicliving volumio[1068]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Mar 08 20:01:53 musicliving volumio[1068]: Reading Config from "/tmp/volspotify.toml" Mar 08 20:01:53 musicliving volumio[1068]: [Vollibrespot] : Using Alsa backend with device: volumio Mar 08 20:01:53 musicliving volumio[1068]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Mar 08 20:01:53 musicliving systemd[1]: systemd-hostnamed.service: Succeeded. Mar 08 20:01:53 musicliving volumio[1068]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Mar 08 20:01:53 musicliving volumio[1068]: [Vollibrespot] : Authenticated as "pyorbau7l21bptcob0r8m4197" ! Mar 08 20:01:53 musicliving volumio[1068]: [Vollibrespot] : Using alsa sink Mar 08 20:01:53 musicliving volumio[1068]: [Vollibrespot] : Country: "DE" Mar 08 20:01:53 musicliving volumio[1068]: [Vollibrespot] : Metadata pipe established Mar 08 20:01:53 musicliving volumio[1068]: [Vollibrespot] : Event: Volume { volume_to_mixer: 32768 } Mar 08 20:01:53 musicliving volumio[838]: [SpotifyConnect] Volume Spotify: 50.000762951094835 Volumio: 50 Mar 08 20:01:53 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:53 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:55 musicliving volumio[838]: info: Checking Spotify Web API Mar 08 20:01:55 musicliving volumio[1068]: [Vollibrespot] : ReqToken Mar 08 20:01:55 musicliving volumio[838]: info: New Spotify Access Token Received Mar 08 20:01:55 musicliving volumio[838]: info: Initializing Spotify Web API Mar 08 20:01:55 musicliving volumio[838]: info: Initliazing Spotify Browsing Facility Mar 08 20:01:55 musicliving volumio[838]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 08 20:01:55 musicliving volumio[838]: info: [1678302115344] CoreMusicLibrary::Adding element Spotify Mar 08 20:01:55 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 20:01:55 musicliving volumio[838]: Cannot find translation for sourceMedia Servers Mar 08 20:01:55 musicliving volumio[838]: Cannot find translation for sourcePodcast Mar 08 20:01:55 musicliving volumio[838]: Cannot find translation for sourceSpotify Mar 08 20:01:55 musicliving volumio[838]: error: Failed to retrieve spotify devices lists: WebapiError: Bad OAuth request Mar 08 20:01:55 musicliving volumio[838]: error: Failed to retrieve user informations: WebapiError: Bad OAuth request Mar 08 20:01:55 musicliving volumio[838]: (node:838) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 4) Mar 08 20:01:55 musicliving volumio[838]: (Use `node --trace-warnings ...` to show where the warning was created) Mar 08 20:01:55 musicliving volumio[838]: (node:838) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Mar 08 20:01:55 musicliving volumio[838]: verbose: New Socket.io Connection to musicliving.fritz.box from 172.16.0.20 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/111.0.0.0 Safari/537.36 Total Clients: 14 Mar 08 20:01:55 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:01:55 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:01:55 musicliving volumio[838]: info: CoreCommandRouter::volumioGetQueue Mar 08 20:01:55 musicliving volumio[838]: info: CoreStateMachine::getQueue Mar 08 20:01:55 musicliving volumio[838]: info: CorePlayQueue::getQueue Mar 08 20:01:55 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 08 20:02:00 musicliving volumio[838]: info: Adding plugin bluetooth to MyMusic Plugins Mar 08 20:02:00 musicliving volumio[838]: info: Adding plugin multiroom to MyMusic Plugins Mar 08 20:02:00 musicliving volumio[838]: info: Adding plugin metavolumio to MyMusic Plugins Mar 08 20:02:00 musicliving volumio[838]: info: Adding plugin cd_controller to MyMusic Plugins Mar 08 20:02:00 musicliving volumio[838]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 08 20:02:00 musicliving volumio[838]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 08 20:02:00 musicliving volumio[838]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 08 20:02:01 musicliving volumio[838]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 08 20:02:01 musicliving volumio[838]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 08 20:02:01 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:01 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:01 musicliving volumio[838]: info: Starting MyVolumio Remote Streaming Endpoints Mar 08 20:02:01 musicliving volumio[838]: info: MyVolumio login type: Token Mar 08 20:02:01 musicliving volumio[838]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 08 20:02:01 musicliving volumio[838]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 08 20:02:01 musicliving volumio[838]: info: Starting Streaming Service Transparent Proxy Mar 08 20:02:01 musicliving volumio[838]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 08 20:02:01 musicliving volumio[838]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 08 20:02:01 musicliving volumio[838]: info: Streaming services startup Mar 08 20:02:01 musicliving volumio[838]: info: Starting Streaming Daemon Mar 08 20:02:02 musicliving sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 08 20:02:02 musicliving sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:02 musicliving sudo[1085]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:02 musicliving volumio[838]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 08 20:02:02 musicliving volumio[838]: error: Cannot start Volumio Streaming Daemon Mar 08 20:02:02 musicliving volumio[838]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 08 20:02:02 musicliving volumio[838]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 08 20:02:02 musicliving volumio[838]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 08 20:02:02 musicliving volumio[838]: info: MYVOLUMIO: Adding device Mar 08 20:02:02 musicliving volumio[838]: info: MYVOLUMIO: Evaluating Server Mar 08 20:02:02 musicliving volumio[838]: STREAMING PROXY: Starting server on port 3245 Mar 08 20:02:02 musicliving volumio[838]: Node JS runtime: 14 Mar 08 20:02:03 musicliving volumio[838]: info: MyVolumio Plan changed: premium Mar 08 20:02:03 musicliving volumio[838]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 08 20:02:03 musicliving volumio[838]: info: Removing browser output: myVolumio user plan is not superstar Mar 08 20:02:03 musicliving volumio[838]: info: Removing audio output: Mar 08 20:02:03 musicliving volumio[838]: info: MYVOLUMIO: Adding device Mar 08 20:02:03 musicliving volumio[838]: info: MYVOLUMIO: Evaluating Server Mar 08 20:02:03 musicliving volumio[838]: info: Remote config written successfully Mar 08 20:02:03 musicliving volumio[838]: info: Starting Tunnel 1 Mar 08 20:02:03 musicliving volumio[838]: info: Starting Tunnel Connection Checker Mar 08 20:02:03 musicliving volumio[838]: info: Completed starting MyVolumio Plugin Mar 08 20:02:03 musicliving volumio[838]: info: MYVolumio Device enabled Mar 08 20:02:03 musicliving volumio[838]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 08 20:02:03 musicliving volumio[838]: info: MyVolumio status changed Mar 08 20:02:03 musicliving volumio[838]: info: Streaming services startup Mar 08 20:02:03 musicliving volumio[838]: info: Starting Streaming Daemon Mar 08 20:02:03 musicliving sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 08 20:02:03 musicliving volumio[838]: info: Setting Geolocation for MyVolumio to eu6 Mar 08 20:02:03 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:03 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:03 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:03 musicliving sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:03 musicliving sudo[1123]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:03 musicliving volumio[838]: error: Cannot start Volumio Streaming Daemon Mar 08 20:02:03 musicliving volumio[838]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 08 20:02:03 musicliving volumio[838]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 08 20:02:03 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 08 20:02:03 musicliving volumio[838]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 08 20:02:03 musicliving volumio[838]: info: [1678302123967] Starting BluetoothController Mar 08 20:02:04 musicliving volumio[838]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 08 20:02:05 musicliving sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast Mar 08 20:02:05 musicliving sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:05 musicliving sudo[1126]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:05 musicliving sudo[1129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 08 20:02:05 musicliving sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:05 musicliving sudo[1129]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:05 musicliving volumio[838]: info: MRS: MultiRoom plugin initialized Mar 08 20:02:05 musicliving volumio[838]: info: MRS: STOPPING SNAPCLIENT Mar 08 20:02:05 musicliving volumio[838]: info: MRS: Snap server stop Mar 08 20:02:05 musicliving sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 08 20:02:05 musicliving sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:05 musicliving volumio[838]: info: MRS: STOPPING volumioStreaming Mar 08 20:02:05 musicliving sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 08 20:02:05 musicliving sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:05 musicliving sudo[1146]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:05 musicliving sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 08 20:02:05 musicliving sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 08 20:02:05 musicliving sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 08 20:02:05 musicliving sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:05 musicliving sudo[1155]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:05 musicliving sudo[1149]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 08 20:02:05 musicliving sudo[1152]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 08 20:02:05 musicliving volumio[838]: info: Preparing to generate the ALSA configuration file Mar 08 20:02:05 musicliving volumio[838]: info: Updating MyVolumio device info Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 08 20:02:05 musicliving volumio[838]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 08 20:02:05 musicliving volumio[838]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 08 20:02:05 musicliving volumio[838]: info: Reading ALSA contributions from plugins. Mar 08 20:02:05 musicliving volumio[838]: info: Setting Geolocation for MyVolumio to eu7 Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: MRS: Removed streaming files Mar 08 20:02:05 musicliving volumio[838]: info: MRS: volumioStreaming STOPPED Mar 08 20:02:05 musicliving volumio[838]: info: MRS: SNAPSERVER STOPPED Mar 08 20:02:05 musicliving volumio[838]: info: MRS: SNAPCLIENT STOPPED Mar 08 20:02:05 musicliving volumio[838]: info: Snap server and client installed Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:02:05 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:02:05 musicliving volumio[838]: info: Listing playlists Mar 08 20:02:05 musicliving volumio[838]: info: Listing playlists Mar 08 20:02:05 musicliving volumio[838]: info: Asound.conf file written Mar 08 20:02:05 musicliving sudo[1158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 08 20:02:05 musicliving sudo[1158]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:05 musicliving sudo[1158]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:05 musicliving volumio[838]: info: Output device has changed, restarting MPD Mar 08 20:02:05 musicliving sudo[1163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 08 20:02:05 musicliving sudo[1163]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:05 musicliving sudo[1163]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:05 musicliving volumio[838]: [SpotifyConnect] Creating VLS config file Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving sudo[1166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 08 20:02:05 musicliving sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 20:02:05 musicliving systemd[1]: Stopping Music Player Daemon... Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 08 20:02:05 musicliving volumio[838]: info: MRS: Pushing multiroomSync output for this device Mar 08 20:02:05 musicliving volumio[838]: info: MRS: Pushing multiroomSync output Mar 08 20:02:05 musicliving volumio[838]: info: Adding audio output: Mar 08 20:02:05 musicliving systemd[1]: mpd.service: Succeeded. Mar 08 20:02:05 musicliving volumio[838]: info: Adding audio output: Mar 08 20:02:05 musicliving systemd[1]: Stopped Music Player Daemon. Mar 08 20:02:05 musicliving sudo[1171]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name musicliving Mar 08 20:02:05 musicliving sudo[1171]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:05 musicliving systemd[1]: Starting Music Player Daemon... Mar 08 20:02:05 musicliving volumio[838]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving sudo[1171]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Mar 08 20:02:05 musicliving volumio[838]: info: Adding METAVOLUMIO REST API Endpoints Mar 08 20:02:05 musicliving volumio[838]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 08 20:02:05 musicliving volumio[838]: info: Adding Manifest REST API Endpoints Mar 08 20:02:05 musicliving volumio[838]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Mar 08 20:02:05 musicliving volumio[838]: error: Hi Res Audio Failed Login: Missing Login Data Mar 08 20:02:05 musicliving volumio[838]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 08 20:02:05 musicliving volumio[838]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 08 20:02:05 musicliving volumio[838]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Mar 08 20:02:05 musicliving volumio[838]: info: Stopping AccessToken refresher cron Mar 08 20:02:05 musicliving volumio[838]: info: AccessToken refresher cron started Mar 08 20:02:05 musicliving volumio[838]: info: Stopping AccessToken refresher cron for QOBUZ Mar 08 20:02:05 musicliving volumio[838]: info: AccessToken refresher cron started for QOBUZ Mar 08 20:02:05 musicliving volumio[838]: info: Adding TIDAL REST API Endpoints Mar 08 20:02:05 musicliving volumio[838]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 08 20:02:05 musicliving volumio[838]: info: Adding QOBUZ REST API Endpoints Mar 08 20:02:05 musicliving volumio[838]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 08 20:02:05 musicliving volumio[838]: [SpotifyConnect] Restarting Vollibrespot Daemon Mar 08 20:02:05 musicliving sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Mar 08 20:02:05 musicliving sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:05 musicliving volumio[838]: info: Bluetooth name changed to musicliving Mar 08 20:02:05 musicliving volumio[838]: info: MPD Permissions set Mar 08 20:02:05 musicliving volumio[838]: info: Successfully Added MyVolumio device Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving systemd[1]: Stopping Volspotconnect2 Daemon... Mar 08 20:02:05 musicliving systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Mar 08 20:02:05 musicliving systemd[1]: volspotconnect.service: Succeeded. Mar 08 20:02:05 musicliving systemd[1]: Stopped Volspotconnect2 Daemon. Mar 08 20:02:05 musicliving systemd[1]: Started Volspotconnect2 Daemon. Mar 08 20:02:05 musicliving sudo[1179]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:05 musicliving volumio[1183]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Mar 08 20:02:05 musicliving volumio[1183]: Reading Config from "/tmp/volspotify.toml" Mar 08 20:02:05 musicliving volumio[1183]: [Vollibrespot] : Using Alsa backend with device: volumio Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: BOOT COMPLETED Mar 08 20:02:05 musicliving volumio[838]: [Metrics] CommandRouter: 23s 602.31ms Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::volumiosetStartupVolume Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:05 musicliving volumio[1183]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::Close All Modals sent Mar 08 20:02:05 musicliving volumio[838]: info: CoreCommandRouter::Close All Modals sent Mar 08 20:02:06 musicliving volumio[838]: info: MRS: Adding multiroomSync output Mar 08 20:02:06 musicliving volumio[838]: info: Adding audio output: Mar 08 20:02:06 musicliving volumio[838]: info: MRS: Pushing multiroomSync output Mar 08 20:02:06 musicliving volumio[838]: [SpotifyConnect] Vollibrespot Daemon service restarted! Mar 08 20:02:06 musicliving volumio[1183]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Mar 08 20:02:06 musicliving volumio[838]: info: Successfully Updated MyVolumio device Mar 08 20:02:06 musicliving volumio[838]: info: Successfully Added MyVolumio device Mar 08 20:02:06 musicliving volumio[1183]: [Vollibrespot] : Authenticated as "pyorbau7l21bptcob0r8m4197" ! Mar 08 20:02:06 musicliving volumio[1183]: [Vollibrespot] : Using alsa sink Mar 08 20:02:06 musicliving volumio[1183]: [Vollibrespot] : Metadata pipe established Mar 08 20:02:06 musicliving volumio[1183]: [Vollibrespot] : Event: Volume { volume_to_mixer: 32768 } Mar 08 20:02:06 musicliving volumio[1183]: [Vollibrespot] : Country: "DE" Mar 08 20:02:06 musicliving volumio[838]: [SpotifyConnect] Volume Spotify: 50.000762951094835 Volumio: 50 Mar 08 20:02:06 musicliving mpd[1181]: Mar 08 20:02 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 08 20:02:06 musicliving volumio[838]: info: Updating MyVolumio device info Mar 08 20:02:06 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:06 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:06 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:06 musicliving mpd[1181]: Mar 08 20:02 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 08 20:02:06 musicliving mpd[1181]: Mar 08 20:02 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 08 20:02:06 musicliving systemd[1]: Started Music Player Daemon. Mar 08 20:02:06 musicliving sudo[1166]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:06 musicliving volumio[838]: error: updateQueue error: null Mar 08 20:02:06 musicliving volumio[838]: info: Successfully Updated MyVolumio device Mar 08 20:02:06 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 08 20:02:07 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 08 20:02:07 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 08 20:02:08 musicliving sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 08 20:02:08 musicliving sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:08 musicliving systemd[1]: Started MyVolumio SSH Tunnel. Mar 08 20:02:08 musicliving sudo[1205]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:08 musicliving volumio[838]: info: Remote SSH Started Mar 08 20:02:08 musicliving autossh[1208]: port set to 0, monitoring disabled Mar 08 20:02:08 musicliving autossh[1208]: starting ssh (count 1) Mar 08 20:02:08 musicliving autossh[1208]: ssh child pid is 1211 Mar 08 20:02:08 musicliving volumiossh-tunnel[1207]: Warning: Permanently added '[eu7.myvolumio.org]:2222,[167.71.43.69]:2222' (RSA) to the list of known hosts. Mar 08 20:02:08 musicliving volumio[838]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15 Mar 08 20:02:08 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:02:08 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:02:08 musicliving sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 08 20:02:08 musicliving sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:08 musicliving systemd[1]: Started Volumio Bluetooth Module. Mar 08 20:02:08 musicliving sudo[1213]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:08 musicliving sudo[1216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Mar 08 20:02:08 musicliving sudo[1216]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:08 musicliving volumiobt[1215]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Mar 08 20:02:08 musicliving sudo[1216]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:08 musicliving volumio[838]: info: Volumio BT Module successfully started Mar 08 20:02:09 musicliving sudo[1219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Mar 08 20:02:09 musicliving sudo[1219]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:02:09 musicliving volumiobt[1215]: pulseaudio: no process found Mar 08 20:02:09 musicliving sudo[1219]: pam_unix(sudo:session): session closed for user root Mar 08 20:02:09 musicliving dbus-daemon[464]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.17' (uid=1000 pid=1223 comm="/usr/bin/pulseaudio --high-priority=true ") Mar 08 20:02:09 musicliving systemd[1]: Starting RealtimeKit Scheduling Policy Service... Mar 08 20:02:09 musicliving volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 20:02:09 musicliving dbus-daemon[464]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Mar 08 20:02:09 musicliving systemd[1]: Started RealtimeKit Scheduling Policy Service. Mar 08 20:02:09 musicliving rtkit-daemon[1224]: Successfully called chroot. Mar 08 20:02:09 musicliving rtkit-daemon[1224]: Successfully dropped privileges. Mar 08 20:02:09 musicliving rtkit-daemon[1224]: Successfully limited resources. Mar 08 20:02:09 musicliving rtkit-daemon[1224]: Running. Mar 08 20:02:09 musicliving rtkit-daemon[1224]: Canary thread running. Mar 08 20:02:09 musicliving rtkit-daemon[1224]: Watchdog thread running. Mar 08 20:02:09 musicliving dbus-daemon[464]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.18' (uid=0 pid=1224 comm="/usr/lib/rtkit/rtkit-daemon ") Mar 08 20:02:09 musicliving systemd[1]: Starting Authorization Manager... Mar 08 20:02:10 musicliving volumio[838]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 20:02:10 musicliving polkitd[1227]: started daemon version 0.105 using authority implementation `local' version `0.105' Mar 08 20:02:10 musicliving dbus-daemon[464]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Mar 08 20:02:10 musicliving systemd[1]: Started Authorization Manager. Mar 08 20:02:10 musicliving pulseaudio[1223]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 08 20:02:10 musicliving pulseaudio[1223]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 08 20:02:10 musicliving volumiobt[1215]: Applying permissions Mar 08 20:02:10 musicliving volumiobt[1215]: Setting BT discoverable and pairable Mar 08 20:02:10 musicliving bluetoothd[698]: Endpoint registered: sender=:1.38 path=/MediaEndpoint/A2DPSource Mar 08 20:02:10 musicliving bluetoothd[698]: Endpoint registered: sender=:1.38 path=/MediaEndpoint/A2DPSink Mar 08 20:02:10 musicliving pulseaudio[1223]: 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 Mar 08 20:02:10 musicliving kernel: Bluetooth: RFCOMM TTY layer initialized Mar 08 20:02:10 musicliving kernel: Bluetooth: RFCOMM socket layer initialized Mar 08 20:02:10 musicliving kernel: Bluetooth: RFCOMM ver 1.11 Mar 08 20:02:10 musicliving volumiobt[1215]: [CHG] Controller E4:5F:01:11:28:60 UUIDs: 00001112-0000-1000-8000-00805f9b34fb Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [55B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [117B blob data] Mar 08 20:02:10 musicliving volumiobt[1215]: [bluetooth]# -e power on Mar 08 20:02:10 musicliving volumiobt[1215]: Invalid command in menu main: -e Mar 08 20:02:10 musicliving volumiobt[1215]: Mar 08 20:02:10 musicliving volumiobt[1215]: Use "help" for a list of available commands in a menu. Mar 08 20:02:10 musicliving volumiobt[1215]: Use "menu " if you want to enter any submenu. Mar 08 20:02:10 musicliving volumiobt[1215]: Use "back" if you want to return to menu main. Mar 08 20:02:10 musicliving volumiobt[1215]: [bluetooth]# agent on Mar 08 20:02:10 musicliving volumiobt[1215]: Agent is already registered Mar 08 20:02:10 musicliving volumiobt[1215]: [bluetooth]# discoverable on Mar 08 20:02:10 musicliving volumiobt[1215]: [bluetooth]# pairable on Mar 08 20:02:10 musicliving volumiobt[1215]: [bluetooth]# agent NoInputNoOutput Mar 08 20:02:10 musicliving volumiobt[1215]: Agent is already registered Mar 08 20:02:10 musicliving volumiobt[1215]: [bluetooth]# default-agent Mar 08 20:02:10 musicliving volumiobt[1215]: [bluetooth]# quit Mar 08 20:02:10 musicliving volumiobt[1215]: [59B blob data] Mar 08 20:02:10 musicliving volumio[838]: info: MRS: Getting audio outputs on start Mar 08 20:02:10 musicliving volumio[838]: info: MRS: Requesting all other devices output Mar 08 20:02:12 musicliving volumio[838]: info: Checking Spotify Web API Mar 08 20:02:13 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:02:13 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:02:16 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 20:02:16 musicliving volumio[838]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 16 Mar 08 20:02:16 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:02:16 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:02:17 musicliving volumio[838]: info: CALLMETHOD: system_controller system enableLiveLog true Mar 08 20:02:17 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Mar 08 20:02:17 musicliving volumio[838]: info: Launching a new LiveLog session Mar 08 20:02:20 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 20:02:20 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 20:02:20 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 20:02:21 musicliving volumio-remote-updater[459]: No test mode Mar 08 20:02:21 musicliving volumio-remote-updater[459]: No alpha test mode Mar 08 20:02:21 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 08 20:02:21 musicliving volumio[838]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 08 20:02:21 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 08 20:02:23 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:02:23 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:02:23 musicliving volumio[838]: info: Listing playlists Mar 08 20:02:23 musicliving volumio[838]: info: Listing playlists Mar 08 20:02:26 musicliving ntpd[634]: Soliciting pool server 176.9.44.212 Mar 08 20:02:26 musicliving ntpd[634]: Soliciting pool server 162.159.200.1 Mar 08 20:02:26 musicliving ntpd[634]: Soliciting pool server 144.76.43.40 Mar 08 20:02:27 musicliving ntpd[634]: Soliciting pool server 144.76.76.107 Mar 08 20:02:27 musicliving ntpd[634]: Soliciting pool server 213.209.109.44 Mar 08 20:02:27 musicliving ntpd[634]: Soliciting pool server 131.188.3.222 Mar 08 20:02:27 musicliving ntpd[634]: Soliciting pool server 131.188.3.220 Mar 08 20:02:28 musicliving ntpd[634]: Soliciting pool server 213.172.105.106 Mar 08 20:02:28 musicliving ntpd[634]: Soliciting pool server 79.133.44.137 Mar 08 20:02:28 musicliving ntpd[634]: Soliciting pool server 138.201.19.107 Mar 08 20:02:28 musicliving ntpd[634]: Soliciting pool server 144.76.137.152 Mar 08 20:02:29 musicliving ntpd[634]: Soliciting pool server 78.46.204.247 Mar 08 20:02:29 musicliving ntpd[634]: Soliciting pool server 3.64.117.201 Mar 08 20:02:29 musicliving ntpd[634]: Soliciting pool server 3.121.254.221 Mar 08 20:02:30 musicliving ntpd[634]: Soliciting pool server 2001:638:502:c015::231 Mar 08 20:02:30 musicliving ntpd[634]: Soliciting pool server 81.169.208.5 Mar 08 20:02:58 musicliving ntpd[634]: receive: Unexpected origin timestamp 0xe7b35a49.7de112da does not match aorg 0000000000.00000000 from server@176.9.44.212 xmt 0xe7b35a62.f1ed8f23 Mar 08 20:02:58 musicliving ntpd[634]: receive: Unexpected origin timestamp 0xe7b35a49.7dd6849b does not match aorg 0000000000.00000000 from server@3.121.254.221 xmt 0xe7b35a62.f1c83ecf Mar 08 20:02:58 musicliving ntpd[634]: receive: Unexpected origin timestamp 0xe7b35a49.7ddfbdf3 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xe7b35a62.f128d2d4 Mar 08 20:02:59 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:02:59 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:03:09 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:03:09 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:03:09 musicliving volumio[838]: info: Listing playlists Mar 08 20:03:09 musicliving volumio[838]: info: Listing playlists Mar 08 20:03:19 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:03:19 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:03:19 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 20:03:19 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 08 20:03:24 musicliving volumio[838]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 20:03:29 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:03:29 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:03:29 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:03:29 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:03:29 musicliving volumio[838]: info: Listing playlists Mar 08 20:03:29 musicliving volumio[838]: info: Listing playlists Mar 08 20:03:31 musicliving volumio[838]: info: Executing endpoint metavolumio Mar 08 20:03:31 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 20:03:31 musicliving volumio[838]: info: Executing endpoint metavolumio Mar 08 20:03:31 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 20:03:31 musicliving volumio[838]: info: Executing endpoint metavolumio Mar 08 20:03:31 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 20:03:37 musicliving volumio[838]: info: CoreCommandRouter::volumioGetQueue Mar 08 20:03:37 musicliving volumio[838]: info: CoreStateMachine::getQueue Mar 08 20:03:37 musicliving volumio[838]: info: CorePlayQueue::getQueue Mar 08 20:03:39 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 08 20:03:39 musicliving volumio[838]: info: In handleBrowseUri, curUri=spotify Mar 08 20:03:39 musicliving volumio[838]: error: An error occurred while listing Spotify featured playlists WebapiError: API rate limit exceeded Mar 08 20:03:39 musicliving volumio[838]: error: An error occurred while listing Spotify new albums WebapiError: Bad OAuth request Mar 08 20:03:39 musicliving volumio[838]: error: An error occurred while listing Spotify categories WebapiError: API rate limit exceeded Mar 08 20:03:39 musicliving volumio[838]: info: An error occurred while getting Spotify ROOT Discover Folders: Mar 08 20:03:39 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:03:39 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:03:39 musicliving volumio[838]: info: Preload queue cleared Mar 08 20:03:49 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:03:49 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:03:49 musicliving volumio[838]: info: Listing playlists Mar 08 20:03:49 musicliving volumio[838]: info: Listing playlists Mar 08 20:03:52 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:03:52 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:03:54 musicliving volumio[838]: info: Executing endpoint metavolumio Mar 08 20:03:54 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 20:03:54 musicliving volumio[838]: info: Executing endpoint metavolumio Mar 08 20:03:54 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 20:03:54 musicliving volumio[838]: info: Executing endpoint metavolumio Mar 08 20:03:54 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 20:03:55 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 20:03:55 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 08 20:03:59 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:03:59 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:03:59 musicliving volumio[838]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 20:04:03 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:04:03 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:04:05 musicliving volumio[838]: info: Executing endpoint metavolumio Mar 08 20:04:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 20:04:05 musicliving volumio[838]: info: Executing endpoint metavolumio Mar 08 20:04:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 20:04:05 musicliving volumio[838]: info: Executing endpoint metavolumio Mar 08 20:04:05 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 20:04:09 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:04:09 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:04:09 musicliving volumio[838]: info: Listing playlists Mar 08 20:04:09 musicliving volumio[838]: info: Listing playlists Mar 08 20:04:19 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:04:19 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:04:29 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:04:29 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:04:29 musicliving volumio[838]: info: Listing playlists Mar 08 20:04:29 musicliving volumio[838]: info: Listing playlists Mar 08 20:04:39 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:04:39 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:04:49 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:04:49 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:04:49 musicliving volumio[838]: info: Listing playlists Mar 08 20:04:49 musicliving volumio[838]: info: Listing playlists Mar 08 20:04:59 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:04:59 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:05:09 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:05:09 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:05:09 musicliving volumio[838]: info: Listing playlists Mar 08 20:05:09 musicliving volumio[838]: info: Listing playlists Mar 08 20:05:19 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:05:19 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:05:29 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:05:29 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:05:29 musicliving volumio[838]: info: Listing playlists Mar 08 20:05:29 musicliving volumio[838]: info: Listing playlists Mar 08 20:05:39 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:05:39 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:05:49 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:05:49 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:05:49 musicliving volumio[838]: info: Listing playlists Mar 08 20:05:49 musicliving volumio[838]: info: Listing playlists Mar 08 20:05:59 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:05:59 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:06:09 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:06:09 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:06:09 musicliving volumio[838]: info: Listing playlists Mar 08 20:06:09 musicliving volumio[838]: info: Listing playlists Mar 08 20:06:19 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:06:19 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:06:29 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:06:29 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:06:29 musicliving volumio[838]: info: Listing playlists Mar 08 20:06:29 musicliving volumio[838]: info: Listing playlists Mar 08 20:06:39 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:06:39 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:06:49 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:06:49 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:06:49 musicliving volumio[838]: info: Listing playlists Mar 08 20:06:49 musicliving volumio[838]: info: Listing playlists Mar 08 20:06:49 musicliving nmbd[616]: [2023/03/08 20:06:49.990292, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 08 20:06:49 musicliving nmbd[616]: query_name_response: Multiple (2) responses received for a query on subnet 172.16.0.49 for name WORKGROUP<1d>. Mar 08 20:06:49 musicliving nmbd[616]: This response was from IP 172.16.0.20, reporting an IP address of 172.16.0.20. Mar 08 20:06:59 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:06:59 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:07:09 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:07:09 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:07:09 musicliving volumio[838]: info: Listing playlists Mar 08 20:07:09 musicliving volumio[838]: info: Listing playlists Mar 08 20:07:19 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:07:19 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:07:29 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:07:29 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:07:29 musicliving volumio[838]: info: Listing playlists Mar 08 20:07:29 musicliving volumio[838]: info: Listing playlists Mar 08 20:07:39 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:07:39 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:07:49 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:07:49 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:07:49 musicliving volumio[838]: info: Listing playlists Mar 08 20:07:49 musicliving volumio[838]: info: Listing playlists Mar 08 20:07:59 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:07:59 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:08:09 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:08:09 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:08:09 musicliving volumio[838]: info: Listing playlists Mar 08 20:08:09 musicliving volumio[838]: info: Listing playlists Mar 08 20:08:19 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:08:19 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:08:24 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 08 20:08:24 musicliving volumio[838]: info: In handleBrowseUri, curUri=spotify Mar 08 20:08:24 musicliving volumio[838]: info: Preload queue cleared Mar 08 20:08:28 musicliving volumio[838]: Searching plugin music_service/spop Mar 08 20:08:28 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 20:08:28 musicliving volumio[838]: error: An error occurred while searching WebapiError: API rate limit exceeded Mar 08 20:08:29 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:08:29 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:08:29 musicliving volumio[838]: info: Listing playlists Mar 08 20:08:29 musicliving volumio[838]: info: Listing playlists Mar 08 20:08:29 musicliving volumio[838]: Searching plugin music_service/spop Mar 08 20:08:29 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 20:08:29 musicliving volumio[838]: error: An error occurred while searching WebapiError: Bad OAuth request Mar 08 20:08:31 musicliving volumio[838]: Searching plugin music_service/spop Mar 08 20:08:31 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 20:08:31 musicliving volumio[838]: error: An error occurred while searching WebapiError: Bad OAuth request Mar 08 20:08:38 musicliving volumio[838]: Searching plugin music_service/spop Mar 08 20:08:38 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 20:08:38 musicliving volumio[838]: error: An error occurred while searching WebapiError: Bad OAuth request Mar 08 20:08:39 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:08:39 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:08:47 musicliving volumio[838]: Searching plugin music_service/spop Mar 08 20:08:47 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 20:08:47 musicliving volumio[838]: error: An error occurred while searching WebapiError: API rate limit exceeded Mar 08 20:08:49 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:08:49 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:08:49 musicliving volumio[838]: info: Listing playlists Mar 08 20:08:49 musicliving volumio[838]: info: Listing playlists Mar 08 20:08:57 musicliving volumio[838]: Searching plugin music_service/spop Mar 08 20:08:57 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 20:08:57 musicliving volumio[838]: error: An error occurred while searching WebapiError: API rate limit exceeded Mar 08 20:08:59 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:08:59 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:09:09 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:09:09 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:09:09 musicliving volumio[838]: info: Listing playlists Mar 08 20:09:09 musicliving volumio[838]: info: Listing playlists Mar 08 20:09:19 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:09:19 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:09:29 musicliving volumio[838]: info: CoreCommandRouter::volumioGetState Mar 08 20:09:29 musicliving volumio[838]: info: CorePlayQueue::getTrack 0 Mar 08 20:09:29 musicliving volumio[838]: info: Listing playlists Mar 08 20:09:29 musicliving volumio[838]: info: Listing playlists Mar 08 20:09:30 musicliving volumio[838]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Mar 08 20:09:30 musicliving volumio[838]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Mar 08 20:09:30 musicliving sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Mar 08 20:09:30 musicliving sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 20:09:30 musicliving sudo[1276]: pam_unix(sudo:session): session closed for user root Mar 08 20:09:34 musicliving sudo[1319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Mar 08 20:09:34 musicliving sudo[1319]: pam_unix(sudo:session): session opened for user root by (uid=0)