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)