Wed Mar 8 19:17:46 CET 2023 Description="'login errors with the new/updated spotify plugin'" # 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 1619 bytes 309356 (302.1 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 1619 bytes 309356 (302.1 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 9289 bytes 8196659 (7.8 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 5096 bytes 885749 (864.9 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=65 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:12 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 19:08 ? 00:00:07 /sbin/init root 2 0 0 19:08 ? 00:00:00 [kthreadd] root 3 2 0 19:08 ? 00:00:00 [rcu_gp] root 4 2 0 19:08 ? 00:00:00 [rcu_par_gp] root 7 2 0 19:08 ? 00:00:00 [kworker/u8:0-brcmf_wq/mmc1:0001:1] root 8 2 0 19:08 ? 00:00:00 [mm_percpu_wq] root 9 2 0 19:08 ? 00:00:00 [rcu_tasks_rude_] root 10 2 0 19:08 ? 00:00:00 [rcu_tasks_trace] root 11 2 0 19:08 ? 00:00:00 [ksoftirqd/0] root 12 2 0 19:08 ? 00:00:00 [rcu_sched] root 13 2 0 19:08 ? 00:00:00 [migration/0] root 14 2 0 19:08 ? 00:00:00 [cpuhp/0] root 15 2 0 19:08 ? 00:00:00 [cpuhp/1] root 16 2 0 19:08 ? 00:00:00 [migration/1] root 17 2 0 19:08 ? 00:00:00 [ksoftirqd/1] root 20 2 0 19:08 ? 00:00:00 [cpuhp/2] root 21 2 0 19:08 ? 00:00:00 [migration/2] root 22 2 0 19:08 ? 00:00:00 [ksoftirqd/2] root 25 2 0 19:08 ? 00:00:00 [cpuhp/3] root 26 2 0 19:08 ? 00:00:00 [migration/3] root 27 2 0 19:08 ? 00:00:00 [ksoftirqd/3] root 29 2 0 19:08 ? 00:00:00 [kworker/3:0H-kblockd] root 30 2 0 19:08 ? 00:00:00 [kdevtmpfs] root 31 2 0 19:08 ? 00:00:00 [netns] root 32 2 0 19:08 ? 00:00:00 [kworker/0:1-mm_percpu_wq] root 34 2 0 19:08 ? 00:00:00 [kauditd] root 35 2 0 19:08 ? 00:00:00 [kworker/0:2-mm_percpu_wq] root 36 2 0 19:08 ? 00:00:00 [khungtaskd] root 37 2 0 19:08 ? 00:00:00 [oom_reaper] root 38 2 0 19:08 ? 00:00:00 [writeback] root 39 2 0 19:08 ? 00:00:00 [kcompactd0] root 59 2 0 19:08 ? 00:00:00 [kblockd] root 60 2 0 19:08 ? 00:00:00 [blkcg_punt_bio] root 61 2 0 19:08 ? 00:00:00 [watchdogd] root 62 2 0 19:08 ? 00:00:00 [kworker/3:1-events_freezable] root 64 2 0 19:08 ? 00:00:00 [rpciod] root 65 2 0 19:08 ? 00:00:00 [kworker/u9:0-hci0] root 66 2 0 19:08 ? 00:00:00 [xprtiod] root 67 2 0 19:08 ? 00:00:00 [kworker/2:1-events_power_efficient] root 68 2 0 19:08 ? 00:00:00 [kswapd0] root 69 2 0 19:08 ? 00:00:00 [nfsiod] root 70 2 0 19:08 ? 00:00:00 [kthrotld] root 71 2 0 19:08 ? 00:00:00 [kworker/1:2-mm_percpu_wq] root 72 2 0 19:08 ? 00:00:00 [iscsi_eh] root 73 2 0 19:08 ? 00:00:00 [iscsi_destroy] root 74 2 0 19:08 ? 00:00:00 [nvme-wq] root 75 2 0 19:08 ? 00:00:00 [nvme-reset-wq] root 76 2 0 19:08 ? 00:00:00 [nvme-delete-wq] root 79 2 0 19:08 ? 00:00:00 [DWC Notificatio] root 80 2 0 19:08 ? 00:00:00 [uas] root 81 2 0 19:08 ? 00:00:00 [kworker/u8:2-brcmf_wq/mmc1:0001:1] root 82 2 0 19:08 ? 00:00:00 [vchiq-slot/0] root 83 2 0 19:08 ? 00:00:00 [vchiq-recy/0] root 84 2 0 19:08 ? 00:00:00 [vchiq-sync/0] root 85 2 0 19:08 ? 00:00:00 [zswap-shrink] root 88 2 0 19:08 ? 00:00:00 [kworker/1:5-mm_percpu_wq] root 89 2 0 19:08 ? 00:00:00 [sdhci] root 90 2 0 19:08 ? 00:00:00 [irq/48-mmc0] root 92 2 0 19:08 ? 00:00:00 [kworker/3:3-events_power_efficient] root 102 2 0 19:08 ? 00:00:00 [mmc_complete] root 103 2 0 19:08 ? 00:00:00 [kworker/0:1H-kblockd] root 123 2 0 19:08 ? 00:00:00 [kworker/1:1H-kblockd] root 124 2 0 19:08 ? 00:00:00 [kworker/1:2H-kblockd] root 128 2 0 19:08 ? 00:00:00 [kworker/0:2H-kblockd] root 129 2 0 19:08 ? 00:00:00 [jbd2/mmcblk0p2-] root 130 2 0 19:08 ? 00:00:00 [ext4-rsv-conver] root 131 2 0 19:08 ? 00:00:00 [kworker/2:1H-kblockd] root 138 2 0 19:08 ? 00:00:01 [loop0] root 164 2 0 19:08 ? 00:00:00 [jbd2/mmcblk0p3-] root 165 2 0 19:08 ? 00:00:00 [ext4-rsv-conver] root 172 2 0 19:08 ? 00:00:00 [kworker/2:2H-kblockd] root 173 2 0 19:08 ? 00:00:00 [ipv6_addrconf] root 174 2 0 19:08 ? 00:00:00 [kworker/2:2-events_power_efficient] root 205 1 0 19:08 ? 00:00:01 /lib/systemd/systemd-journald root 223 1 0 19:08 ? 00:00:00 /lib/systemd/systemd-udevd root 262 2 0 19:08 ? 00:00:00 [vchiq-keep/0] root 263 2 0 19:08 ? 00:00:00 [SMIO] root 316 2 0 19:08 ? 00:00:00 [mmal-vchiq] root 317 2 0 19:08 ? 00:00:00 [mmal-vchiq] root 320 2 0 19:08 ? 00:00:00 [mmal-vchiq] root 321 2 0 19:08 ? 00:00:00 [mmal-vchiq] root 323 2 0 19:08 ? 00:00:00 [mmal-vchiq] root 326 2 0 19:08 ? 00:00:00 [mmal-vchiq] root 339 2 0 19:08 ? 00:00:00 [kworker/3:2H-kblockd] root 358 2 0 19:08 ? 00:00:00 [cfg80211] root 360 2 0 19:08 ? 00:00:00 [brcmf_wq/mmc1:0] root 361 2 0 19:08 ? 00:00:00 [brcmf_wdog/mmc1] root 435 1 0 19:08 ? 00:00:02 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 _rpc 440 1 0 19:08 ? 00:00:00 /sbin/rpcbind -f -w volumio 466 1 0 19:08 ? 00:00:00 /usr/local/bin/volumio-remote-updater avahi 470 1 0 19:08 ? 00:00:00 avahi-daemon: running [musicliving.local] nobody 474 1 0 19:08 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* message+ 475 1 0 19:08 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 479 1 0 19:08 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant root 481 1 0 19:08 ? 00:00:00 /lib/systemd/systemd-logind root 490 1 0 19:08 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon avahi 541 470 0 19:08 ? 00:00:00 avahi-daemon: chroot helper shairpo+ 591 1 0 19:08 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf root 606 1 0 19:08 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 610 1 0 19:08 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux root 621 1 0 19:08 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I ntp 623 1 0 19:08 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 632 1 0 19:08 ? 00:00:00 /usr/sbin/sshd -D root 638 606 0 19:08 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 678 2 0 19:08 ? 00:00:00 [kworker/u9:1-hci0] root 679 1 0 19:08 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 3000000 flow - root 692 1 0 19:08 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap root 724 1 0 19:08 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 736 1 0 19:08 ? 00:00:00 /sbin/dhcpcd volumio 830 1 3 19:09 ? 00:00:19 /usr/bin/node /volumio/index.js root 864 1 0 19:09 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 870 864 0 19:09 ? 00:00:00 winbindd: domain child [MUSICLIVING] root 872 1 0 19:09 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 874 872 0 19:09 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 875 872 0 19:09 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 876 864 0 19:09 ? 00:00:00 winbindd: idmap child root 877 864 0 19:09 ? 00:00:00 winbindd: domain child [BUILTIN] root 879 872 0 19:09 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 891 2 0 19:09 ? 00:00:00 [cifsiod] root 892 2 0 19:09 ? 00:00:00 [smb3decryptd] root 893 2 0 19:09 ? 00:00:00 [cifsfileinfoput] root 894 2 0 19:09 ? 00:00:00 [cifsoplockd] root 895 2 0 19:09 ? 00:00:00 [cifs-dfscache] root 897 2 0 19:09 ? 00:00:00 [cifsd] volumio 915 830 0 19:09 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 922 915 0 19:09 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 923 915 0 19:09 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 924 915 0 19:09 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1019 830 0 19:09 ? 00:00:00 /usr/bin/node /myvolumio/plugins/music_service/streaming_services/streamingProxy.js mpd 1119 1 0 19:09 ? 00:00:00 /usr/bin/mpd --no-daemon volumio 1137 1 0 19:09 ? 00:00:00 /bin/sh /tmp/sshtunnel.sh volumio 1138 1137 0 19:09 ? 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 1141 1138 0 19:09 ? 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 1145 1 0 19:09 ? 00:00:00 /bin/sh /bin/bt/btstart.sh start volumio 1153 1 0 19:09 ? 00:00:00 /usr/bin/pulseaudio --high-priority=true rtkit 1154 1 0 19:09 ? 00:00:00 /usr/lib/rtkit/rtkit-daemon root 1157 1 0 19:09 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 1165 2 0 19:09 ? 00:00:00 [krfcommd] volumio 1167 1145 0 19:09 ? 00:00:00 /usr/bin/python /bin/bt/simple-agent -c NoInputNoOutput volumio 1255 830 0 19:14 ? 00:00:00 /bin/journalctl --output cat -f root 1256 2 0 19:14 ? 00:00:00 [kworker/1:0-events] root 1269 2 0 19:14 ? 00:00:00 [kworker/0:0-events] volumio 1299 1 0 19:16 ? 00:00:00 /bin/bash /usr/lib/startconnect.sh volumio 1300 1299 0 19:16 ? 00:00:00 ./usr/bin/vollibrespot -c /tmp/volspotify.toml - volumio 1310 830 1 19:17 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'login errors with the new/updated spotify plugin' volumio 1311 1310 22 19:17 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js login errors with the new/updated spotify plugin volumio 1331 1311 0 19:17 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1332 1331 0 19:17 ? 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 0 3.9G 0% /var/spool/cups tmpfs 3.9G 64K 3.9G 1% /tmp 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/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=29,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) debugfs on /sys/kernel/debug type debugfs (rw,relatime) mqueue on /dev/mqueue type mqueue (rw,relatime) configfs on /sys/kernel/config type configfs (rw,relatime) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) tmpfs on /var/spool/cups/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: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 1153/pulseaudio tcp 0 0 0.0.0.0:139 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:40175 0.0.0.0:* LISTEN 1300/./usr/bin/voll tcp 0 0 0.0.0.0:111 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:22 0.0.0.0:* LISTEN - tcp 0 0 172.16.0.49:42368 172.16.0.25:445 ESTABLISHED - tcp 0 0 127.0.0.1:47086 127.0.0.1:3000 ESTABLISHED 830/node tcp 0 0 172.16.0.49:43620 84.247.14.230:80 ESTABLISHED 830/node tcp 0 0 172.16.0.49:47292 167.71.43.69:2222 ESTABLISHED 1141/ssh tcp 0 0 172.16.0.49:55820 172.16.0.39:3000 ESTABLISHED 830/node tcp 0 0 127.0.0.1:47206 127.0.0.1:3000 ESTABLISHED 830/node tcp 0 0 172.16.0.49:44048 104.199.65.124:443 ESTABLISHED 1300/./usr/bin/voll tcp 0 0 127.0.0.1:47136 127.0.0.1:3000 ESTABLISHED 830/node tcp 0 0 127.0.0.1:47146 127.0.0.1:3000 ESTABLISHED 830/node tcp 0 0 127.0.0.1:47334 127.0.0.1:3000 TIME_WAIT - tcp 0 0 127.0.0.1:47102 127.0.0.1:3000 ESTABLISHED 466/volumio-remote- tcp 0 0 172.16.0.49:46846 34.120.160.131:443 ESTABLISHED 830/node tcp6 0 0 :::3005 :::* LISTEN 830/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::8098 :::* LISTEN 830/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::4713 :::* LISTEN 1153/pulseaudio tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::3245 :::* LISTEN 1019/node tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::22 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 830/node tcp6 0 0 :::3001 :::* LISTEN 915/node tcp6 0 0 172.16.0.49:3000 172.16.0.52:47594 TIME_WAIT - tcp6 0 0 172.16.0.49:3000 172.16.0.20:39222 ESTABLISHED 830/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:47086 ESTABLISHED 830/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:47136 ESTABLISHED 830/node tcp6 0 0 172.16.0.49:3000 172.16.0.20:57780 TIME_WAIT - tcp6 0 0 172.16.0.49:3000 172.16.0.20:45198 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:47102 ESTABLISHED 830/node tcp6 0 0 172.16.0.49:3000 172.16.0.20:57772 TIME_WAIT - tcp6 0 0 172.16.0.49:3000 172.16.0.52:50732 TIME_WAIT - tcp6 0 0 172.16.0.49:3000 172.16.0.39:39386 ESTABLISHED 830/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:47206 ESTABLISHED 830/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:47146 ESTABLISHED 830/node tcp6 0 0 172.16.0.49:3000 172.16.0.52:40474 TIME_WAIT - tcp6 0 0 172.16.0.49:3000 172.16.0.52:50520 TIME_WAIT - tcp6 0 0 172.16.0.49:3000 172.16.0.20:35678 ESTABLISHED 830/node tcp6 0 0 172.16.0.49:3000 172.16.0.52:34178 TIME_WAIT - tcp6 0 0 172.16.0.49:3000 172.16.0.52:39540 TIME_WAIT - # cat /etc/asound.conf --------------- pcm.!default { type empty slave.pcm "volumio" } pcm.volumio { type empty slave.pcm "volumioMultiRoomServer" } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/server/switch.target pcm.volumioMultiRoomServer { type volumioswitch target_file "/tmp/multiroom/server/switch.target" control_fifo "/tmp/multiroom/server/switch.fifo" } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/client/switch.target pcm.volumioMultiRoomClient { type volumioswitch target_file "/tmp/multiroom/client/switch.target" control_fifo "/tmp/multiroom/client/switch.fifo" } # This PCM performs any necessary rate/format/channel conversion before sending on to the Server FIFO pcm.volumioMultiRoom { type plug slave { pcm "volumioMultiRoomFIFO" rate 48000 format "S16_LE" channels 2 } rate_converter "speexrate_medium" } # Write to the FIFO watched by the Server pcm.volumioMultiRoomFIFO { type volumiofifo fifo "/tmp/multiroom/server/fifo" clear_on_drop false lead_in_frames 16384 } # This pcm is used by pcm.volumioMultiRoomServer and pcm.volumioMultiRoomClient to play audio on the local output pcm.volumioLocalPlayback { type empty slave.pcm "postMultiRoom" } # This pcm is used by pcm.volumioMultiRoomClient to discard audio and allow for faster switching pcm.volumioDiscard { type null } pcm.postMultiRoom { type empty slave.pcm "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 19:08 : zeroconf: No global port, disabling zeroconf Mar 08 19:08 : zeroconf: No global port, disabling zeroconf Mar 08 19:08 : client: [0] opened from local Mar 08 19:08 : exception: No such directory Mar 08 19:08 : client: [1] opened from local Mar 08 19:08 : zeroconf: No global port, disabling zeroconf Mar 08 19:08 : client: [0] opened from 127.0.0.1:44036 Mar 08 19:08 : client: [1] opened from local Mar 08 19:08 : client: [2] opened from local Mar 08 19:09 : zeroconf: No global port, disabling zeroconf Mar 08 19:09 : 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, 328 ms: OK https://www.googleapis.com, 256 ms: OK https://securetoken.googleapis.com, 386 ms: OK https://myvolumio.firebaseio.com, 371 ms: OK https://functions.volumio.cloud, 890 ms: OK https://oauth-performer.dfs.volumio.org, 262 ms: OK https://browsing-performer.dfs.volumio.org, 272 ms: OK http://cddb.volumio.org, 788 ms: OK https://functions.volumio.cloud, 892 ms: OK http://pushupdates.volumio.org, 185 ms: OK http://plugins.volumio.org, 1261 ms: OK https://database.volumio.cloud, 566 ms: OK https://radio-directory.firebaseapp.com, 272 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # sudo journalctl -p 7 --------------- -- Logs begin at Wed 2023-03-08 19:08:15 CET, end at Wed 2023-03-08 19:17:50 CET. -- Mar 08 19:08:15 musicliving kernel: Booting Linux on physical CPU 0x0 Mar 08 19:08:15 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 19:08:15 musicliving kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Mar 08 19:08:15 musicliving kernel: CPU: div instructions available: patching division code Mar 08 19:08:15 musicliving kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Mar 08 19:08:15 musicliving kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.4 Mar 08 19:08:15 musicliving kernel: random: fast init done Mar 08 19:08:15 musicliving kernel: Memory policy: Data cache writealloc Mar 08 19:08:15 musicliving kernel: Reserved memory: created CMA memory pool at 0x000000002a000000, size 64 MiB Mar 08 19:08:15 musicliving kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Mar 08 19:08:15 musicliving kernel: Zone ranges: Mar 08 19:08:15 musicliving kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Mar 08 19:08:15 musicliving kernel: Normal empty Mar 08 19:08:15 musicliving kernel: HighMem [mem 0x0000000030000000-0x00000001ffffffff] Mar 08 19:08:15 musicliving kernel: Movable zone start for each node Mar 08 19:08:15 musicliving kernel: Early memory node ranges Mar 08 19:08:15 musicliving kernel: node 0: [mem 0x0000000000000000-0x000000003dffffff] Mar 08 19:08:15 musicliving kernel: node 0: [mem 0x0000000040000000-0x00000000fbffffff] Mar 08 19:08:15 musicliving kernel: node 0: [mem 0x0000000100000000-0x00000001ffffffff] Mar 08 19:08:15 musicliving kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001ffffffff] Mar 08 19:08:15 musicliving kernel: On node 0 totalpages: 2072576 Mar 08 19:08:15 musicliving kernel: DMA zone: 1728 pages used for memmap Mar 08 19:08:15 musicliving kernel: DMA zone: 0 pages reserved Mar 08 19:08:15 musicliving kernel: DMA zone: 196608 pages, LIFO batch:63 Mar 08 19:08:15 musicliving kernel: HighMem zone: 1875968 pages, LIFO batch:63 Mar 08 19:08:15 musicliving kernel: percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920 Mar 08 19:08:15 musicliving kernel: pcpu-alloc: s50828 r8192 d22900 u81920 alloc=20*4096 Mar 08 19:08:15 musicliving kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Mar 08 19:08:15 musicliving kernel: Built 1 zonelists, mobility grouping on. Total pages: 2070848 Mar 08 19:08:15 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 19:08:15 musicliving kernel: Kernel parameter elevator= does not have any effect anymore. Please use sysfs to set IO scheduler for individual devices. Mar 08 19:08:15 musicliving kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Mar 08 19:08:15 musicliving kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 08 19:08:15 musicliving kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 08 19:08:15 musicliving kernel: software IO TLB: mapped [mem 0x0000000021800000-0x0000000025800000] (64MB) Mar 08 19:08:15 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 19:08:15 musicliving kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Mar 08 19:08:15 musicliving kernel: ftrace: allocating 34360 entries in 101 pages Mar 08 19:08:15 musicliving kernel: ftrace: allocated 101 pages with 4 groups Mar 08 19:08:15 musicliving kernel: rcu: Hierarchical RCU implementation. Mar 08 19:08:15 musicliving kernel: Rude variant of Tasks RCU enabled. Mar 08 19:08:15 musicliving kernel: Tracing variant of Tasks RCU enabled. Mar 08 19:08:15 musicliving kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Mar 08 19:08:15 musicliving kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Mar 08 19:08:15 musicliving kernel: GIC: Using split EOI/Deactivate mode Mar 08 19:08:15 musicliving kernel: random: get_random_bytes called from start_kernel+0x3c8/0x59c with crng_init=1 Mar 08 19:08:15 musicliving kernel: sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns Mar 08 19:08:15 musicliving kernel: clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns Mar 08 19:08:15 musicliving kernel: bcm2835: system timer (irq = 25) Mar 08 19:08:15 musicliving kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Mar 08 19:08:15 musicliving kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Mar 08 19:08:15 musicliving kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Mar 08 19:08:15 musicliving kernel: Switching to timer-based delay loop, resolution 18ns Mar 08 19:08:15 musicliving kernel: Console: colour dummy device 80x30 Mar 08 19:08:15 musicliving kernel: printk: console [tty1] enabled Mar 08 19:08:15 musicliving kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Mar 08 19:08:15 musicliving kernel: pid_max: default: 32768 minimum: 301 Mar 08 19:08:15 musicliving kernel: LSM: Security Framework initializing Mar 08 19:08:15 musicliving kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Mar 08 19:08:15 musicliving kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Mar 08 19:08:15 musicliving kernel: cgroup: Disabling memory control group subsystem Mar 08 19:08:15 musicliving kernel: CPU: Testing write buffer coherency: ok Mar 08 19:08:15 musicliving kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Mar 08 19:08:15 musicliving kernel: Setting up static identity map for 0x200000 - 0x20003c Mar 08 19:08:15 musicliving kernel: rcu: Hierarchical SRCU implementation. Mar 08 19:08:15 musicliving kernel: smp: Bringing up secondary CPUs ... Mar 08 19:08:15 musicliving kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Mar 08 19:08:15 musicliving kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Mar 08 19:08:15 musicliving kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Mar 08 19:08:15 musicliving kernel: smp: Brought up 1 node, 4 CPUs Mar 08 19:08:15 musicliving kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Mar 08 19:08:15 musicliving kernel: CPU: All CPU(s) started in HYP mode. Mar 08 19:08:15 musicliving kernel: CPU: Virtualization extensions available. Mar 08 19:08:15 musicliving kernel: devtmpfs: initialized Mar 08 19:08:15 musicliving kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Mar 08 19:08:15 musicliving kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Mar 08 19:08:15 musicliving kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Mar 08 19:08:15 musicliving kernel: pinctrl core: initialized pinctrl subsystem Mar 08 19:08:15 musicliving kernel: NET: Registered protocol family 16 Mar 08 19:08:15 musicliving kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Mar 08 19:08:15 musicliving kernel: audit: initializing netlink subsys (disabled) Mar 08 19:08:15 musicliving kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Mar 08 19:08:15 musicliving kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 08 19:08:15 musicliving kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Mar 08 19:08:15 musicliving kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Mar 08 19:08:15 musicliving kernel: Serial: AMBA PL011 UART driver Mar 08 19:08:15 musicliving kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Mar 08 19:08:15 musicliving kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2022-01-20T13:56:48, variant start Mar 08 19:08:15 musicliving kernel: raspberrypi-firmware soc:firmware: Firmware hash is bd88f66f8952d34e4e0613a85c7a6d3da49e13e2 Mar 08 19:08:15 musicliving kernel: Kprobes globally optimized Mar 08 19:08:15 musicliving kernel: bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 Mar 08 19:08:15 musicliving kernel: vgaarb: loaded Mar 08 19:08:15 musicliving kernel: SCSI subsystem initialized Mar 08 19:08:15 musicliving kernel: usbcore: registered new interface driver usbfs Mar 08 19:08:15 musicliving kernel: usbcore: registered new interface driver hub Mar 08 19:08:15 musicliving kernel: usbcore: registered new device driver usb Mar 08 19:08:15 musicliving kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Mar 08 19:08:15 musicliving kernel: clocksource: Switched to clocksource arch_sys_counter Mar 08 19:08:15 musicliving kernel: VFS: Disk quotas dquot_6.6.0 Mar 08 19:08:15 musicliving kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Mar 08 19:08:15 musicliving kernel: FS-Cache: Loaded Mar 08 19:08:15 musicliving kernel: CacheFiles: Loaded Mar 08 19:08:15 musicliving kernel: NET: Registered protocol family 2 Mar 08 19:08:15 musicliving kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Mar 08 19:08:15 musicliving kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Mar 08 19:08:15 musicliving kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Mar 08 19:08:15 musicliving kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 08 19:08:15 musicliving kernel: TCP: Hash tables configured (established 8192 bind 8192) Mar 08 19:08:15 musicliving kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Mar 08 19:08:15 musicliving kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Mar 08 19:08:15 musicliving kernel: NET: Registered protocol family 1 Mar 08 19:08:15 musicliving kernel: RPC: Registered named UNIX socket transport module. Mar 08 19:08:15 musicliving kernel: RPC: Registered udp transport module. Mar 08 19:08:15 musicliving kernel: RPC: Registered tcp transport module. Mar 08 19:08:15 musicliving kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Mar 08 19:08:15 musicliving kernel: PCI: CLS 0 bytes, default 64 Mar 08 19:08:15 musicliving kernel: Trying to unpack rootfs image as initramfs... Mar 08 19:08:15 musicliving kernel: Freeing initrd memory: 13028K Mar 08 19:08:15 musicliving kernel: Initialise system trusted keyrings Mar 08 19:08:15 musicliving kernel: workingset: timestamp_bits=14 max_order=21 bucket_order=7 Mar 08 19:08:15 musicliving kernel: zbud: loaded Mar 08 19:08:15 musicliving kernel: FS-Cache: Netfs 'nfs' registered for caching Mar 08 19:08:15 musicliving kernel: NFS: Registering the id_resolver key type Mar 08 19:08:15 musicliving kernel: Key type id_resolver registered Mar 08 19:08:15 musicliving kernel: Key type id_legacy registered Mar 08 19:08:15 musicliving kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Mar 08 19:08:15 musicliving kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Mar 08 19:08:15 musicliving kernel: Key type asymmetric registered Mar 08 19:08:15 musicliving kernel: Asymmetric key parser 'x509' registered Mar 08 19:08:15 musicliving kernel: bounce: pool size: 64 pages Mar 08 19:08:15 musicliving kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Mar 08 19:08:15 musicliving kernel: io scheduler mq-deadline registered Mar 08 19:08:15 musicliving kernel: io scheduler kyber registered Mar 08 19:08:15 musicliving kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Mar 08 19:08:15 musicliving kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Mar 08 19:08:15 musicliving kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Mar 08 19:08:15 musicliving kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000 Mar 08 19:08:15 musicliving kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Mar 08 19:08:15 musicliving kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Mar 08 19:08:15 musicliving kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Mar 08 19:08:15 musicliving kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Mar 08 19:08:15 musicliving kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Mar 08 19:08:15 musicliving kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Mar 08 19:08:15 musicliving kernel: PCI: bus0: Fast back to back transfers disabled Mar 08 19:08:15 musicliving kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Mar 08 19:08:15 musicliving kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Mar 08 19:08:15 musicliving kernel: pci 0000:01:00.0: PME# supported from D0 D3hot Mar 08 19:08:15 musicliving kernel: PCI: bus1: Fast back to back transfers disabled Mar 08 19:08:15 musicliving kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Mar 08 19:08:15 musicliving kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Mar 08 19:08:15 musicliving kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Mar 08 19:08:15 musicliving kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Mar 08 19:08:15 musicliving kernel: pcieport 0000:00:00.0: enabling device (0140 -> 0142) Mar 08 19:08:15 musicliving kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 62 Mar 08 19:08:15 musicliving kernel: bcm2708_fb soc:fb: FB found 1 display(s) Mar 08 19:08:15 musicliving kernel: Console: switching to colour frame buffer device 80x30 Mar 08 19:08:15 musicliving kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Mar 08 19:08:15 musicliving kernel: iproc-rng200 fe104000.rng: hwrng registered Mar 08 19:08:15 musicliving kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Mar 08 19:08:15 musicliving kernel: gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000 Mar 08 19:08:15 musicliving kernel: brd: module loaded Mar 08 19:08:15 musicliving kernel: loop: module loaded Mar 08 19:08:15 musicliving kernel: Loading iSCSI transport class v2.0-870. Mar 08 19:08:15 musicliving kernel: libphy: Fixed MDIO Bus: probed Mar 08 19:08:15 musicliving kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Mar 08 19:08:15 musicliving kernel: libphy: bcmgenet MII bus: probed Mar 08 19:08:15 musicliving kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Mar 08 19:08:15 musicliving kernel: usbcore: registered new interface driver r8152 Mar 08 19:08:15 musicliving kernel: usbcore: registered new interface driver lan78xx Mar 08 19:08:15 musicliving kernel: usbcore: registered new interface driver smsc95xx Mar 08 19:08:15 musicliving kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Mar 08 19:08:15 musicliving kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Mar 08 19:08:15 musicliving kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00000e0000000890 Mar 08 19:08:15 musicliving kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Mar 08 19:08:15 musicliving kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Mar 08 19:08:15 musicliving kernel: usb usb1: Product: xHCI Host Controller Mar 08 19:08:15 musicliving kernel: usb usb1: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Mar 08 19:08:15 musicliving kernel: usb usb1: SerialNumber: 0000:01:00.0 Mar 08 19:08:15 musicliving kernel: hub 1-0:1.0: USB hub found Mar 08 19:08:15 musicliving kernel: hub 1-0:1.0: 1 port detected Mar 08 19:08:15 musicliving kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Mar 08 19:08:15 musicliving kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Mar 08 19:08:15 musicliving kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Mar 08 19:08:15 musicliving kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 Mar 08 19:08:15 musicliving kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Mar 08 19:08:15 musicliving kernel: usb usb2: Product: xHCI Host Controller Mar 08 19:08:15 musicliving kernel: usb usb2: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Mar 08 19:08:15 musicliving kernel: usb usb2: SerialNumber: 0000:01:00.0 Mar 08 19:08:15 musicliving kernel: hub 2-0:1.0: USB hub found Mar 08 19:08:15 musicliving kernel: hub 2-0:1.0: 4 ports detected Mar 08 19:08:15 musicliving kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Mar 08 19:08:15 musicliving kernel: dwc_otg: FIQ enabled Mar 08 19:08:15 musicliving kernel: dwc_otg: NAK holdoff enabled Mar 08 19:08:15 musicliving kernel: dwc_otg: FIQ split-transaction FSM enabled Mar 08 19:08:15 musicliving kernel: Module dwc_common_port init Mar 08 19:08:15 musicliving kernel: usbcore: registered new interface driver uas Mar 08 19:08:15 musicliving kernel: usbcore: registered new interface driver usb-storage Mar 08 19:08:15 musicliving kernel: mousedev: PS/2 mouse device common for all mice Mar 08 19:08:15 musicliving kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Mar 08 19:08:15 musicliving kernel: sdhci: Secure Digital Host Controller Interface driver Mar 08 19:08:15 musicliving kernel: sdhci: Copyright(c) Pierre Ossman Mar 08 19:08:15 musicliving kernel: mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe Mar 08 19:08:15 musicliving kernel: sdhci-pltfm: SDHCI platform and OF driver helper Mar 08 19:08:15 musicliving kernel: ledtrig-cpu: registered to indicate activity on CPUs Mar 08 19:08:15 musicliving kernel: hid: raw HID events driver (C) Jiri Kosina Mar 08 19:08:15 musicliving kernel: usbcore: registered new interface driver usbhid Mar 08 19:08:15 musicliving kernel: usbhid: USB HID core driver Mar 08 19:08:15 musicliving kernel: Initializing XFRM netlink socket Mar 08 19:08:15 musicliving kernel: NET: Registered protocol family 17 Mar 08 19:08:15 musicliving kernel: Key type dns_resolver registered Mar 08 19:08:15 musicliving kernel: Registering SWP/SWPB emulation handler Mar 08 19:08:15 musicliving kernel: registered taskstats version 1 Mar 08 19:08:15 musicliving kernel: Loading compiled-in X.509 certificates Mar 08 19:08:15 musicliving kernel: Key type ._fscrypt registered Mar 08 19:08:15 musicliving kernel: Key type .fscrypt registered Mar 08 19:08:15 musicliving kernel: Key type fscrypt-provisioning registered Mar 08 19:08:15 musicliving kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Mar 08 19:08:15 musicliving kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Mar 08 19:08:15 musicliving kernel: fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2 Mar 08 19:08:15 musicliving kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Mar 08 19:08:15 musicliving kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Mar 08 19:08:15 musicliving kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Mar 08 19:08:15 musicliving kernel: of_cfs_init Mar 08 19:08:15 musicliving kernel: of_cfs_init: OK Mar 08 19:08:15 musicliving kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Mar 08 19:08:15 musicliving kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Mar 08 19:08:15 musicliving kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Mar 08 19:08:15 musicliving kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Mar 08 19:08:15 musicliving kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Mar 08 19:08:15 musicliving kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Mar 08 19:08:15 musicliving kernel: Freeing unused kernel memory: 2048K Mar 08 19:08:15 musicliving kernel: Run /init as init process Mar 08 19:08:15 musicliving kernel: with arguments: Mar 08 19:08:15 musicliving kernel: /init Mar 08 19:08:15 musicliving kernel: splash Mar 08 19:08:15 musicliving kernel: with environment: Mar 08 19:08:15 musicliving kernel: HOME=/ Mar 08 19:08:15 musicliving kernel: TERM=linux Mar 08 19:08:15 musicliving kernel: imgpart=/dev/mmcblk0p2 Mar 08 19:08:15 musicliving kernel: imgfile=/volumio_current.sqsh Mar 08 19:08:15 musicliving kernel: bootdelay=5 Mar 08 19:08:15 musicliving kernel: mmc1: new high speed SDIO card at address 0001 Mar 08 19:08:15 musicliving kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Mar 08 19:08:15 musicliving kernel: mmcblk0: mmc0:aaaa SE32G 29.7 GiB Mar 08 19:08:15 musicliving kernel: mmcblk0: p1 p2 p3 Mar 08 19:08:15 musicliving kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Mar 08 19:08:15 musicliving initramfs: Booting Volumio for BCM2711 Mar 08 19:08:15 musicliving initramfs: This script mounts rootfs RO with an overlay RW layer. Mar 08 19:08:15 musicliving kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 08 19:08:15 musicliving initramfs: IMGPART=/dev/mmcblk0p2 Mar 08 19:08:15 musicliving initramfs: IMGFILE=/volumio_current.sqsh Mar 08 19:08:15 musicliving initramfs: Boot delay (except first time) will be 5 seconds Mar 08 19:08:15 musicliving initramfs: /dev/mmcblk0p2: Mar 08 19:08:15 musicliving kernel: EXT4-fs (mmcblk0p2): recovery complete Mar 08 19:08:15 musicliving kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Mar 08 19:08:15 musicliving initramfs: Doing a 5 second delay here to give kernel load a headstart Mar 08 19:08:15 musicliving kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Mar 08 19:08:15 musicliving kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Mar 08 19:08:15 musicliving kernel: usb 1-1: Product: USB2.0 Hub Mar 08 19:08:15 musicliving kernel: hub 1-1:1.0: USB hub found Mar 08 19:08:15 musicliving kernel: hub 1-1:1.0: 4 ports detected Mar 08 19:08:15 musicliving initramfs: Checking for USB updates Mar 08 19:08:15 musicliving initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Mar 08 19:08:15 musicliving kernel: EXT4-fs (mmcblk0p3): recovery complete Mar 08 19:08:15 musicliving kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Mar 08 19:08:15 musicliving initramfs: With Option: Mar 08 19:08:15 musicliving initramfs: VOLUMIO_VERSION="3.429" Mar 08 19:08:15 musicliving initramfs: Finish initramfs, continue booting Volumio Mar 08 19:08:15 musicliving systemd[1]: System time before build time, advancing clock. Mar 08 19:08:15 musicliving kernel: NET: Registered protocol family 10 Mar 08 19:08:15 musicliving kernel: Segment Routing with IPv6 Mar 08 19:08:15 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 19:08:15 musicliving systemd[1]: Detected architecture arm. Mar 08 19:08:15 musicliving systemd[1]: Set hostname to . Mar 08 19:08:15 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 19:08:15 musicliving kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 08 19:08:15 musicliving kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 08 19:08:15 musicliving kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 08 19:08:15 musicliving systemd[1]: Listening on fsck to fsckd communication Socket. Mar 08 19:08:15 musicliving systemd[1]: Listening on Journal Audit Socket. Mar 08 19:08:15 musicliving systemd[1]: Created slice system-getty.slice. Mar 08 19:08:15 musicliving systemd[1]: Listening on udev Kernel Socket. Mar 08 19:08:15 musicliving systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Mar 08 19:08:15 musicliving systemd[1]: Listening on udev Control Socket. Mar 08 19:08:15 musicliving kernel: i2c /dev entries driver Mar 08 19:08:15 musicliving systemd-journald[205]: Journal started Mar 08 19:08:15 musicliving systemd-journald[205]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 3.7M, max 30.0M, 26.2M free. Mar 08 19:08:15 musicliving systemd-modules-load[201]: Inserted module 'i2c_dev' Mar 08 19:08:15 musicliving fake-hwclock[198]: Wed Mar 8 18:08:15 UTC 2023 Mar 08 19:08:15 musicliving systemd[1]: Started Create Static Device Nodes in /dev. Mar 08 19:08:15 musicliving systemd[1]: Reached target Local File Systems (Pre). Mar 08 19:08:15 musicliving systemd[1]: Mounting /var/spool/cups... Mar 08 19:08:15 musicliving systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Mar 08 19:08:15 musicliving systemd[1]: Mounting /tmp... Mar 08 19:08:15 musicliving systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Mar 08 19:08:15 musicliving systemd[1]: Mounting /var/log... Mar 08 19:08:15 musicliving systemd[1]: Starting udev Kernel Device Manager... Mar 08 19:08:15 musicliving systemd[1]: Mounted /var/spool/cups. Mar 08 19:08:15 musicliving systemd[1]: Mounted /tmp. Mar 08 19:08:15 musicliving systemd[1]: Mounting /var/spool/cups/tmp... Mar 08 19:08:15 musicliving systemd[1]: Mounted /var/log. Mar 08 19:08:15 musicliving systemd[1]: Starting Flush Journal to Persistent Storage... Mar 08 19:08:15 musicliving systemd[1]: Mounted /var/spool/cups/tmp. Mar 08 19:08:15 musicliving systemd-udevd[223]: Network interface NamePolicy= disabled on kernel command line, ignoring. Mar 08 19:08:15 musicliving systemd-journald[205]: Runtime journal (/run/log/journal/3c3812e622e46b6dc8110864638cf76c) is 3.7M, max 30.0M, 26.2M free. Mar 08 19:08:15 musicliving systemd[1]: Started udev Kernel Device Manager. Mar 08 19:08:15 musicliving systemd[1]: Started Flush Journal to Persistent Storage. Mar 08 19:08:15 musicliving systemd[1]: Started udev Coldplug all Devices. Mar 08 19:08:15 musicliving systemd[1]: Starting Helper to synchronize boot up for ifupdown... Mar 08 19:08:15 musicliving systemd[1]: Starting Show Plymouth Boot Screen... Mar 08 19:08:15 musicliving systemd[1]: Started Helper to synchronize boot up for ifupdown. Mar 08 19:08:15 musicliving systemd[1]: Received SIGRTMIN+20 from PID 251 (plymouthd). Mar 08 19:08:15 musicliving systemd[1]: Started Show Plymouth Boot Screen. Mar 08 19:08:15 musicliving systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Mar 08 19:08:15 musicliving systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 08 19:08:15 musicliving systemd[1]: Reached target Paths. Mar 08 19:08:15 musicliving systemd[1]: Reached target Local Encrypted Volumes. Mar 08 19:08:15 musicliving kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 19:08:15 musicliving kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Mar 08 19:08:15 musicliving kernel: [vc_sm_connected_init]: start Mar 08 19:08:15 musicliving kernel: [vc_sm_connected_init]: installed successfully Mar 08 19:08:15 musicliving systemd-udevd[256]: Using default interface naming scheme 'v240'. Mar 08 19:08:15 musicliving kernel: rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000 Mar 08 19:08:15 musicliving kernel: rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000 Mar 08 19:08:15 musicliving kernel: rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000 Mar 08 19:08:15 musicliving kernel: rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000 Mar 08 19:08:15 musicliving kernel: mc: Linux media interface: v0.10 Mar 08 19:08:16 musicliving systemd-udevd[256]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 08 19:08:16 musicliving systemd-udevd[230]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 08 19:08:16 musicliving kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 19:08:16 musicliving kernel: videodev: Linux video capture interface: v2.00 Mar 08 19:08:16 musicliving kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 19:08:16 musicliving kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Mar 08 19:08:16 musicliving kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Mar 08 19:08:16 musicliving kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 08 19:08:16 musicliving kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Mar 08 19:08:16 musicliving kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Mar 08 19:08:16 musicliving kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Mar 08 19:08:16 musicliving kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Mar 08 19:08:16 musicliving kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 08 19:08:16 musicliving kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Mar 08 19:08:16 musicliving kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Mar 08 19:08:16 musicliving kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Mar 08 19:08:16 musicliving kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Mar 08 19:08:16 musicliving kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Mar 08 19:08:16 musicliving kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 19:08:17 musicliving kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Mar 08 19:08:17 musicliving kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Mar 08 19:08:17 musicliving kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Mar 08 19:08:17 musicliving kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Mar 08 19:08:17 musicliving kernel: usbcore: registered new interface driver brcmfmac Mar 08 19:08:18 musicliving kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Mar 08 19:08:18 musicliving kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Mar 08 19:08:18 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 19:08:18 musicliving systemd-udevd[253]: Using default interface naming scheme 'v240'. Mar 08 19:08:18 musicliving systemd-udevd[253]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 08 19:08:18 musicliving systemd[1]: Found device /dev/mmcblk0p1. Mar 08 19:08:18 musicliving systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Mar 08 19:08:18 musicliving systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Mar 08 19:08:18 musicliving systemd[1]: Condition check resulted in Huge Pages File System being skipped. Mar 08 19:08:18 musicliving systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Mar 08 19:08:18 musicliving systemd[1]: Starting File System Check on /dev/mmcblk0p1... Mar 08 19:08:18 musicliving systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 08 19:08:18 musicliving systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 08 19:08:18 musicliving systemd[1]: Condition check resulted in FUSE Control File System being skipped. Mar 08 19:08:18 musicliving systemd[1]: Starting Load/Save RF Kill Switch Status... Mar 08 19:08:18 musicliving systemd[1]: Started File System Check Daemon to report status. Mar 08 19:08:18 musicliving systemd[1]: Started Load/Save RF Kill Switch Status. Mar 08 19:08:18 musicliving systemd-fsck[372]: fsck.fat 4.1 (2017-01-24) Mar 08 19:08:18 musicliving systemd-fsck[372]: /dev/mmcblk0p1: 294 files, 27483/46774 clusters Mar 08 19:08:18 musicliving systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Mar 08 19:08:18 musicliving systemd[1]: Started File System Check on /dev/mmcblk0p1. Mar 08 19:08:18 musicliving systemd[1]: Mounting /boot... Mar 08 19:08:19 musicliving systemd[1]: Mounted /boot. Mar 08 19:08:19 musicliving systemd[1]: Reached target Local File Systems. Mar 08 19:08:19 musicliving systemd[1]: Starting Raise network interfaces... Mar 08 19:08:19 musicliving systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Mar 08 19:08:19 musicliving systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Mar 08 19:08:19 musicliving systemd[1]: Started ifup for wlan0. Mar 08 19:08:19 musicliving systemd[1]: Starting Preprocess NFS configuration... Mar 08 19:08:19 musicliving systemd[1]: Starting Create Volatile Files and Directories... Mar 08 19:08:19 musicliving systemd[1]: Started ifup for eth0. Mar 08 19:08:19 musicliving systemd[1]: plymouth-read-write.service: Succeeded. Mar 08 19:08:19 musicliving systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Mar 08 19:08:19 musicliving systemd[1]: Received SIGRTMIN+20 from PID 251 (plymouthd). Mar 08 19:08:19 musicliving systemd[1]: nfs-config.service: Succeeded. Mar 08 19:08:19 musicliving systemd[1]: Started Preprocess NFS configuration. Mar 08 19:08:19 musicliving systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Mar 08 19:08:19 musicliving systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Mar 08 19:08:19 musicliving systemd[1]: Reached target NFS client services. Mar 08 19:08:19 musicliving systemd[1]: Started Create Volatile Files and Directories. Mar 08 19:08:19 musicliving systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Mar 08 19:08:19 musicliving systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Mar 08 19:08:19 musicliving systemd[1]: Starting RPC bind portmap service... Mar 08 19:08:19 musicliving systemd[1]: Starting Update UTMP about System Boot/Shutdown... Mar 08 19:08:19 musicliving kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 08 19:08:19 musicliving systemd[1]: Started Update UTMP about System Boot/Shutdown. Mar 08 19:08:19 musicliving systemd[1]: Reached target System Initialization. Mar 08 19:08:19 musicliving systemd[1]: Started Daily Cleanup of Temporary Directories. Mar 08 19:08:19 musicliving systemd[1]: Listening on triggerhappy.socket. Mar 08 19:08:19 musicliving systemd[1]: Listening on D-Bus System Message Bus Socket. Mar 08 19:08:19 musicliving kernel: 8021q: 802.1Q VLAN Support v1.8 Mar 08 19:08:19 musicliving systemd[1]: Listening on mpd.socket. Mar 08 19:08:19 musicliving systemd[1]: Started Daily apt download activities. Mar 08 19:08:19 musicliving systemd[1]: Started Daily apt upgrade and clean activities. Mar 08 19:08:19 musicliving systemd[1]: Reached target Timers. Mar 08 19:08:19 musicliving systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Mar 08 19:08:19 musicliving systemd[1]: Reached target Sockets. Mar 08 19:08:19 musicliving systemd[1]: Reached target Basic System. Mar 08 19:08:19 musicliving systemd[1]: Started volumio-remote-updater.service. Mar 08 19:08:19 musicliving systemd[1]: Starting Wireless Services... Mar 08 19:08:19 musicliving systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Mar 08 19:08:19 musicliving systemd[1]: Starting triggerhappy global hotkey daemon... Mar 08 19:08:19 musicliving systemd[1]: Started D-Bus System Message Bus. Mar 08 19:08:19 musicliving systemd[1]: Starting WPA supplicant... Mar 08 19:08:19 musicliving systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Mar 08 19:08:19 musicliving systemd[1]: Starting dhcpcd on all interfaces... Mar 08 19:08:19 musicliving systemd[1]: Starting Login Service... Mar 08 19:08:19 musicliving systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Mar 08 19:08:19 musicliving thd[474]: Unable to parse trigger line: Mar 08 19:08:19 musicliving thd[474]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Mar 08 19:08:19 musicliving thd[474]: Unable to parse trigger line: Mar 08 19:08:19 musicliving systemd[1]: Starting Configure Bluetooth Modems connected by UART... Mar 08 19:08:19 musicliving systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Mar 08 19:08:19 musicliving systemd[1]: Started Volumio Iptables Module. Mar 08 19:08:19 musicliving systemd[1]: Started Manage Sound Card State (restore and store). Mar 08 19:08:19 musicliving systemd[1]: Starting Save/Restore Sound Card State... Mar 08 19:08:19 musicliving alsactl[490]: alsactl 1.1.8 daemon started Mar 08 19:08:19 musicliving dhcpcd[480]: Not running dhcpcd because /etc/network/interfaces Mar 08 19:08:19 musicliving dhcpcd[480]: defines some interfaces that will use a Mar 08 19:08:19 musicliving dhcpcd[480]: DHCP client or static address Mar 08 19:08:19 musicliving systemd[1]: Started UPnP Renderer front-end to MPD. Mar 08 19:08:19 musicliving systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Mar 08 19:08:19 musicliving systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Mar 08 19:08:19 musicliving systemd[1]: Failed to start dhcpcd on all interfaces. Mar 08 19:08:20 musicliving thd[474]: Found socket passed from systemd Mar 08 19:08:20 musicliving sh[388]: eth0: waiting for carrier Mar 08 19:08:20 musicliving dhcpcd[429]: eth0: waiting for carrier Mar 08 19:08:20 musicliving kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Mar 08 19:08:20 musicliving kernel: bcmgenet fd580000.ethernet eth0: Link is Down Mar 08 19:08:20 musicliving systemd-logind[481]: New seat seat0. Mar 08 19:08:20 musicliving kernel: uart-pl011 fe201000.serial: no DMA platform data Mar 08 19:08:20 musicliving systemd[1]: Started triggerhappy global hotkey daemon. Mar 08 19:08:20 musicliving systemd[1]: Started Save/Restore Sound Card State. Mar 08 19:08:20 musicliving systemd[1]: Started Login Service. Mar 08 19:08:20 musicliving systemd[1]: Reached target Sound Card. Mar 08 19:08:20 musicliving systemd[1]: Started RPC bind portmap service. Mar 08 19:08:20 musicliving systemd[1]: Reached target RPC Port Mapper. Mar 08 19:08:20 musicliving systemd[1]: Reached target Remote File Systems (Pre). Mar 08 19:08:20 musicliving systemd[1]: Reached target Remote File Systems. Mar 08 19:08:20 musicliving sh[382]: wlan0=wlan0 Mar 08 19:08:20 musicliving systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Mar 08 19:08:20 musicliving systemd[1]: Started Raise network interfaces. Mar 08 19:08:20 musicliving avahi-daemon[470]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Mar 08 19:08:20 musicliving avahi-daemon[470]: Successfully dropped root privileges. Mar 08 19:08:20 musicliving avahi-daemon[470]: avahi-daemon 0.7 starting up. Mar 08 19:08:20 musicliving systemd[1]: Started Avahi mDNS/DNS-SD Stack. Mar 08 19:08:20 musicliving avahi-daemon[470]: Successfully called chroot(). Mar 08 19:08:20 musicliving avahi-daemon[470]: Successfully dropped remaining capabilities. Mar 08 19:08:20 musicliving avahi-daemon[470]: Loading service file /services/volumio.service. Mar 08 19:08:20 musicliving avahi-daemon[470]: Network interface enumeration completed. Mar 08 19:08:20 musicliving avahi-daemon[470]: Server startup complete. Host name is musicliving.local. Local service cookie is 2755431615. Mar 08 19:08:20 musicliving avahi-daemon[470]: Service "musicliving" (/services/volumio.service) successfully established. Mar 08 19:08:21 musicliving loadcpufreq[540]: Loading cpufreq kernel modules...done (none). Mar 08 19:08:21 musicliving systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Mar 08 19:08:21 musicliving systemd[1]: Starting LSB: set CPUFreq kernel parameters... Mar 08 19:08:21 musicliving cpufrequtils[572]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Mar 08 19:08:21 musicliving systemd[1]: Started LSB: set CPUFreq kernel parameters. Mar 08 19:08:21 musicliving systemd[1]: Started WPA supplicant. Mar 08 19:08:21 musicliving systemd[1]: Reached target Network. Mar 08 19:08:21 musicliving wpa_supplicant[479]: Successfully initialized wpa_supplicant Mar 08 19:08:21 musicliving systemd[1]: Starting Permit User Sessions... Mar 08 19:08:21 musicliving systemd[1]: Starting Network Time Service... Mar 08 19:08:21 musicliving systemd[1]: Reached target Network is Online. Mar 08 19:08:21 musicliving systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 08 19:08:21 musicliving systemd[1]: Starting LSB: Brings up/down network automatically... Mar 08 19:08:21 musicliving systemd[1]: Starting /etc/rc.local Compatibility... Mar 08 19:08:21 musicliving systemd[1]: Starting Samba NMB Daemon... Mar 08 19:08:21 musicliving systemd[1]: Starting OpenBSD Secure Shell server... Mar 08 19:08:21 musicliving systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Mar 08 19:08:21 musicliving systemd[1]: Starting Music Player Daemon... Mar 08 19:08:21 musicliving systemd[1]: Started Permit User Sessions. Mar 08 19:08:21 musicliving systemd[1]: Started /etc/rc.local Compatibility. Mar 08 19:08:21 musicliving systemd[1]: Starting Terminate Plymouth Boot Screen... Mar 08 19:08:21 musicliving systemd[1]: Starting Hold until boot process finishes up... Mar 08 19:08:21 musicliving systemd[1]: Received SIGRTMIN+21 from PID 251 (plymouthd). Mar 08 19:08:21 musicliving systemd[1]: plymouth-start.service: Succeeded. Mar 08 19:08:21 musicliving systemd[1]: plymouth-quit.service: Succeeded. Mar 08 19:08:21 musicliving systemd[1]: Started Terminate Plymouth Boot Screen. Mar 08 19:08:21 musicliving systemd[1]: plymouth-quit-wait.service: Succeeded. Mar 08 19:08:21 musicliving systemd[1]: Started Hold until boot process finishes up. Mar 08 19:08:21 musicliving systemd[1]: Received SIGRTMIN+21 from PID 251 (n/a). Mar 08 19:08:21 musicliving systemd[1]: Started Getty on tty1. Mar 08 19:08:21 musicliving systemd[1]: Reached target Login Prompts. Mar 08 19:08:21 musicliving ifplugd(eth0)[621]: ifplugd 0.28 initializing. Mar 08 19:08:21 musicliving ifplugd(eth0)[621]: Using interface eth0/E4:5F:01:11:28:5E with driver (version: 5.10.92-v7l+) Mar 08 19:08:21 musicliving ifplugd(eth0)[621]: Using detection mode: SIOCETHTOOL Mar 08 19:08:21 musicliving ifplugd(eth0)[621]: Initialization complete, link beat not detected. Mar 08 19:08:21 musicliving ntpd[590]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 08 19:08:21 musicliving systemd[1]: Started Network Time Service. Mar 08 19:08:21 musicliving ntpd[590]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Mar 08 19:08:21 musicliving systemd[1]: iptables.service: Succeeded. Mar 08 19:08:21 musicliving ntpd[623]: proto: precision = 0.815 usec (-20) Mar 08 19:08:21 musicliving ifplugd[592]: Network Interface Plugging Daemon...start eth0...done. Mar 08 19:08:21 musicliving systemd[1]: Started LSB: Brings up/down network automatically. Mar 08 19:08:21 musicliving ntpd[623]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 08 19:08:21 musicliving ntpd[623]: 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 19:08:22 musicliving ntpd[623]: Listen and drop on 0 v6wildcard [::]:123 Mar 08 19:08:22 musicliving ntpd[623]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 08 19:08:22 musicliving ntpd[623]: Listen normally on 2 lo 127.0.0.1:123 Mar 08 19:08:22 musicliving ntpd[623]: Listening on routing socket on fd #19 for interface updates Mar 08 19:08:22 musicliving ntpd[623]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 08 19:08:22 musicliving ntpd[623]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 08 19:08:22 musicliving haveged[435]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Mar 08 19:08:22 musicliving haveged[435]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Mar 08 19:08:22 musicliving haveged[435]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00136 Mar 08 19:08:22 musicliving haveged[435]: haveged: fills: 0, generated: 0 Mar 08 19:08:22 musicliving kernel: random: crng init done Mar 08 19:08:22 musicliving kernel: random: 7 urandom warning(s) missed due to ratelimiting Mar 08 19:08:22 musicliving volumio-remote-updater[466]: Error: No active session Mar 08 19:08:22 musicliving volumio-remote-updater[466]: [2023-03-08 19:08:22] [info] asio async_connect error: system:111 (Connection refused) Mar 08 19:08:22 musicliving volumio-remote-updater[466]: [2023-03-08 19:08:22] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 08 19:08:22 musicliving volumio-remote-updater[466]: [2023-03-08 19:08:22] [error] handle_connect error: Underlying Transport Error Mar 08 19:08:22 musicliving volumio[495]: Could not open config: /tmp/upmpdcli.conf Mar 08 19:08:22 musicliving systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 08 19:08:22 musicliving systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 08 19:08:22 musicliving sshd[632]: Server listening on 0.0.0.0 port 22. Mar 08 19:08:22 musicliving sshd[632]: Server listening on :: port 22. Mar 08 19:08:22 musicliving systemd[1]: Started OpenBSD Secure Shell server. Mar 08 19:08:22 musicliving ntpd[623]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 08 19:08:23 musicliving nmbd[606]: [2023/03/08 19:08:23.657845, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 08 19:08:23 musicliving nmbd[606]: started asyncdns process 638 Mar 08 19:08:23 musicliving nmbd[606]: [2023/03/08 19:08:23.659972, 0] ../lib/util/become_daemon.c:149(daemon_status) Mar 08 19:08:23 musicliving nmbd[606]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 08 19:08:23 musicliving nmbd[606]: [2023/03/08 19:08:23.660122, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Mar 08 19:08:23 musicliving nmbd[606]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 08 19:08:23 musicliving systemd[1]: systemd-rfkill.service: Succeeded. Mar 08 19:08:23 musicliving ntpd[623]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 08 19:08:24 musicliving wireless.js[469]: Cleaning previous... Mar 08 19:08:24 musicliving sudo[647]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 08 19:08:24 musicliving sudo[647]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:24 musicliving sudo[647]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:24 musicliving sudo[649]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 08 19:08:24 musicliving sudo[649]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:24 musicliving mpd[626]: Mar 08 19:08 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 08 19:08:24 musicliving mpd[626]: Mar 08 19:08 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 08 19:08:24 musicliving mpd[626]: Mar 08 19:08 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 08 19:08:24 musicliving ntpd[623]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 08 19:08:25 musicliving sudo[649]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:25 musicliving systemd[1]: Started Music Player Daemon. Mar 08 19:08:25 musicliving wireless.js[469]: Stopped aP Mar 08 19:08:25 musicliving sudo[658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 08 19:08:25 musicliving sudo[658]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:25 musicliving kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 08 19:08:25 musicliving sudo[658]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:25 musicliving sudo[660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 08 19:08:25 musicliving sudo[660]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:25 musicliving sudo[660]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:25 musicliving sudo[667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 08 19:08:25 musicliving sudo[667]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:25 musicliving sudo[667]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:25 musicliving sudo[669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 08 19:08:25 musicliving sudo[669]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:25 musicliving ntpd[623]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 08 19:08:27 musicliving kernel: Bluetooth: Core ver 2.22 Mar 08 19:08:27 musicliving kernel: NET: Registered protocol family 31 Mar 08 19:08:27 musicliving kernel: Bluetooth: HCI device and connection manager initialized Mar 08 19:08:27 musicliving kernel: Bluetooth: HCI socket layer initialized Mar 08 19:08:27 musicliving kernel: Bluetooth: L2CAP socket layer initialized Mar 08 19:08:27 musicliving kernel: Bluetooth: SCO socket layer initialized Mar 08 19:08:27 musicliving btuart[486]: bcm43xx_init Mar 08 19:08:27 musicliving btuart[486]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Mar 08 19:08:27 musicliving btuart[486]: Set Controller UART speed to 3000000 bit/s Mar 08 19:08:27 musicliving btuart[486]: Device setup complete Mar 08 19:08:27 musicliving systemd[1]: Started Configure Bluetooth Modems connected by UART. Mar 08 19:08:27 musicliving kernel: Bluetooth: HCI UART driver ver 2.3 Mar 08 19:08:27 musicliving kernel: Bluetooth: HCI UART protocol H4 registered Mar 08 19:08:27 musicliving kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Mar 08 19:08:27 musicliving kernel: Bluetooth: HCI UART protocol Broadcom registered Mar 08 19:08:27 musicliving systemd[1]: Created slice system-bthelper.slice. Mar 08 19:08:27 musicliving systemd[1]: Starting Raspberry Pi bluetooth helper... Mar 08 19:08:27 musicliving systemd[1]: Starting Load/Save RF Kill Switch Status... Mar 08 19:08:27 musicliving bthelper[681]: Raspberry Pi BDADDR already set Mar 08 19:08:27 musicliving systemd[1]: Started Load/Save RF Kill Switch Status. Mar 08 19:08:27 musicliving systemd[1]: Started Raspberry Pi bluetooth helper. Mar 08 19:08:27 musicliving systemd[1]: Starting Bluetooth service... Mar 08 19:08:27 musicliving bluetoothd[692]: Bluetooth daemon 5.50 Mar 08 19:08:27 musicliving bluetoothd[692]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Mar 08 19:08:27 musicliving systemd[1]: Started Bluetooth service. Mar 08 19:08:27 musicliving systemd[1]: Reached target Bluetooth. Mar 08 19:08:27 musicliving bluetoothd[692]: Starting SDP server Mar 08 19:08:27 musicliving bluetoothd[692]: Excluding (cli) sap Mar 08 19:08:27 musicliving bluetoothd[692]: Bluetooth management interface 1.18 initialized Mar 08 19:08:27 musicliving dbus-daemon[475]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=692 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Mar 08 19:08:27 musicliving kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Mar 08 19:08:27 musicliving kernel: Bluetooth: BNEP filters: protocol multicast Mar 08 19:08:27 musicliving kernel: Bluetooth: BNEP socket layer initialized Mar 08 19:08:27 musicliving systemd[1]: Starting Hostname Service... Mar 08 19:08:27 musicliving volumio-remote-updater[466]: [2023-03-08 19:08:27] [info] asio async_connect error: system:111 (Connection refused) Mar 08 19:08:27 musicliving volumio-remote-updater[466]: [2023-03-08 19:08:27] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 08 19:08:27 musicliving volumio-remote-updater[466]: [2023-03-08 19:08:27] [error] handle_connect error: Underlying Transport Error Mar 08 19:08:27 musicliving dbus-daemon[475]: [system] Successfully activated service 'org.freedesktop.hostname1' Mar 08 19:08:27 musicliving systemd[1]: Started Hostname Service. Mar 08 19:08:27 musicliving bluetoothd[692]: Failed to set privacy: Rejected (0x0b) Mar 08 19:08:27 musicliving sudo[669]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:27 musicliving wireless.js[469]: Start wireless flow Mar 08 19:08:27 musicliving wireless.js[469]: Stopped hotspot (if there).. Mar 08 19:08:27 musicliving sudo[696]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 08 19:08:27 musicliving sudo[696]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:27 musicliving sudo[696]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:27 musicliving sudo[698]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 08 19:08:27 musicliving sudo[698]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:28 musicliving sudo[698]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:28 musicliving wireless.js[469]: DHCP IP Mar 08 19:08:28 musicliving wireless.js[469]: Start ap Mar 08 19:08:28 musicliving wpa_supplicant[700]: Successfully initialized wpa_supplicant Mar 08 19:08:28 musicliving sudo[701]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 08 19:08:28 musicliving sudo[701]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:28 musicliving dhcpcd[702]: dev: loaded udev Mar 08 19:08:28 musicliving kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 08 19:08:28 musicliving dhcpcd-run-hooks[720]: wlan0: starting wpa_supplicant Mar 08 19:08:28 musicliving dhcpcd-run-hooks[725]: wlan0: failed to start wpa_supplicant Mar 08 19:08:28 musicliving dhcpcd-run-hooks[726]: 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 19:08:28 musicliving dhcpcd[702]: wlan0: connected to Access Point `' Mar 08 19:08:28 musicliving dhcpcd[702]: no interfaces have a carrier Mar 08 19:08:28 musicliving dhcpcd[702]: forked to background, child pid 736 Mar 08 19:08:28 musicliving dhcpcd[736]: eth0: waiting for carrier Mar 08 19:08:28 musicliving dhcpcd[736]: wlan0: waiting for carrier Mar 08 19:08:28 musicliving sudo[701]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:29 musicliving wireless.js[469]: trying... Mar 08 19:08:29 musicliving sudo[738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:29 musicliving sudo[738]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:29 musicliving sudo[738]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:30 musicliving wireless.js[469]: trying... Mar 08 19:08:30 musicliving sudo[741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:30 musicliving sudo[741]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:30 musicliving sudo[741]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:31 musicliving wpa_supplicant[724]: wlan0: Trying to associate with SSID 'Isengard' Mar 08 19:08:31 musicliving wireless.js[469]: trying... Mar 08 19:08:31 musicliving sudo[744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:31 musicliving sudo[744]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:31 musicliving sudo[744]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:32 musicliving systemd[1]: systemd-rfkill.service: Succeeded. Mar 08 19:08:32 musicliving bthelper[681]: Changing power off succeeded Mar 08 19:08:32 musicliving wireless.js[469]: trying... Mar 08 19:08:32 musicliving sudo[748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:32 musicliving sudo[748]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:32 musicliving sudo[748]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:32 musicliving bthelper[681]: [CHG] Controller E4:5F:01:11:28:60 Class: 0x0000041c Mar 08 19:08:32 musicliving bthelper[681]: Changing power on succeeded Mar 08 19:08:33 musicliving wireless.js[469]: trying... Mar 08 19:08:33 musicliving sudo[757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:33 musicliving sudo[757]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:33 musicliving sudo[757]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:33 musicliving wpa_supplicant[724]: RRM: Ignoring radio measurement request: Not associated Mar 08 19:08:33 musicliving dhcpcd[736]: wlan0: carrier acquired Mar 08 19:08:33 musicliving dhcpcd[736]: wlan0: connected to Access Point `Isengard' Mar 08 19:08:33 musicliving wpa_supplicant[724]: wlan0: Associated with 74:42:7f:01:19:ab Mar 08 19:08:33 musicliving wpa_supplicant[724]: wlan0: CTRL-EVENT-CONNECTED - Connection to 74:42:7f:01:19:ab completed [id=0 id_str=] Mar 08 19:08:33 musicliving wpa_supplicant[724]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 08 19:08:33 musicliving wpa_supplicant[724]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Mar 08 19:08:33 musicliving dhcpcd[736]: DUID 00:01:00:01:2b:1f:b7:4a:e4:5f:01:11:28:5f Mar 08 19:08:33 musicliving dhcpcd[736]: wlan0: IAID 01:11:28:5f Mar 08 19:08:33 musicliving dhcpcd[736]: wlan0: adding address fe80::9a0f:54b0:1f8e:8539 Mar 08 19:08:33 musicliving dhcpcd[736]: ipv6_addaddr1: Permission denied Mar 08 19:08:33 musicliving dhcpcd[736]: wlan0: carrier lost Mar 08 19:08:33 musicliving dhcpcd[736]: wlan0: carrier acquired Mar 08 19:08:33 musicliving dhcpcd[736]: wlan0: IAID 01:11:28:5f Mar 08 19:08:33 musicliving dhcpcd[736]: wlan0: soliciting an IPv6 router Mar 08 19:08:33 musicliving dhcpcd[736]: wlan0: rebinding lease of 172.16.0.49 Mar 08 19:08:34 musicliving wpa_supplicant[724]: RRM: Ignoring radio measurement request: Not RRM network Mar 08 19:08:34 musicliving wpa_supplicant[724]: RRM: Ignoring radio measurement request: Not RRM network Mar 08 19:08:34 musicliving wireless.js[469]: trying... Mar 08 19:08:34 musicliving sudo[772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:34 musicliving sudo[772]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:34 musicliving sudo[772]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:34 musicliving wpa_supplicant[724]: RRM: Ignoring radio measurement request: Not RRM network Mar 08 19:08:34 musicliving wpa_supplicant[724]: RRM: Ignoring radio measurement request: Not RRM network Mar 08 19:08:34 musicliving wpa_supplicant[724]: RRM: Ignoring radio measurement request: Not RRM network Mar 08 19:08:35 musicliving volumio-remote-updater[466]: [2023-03-08 19:08:35] [info] asio async_connect error: system:111 (Connection refused) Mar 08 19:08:35 musicliving volumio-remote-updater[466]: [2023-03-08 19:08:35] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 08 19:08:35 musicliving volumio-remote-updater[466]: [2023-03-08 19:08:35] [error] handle_connect error: Underlying Transport Error Mar 08 19:08:35 musicliving wireless.js[469]: trying... Mar 08 19:08:35 musicliving sudo[775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:35 musicliving sudo[775]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:35 musicliving sudo[775]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:36 musicliving wireless.js[469]: trying... Mar 08 19:08:36 musicliving sudo[778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:36 musicliving sudo[778]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:36 musicliving sudo[778]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:36 musicliving kernel: cam-dummy-reg: disabling Mar 08 19:08:36 musicliving kernel: cam1-reg: disabling Mar 08 19:08:37 musicliving wireless.js[469]: trying... Mar 08 19:08:37 musicliving sudo[781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:37 musicliving sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:37 musicliving sudo[781]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:38 musicliving wireless.js[469]: trying... Mar 08 19:08:38 musicliving sudo[784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:38 musicliving sudo[784]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:38 musicliving sudo[784]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:38 musicliving dhcpcd[736]: wlan0: probing address 172.16.0.49/24 Mar 08 19:08:39 musicliving wireless.js[469]: trying... Mar 08 19:08:39 musicliving sudo[787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:39 musicliving sudo[787]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:39 musicliving sudo[787]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:40 musicliving wireless.js[469]: trying... Mar 08 19:08:40 musicliving sudo[790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:40 musicliving sudo[790]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:40 musicliving sudo[790]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:41 musicliving wireless.js[469]: trying... Mar 08 19:08:41 musicliving sudo[793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:41 musicliving sudo[793]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:41 musicliving sudo[793]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:42 musicliving wireless.js[469]: trying... Mar 08 19:08:42 musicliving sudo[796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:42 musicliving sudo[796]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:42 musicliving sudo[796]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:43 musicliving wireless.js[469]: trying... Mar 08 19:08:43 musicliving sudo[799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:43 musicliving sudo[799]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:43 musicliving sudo[799]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:44 musicliving dhcpcd[736]: wlan0: leased 172.16.0.49 for 864000 seconds Mar 08 19:08:44 musicliving avahi-daemon[470]: Joining mDNS multicast group on interface wlan0.IPv4 with address 172.16.0.49. Mar 08 19:08:44 musicliving dhcpcd[736]: wlan0: adding route to 172.16.0.0/24 Mar 08 19:08:44 musicliving dhcpcd[736]: wlan0: adding default route via 172.16.0.1 Mar 08 19:08:44 musicliving avahi-daemon[470]: New relevant interface wlan0.IPv4 for mDNS. Mar 08 19:08:44 musicliving avahi-daemon[470]: Registering new address record for 172.16.0.49 on wlan0.IPv4. Mar 08 19:08:44 musicliving wireless.js[469]: trying... Mar 08 19:08:44 musicliving sudo[823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 19:08:44 musicliving sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:44 musicliving sudo[823]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:44 musicliving wireless.js[469]: Connected to: ----Isengard Mar 08 19:08:44 musicliving wireless.js[469]: ---- Mar 08 19:08:44 musicliving sudo[826]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 19:08:44 musicliving sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:44 musicliving sudo[826]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:44 musicliving wireless.js[469]: ... joined AP, wlan0 IPv4 is 172.16.0.49, ipV6 is undefined Mar 08 19:08:44 musicliving wireless.js[469]: It's done! AP Mar 08 19:08:44 musicliving systemd[1]: Started Wireless Services. Mar 08 19:08:44 musicliving systemd[1]: Started Volumio Backend Module. Mar 08 19:08:45 musicliving volumio-remote-updater[466]: [2023-03-08 19:08:45] [info] asio async_connect error: system:111 (Connection refused) Mar 08 19:08:45 musicliving volumio-remote-updater[466]: [2023-03-08 19:08:45] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 08 19:08:45 musicliving volumio-remote-updater[466]: [2023-03-08 19:08:45] [error] handle_connect error: Underlying Transport Error Mar 08 19:08:45 musicliving ntpd[623]: Listen normally on 3 wlan0 172.16.0.49:123 Mar 08 19:08:45 musicliving ntpd[623]: new interface(s) found: waking up resolver Mar 08 19:08:46 musicliving volumio[830]: info: ------------------------------------------- Mar 08 19:08:46 musicliving volumio[830]: info: ----- Volumio3 ---- Mar 08 19:08:46 musicliving volumio[830]: info: ------------------------------------------- Mar 08 19:08:46 musicliving volumio[830]: info: ----- System startup ---- Mar 08 19:08:46 musicliving volumio[830]: info: ------------------------------------------- Mar 08 19:08:47 musicliving volumio[830]: info: MYVOLUMIO Environment detected Mar 08 19:08:47 musicliving volumio[830]: info: Plugin folders cleanup Mar 08 19:08:47 musicliving volumio[830]: info: Scanning into folder /volumio/app/plugins/ Mar 08 19:08:47 musicliving volumio[830]: info: Scanning category audio_interface Mar 08 19:08:47 musicliving volumio[830]: info: Scanning category miscellanea Mar 08 19:08:47 musicliving volumio[830]: info: Scanning category music_service Mar 08 19:08:47 musicliving volumio[830]: info: Scanning category plugins.json Mar 08 19:08:47 musicliving volumio[830]: info: Scanning category system_controller Mar 08 19:08:47 musicliving volumio[830]: info: Scanning category user_interface Mar 08 19:08:47 musicliving volumio[830]: info: Scanning into folder /data/plugins/ Mar 08 19:08:47 musicliving volumio[830]: info: Scanning category music_service Mar 08 19:08:47 musicliving volumio[830]: info: Scanning category user_interface Mar 08 19:08:47 musicliving volumio[830]: info: Plugin folders cleanup completed Mar 08 19:08:47 musicliving volumio[830]: info: ------------------------------------------- Mar 08 19:08:47 musicliving volumio[830]: info: ----- Core plugins startup ---- Mar 08 19:08:47 musicliving volumio[830]: info: ------------------------------------------- Mar 08 19:08:47 musicliving volumio[830]: info: Loading plugins from folder /volumio/app/plugins/ Mar 08 19:08:47 musicliving volumio[830]: info: Adding plugin upnp to MyMusic Plugins Mar 08 19:08:47 musicliving volumio[830]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 08 19:08:47 musicliving volumio[830]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 08 19:08:47 musicliving volumio[830]: info: Loading plugins from folder /data/plugins/ Mar 08 19:08:47 musicliving volumio[830]: info: Loading plugin "system"... Mar 08 19:08:47 musicliving volumio[830]: info: Loading plugin "appearance"... Mar 08 19:08:48 musicliving volumio[830]: info: Loading plugin "network"... Mar 08 19:08:48 musicliving volumio[830]: info: Refreshing Cached IP Addresses Mar 08 19:08:48 musicliving sudo[848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 08 19:08:48 musicliving sudo[848]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:48 musicliving sudo[848]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:48 musicliving volumio[830]: info: Loading plugin "services"... Mar 08 19:08:48 musicliving sudo[850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 19:08:48 musicliving sudo[850]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:48 musicliving volumio[830]: info: Loading plugin "alsa_controller"... Mar 08 19:08:48 musicliving sudo[850]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:48 musicliving sudo[853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 08 19:08:48 musicliving sudo[853]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:48 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 19:08:48 musicliving volumio[830]: info: Loading plugin "wizard"... Mar 08 19:08:48 musicliving volumio[830]: info: Loading plugin "volumio_command_line_client"... Mar 08 19:08:48 musicliving volumio[830]: info: Plugin upnp is not enabled Mar 08 19:08:48 musicliving volumio[830]: info: Loading plugin "my_music"... Mar 08 19:08:48 musicliving volumio[830]: info: Loading plugin "mpd"... Mar 08 19:08:48 musicliving volumio[830]: info: Creating MPD Configuration file Mar 08 19:08:48 musicliving volumio[830]: info: Loading plugin "upnp_browser"... Mar 08 19:08:48 musicliving sudo[859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 08 19:08:48 musicliving sudo[859]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:48 musicliving sudo[859]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:48 musicliving sudo[861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 08 19:08:48 musicliving sudo[861]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:48 musicliving systemd[1]: Stopping Music Player Daemon... Mar 08 19:08:48 musicliving nmbd[606]: [2023/03/08 19:08:48.710279, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 08 19:08:48 musicliving nmbd[606]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 08 19:08:48 musicliving systemd[1]: Started Samba NMB Daemon. Mar 08 19:08:48 musicliving systemd[1]: Starting Samba Winbind Daemon... Mar 08 19:08:48 musicliving systemd[1]: mpd.service: Succeeded. Mar 08 19:08:48 musicliving systemd[1]: Stopped Music Player Daemon. Mar 08 19:08:48 musicliving systemd[1]: Starting Music Player Daemon... Mar 08 19:08:48 musicliving systemd[1]: systemd-fsckd.service: Succeeded. Mar 08 19:08:48 musicliving winbindd[864]: [2023/03/08 19:08:48.993068, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 08 19:08:48 musicliving winbindd[864]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 08 19:08:49 musicliving winbindd[864]: [2023/03/08 19:08:49.014037, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 08 19:08:49 musicliving winbindd[864]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 08 19:08:49 musicliving systemd[1]: Started Samba Winbind Daemon. Mar 08 19:08:49 musicliving systemd[1]: Starting Samba SMB Daemon... Mar 08 19:08:49 musicliving volumio[830]: info: Loading plugin "networkfs"... Mar 08 19:08:49 musicliving smbd[872]: [2023/03/08 19:08:49.445835, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 08 19:08:49 musicliving systemd[1]: Started Samba SMB Daemon. Mar 08 19:08:49 musicliving smbd[872]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 08 19:08:49 musicliving volumio[830]: info: Starting Udev Watcher for removable devices Mar 08 19:08:49 musicliving sudo[882]: 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 19:08:49 musicliving sudo[882]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:49 musicliving mpd[868]: Mar 08 19:08 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 08 19:08:49 musicliving mpd[868]: Mar 08 19:08 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 08 19:08:49 musicliving mpd[868]: Mar 08 19:08 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 08 19:08:49 musicliving systemd[1]: Started Music Player Daemon. Mar 08 19:08:49 musicliving volumio[830]: info: Ignoring mount for partition: boot Mar 08 19:08:49 musicliving volumio[830]: info: Ignoring mount for partition: volumio Mar 08 19:08:49 musicliving volumio[830]: info: Ignoring mount for partition: volumio_data Mar 08 19:08:49 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 19:08:49 musicliving volumio[830]: info: Loading plugin "alarm-clock"... Mar 08 19:08:49 musicliving systemd[1]: Reached target Multi-User System. Mar 08 19:08:49 musicliving sudo[861]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:49 musicliving systemd[1]: Reached target Graphical Interface. Mar 08 19:08:49 musicliving systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 08 19:08:49 musicliving systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 08 19:08:49 musicliving systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 08 19:08:49 musicliving systemd[1]: Startup finished in 9.459s (kernel) + 36.444s (userspace) = 45.903s. Mar 08 19:08:49 musicliving kernel: FS-Cache: Netfs 'cifs' registered for caching Mar 08 19:08:49 musicliving kernel: Key type cifs.spnego registered Mar 08 19:08:49 musicliving kernel: Key type cifs.idmap registered Mar 08 19:08:49 musicliving kernel: CIFS: Attempting to mount //nas02.fritz.box/Multimedia\Musik Mar 08 19:08:49 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 19:08:49 musicliving volumio[830]: info: Plugin airplay_emulation is not enabled Mar 08 19:08:49 musicliving volumio[830]: info: Loading plugin "last_100"... Mar 08 19:08:49 musicliving volumio[830]: info: Loading plugin "webradio"... Mar 08 19:08:50 musicliving volumio[830]: info: Loading plugin "i2s_dacs"... Mar 08 19:08:50 musicliving volumio[830]: info: I2S DAC not set, start Auto-detection Mar 08 19:08:50 musicliving volumio[830]: info: Loading plugin "volumiodiscovery"... Mar 08 19:08:50 musicliving volumio[830]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 08 19:08:50 musicliving volumio[830]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 19:08:50 musicliving volumio[830]: *** WARNING *** For more information see Mar 08 19:08:50 musicliving node[830]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 08 19:08:50 musicliving volumio[830]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 08 19:08:50 musicliving volumio[830]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 19:08:50 musicliving volumio[830]: *** WARNING *** For more information see Mar 08 19:08:50 musicliving node[830]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 19:08:50 musicliving node[830]: *** WARNING *** For more information see Mar 08 19:08:50 musicliving node[830]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 08 19:08:50 musicliving node[830]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 19:08:50 musicliving node[830]: *** WARNING *** For more information see Mar 08 19:08:50 musicliving volumio[830]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 08 19:08:50 musicliving volumio[830]: info: Discovery: Started advertising with name: musicliving Mar 08 19:08:50 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 19:08:50 musicliving volumio[830]: info: Loading plugin "ytcr"... Mar 08 19:08:50 musicliving sh[388]: timed out Mar 08 19:08:50 musicliving dhcpcd[429]: timed out Mar 08 19:08:50 musicliving sh[388]: dhcpcd exited Mar 08 19:08:50 musicliving dhcpcd[429]: dhcpcd exited Mar 08 19:08:50 musicliving sh[388]: ifup: failed to bring up eth0 Mar 08 19:08:50 musicliving systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 08 19:08:50 musicliving systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 08 19:08:50 musicliving sudo[882]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:51 musicliving volumio[830]: info: Loading plugin "outputs"... Mar 08 19:08:51 musicliving volumio[830]: info: Loading plugin "albumart"... Mar 08 19:08:51 musicliving volumio[830]: info: Plugin example_plugin is not enabled Mar 08 19:08:51 musicliving volumio[830]: info: Loading plugin "inputs"... Mar 08 19:08:51 musicliving volumio[830]: info: Loading plugin "updater_comm"... Mar 08 19:08:51 musicliving volumio[830]: info: Plugin mpdemulation is not enabled Mar 08 19:08:51 musicliving volumio[830]: info: Loading plugin "rest_api"... Mar 08 19:08:51 musicliving volumio[830]: info: Loading plugin "websocket"... Mar 08 19:08:51 musicliving volumio[830]: info: Loading plugin "podcast"... Mar 08 19:08:51 musicliving volumio[830]: Forking 3 albumart workers Mar 08 19:08:51 musicliving volumio[830]: info: ControllerPodcast::constructor Mar 08 19:08:51 musicliving sudo[853]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:51 musicliving volumio[830]: info: Loading plugin "Systeminfo"... Mar 08 19:08:52 musicliving volumio[830]: Starting albumart workers Mar 08 19:08:52 musicliving volumio[830]: Starting albumart workers Mar 08 19:08:52 musicliving volumio[830]: Starting albumart workers Mar 08 19:08:52 musicliving volumio[830]: info: Loading i18n strings for locale de Mar 08 19:08:52 musicliving volumio[830]: Updating browse sources language Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::initPlayerControls Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: Express server listening on port 3000 Mar 08 19:08:52 musicliving volumio[830]: [Metrics] WebUI: 6s 998.91ms Mar 08 19:08:52 musicliving volumio[830]: info: CoreStateMachine::resetVolumioState Mar 08 19:08:52 musicliving volumio[830]: info: CoreStateMachine::getcurrentVolume Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::volumioRetrievevolume Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: MPD Permissions set Mar 08 19:08:52 musicliving volumio[830]: 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 19:08:52 musicliving volumio[830]: 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 19:08:52 musicliving volumio[830]: verbose: New Socket.io Connection to 172.16.0.49:3000 from 172.16.0.39 UA: node-XMLHttpRequest Total Clients: 3 Mar 08 19:08:52 musicliving volumio[830]: info: CoreStateMachine::pushState Mar 08 19:08:52 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::volumioPushState Mar 08 19:08:52 musicliving volumio[830]: info: CoreStateMachine::updateTrackBlock Mar 08 19:08:52 musicliving volumio[830]: info: CorePlayQueue::getTrackBlock Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::volumioRetrievevolume Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:08:52 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:08:52 musicliving volumio[830]: info: MPD running with PID868 Mar 08 19:08:52 musicliving volumio[830]: ,establishing connection Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:52 musicliving volumio[830]: info: Reloading queue from file Mar 08 19:08:53 musicliving volumio[830]: error: updateQueue error: null Mar 08 19:08:53 musicliving volumio[830]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 08 19:08:53 musicliving volumio[830]: 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: 4 Mar 08 19:08:53 musicliving volumio[830]: 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 19:08:53 musicliving volumio[830]: info: CoreStateMachine::pushState Mar 08 19:08:53 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioPushState Mar 08 19:08:53 musicliving volumio[830]: info: Setting Device type: Raspberry PI Mar 08 19:08:53 musicliving volumio[830]: info: CoreStateMachine::setRepeat null single undefined Mar 08 19:08:53 musicliving volumio[830]: info: CoreStateMachine::pushState Mar 08 19:08:53 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioPushState Mar 08 19:08:53 musicliving volumio[830]: info: CoreStateMachine::setRandom false Mar 08 19:08:53 musicliving volumio[830]: info: CoreStateMachine::pushState Mar 08 19:08:53 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioPushState Mar 08 19:08:53 musicliving volumio[830]: info: Listing playlists Mar 08 19:08:53 musicliving volumio[830]: info: Listing playlists Mar 08 19:08:53 musicliving volumio[830]: 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 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 08 19:08:53 musicliving volumio[830]: info: Completed loading Core Plugins Mar 08 19:08:53 musicliving volumio[830]: info: Preparing to generate the ALSA configuration file Mar 08 19:08:53 musicliving volumio[830]: info: Discovery: adding 8a8eaf5c-8893-482f-b7b1-91391bd03f86 Mar 08 19:08:53 musicliving volumio[830]: info: Discovery: Found device musicliving Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:08:53 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:08:53 musicliving volumio[830]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 08 19:08:53 musicliving volumio[830]: info: Reading ALSA contributions from plugins. Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:08:53 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:08:53 musicliving volumio[830]: info: Asound.conf file written Mar 08 19:08:53 musicliving sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 08 19:08:53 musicliving sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:53 musicliving sudo[994]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:53 musicliving volumio[830]: info: Output device has changed, restarting MPD Mar 08 19:08:53 musicliving sudo[999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 08 19:08:53 musicliving sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:53 musicliving volumio[830]: info: ___________ START PLUGINS ___________ Mar 08 19:08:53 musicliving sudo[999]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 08 19:08:53 musicliving volumio[830]: info: [1678298933236] CoreMusicLibrary::Adding element Media Servers Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 19:08:53 musicliving volumio[830]: Cannot find translation for sourceMedia Servers Mar 08 19:08:53 musicliving sudo[1002]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 08 19:08:53 musicliving sudo[1002]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:08:53 musicliving systemd[1]: Stopping Music Player Daemon... Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 08 19:08:53 musicliving volumio[830]: info: [1678298933265] CoreMusicLibrary::Adding element Last_100 Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 19:08:53 musicliving volumio[830]: Cannot find translation for sourceMedia Servers Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 08 19:08:53 musicliving volumio[830]: info: [1678298933268] CoreMusicLibrary::Adding element Webradio Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 19:08:53 musicliving volumio[830]: Cannot find translation for sourceMedia Servers Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 19:08:53 musicliving systemd[1]: mpd.service: Succeeded. Mar 08 19:08:53 musicliving systemd[1]: Stopped Music Player Daemon. Mar 08 19:08:53 musicliving systemd[1]: Starting Music Player Daemon... Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 08 19:08:53 musicliving volumio[830]: info: [1678298933306] CoreMusicLibrary::Adding element Podcast Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 19:08:53 musicliving volumio[830]: Cannot find translation for sourceMedia Servers Mar 08 19:08:53 musicliving volumio[830]: Cannot find translation for sourcePodcast Mar 08 19:08:53 musicliving volumio[830]: info: Volumio Calling Home Mar 08 19:08:53 musicliving volumio[830]: error: MPD error: Error: write EPIPE Mar 08 19:08:53 musicliving volumio[830]: 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 19:08:53 musicliving volumio[830]: info: MPD Permissions set Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:08:53 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:08:53 musicliving volumio[830]: info: Volumio called home Mar 08 19:08:53 musicliving volumio[830]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:08:53 musicliving volumio[830]: info: Discovery: adding 70428f1a-7827-4e3f-9bb4-5b3f016572b3 Mar 08 19:08:53 musicliving volumio[830]: info: Discovery: Found device musicsewing Mar 08 19:08:53 musicliving volumio[830]: info: Discovery: Connecting to remote: 172.16.0.39 Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 19:08:53 musicliving volumio[830]: info: Discovery: Connected to remote: 172.16.0.39 Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:08:53 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:08:53 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:08:53 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioGetQueue Mar 08 19:08:53 musicliving volumio[830]: info: CoreStateMachine::getQueue Mar 08 19:08:53 musicliving volumio[830]: info: CorePlayQueue::getQueue Mar 08 19:08:53 musicliving volumio[830]: info: Listing playlists Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 08 19:08:53 musicliving volumio[830]: info: Received Get System Info Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:08:53 musicliving volumio[830]: info: Discovery: Getting this device information Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:08:53 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:08:53 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:08:53 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:08:54 musicliving mpd[1009]: Mar 08 19:08 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 08 19:08:54 musicliving mpd[1009]: Mar 08 19:08 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 08 19:08:54 musicliving mpd[1009]: Mar 08 19:08 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 08 19:08:54 musicliving systemd[1]: Started Music Player Daemon. Mar 08 19:08:54 musicliving sudo[1002]: pam_unix(sudo:session): session closed for user root Mar 08 19:08:54 musicliving volumio[830]: error: updateQueue error: null Mar 08 19:08:54 musicliving volumio[830]: [yt-cast-receiver] Listening on port 8098 Mar 08 19:08:54 musicliving volumio[830]: info: Completed starting Core Plugins Mar 08 19:08:54 musicliving volumio[830]: info: ------------------------------------------- Mar 08 19:08:54 musicliving volumio[830]: info: ----- MyVolumio plugins startup ---- Mar 08 19:08:54 musicliving volumio[830]: info: ------------------------------------------- Mar 08 19:08:54 musicliving volumio[830]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 08 19:08:55 musicliving volumio-remote-updater[466]: [2023-03-08 19:08:55] [connect] Successful connection Mar 08 19:08:55 musicliving volumio-remote-updater[466]: [2023-03-08 19:08:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1678298935 101 Mar 08 19:08:55 musicliving volumio[830]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 9 Mar 08 19:08:57 musicliving systemd[1]: systemd-hostnamed.service: Succeeded. Mar 08 19:08:58 musicliving volumio[830]: error: updateQueue error: null Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 08 19:09:02 musicliving volumio[830]: info: Adding plugin bluetooth to MyMusic Plugins Mar 08 19:09:02 musicliving volumio[830]: info: Adding plugin multiroom to MyMusic Plugins Mar 08 19:09:02 musicliving volumio[830]: info: Adding plugin metavolumio to MyMusic Plugins Mar 08 19:09:02 musicliving volumio[830]: info: Adding plugin cd_controller to MyMusic Plugins Mar 08 19:09:02 musicliving volumio[830]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 08 19:09:02 musicliving volumio[830]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 08 19:09:02 musicliving volumio[830]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 08 19:09:03 musicliving volumio[830]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 08 19:09:03 musicliving volumio[830]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 08 19:09:03 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:03 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:03 musicliving volumio[830]: info: Starting MyVolumio Remote Streaming Endpoints Mar 08 19:09:03 musicliving volumio[830]: info: MyVolumio login type: Token Mar 08 19:09:03 musicliving volumio[830]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 08 19:09:03 musicliving volumio[830]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 08 19:09:04 musicliving volumio[830]: info: Starting Streaming Service Transparent Proxy Mar 08 19:09:04 musicliving volumio[830]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 08 19:09:04 musicliving volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 08 19:09:04 musicliving volumio[830]: info: Streaming services startup Mar 08 19:09:04 musicliving volumio[830]: info: Starting Streaming Daemon Mar 08 19:09:04 musicliving sudo[1026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 08 19:09:04 musicliving sudo[1026]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:04 musicliving sudo[1026]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:04 musicliving volumio[830]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 08 19:09:04 musicliving volumio[830]: error: Cannot start Volumio Streaming Daemon Mar 08 19:09:04 musicliving volumio[830]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 08 19:09:04 musicliving volumio[830]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 08 19:09:04 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:09:04 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:09:04 musicliving volumio[830]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 08 19:09:04 musicliving volumio[830]: info: MYVOLUMIO: Adding device Mar 08 19:09:04 musicliving volumio[830]: info: MYVOLUMIO: Evaluating Server Mar 08 19:09:05 musicliving volumio[830]: STREAMING PROXY: Starting server on port 3245 Mar 08 19:09:05 musicliving volumio[830]: Node JS runtime: 14 Mar 08 19:09:05 musicliving volumio[830]: info: MyVolumio Plan changed: premium Mar 08 19:09:05 musicliving volumio[830]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 08 19:09:05 musicliving volumio[830]: info: Removing browser output: myVolumio user plan is not superstar Mar 08 19:09:05 musicliving volumio[830]: info: Removing audio output: Mar 08 19:09:05 musicliving volumio[830]: info: MYVOLUMIO: Adding device Mar 08 19:09:05 musicliving volumio[830]: info: MYVOLUMIO: Evaluating Server Mar 08 19:09:05 musicliving volumio[830]: info: Remote config written successfully Mar 08 19:09:05 musicliving volumio[830]: info: Starting Tunnel 1 Mar 08 19:09:05 musicliving volumio[830]: info: Starting Tunnel Connection Checker Mar 08 19:09:05 musicliving volumio[830]: info: Completed starting MyVolumio Plugin Mar 08 19:09:05 musicliving volumio[830]: info: MYVolumio Device enabled Mar 08 19:09:05 musicliving volumio[830]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 08 19:09:05 musicliving volumio[830]: info: MyVolumio status changed Mar 08 19:09:05 musicliving volumio[830]: info: Streaming services startup Mar 08 19:09:05 musicliving volumio[830]: info: Starting Streaming Daemon Mar 08 19:09:06 musicliving sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 08 19:09:06 musicliving sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:06 musicliving volumio[830]: info: Setting Geolocation for MyVolumio to eu10 Mar 08 19:09:06 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:06 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:06 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:06 musicliving sudo[1064]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:06 musicliving volumio[830]: error: Cannot start Volumio Streaming Daemon Mar 08 19:09:06 musicliving volumio[830]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 08 19:09:06 musicliving volumio[830]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 08 19:09:06 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 08 19:09:06 musicliving volumio[830]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 08 19:09:06 musicliving volumio[830]: info: [1678298946214] Starting BluetoothController Mar 08 19:09:06 musicliving volumio[830]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 08 19:09:07 musicliving sudo[1067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast Mar 08 19:09:07 musicliving sudo[1067]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:07 musicliving sudo[1067]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:07 musicliving sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 08 19:09:07 musicliving sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:07 musicliving sudo[1070]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:07 musicliving volumio[830]: info: MRS: MultiRoom plugin initialized Mar 08 19:09:07 musicliving volumio[830]: info: MRS: STOPPING SNAPCLIENT Mar 08 19:09:07 musicliving volumio[830]: info: MRS: Snap server stop Mar 08 19:09:07 musicliving sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 08 19:09:07 musicliving sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:07 musicliving volumio[830]: info: MRS: STOPPING volumioStreaming Mar 08 19:09:07 musicliving sudo[1090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 08 19:09:07 musicliving sudo[1090]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:07 musicliving sudo[1087]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:07 musicliving sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 08 19:09:07 musicliving sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 08 19:09:07 musicliving sudo[1096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 08 19:09:07 musicliving sudo[1096]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:07 musicliving sudo[1096]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 08 19:09:07 musicliving sudo[1090]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:07 musicliving sudo[1092]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 08 19:09:07 musicliving volumio[830]: info: Preparing to generate the ALSA configuration file Mar 08 19:09:07 musicliving volumio[830]: info: Updating MyVolumio device info Mar 08 19:09:07 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:07 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:07 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:07 musicliving volumio[830]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 08 19:09:07 musicliving volumio[830]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 08 19:09:07 musicliving volumio[830]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 08 19:09:07 musicliving volumio[830]: info: Reading ALSA contributions from plugins. Mar 08 19:09:07 musicliving volumio[830]: info: Setting Geolocation for MyVolumio to eu7 Mar 08 19:09:07 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:07 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:07 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:07 musicliving volumio[830]: info: MRS: Removed streaming files Mar 08 19:09:07 musicliving volumio[830]: info: MRS: volumioStreaming STOPPED Mar 08 19:09:07 musicliving volumio[830]: info: MRS: SNAPSERVER STOPPED Mar 08 19:09:07 musicliving volumio[830]: info: MRS: SNAPCLIENT STOPPED Mar 08 19:09:07 musicliving volumio[830]: info: Snap server and client installed Mar 08 19:09:07 musicliving volumio[830]: info: Asound.conf file written Mar 08 19:09:07 musicliving sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 08 19:09:07 musicliving sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:07 musicliving sudo[1099]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:07 musicliving volumio[830]: info: Output device has changed, restarting MPD Mar 08 19:09:07 musicliving sudo[1104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 08 19:09:07 musicliving sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:07 musicliving volumio[830]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 08 19:09:07 musicliving sudo[1104]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:07 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:09:07 musicliving sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 08 19:09:07 musicliving sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 08 19:09:07 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 19:09:07 musicliving systemd[1]: Stopping Music Player Daemon... Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Mar 08 19:09:07 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 08 19:09:07 musicliving volumio[830]: info: MRS: Pushing multiroomSync output for this device Mar 08 19:09:07 musicliving volumio[830]: info: MRS: Pushing multiroomSync output Mar 08 19:09:07 musicliving sudo[1112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name musicliving Mar 08 19:09:07 musicliving sudo[1112]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:07 musicliving systemd[1]: mpd.service: Succeeded. Mar 08 19:09:07 musicliving systemd[1]: Stopped Music Player Daemon. Mar 08 19:09:07 musicliving volumio[830]: info: Adding audio output: Mar 08 19:09:07 musicliving volumio[830]: info: Adding audio output: Mar 08 19:09:07 musicliving systemd[1]: Starting Music Player Daemon... Mar 08 19:09:07 musicliving sudo[1112]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:07 musicliving volumio[830]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 08 19:09:07 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Mar 08 19:09:07 musicliving volumio[830]: info: Adding METAVOLUMIO REST API Endpoints Mar 08 19:09:07 musicliving volumio[830]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 08 19:09:07 musicliving volumio[830]: info: Adding Manifest REST API Endpoints Mar 08 19:09:07 musicliving volumio[830]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Mar 08 19:09:07 musicliving volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Mar 08 19:09:08 musicliving volumio[830]: error: Hi Res Audio Failed Login: Missing Login Data Mar 08 19:09:08 musicliving volumio[830]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 08 19:09:08 musicliving volumio[830]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 08 19:09:08 musicliving volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 08 19:09:08 musicliving volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 08 19:09:08 musicliving volumio[830]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 08 19:09:08 musicliving volumio[830]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Mar 08 19:09:08 musicliving volumio[830]: info: Stopping AccessToken refresher cron Mar 08 19:09:08 musicliving volumio[830]: info: AccessToken refresher cron started Mar 08 19:09:08 musicliving volumio[830]: info: Stopping AccessToken refresher cron for QOBUZ Mar 08 19:09:08 musicliving volumio[830]: info: AccessToken refresher cron started for QOBUZ Mar 08 19:09:08 musicliving volumio[830]: info: Adding TIDAL REST API Endpoints Mar 08 19:09:08 musicliving volumio[830]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 08 19:09:08 musicliving volumio[830]: info: Adding QOBUZ REST API Endpoints Mar 08 19:09:08 musicliving volumio[830]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 08 19:09:08 musicliving volumio[830]: info: Bluetooth name changed to musicliving Mar 08 19:09:08 musicliving volumio[830]: info: MPD Permissions set Mar 08 19:09:08 musicliving volumio[830]: info: Successfully Added MyVolumio device Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:09:08 musicliving volumio[830]: info: BOOT COMPLETED Mar 08 19:09:08 musicliving volumio[830]: [Metrics] CommandRouter: 21s 837.95ms Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::volumiosetStartupVolume Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::Close All Modals sent Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::Close All Modals sent Mar 08 19:09:08 musicliving volumio[830]: info: MRS: Adding multiroomSync output Mar 08 19:09:08 musicliving volumio[830]: info: Adding audio output: Mar 08 19:09:08 musicliving volumio[830]: info: MRS: Pushing multiroomSync output Mar 08 19:09:08 musicliving mpd[1119]: Mar 08 19:09 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 08 19:09:08 musicliving mpd[1119]: Mar 08 19:09 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 08 19:09:08 musicliving mpd[1119]: Mar 08 19:09 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 08 19:09:08 musicliving systemd[1]: Started Music Player Daemon. Mar 08 19:09:08 musicliving sudo[1107]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:08 musicliving volumio[830]: error: updateQueue error: null Mar 08 19:09:08 musicliving volumio[830]: info: Updating MyVolumio device info Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:08 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:09 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 08 19:09:09 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 08 19:09:09 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 08 19:09:09 musicliving volumio[830]: info: Successfully Added MyVolumio device Mar 08 19:09:09 musicliving volumio[830]: info: Successfully Updated MyVolumio device Mar 08 19:09:09 musicliving volumio[830]: info: Successfully Updated MyVolumio device Mar 08 19:09:10 musicliving sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 08 19:09:10 musicliving sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:10 musicliving systemd[1]: Started MyVolumio SSH Tunnel. Mar 08 19:09:10 musicliving sudo[1135]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:10 musicliving volumio[830]: info: Remote SSH Started Mar 08 19:09:10 musicliving autossh[1138]: port set to 0, monitoring disabled Mar 08 19:09:10 musicliving autossh[1138]: starting ssh (count 1) Mar 08 19:09:10 musicliving autossh[1138]: ssh child pid is 1141 Mar 08 19:09:10 musicliving volumio[830]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 10 Mar 08 19:09:10 musicliving volumiossh-tunnel[1137]: Warning: Permanently added '[eu7.myvolumio.org]:2222,[167.71.43.69]:2222' (RSA) to the list of known hosts. Mar 08 19:09:10 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:09:10 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:09:10 musicliving sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 08 19:09:10 musicliving sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:11 musicliving systemd[1]: Started Volumio Bluetooth Module. Mar 08 19:09:11 musicliving sudo[1143]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:11 musicliving volumio[830]: info: Volumio BT Module successfully started Mar 08 19:09:11 musicliving sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Mar 08 19:09:11 musicliving sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:11 musicliving volumiobt[1145]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Mar 08 19:09:11 musicliving sudo[1146]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:12 musicliving sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Mar 08 19:09:12 musicliving sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:09:12 musicliving volumiobt[1145]: pulseaudio: no process found Mar 08 19:09:12 musicliving sudo[1149]: pam_unix(sudo:session): session closed for user root Mar 08 19:09:12 musicliving dbus-daemon[475]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.17' (uid=1000 pid=1153 comm="/usr/bin/pulseaudio --high-priority=true ") Mar 08 19:09:12 musicliving systemd[1]: Starting RealtimeKit Scheduling Policy Service... Mar 08 19:09:12 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 19:09:12 musicliving dbus-daemon[475]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Mar 08 19:09:12 musicliving systemd[1]: Started RealtimeKit Scheduling Policy Service. Mar 08 19:09:12 musicliving rtkit-daemon[1154]: Successfully called chroot. Mar 08 19:09:12 musicliving rtkit-daemon[1154]: Successfully dropped privileges. Mar 08 19:09:12 musicliving rtkit-daemon[1154]: Successfully limited resources. Mar 08 19:09:12 musicliving rtkit-daemon[1154]: Running. Mar 08 19:09:12 musicliving rtkit-daemon[1154]: Canary thread running. Mar 08 19:09:12 musicliving rtkit-daemon[1154]: Watchdog thread running. Mar 08 19:09:12 musicliving dbus-daemon[475]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.18' (uid=0 pid=1154 comm="/usr/lib/rtkit/rtkit-daemon ") Mar 08 19:09:12 musicliving systemd[1]: Starting Authorization Manager... Mar 08 19:09:12 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 19:09:12 musicliving polkitd[1157]: started daemon version 0.105 using authority implementation `local' version `0.105' Mar 08 19:09:12 musicliving dbus-daemon[475]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Mar 08 19:09:12 musicliving systemd[1]: Started Authorization Manager. Mar 08 19:09:12 musicliving pulseaudio[1153]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 08 19:09:12 musicliving pulseaudio[1153]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 08 19:09:12 musicliving bluetoothd[692]: Endpoint registered: sender=:1.38 path=/MediaEndpoint/A2DPSource Mar 08 19:09:12 musicliving bluetoothd[692]: Endpoint registered: sender=:1.38 path=/MediaEndpoint/A2DPSink Mar 08 19:09:12 musicliving volumiobt[1145]: Applying permissions Mar 08 19:09:12 musicliving volumiobt[1145]: Setting BT discoverable and pairable Mar 08 19:09:12 musicliving pulseaudio[1153]: 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 19:09:12 musicliving kernel: Bluetooth: RFCOMM TTY layer initialized Mar 08 19:09:12 musicliving kernel: Bluetooth: RFCOMM socket layer initialized Mar 08 19:09:12 musicliving kernel: Bluetooth: RFCOMM ver 1.11 Mar 08 19:09:12 musicliving volumiobt[1145]: Agent registered Mar 08 19:09:12 musicliving volumiobt[1145]: [bluetooth]# -e power on Mar 08 19:09:12 musicliving volumiobt[1145]: Invalid command in menu main: -e Mar 08 19:09:12 musicliving volumiobt[1145]: Mar 08 19:09:12 musicliving volumiobt[1145]: Use "help" for a list of available commands in a menu. Mar 08 19:09:12 musicliving volumiobt[1145]: Use "menu " if you want to enter any submenu. Mar 08 19:09:12 musicliving volumiobt[1145]: Use "back" if you want to return to menu main. Mar 08 19:09:12 musicliving volumiobt[1145]: [bluetooth]# agent on Mar 08 19:09:12 musicliving volumiobt[1145]: Agent is already registered Mar 08 19:09:12 musicliving volumiobt[1145]: [bluetooth]# discoverable on Mar 08 19:09:12 musicliving volumiobt[1145]: [bluetooth]# pairable on Mar 08 19:09:12 musicliving volumiobt[1145]: [bluetooth]# agent NoInputNoOutput Mar 08 19:09:12 musicliving volumiobt[1145]: Agent is already registered Mar 08 19:09:12 musicliving volumiobt[1145]: [bluetooth]# default-agent Mar 08 19:09:12 musicliving volumiobt[1145]: [bluetooth]# quit Mar 08 19:09:12 musicliving volumiobt[1145]: [59B blob data] Mar 08 19:09:12 musicliving volumio[830]: info: MRS: Getting audio outputs on start Mar 08 19:09:12 musicliving volumio[830]: info: MRS: Requesting all other devices output Mar 08 19:09:13 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:09:13 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:09:13 musicliving volumio[830]: info: Listing playlists Mar 08 19:09:13 musicliving volumio[830]: info: Listing playlists Mar 08 19:09:18 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 19:09:18 musicliving volumio[830]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Mar 08 19:09:18 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:09:18 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:09:23 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 19:09:23 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 08 19:09:23 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:09:23 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:09:23 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 19:09:23 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:09:23 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 19:09:23 musicliving volumio-remote-updater[466]: No test mode Mar 08 19:09:23 musicliving volumio-remote-updater[466]: No alpha test mode Mar 08 19:09:23 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 08 19:09:23 musicliving volumio[830]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 08 19:09:23 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 08 19:09:29 musicliving ntpd[623]: Soliciting pool server 188.68.36.203 Mar 08 19:09:30 musicliving ntpd[623]: Soliciting pool server 162.55.101.82 Mar 08 19:09:30 musicliving ntpd[623]: Soliciting pool server 129.70.132.37 Mar 08 19:09:30 musicliving ntpd[623]: Soliciting pool server 212.18.3.19 Mar 08 19:09:30 musicliving ntpd[623]: Soliciting pool server 173.249.33.207 Mar 08 19:09:31 musicliving ntpd[623]: Soliciting pool server 129.70.132.34 Mar 08 19:09:31 musicliving ntpd[623]: Soliciting pool server 162.159.200.1 Mar 08 19:09:31 musicliving ntpd[623]: Soliciting pool server 78.46.53.2 Mar 08 19:09:31 musicliving ntpd[623]: Soliciting pool server 167.235.228.35 Mar 08 19:09:32 musicliving ntpd[623]: Soliciting pool server 46.4.54.78 Mar 08 19:09:32 musicliving ntpd[623]: Soliciting pool server 185.13.148.71 Mar 08 19:09:32 musicliving ntpd[623]: Soliciting pool server 144.76.66.156 Mar 08 19:09:32 musicliving ntpd[623]: Soliciting pool server 81.7.4.127 Mar 08 19:09:33 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:09:33 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:09:33 musicliving volumio[830]: info: Listing playlists Mar 08 19:09:33 musicliving volumio[830]: info: Listing playlists Mar 08 19:09:33 musicliving ntpd[623]: Soliciting pool server 94.16.114.254 Mar 08 19:09:33 musicliving ntpd[623]: Soliciting pool server 144.76.138.23 Mar 08 19:09:34 musicliving ntpd[623]: Soliciting pool server 89.58.51.72 Mar 08 19:09:35 musicliving volumio[830]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Mar 08 19:09:35 musicliving ntpd[623]: Soliciting pool server 2a01:238:4275:4d00:b851:6489:fb18:f738 Mar 08 19:09:37 musicliving volumio[830]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/spop/volumio/buster/armhf Mar 08 19:09:37 musicliving volumio[830]: info: Folder /tmp/plugins removed Mar 08 19:10:03 musicliving ntpd[623]: receive: Unexpected origin timestamp 0xe7b34de1.f3b6f387 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xe7b34dfb.af3cfa29 Mar 08 19:10:03 musicliving volumio[830]: info: Check plugin dependencies Mar 08 19:10:03 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 19:10:03 musicliving volumio[830]: info: Checking if plugin already exists Mar 08 19:10:03 musicliving volumio[830]: info: Rename folder Mar 08 19:10:03 musicliving volumio[830]: info: Folder /tmp/downloaded_plugin.zip removed Mar 08 19:10:03 musicliving volumio[830]: info: Move to category Mar 08 19:10:04 musicliving volumio[830]: info: Checking if install.sh is present Mar 08 19:10:04 musicliving volumio[830]: info: Executing install.sh Mar 08 19:10:04 musicliving sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/music_service/spop/install.sh Mar 08 19:10:04 musicliving sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:10:04 musicliving volumio[830]: info: Launching a bash shell Mar 08 19:10:04 musicliving volumio[830]: info: Installing spop dependencies Mar 08 19:10:04 musicliving volumio[830]: info: Detected cpu architecture as armv7l Mar 08 19:10:04 musicliving volumio[830]: info: Latest version: v0.2.5 Requested version: v0.2.5 Mar 08 19:10:04 musicliving volumio[830]: info: Supported device (arch = armv7l), downloading required packages for vollibrespot v0.2.5 Mar 08 19:10:05 musicliving volumio[830]: info: Downloading file Mar 08 19:10:07 musicliving volumio[830]: info: Extracting... Mar 08 19:10:07 musicliving volumio[830]: info: -rw-r--r-- 1 root root 4422967 Mar 8 19:10 vollibrespot-armv7l.tar.xz Mar 08 19:10:07 musicliving sudo[1201]: root : TTY=unknown ; PWD=/data/plugins/music_service/spop ; USER=root ; COMMAND=/bin/tar -xf vollibrespot-armv7l.tar.xz -C /usr/bin Mar 08 19:10:07 musicliving sudo[1201]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:10:07 musicliving sudo[1201]: pam_unix(sudo:session): session closed for user root Mar 08 19:10:07 musicliving volumio[830]: info: Writing systemd unit Mar 08 19:10:07 musicliving sudo[1183]: pam_unix(sudo:session): session closed for user root Mar 08 19:10:07 musicliving volumio[830]: info: Writing startconnect unit Mar 08 19:10:07 musicliving volumio[830]: info: Install script completed Mar 08 19:10:07 musicliving volumio[830]: info: Adding reference to registry Mar 08 19:10:07 musicliving volumio[830]: info: Done installing plugin. Mar 08 19:10:07 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 19:10:07 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 08 19:10:07 musicliving volumio[830]: info: Folder /tmp/plugins removed Mar 08 19:10:07 musicliving volumio[830]: info: Folder /tmp/downloaded_plugin.zip removed Mar 08 19:10:07 musicliving volumio[830]: info: spop installed Mar 08 19:10:07 musicliving volumio[830]: Plugin install end detected on script Mar 08 19:10:07 musicliving volumio[830]: info: Folder /data/temp removed Mar 08 19:10:08 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:10:08 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:10:11 musicliving volumio[830]: info: Enabling plugin spop Mar 08 19:10:11 musicliving volumio[830]: info: Loading plugin "spop"... Mar 08 19:10:12 musicliving volumio[830]: info: PLUGIN START: spop Mar 08 19:10:12 musicliving volumio[830]: info: Checking for old spotify connect plugin installed Mar 08 19:10:12 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 19:10:12 musicliving volumio[830]: [SpotifyConnect] Creating VLS config file Mar 08 19:10:12 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:10:12 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:10:12 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:10:12 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:10:12 musicliving volumio[830]: [SpotifyConnect] Starting metadata listener Mar 08 19:10:12 musicliving volumio[830]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Mar 08 19:10:12 musicliving sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Mar 08 19:10:12 musicliving sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:10:12 musicliving systemd[1]: Started Volspotconnect2 Daemon. Mar 08 19:10:12 musicliving sudo[1207]: pam_unix(sudo:session): session closed for user root Mar 08 19:10:12 musicliving volumio[1209]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Mar 08 19:10:12 musicliving volumio[1209]: Reading Config from "/tmp/volspotify.toml" Mar 08 19:10:12 musicliving volumio[1209]: [Vollibrespot] : Using Alsa backend with device: volumio Mar 08 19:10:12 musicliving volumio[830]: [SpotifyConnect] Vollibrespot Daemon service started! Mar 08 19:10:12 musicliving volumio[830]: [Metrics] SpotifyConnect: 0s 98.51ms Mar 08 19:10:12 musicliving volumio[830]: info: Done. Mar 08 19:10:12 musicliving volumio[1209]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Mar 08 19:10:16 musicliving volumio[830]: info: Enabling plugin spop Mar 08 19:10:16 musicliving volumio[830]: info: Loading plugin "spop"... Mar 08 19:10:16 musicliving volumio[830]: info: PLUGIN START: spop Mar 08 19:10:16 musicliving volumio[830]: info: Checking for old spotify connect plugin installed Mar 08 19:10:16 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 19:10:16 musicliving volumio[830]: [SpotifyConnect] Creating VLS config file Mar 08 19:10:16 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:10:16 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:10:16 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:10:16 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:10:16 musicliving volumio[830]: [SpotifyConnect] Starting metadata listener Mar 08 19:10:16 musicliving volumio[830]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030 Mar 08 19:10:16 musicliving volumio[830]: at dgram.js:337:20 Mar 08 19:10:16 musicliving volumio[830]: at processTicksAndRejections (internal/process/task_queues.js:81:21) Mar 08 19:10:16 musicliving sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Mar 08 19:10:16 musicliving sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:10:16 musicliving volumio[830]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 13 Mar 08 19:10:16 musicliving volumio[830]: info: Checking Spotify Web API Mar 08 19:10:16 musicliving sudo[1212]: pam_unix(sudo:session): session closed for user root Mar 08 19:10:16 musicliving volumio[830]: [SpotifyConnect] Vollibrespot Daemon service started! Mar 08 19:10:16 musicliving volumio[830]: [Metrics] SpotifyConnect: 0s 77.27ms Mar 08 19:10:16 musicliving volumio[830]: info: Done. Mar 08 19:10:18 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:10:18 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:10:18 musicliving volumio[830]: info: Listing playlists Mar 08 19:10:18 musicliving volumio[830]: info: Listing playlists Mar 08 19:10:20 musicliving volumio[830]: info: Checking Spotify Web API Mar 08 19:10:21 musicliving volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 19:10:28 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:10:28 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:10:38 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:10:38 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:10:38 musicliving volumio[830]: info: Listing playlists Mar 08 19:10:38 musicliving volumio[830]: info: Listing playlists Mar 08 19:10:40 musicliving volumio[830]: info: CALLMETHOD: music_service spop saveVolspotconnectAccount [object Object] Mar 08 19:10:40 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: spop , saveVolspotconnectAccount Mar 08 19:10:40 musicliving volumio[830]: [SpotifyConnect] Creating VLS config file Mar 08 19:10:40 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:10:40 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:10:40 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:10:40 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:10:40 musicliving volumio[830]: [SpotifyConnect] Restarting Vollibrespot Daemon Mar 08 19:10:40 musicliving sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Mar 08 19:10:40 musicliving sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:10:40 musicliving systemd[1]: Stopping Volspotconnect2 Daemon... Mar 08 19:10:40 musicliving systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Mar 08 19:10:40 musicliving systemd[1]: volspotconnect.service: Succeeded. Mar 08 19:10:40 musicliving systemd[1]: Stopped Volspotconnect2 Daemon. Mar 08 19:10:40 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 19:10:40 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 08 19:10:40 musicliving sudo[1215]: pam_unix(sudo:session): session closed for user root Mar 08 19:10:40 musicliving systemd[1]: Started Volspotconnect2 Daemon. Mar 08 19:10:40 musicliving volumio[1217]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Mar 08 19:10:40 musicliving volumio[1217]: Reading Config from "/tmp/volspotify.toml" Mar 08 19:10:40 musicliving volumio[1217]: [Vollibrespot] : Using Alsa backend with device: volumio Mar 08 19:10:40 musicliving volumio[830]: [SpotifyConnect] Vollibrespot Daemon service restarted! Mar 08 19:10:40 musicliving volumio[1217]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Mar 08 19:10:40 musicliving volumio[1217]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Mar 08 19:10:40 musicliving volumio[1217]: [Vollibrespot] : Authenticated as "pyorbau7l21bptcob0r8m4197" ! Mar 08 19:10:40 musicliving volumio[1217]: [Vollibrespot] : Using alsa sink Mar 08 19:10:40 musicliving volumio[1217]: [Vollibrespot] : Metadata pipe established Mar 08 19:10:40 musicliving volumio[1217]: [Vollibrespot] : Event: Volume { volume_to_mixer: 32768 } Mar 08 19:10:40 musicliving volumio[1217]: [Vollibrespot] : Country: "DE" Mar 08 19:10:40 musicliving volumio[830]: [SpotifyConnect] Volume Spotify: 50.000762951094835 Volumio: 50 Mar 08 19:10:46 musicliving volumio[830]: info: Checking Spotify Web API Mar 08 19:10:46 musicliving volumio[1217]: [Vollibrespot] : ReqToken Mar 08 19:10:46 musicliving volumio[830]: info: New Spotify Access Token Received Mar 08 19:10:46 musicliving volumio[830]: info: Initializing Spotify Web API Mar 08 19:10:46 musicliving volumio[830]: (node:830) 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: 3) Mar 08 19:10:46 musicliving volumio[830]: (Use `node --trace-warnings ...` to show where the warning was created) Mar 08 19:10:46 musicliving volumio[830]: (node:830) [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 19:10:48 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:10:48 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:10:58 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:10:58 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:10:58 musicliving volumio[830]: info: Listing playlists Mar 08 19:10:58 musicliving volumio[830]: info: Listing playlists Mar 08 19:11:03 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 19:11:03 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 08 19:11:08 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:11:08 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:11:09 musicliving volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 19:11:19 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:11:19 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:11:19 musicliving volumio[830]: info: Listing playlists Mar 08 19:11:19 musicliving volumio[830]: info: Listing playlists Mar 08 19:11:19 musicliving volumio[830]: info: CALLMETHOD: music_service spop saveVolspotconnectAccount [object Object] Mar 08 19:11:19 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: spop , saveVolspotconnectAccount Mar 08 19:11:19 musicliving volumio[830]: [SpotifyConnect] Creating VLS config file Mar 08 19:11:19 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:11:19 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:11:19 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:11:19 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:11:19 musicliving volumio[830]: [SpotifyConnect] Restarting Vollibrespot Daemon Mar 08 19:11:19 musicliving sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Mar 08 19:11:19 musicliving sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:11:19 musicliving systemd[1]: Stopping Volspotconnect2 Daemon... Mar 08 19:11:19 musicliving systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Mar 08 19:11:19 musicliving systemd[1]: volspotconnect.service: Succeeded. Mar 08 19:11:19 musicliving systemd[1]: Stopped Volspotconnect2 Daemon. Mar 08 19:11:19 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 19:11:19 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 08 19:11:19 musicliving systemd[1]: Started Volspotconnect2 Daemon. Mar 08 19:11:19 musicliving sudo[1226]: pam_unix(sudo:session): session closed for user root Mar 08 19:11:19 musicliving volumio[830]: [SpotifyConnect] Vollibrespot Daemon service restarted! Mar 08 19:11:19 musicliving volumio[1228]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Mar 08 19:11:19 musicliving volumio[1228]: Reading Config from "/tmp/volspotify.toml" Mar 08 19:11:19 musicliving volumio[1228]: [Vollibrespot] : Using Alsa backend with device: volumio Mar 08 19:11:19 musicliving volumio[1228]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Mar 08 19:11:20 musicliving volumio[1228]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Mar 08 19:11:20 musicliving volumio[1228]: [Vollibrespot] : Authenticated as "pyorbau7l21bptcob0r8m4197" ! Mar 08 19:11:20 musicliving volumio[1228]: [Vollibrespot] : Using alsa sink Mar 08 19:11:20 musicliving volumio[1228]: [Vollibrespot] : Country: "DE" Mar 08 19:11:20 musicliving volumio[1228]: [Vollibrespot] : Metadata pipe established Mar 08 19:11:20 musicliving volumio[1228]: [Vollibrespot] : Event: Volume { volume_to_mixer: 32768 } Mar 08 19:11:20 musicliving volumio[830]: [SpotifyConnect] Volume Spotify: 50.000762951094835 Volumio: 50 Mar 08 19:11:25 musicliving volumio[830]: info: Checking Spotify Web API Mar 08 19:11:26 musicliving volumio[1228]: [Vollibrespot] : ReqToken Mar 08 19:11:26 musicliving volumio[830]: info: New Spotify Access Token Received Mar 08 19:11:26 musicliving volumio[830]: info: Initializing Spotify Web API Mar 08 19:11:26 musicliving volumio[830]: (node:830) 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: 6) Mar 08 19:11:28 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:11:28 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:11:38 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:11:38 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:11:38 musicliving volumio[830]: info: Listing playlists Mar 08 19:11:38 musicliving volumio[830]: info: Listing playlists Mar 08 19:11:48 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:11:48 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:11:58 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:11:58 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:11:58 musicliving volumio[830]: info: Listing playlists Mar 08 19:11:58 musicliving volumio[830]: info: Listing playlists Mar 08 19:12:08 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:12:08 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:12:18 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:12:18 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:12:18 musicliving volumio[830]: info: Listing playlists Mar 08 19:12:18 musicliving volumio[830]: info: Listing playlists Mar 08 19:12:28 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:12:28 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:12:38 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:12:38 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:12:38 musicliving volumio[830]: info: Listing playlists Mar 08 19:12:38 musicliving volumio[830]: info: Listing playlists Mar 08 19:12:42 musicliving volumio[830]: info: CALLMETHOD: music_service spop saveVolspotconnectAccount [object Object] Mar 08 19:12:42 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: spop , saveVolspotconnectAccount Mar 08 19:12:42 musicliving volumio[830]: [SpotifyConnect] Creating VLS config file Mar 08 19:12:42 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:12:42 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:12:42 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:12:42 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:12:42 musicliving volumio[830]: [SpotifyConnect] Restarting Vollibrespot Daemon Mar 08 19:12:42 musicliving sudo[1241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Mar 08 19:12:42 musicliving sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:12:42 musicliving systemd[1]: Stopping Volspotconnect2 Daemon... Mar 08 19:12:42 musicliving systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Mar 08 19:12:42 musicliving systemd[1]: volspotconnect.service: Succeeded. Mar 08 19:12:42 musicliving systemd[1]: Stopped Volspotconnect2 Daemon. Mar 08 19:12:42 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 19:12:42 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 08 19:12:42 musicliving systemd[1]: Started Volspotconnect2 Daemon. Mar 08 19:12:42 musicliving volumio[1243]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Mar 08 19:12:42 musicliving volumio[1243]: Reading Config from "/tmp/volspotify.toml" Mar 08 19:12:42 musicliving volumio[1243]: [Vollibrespot] : Using Alsa backend with device: volumio Mar 08 19:12:42 musicliving sudo[1241]: pam_unix(sudo:session): session closed for user root Mar 08 19:12:42 musicliving volumio[830]: [SpotifyConnect] Vollibrespot Daemon service restarted! Mar 08 19:12:42 musicliving volumio[1243]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Mar 08 19:12:43 musicliving volumio[1243]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Mar 08 19:12:43 musicliving volumio[1243]: [Vollibrespot] : Authenticated as "pyorbau7l21bptcob0r8m4197" ! Mar 08 19:12:43 musicliving volumio[1243]: [Vollibrespot] : Using alsa sink Mar 08 19:12:43 musicliving volumio[1243]: [Vollibrespot] : Metadata pipe established Mar 08 19:12:43 musicliving volumio[1243]: [Vollibrespot] : Country: "DE" Mar 08 19:12:43 musicliving volumio[1243]: [Vollibrespot] : Event: Volume { volume_to_mixer: 32768 } Mar 08 19:12:43 musicliving volumio[830]: [SpotifyConnect] Volume Spotify: 50.000762951094835 Volumio: 50 Mar 08 19:12:48 musicliving volumio[830]: info: Checking Spotify Web API Mar 08 19:12:48 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:12:48 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:12:49 musicliving volumio[1243]: [Vollibrespot] : ReqToken Mar 08 19:12:49 musicliving volumio[830]: info: New Spotify Access Token Received Mar 08 19:12:49 musicliving volumio[830]: info: Initializing Spotify Web API Mar 08 19:12:49 musicliving volumio[830]: (node:830) 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: 9) Mar 08 19:12:58 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:12:58 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:12:58 musicliving volumio[830]: info: Listing playlists Mar 08 19:12:58 musicliving volumio[830]: info: Listing playlists Mar 08 19:13:04 musicliving volumio[830]: verbose: New Socket.io Connection to musicliving.fritz.box from 172.16.0.20 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:109.0) Gecko/20100101 Firefox/110.0 Total Clients: 8 Mar 08 19:13:04 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:13:04 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:13:04 musicliving volumio[830]: info: CoreCommandRouter::volumioGetQueue Mar 08 19:13:04 musicliving volumio[830]: info: CoreStateMachine::getQueue Mar 08 19:13:04 musicliving volumio[830]: info: CorePlayQueue::getQueue Mar 08 19:13:04 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Mar 08 19:13:08 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:13:08 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:13:18 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:13:18 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:13:18 musicliving volumio[830]: info: Listing playlists Mar 08 19:13:18 musicliving volumio[830]: info: Listing playlists Mar 08 19:13:28 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:13:28 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:13:38 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:13:38 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:13:38 musicliving volumio[830]: info: Listing playlists Mar 08 19:13:38 musicliving volumio[830]: info: Listing playlists Mar 08 19:13:48 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:13:48 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:13:58 musicliving nmbd[606]: [2023/03/08 19:13:58.204248, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Mar 08 19:13:58 musicliving nmbd[606]: query_name_response: Multiple (2) responses received for a query on subnet 172.16.0.49 for name WORKGROUP<1d>. Mar 08 19:13:58 musicliving nmbd[606]: This response was from IP 172.16.0.20, reporting an IP address of 172.16.0.20. Mar 08 19:13:58 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:13:58 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:13:58 musicliving volumio[830]: info: Listing playlists Mar 08 19:13:58 musicliving volumio[830]: info: Listing playlists Mar 08 19:14:08 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:14:08 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:14:12 musicliving volumio[830]: info: CALLMETHOD: system_controller system enableLiveLog true Mar 08 19:14:12 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Mar 08 19:14:12 musicliving volumio[830]: info: Launching a new LiveLog session Mar 08 19:14:19 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:14:19 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:14:19 musicliving volumio[830]: info: Listing playlists Mar 08 19:14:19 musicliving volumio[830]: info: Listing playlists Mar 08 19:14:28 musicliving volumio[830]: info: CALLMETHOD: music_service spop saveVolspotconnectAccount [object Object] Mar 08 19:14:28 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: spop , saveVolspotconnectAccount Mar 08 19:14:28 musicliving volumio[830]: [SpotifyConnect] Creating VLS config file Mar 08 19:14:28 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:14:28 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:14:28 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:14:28 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:14:28 musicliving volumio[830]: [SpotifyConnect] Restarting Vollibrespot Daemon Mar 08 19:14:28 musicliving sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Mar 08 19:14:28 musicliving sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:14:28 musicliving systemd[1]: Stopping Volspotconnect2 Daemon... Mar 08 19:14:28 musicliving systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Mar 08 19:14:28 musicliving systemd[1]: volspotconnect.service: Succeeded. Mar 08 19:14:28 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 19:14:28 musicliving systemd[1]: Stopped Volspotconnect2 Daemon. Mar 08 19:14:28 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 08 19:14:28 musicliving systemd[1]: Started Volspotconnect2 Daemon. Mar 08 19:14:28 musicliving sudo[1259]: pam_unix(sudo:session): session closed for user root Mar 08 19:14:28 musicliving volumio[830]: [SpotifyConnect] Vollibrespot Daemon service restarted! Mar 08 19:14:28 musicliving volumio[1261]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Mar 08 19:14:28 musicliving volumio[1261]: Reading Config from "/tmp/volspotify.toml" Mar 08 19:14:28 musicliving volumio[1261]: [Vollibrespot] : Using Alsa backend with device: volumio Mar 08 19:14:28 musicliving volumio[1261]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Mar 08 19:14:28 musicliving volumio[1261]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Mar 08 19:14:28 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:14:28 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:14:29 musicliving volumio[1261]: [Vollibrespot] : Authenticated as "pyorbau7l21bptcob0r8m4197" ! Mar 08 19:14:29 musicliving volumio[1261]: [Vollibrespot] : Using alsa sink Mar 08 19:14:29 musicliving volumio[1261]: [Vollibrespot] : Country: "DE" Mar 08 19:14:29 musicliving volumio[1261]: [Vollibrespot] : Metadata pipe established Mar 08 19:14:29 musicliving volumio[1261]: [Vollibrespot] : Event: Volume { volume_to_mixer: 32768 } Mar 08 19:14:29 musicliving volumio[830]: [SpotifyConnect] Volume Spotify: 50.000762951094835 Volumio: 50 Mar 08 19:14:34 musicliving volumio[830]: info: Checking Spotify Web API Mar 08 19:14:35 musicliving volumio[1261]: [Vollibrespot] : ReqToken Mar 08 19:14:35 musicliving volumio[830]: info: New Spotify Access Token Received Mar 08 19:14:35 musicliving volumio[830]: info: Initializing Spotify Web API Mar 08 19:14:35 musicliving volumio[830]: (node:830) 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: 12) Mar 08 19:14:38 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:14:38 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:14:38 musicliving volumio[830]: info: Listing playlists Mar 08 19:14:38 musicliving volumio[830]: info: Listing playlists Mar 08 19:14:48 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:14:48 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:14:58 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:14:58 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:14:58 musicliving volumio[830]: info: Listing playlists Mar 08 19:14:58 musicliving volumio[830]: info: Listing playlists Mar 08 19:15:08 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:15:08 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:15:18 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:15:18 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:15:19 musicliving volumio[830]: info: Listing playlists Mar 08 19:15:19 musicliving volumio[830]: info: Listing playlists Mar 08 19:15:24 musicliving volumio[830]: info: CALLMETHOD: music_service spop saveVolspotconnectAccount [object Object] Mar 08 19:15:24 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: spop , saveVolspotconnectAccount Mar 08 19:15:24 musicliving volumio[830]: [SpotifyConnect] Creating VLS config file Mar 08 19:15:24 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:15:24 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:15:24 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:15:24 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:15:24 musicliving volumio[830]: [SpotifyConnect] Restarting Vollibrespot Daemon Mar 08 19:15:24 musicliving sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Mar 08 19:15:24 musicliving sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:15:24 musicliving systemd[1]: Stopping Volspotconnect2 Daemon... Mar 08 19:15:24 musicliving systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Mar 08 19:15:24 musicliving systemd[1]: volspotconnect.service: Succeeded. Mar 08 19:15:24 musicliving systemd[1]: Stopped Volspotconnect2 Daemon. Mar 08 19:15:24 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 19:15:24 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 08 19:15:24 musicliving systemd[1]: Started Volspotconnect2 Daemon. Mar 08 19:15:24 musicliving sudo[1273]: pam_unix(sudo:session): session closed for user root Mar 08 19:15:24 musicliving volumio[830]: [SpotifyConnect] Vollibrespot Daemon service restarted! Mar 08 19:15:24 musicliving volumio[1275]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Mar 08 19:15:24 musicliving volumio[1275]: Reading Config from "/tmp/volspotify.toml" Mar 08 19:15:24 musicliving volumio[1275]: [Vollibrespot] : Using Alsa backend with device: volumio Mar 08 19:15:24 musicliving volumio[1275]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Mar 08 19:15:24 musicliving volumio[1275]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Mar 08 19:15:28 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:15:28 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:15:30 musicliving volumio[830]: info: Checking Spotify Web API Mar 08 19:15:38 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:15:38 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:15:39 musicliving volumio[830]: info: Listing playlists Mar 08 19:15:39 musicliving volumio[830]: info: Listing playlists Mar 08 19:15:48 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:15:48 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:15:59 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:15:59 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:15:59 musicliving volumio[830]: info: Listing playlists Mar 08 19:15:59 musicliving volumio[830]: info: Listing playlists Mar 08 19:16:08 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:16:08 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:16:09 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:16:09 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:16:11 musicliving volumio[830]: info: Executing endpoint metavolumio Mar 08 19:16:11 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 19:16:11 musicliving volumio[830]: info: Executing endpoint metavolumio Mar 08 19:16:11 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 19:16:11 musicliving volumio[830]: info: Executing endpoint metavolumio Mar 08 19:16:11 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 19:16:16 musicliving volumio[830]: Searching plugin music_service/spop Mar 08 19:16:16 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 19:16:16 musicliving volumio[830]: error: An error occurred while searching WebapiError: Unauthorized Mar 08 19:16:18 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:16:18 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:16:19 musicliving volumio[830]: info: Listing playlists Mar 08 19:16:19 musicliving volumio[830]: info: Listing playlists Mar 08 19:16:21 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:16:21 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:16:23 musicliving volumio[830]: info: Executing endpoint metavolumio Mar 08 19:16:23 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 19:16:23 musicliving volumio[830]: info: Executing endpoint metavolumio Mar 08 19:16:23 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 19:16:23 musicliving volumio[830]: info: Executing endpoint metavolumio Mar 08 19:16:23 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 19:16:27 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 19:16:27 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 08 19:16:28 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:16:28 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:16:31 musicliving volumio[830]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 19:16:35 musicliving volumio[830]: info: CALLMETHOD: music_service spop saveVolspotconnectAccount [object Object] Mar 08 19:16:35 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: spop , saveVolspotconnectAccount Mar 08 19:16:35 musicliving volumio[830]: [SpotifyConnect] Creating VLS config file Mar 08 19:16:35 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:16:35 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:16:35 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:16:35 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:16:35 musicliving volumio[830]: [SpotifyConnect] Restarting Vollibrespot Daemon Mar 08 19:16:35 musicliving sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Mar 08 19:16:35 musicliving sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:16:35 musicliving systemd[1]: Stopping Volspotconnect2 Daemon... Mar 08 19:16:35 musicliving systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Mar 08 19:16:35 musicliving systemd[1]: volspotconnect.service: Succeeded. Mar 08 19:16:35 musicliving systemd[1]: Stopped Volspotconnect2 Daemon. Mar 08 19:16:35 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 19:16:35 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 08 19:16:35 musicliving systemd[1]: Started Volspotconnect2 Daemon. Mar 08 19:16:35 musicliving sudo[1286]: pam_unix(sudo:session): session closed for user root Mar 08 19:16:35 musicliving volumio[830]: [SpotifyConnect] Vollibrespot Daemon service restarted! Mar 08 19:16:35 musicliving volumio[1288]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Mar 08 19:16:35 musicliving volumio[1288]: Reading Config from "/tmp/volspotify.toml" Mar 08 19:16:35 musicliving volumio[1288]: [Vollibrespot] : Using Alsa backend with device: volumio Mar 08 19:16:35 musicliving volumio[1288]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Mar 08 19:16:35 musicliving volumio[1288]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Mar 08 19:16:36 musicliving volumio[1288]: [Vollibrespot] : Authenticated as "pyorbau7l21bptcob0r8m4197" ! Mar 08 19:16:36 musicliving volumio[1288]: [Vollibrespot] : Using alsa sink Mar 08 19:16:36 musicliving volumio[1288]: [Vollibrespot] : Country: "DE" Mar 08 19:16:36 musicliving volumio[1288]: [Vollibrespot] : Metadata pipe established Mar 08 19:16:36 musicliving volumio[1288]: [Vollibrespot] : Event: Volume { volume_to_mixer: 32768 } Mar 08 19:16:36 musicliving volumio[830]: [SpotifyConnect] Volume Spotify: 50.000762951094835 Volumio: 50 Mar 08 19:16:36 musicliving volumio[830]: info: CALLMETHOD: music_service spop saveVolspotconnectAccount [object Object] Mar 08 19:16:36 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: spop , saveVolspotconnectAccount Mar 08 19:16:36 musicliving volumio[830]: [SpotifyConnect] Creating VLS config file Mar 08 19:16:36 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:16:36 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:16:36 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:16:36 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:16:36 musicliving volumio[830]: [SpotifyConnect] Restarting Vollibrespot Daemon Mar 08 19:16:36 musicliving sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Mar 08 19:16:36 musicliving sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:16:36 musicliving systemd[1]: Stopping Volspotconnect2 Daemon... Mar 08 19:16:36 musicliving systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Mar 08 19:16:36 musicliving systemd[1]: volspotconnect.service: Succeeded. Mar 08 19:16:36 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 19:16:36 musicliving systemd[1]: Stopped Volspotconnect2 Daemon. Mar 08 19:16:36 musicliving volumio[830]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 08 19:16:36 musicliving systemd[1]: Started Volspotconnect2 Daemon. Mar 08 19:16:36 musicliving sudo[1297]: pam_unix(sudo:session): session closed for user root Mar 08 19:16:36 musicliving volumio[830]: [SpotifyConnect] Vollibrespot Daemon service restarted! Mar 08 19:16:36 musicliving volumio[1299]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Mar 08 19:16:36 musicliving volumio[1299]: Reading Config from "/tmp/volspotify.toml" Mar 08 19:16:36 musicliving volumio[1299]: [Vollibrespot] : Using Alsa backend with device: volumio Mar 08 19:16:36 musicliving volumio[1299]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Mar 08 19:16:36 musicliving volumio[1299]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443" Mar 08 19:16:36 musicliving volumio[1299]: [Vollibrespot] : Authenticated as "pyorbau7l21bptcob0r8m4197" ! Mar 08 19:16:36 musicliving volumio[1299]: [Vollibrespot] : Using alsa sink Mar 08 19:16:36 musicliving volumio[1299]: [Vollibrespot] : Country: "DE" Mar 08 19:16:36 musicliving volumio[1299]: [Vollibrespot] : Metadata pipe established Mar 08 19:16:36 musicliving volumio[1299]: [Vollibrespot] : Event: Volume { volume_to_mixer: 32768 } Mar 08 19:16:36 musicliving volumio[830]: [SpotifyConnect] Volume Spotify: 50.000762951094835 Volumio: 50 Mar 08 19:16:38 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:16:38 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:16:39 musicliving volumio[830]: info: Listing playlists Mar 08 19:16:39 musicliving volumio[830]: info: Listing playlists Mar 08 19:16:41 musicliving volumio[830]: info: Checking Spotify Web API Mar 08 19:16:42 musicliving volumio[1299]: [Vollibrespot] : ReqToken Mar 08 19:16:42 musicliving volumio[830]: info: New Spotify Access Token Received Mar 08 19:16:42 musicliving volumio[830]: info: Initializing Spotify Web API Mar 08 19:16:42 musicliving volumio[830]: (node:830) 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: 15) Mar 08 19:16:42 musicliving volumio[830]: info: Checking Spotify Web API Mar 08 19:16:42 musicliving volumio[1299]: [Vollibrespot] : ReqToken Mar 08 19:16:42 musicliving volumio[830]: info: New Spotify Access Token Received Mar 08 19:16:42 musicliving volumio[830]: info: Initializing Spotify Web API Mar 08 19:16:42 musicliving volumio[830]: (node:830) 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: 17) Mar 08 19:16:49 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:16:49 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:16:58 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:16:58 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:16:59 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:16:59 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:16:59 musicliving volumio[830]: info: Listing playlists Mar 08 19:16:59 musicliving volumio[830]: info: Listing playlists Mar 08 19:17:01 musicliving volumio[830]: info: Executing endpoint metavolumio Mar 08 19:17:01 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 19:17:01 musicliving volumio[830]: info: Executing endpoint metavolumio Mar 08 19:17:01 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 19:17:01 musicliving volumio[830]: info: Executing endpoint metavolumio Mar 08 19:17:01 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 19:17:02 musicliving volumio[830]: info: CoreCommandRouter::volumioPlay Mar 08 19:17:02 musicliving volumio[830]: info: CoreStateMachine::play index undefined Mar 08 19:17:02 musicliving volumio[830]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 19:17:02 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:17:02 musicliving volumio[830]: info: CoreStateMachine::startPlaybackTimer Mar 08 19:17:02 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:17:02 musicliving volumio[830]: info: [1678299422529] ControllerSpotify::clearAddPlayTrack Mar 08 19:17:02 musicliving volumio[830]: info: CoreCommandRouter::volumioGetQueue Mar 08 19:17:02 musicliving volumio[830]: info: CoreStateMachine::getQueue Mar 08 19:17:02 musicliving volumio[830]: info: CorePlayQueue::getQueue Mar 08 19:17:02 musicliving volumio[830]: info: Setting this device active Mar 08 19:17:02 musicliving volumio[830]: error: Failed to Set Device Active: Error: Bad Request Device ID: undefined Mar 08 19:17:09 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:17:09 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:17:10 musicliving volumio[830]: Searching plugin music_service/spop Mar 08 19:17:10 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 19:17:10 musicliving volumio[830]: error: An error occurred while searching WebapiError: Bad OAuth request Mar 08 19:17:18 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:17:19 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:17:19 musicliving volumio[830]: info: Listing playlists Mar 08 19:17:19 musicliving volumio[830]: info: Listing playlists Mar 08 19:17:20 musicliving volumio[830]: Searching plugin music_service/spop Mar 08 19:17:20 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 19:17:20 musicliving volumio[830]: error: An error occurred while searching WebapiError: Bad OAuth request Mar 08 19:17:29 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:17:29 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:17:39 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:17:39 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:17:39 musicliving volumio[830]: info: Listing playlists Mar 08 19:17:39 musicliving volumio[830]: info: Listing playlists Mar 08 19:17:46 musicliving volumio[830]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Mar 08 19:17:46 musicliving volumio[830]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Mar 08 19:17:46 musicliving sudo[1327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Mar 08 19:17:46 musicliving sudo[1327]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:17:46 musicliving sudo[1327]: pam_unix(sudo:session): session closed for user root Mar 08 19:17:49 musicliving volumio[830]: info: CoreCommandRouter::volumioGetState Mar 08 19:17:49 musicliving volumio[830]: info: CorePlayQueue::getTrack 0 Mar 08 19:17:50 musicliving sudo[1372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Mar 08 19:17:50 musicliving sudo[1372]: pam_unix(sudo:session): session opened for user root by (uid=0)