Mon Nov 8 19:14:16 UTC 2021 Description="'Tidal Connect failing after a while - Version 2021-10-29/RPI4/Topping Dx7 PRO/Tidal '" # cat /proc/version --------------- Linux version 5.4.83-v7l+ (dom@buildbot) (gcc version 8.4.0 (Ubuntu/Linaro 8.4.0-3ubuntu1)) #1379 SMP Mon Dec 14 13:11:54 GMT 2020 # 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="c9a59086ecc33ef67efe5050b9e6041222366fda" VOLUMIO_FE_VERSION="00e2e4c742ba2276272c3bbf019e084877078dfe" VOLUMIO_FE3_VERSION="f97ca093505710744c259f0016c7a5c857decb5e" VOLUMIO_BE_VERSION="1ae4f31c145a39358ef738df59a1bad8e976bcd7" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumiobuster" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 29 Oct 2021 03:40:35 PM CEST" VOLUMIO_VERSION="3.129" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="302d5dfd9833ce33b1399bc53bb12afe" # ifconfig --------------- eth0: flags=4099 mtu 1500 ether dc:a6:32:6d:3a:89 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 12703 bytes 2524137 (2.4 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 12703 bytes 2524137 (2.4 MiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 wlan0: flags=4163 mtu 1500 inet 192.168.86.53 netmask 255.255.255.0 broadcast 192.168.86.255 ether dc:a6:32:6d:3a:8b txqueuelen 1000 (Ethernet) RX packets 324928 bytes 408125140 (389.2 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 194402 bytes 22814267 (21.7 MiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- eth0 no wireless extensions. lo no wireless extensions. wlan0 IEEE 802.11 ESSID:"RM" Mode:Managed Frequency:2.412 GHz Access Point: E4:F0:42:CF:FC:78 Bit Rate=58.5 Mb/s Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off Link Quality=70/70 Signal level=-28 dBm Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0 Tx excessive retries:972 Invalid misc:0 Missed beacon:0 # aplay -l --------------- xcb_connection_has_error() returned true **** List of PLAYBACK Hardware Devices **** card 0: b1 [bcm2835 HDMI 1], device 0: bcm2835 HDMI 1 [bcm2835 HDMI 1] Subdevices: 4/4 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 card 1: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones] Subdevices: 4/4 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 card 5: S112 [SMSL M8A 1.1.2], device 0: USB Audio [USB Audio] Subdevices: 1/1 Subdevice #0: subdevice #0 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 0 18:10 ? 00:00:07 /sbin/init root 2 0 0 18:10 ? 00:00:00 [kthreadd] root 3 2 0 18:10 ? 00:00:00 [rcu_gp] root 4 2 0 18:10 ? 00:00:00 [rcu_par_gp] root 7 2 0 18:10 ? 00:00:14 [kworker/u8:0-brcmf_wq/mmc1:0001:1] root 8 2 0 18:10 ? 00:00:00 [mm_percpu_wq] root 9 2 0 18:10 ? 00:00:00 [ksoftirqd/0] root 10 2 0 18:10 ? 00:00:01 [rcu_sched] root 11 2 0 18:10 ? 00:00:00 [migration/0] root 12 2 0 18:10 ? 00:00:00 [cpuhp/0] root 13 2 0 18:10 ? 00:00:00 [cpuhp/1] root 14 2 0 18:10 ? 00:00:00 [migration/1] root 15 2 0 18:10 ? 00:00:00 [ksoftirqd/1] root 18 2 0 18:10 ? 00:00:00 [cpuhp/2] root 19 2 0 18:10 ? 00:00:00 [migration/2] root 20 2 0 18:10 ? 00:00:00 [ksoftirqd/2] root 23 2 0 18:10 ? 00:00:00 [cpuhp/3] root 24 2 0 18:10 ? 00:00:00 [migration/3] root 25 2 0 18:10 ? 00:00:00 [ksoftirqd/3] root 28 2 0 18:10 ? 00:00:00 [kdevtmpfs] root 29 2 0 18:10 ? 00:00:00 [netns] root 31 2 0 18:10 ? 00:00:00 [kworker/1:1-events_power_efficient] root 32 2 0 18:10 ? 00:00:00 [kauditd] root 33 2 0 18:10 ? 00:00:00 [khungtaskd] root 34 2 0 18:10 ? 00:00:00 [oom_reaper] root 35 2 0 18:10 ? 00:00:00 [writeback] root 36 2 0 18:10 ? 00:00:00 [kcompactd0] root 54 2 0 18:10 ? 00:00:00 [kblockd] root 55 2 0 18:10 ? 00:00:00 [blkcg_punt_bio] root 56 2 0 18:10 ? 00:00:00 [watchdogd] root 57 2 0 18:10 ? 00:00:00 [rpciod] root 58 2 0 18:10 ? 00:00:00 [kworker/u9:0-hci0] root 59 2 0 18:10 ? 00:00:00 [xprtiod] root 61 2 0 18:10 ? 00:00:00 [kswapd0] root 62 2 0 18:10 ? 00:00:00 [nfsiod] root 63 2 0 18:10 ? 00:00:00 [kthrotld] root 64 2 0 18:10 ? 00:00:00 [irq/54-aerdrv] root 66 2 0 18:10 ? 00:00:00 [iscsi_eh] root 69 2 0 18:10 ? 00:00:00 [DWC Notificatio] root 70 2 0 18:10 ? 00:00:00 [uas] root 72 2 0 18:10 ? 00:00:00 [vchiq-slot/0] root 73 2 0 18:10 ? 00:00:00 [vchiq-recy/0] root 74 2 0 18:10 ? 00:00:00 [vchiq-sync/0] root 75 2 0 18:10 ? 00:00:00 [vchiq-keep/0] root 76 2 0 18:10 ? 00:00:00 [SMIO] root 78 2 0 18:10 ? 00:00:00 [sdhci] root 79 2 0 18:10 ? 00:00:00 [irq/41-mmc0] root 88 2 0 18:10 ? 00:00:00 [mmc_complete] root 89 2 0 18:10 ? 00:00:00 [kworker/0:1H-mmc_complete] root 107 2 0 18:10 ? 00:00:00 [kworker/2:2-cgroup_destroy] root 112 2 0 18:10 ? 00:00:00 [kworker/3:1H-kblockd] root 118 2 0 18:10 ? 00:00:00 [kworker/1:2H-kblockd] root 119 2 0 18:10 ? 00:00:00 [jbd2/mmcblk0p2-] root 120 2 0 18:10 ? 00:00:00 [ext4-rsv-conver] root 127 2 0 18:10 ? 00:00:01 [loop0] root 130 2 0 18:10 ? 00:00:00 [kworker/2:1H-kblockd] root 155 2 0 18:10 ? 00:00:00 [jbd2/mmcblk0p3-] root 156 2 0 18:10 ? 00:00:00 [ext4-rsv-conver] root 164 2 0 18:10 ? 00:00:00 [ipv6_addrconf] root 195 1 0 18:11 ? 00:00:01 /lib/systemd/systemd-journald root 216 1 0 18:11 ? 00:00:00 /lib/systemd/systemd-udevd root 256 2 0 18:11 ? 00:00:00 [SMIO] root 282 2 0 18:11 ? 00:00:00 [mmal-vchiq] root 285 2 0 18:11 ? 00:00:00 [mmal-vchiq] root 287 2 0 18:11 ? 00:00:00 [mmal-vchiq] root 289 2 0 18:11 ? 00:00:00 [mmal-vchiq] root 317 2 0 18:11 ? 00:00:00 [cfg80211] root 324 2 0 18:11 ? 00:00:00 [brcmf_wq/mmc1:0] root 325 2 0 18:11 ? 00:00:01 [brcmf_wdog/mmc1] _rpc 388 1 0 18:11 ? 00:00:00 /sbin/rpcbind -f -w root 394 1 0 18:11 ? 00:00:03 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 root 424 1 0 18:11 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon avahi 430 1 0 18:11 ? 00:00:03 avahi-daemon: running [bureau.local] nobody 433 1 0 18:11 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* volumio 439 1 0 18:11 ? 00:00:00 /usr/local/bin/volumio-remote-updater message+ 449 1 0 18:11 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only avahi 453 430 0 18:11 ? 00:00:00 avahi-daemon: chroot helper root 457 1 0 18:11 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant root 464 1 0 18:11 ? 00:00:00 /lib/systemd/systemd-logind root 556 1 0 18:11 ? 00:00:01 /usr/sbin/nmbd --foreground --no-process-group root 562 2 0 18:11 ? 00:00:00 [kworker/3:4-mm_percpu_wq] root 575 1 0 18:11 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux ntp 578 1 0 18:11 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 588 1 0 18:11 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I root 599 556 0 18:11 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 649 1 0 18:11 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 661 1 0 18:11 ? 00:00:00 /sbin/dhcpcd root 670 2 0 18:11 ? 00:00:00 [kworker/u9:1-hci0] root 672 1 0 18:11 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 3000000 flow - root 685 1 0 18:11 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap volumio 762 1 0 18:11 ? 00:00:34 /usr/bin/node /volumio/index.js root 775 1 0 18:11 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 778 775 0 18:11 ? 00:00:00 winbindd: domain child [BUREAU] root 780 1 0 18:11 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 782 780 0 18:11 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 783 780 0 18:11 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 784 775 0 18:11 ? 00:00:00 winbindd: idmap child root 785 775 0 18:11 ? 00:00:00 winbindd: domain child [BUILTIN] root 787 780 0 18:11 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 825 2 0 18:11 ? 00:00:00 [cifsiod] root 826 2 0 18:11 ? 00:00:00 [smb3decryptd] root 827 2 0 18:11 ? 00:00:00 [cifsfileinfoput] root 828 2 0 18:11 ? 00:00:00 [cifsoplockd] root 830 2 0 18:11 ? 00:00:00 [cifsd] volumio 851 762 0 18:11 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 858 851 0 18:11 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 859 851 0 18:11 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 860 851 0 18:11 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 984 1 0 18:11 ? 00:00:05 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf volumio 1007 1 0 18:11 ? 00:00:00 /bin/streaming-daemon mpd 1085 1 0 18:11 ? 00:00:00 /usr/bin/mpd --no-daemon shairpo+ 1101 1 0 18:11 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf volumio 1105 1 0 18:11 ? 00:00:00 /bin/bash /data/plugins/music_service/volspotconnect2/startconnect.sh volumio 1106 1105 0 18:11 ? 00:00:01 ./vollibrespot -c volspotify.toml volumio 1112 1 0 18:11 ? 00:00:00 /bin/sh /bin/bt/btstart.sh start volumio 1123 1 0 18:11 ? 00:00:00 /bin/sh -c /usr/bin/vtcs volumio 1124 1123 1 18:11 ? 00:00:54 /usr/bin/vtcs volumio 1145 1 0 18:11 ? 00:00:00 /usr/bin/pulseaudio rtkit 1146 1 0 18:11 ? 00:00:00 /usr/lib/rtkit/rtkit-daemon root 1149 1 0 18:11 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 1157 2 0 18:11 ? 00:00:00 [krfcommd] volumio 1158 1112 0 18:11 ? 00:00:00 /usr/bin/python /bin/bt/simple-agent -c NoInputNoOutput volumio 1286 762 0 18:16 ? 00:00:00 /bin/journalctl --output cat -f root 1646 2 0 18:31 ? 00:00:00 [kworker/1:0H] root 1882 2 0 18:39 ? 00:00:00 [kworker/3:0-cgroup_destroy] root 2183 2 0 18:48 ? 00:00:00 [kworker/2:0-mm_percpu_wq] root 2289 2 0 18:53 ? 00:00:01 [kworker/0:0-mm_percpu_wq] root 2353 2 0 18:57 ? 00:00:00 [kworker/u8:1-flush-179:0] root 2354 2 0 18:57 ? 00:00:00 [kworker/2:0H] root 2436 2 0 19:00 ? 00:00:00 [kworker/3:0H] root 2508 2 0 19:03 ? 00:00:00 [kworker/0:0H] root 2533 2 0 19:04 ? 00:00:00 [kworker/0:2-events_power_efficient] root 2585 2 0 19:07 ? 00:00:00 [kworker/1:0-events_power_efficient] root 2622 2 0 19:09 ? 00:00:00 [kworker/0:1-events] root 2627 2 0 19:09 ? 00:00:01 [kworker/u8:2-flush-179:0] root 2781 2 0 19:11 ? 00:00:00 [kworker/0:3-events] root 2800 2 0 19:12 ? 00:00:00 [kworker/1:2-events_power_efficient] root 2807 2 0 19:12 ? 00:00:00 [kworker/3:2H] volumio 2811 1 0 19:12 ? 00:00:00 /bin/sh /tmp/sshtunnel.sh volumio 2812 2811 0 19:12 ? 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 zl6ceo9y0lgxUUC5ZKbyZprINLb2-9421440ae1ee55b3063667265fca75c3.us1.myvolumio.org:8091:localhost:3000 us1.myvolumio.org volumio 2815 2812 0 19:12 ? 00:00:00 /usr/bin/ssh -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R zl6ceo9y0lgxUUC5ZKbyZprINLb2-9421440ae1ee55b3063667265fca75c3.us1.myvolumio.org:8091:localhost:3000 us1.myvolumio.org volumio 2825 762 0 19:14 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'Tidal Connect failing after a while - Version 2021-10-29/RPI4/Topping Dx7 PRO/Tidal ' volumio 2826 2825 0 19:14 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js Tidal Connect failing after a while - Version 2021-10-29/RPI4/Topping Dx7 PRO/Tidal volumio 2846 2826 0 19:14 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 2847 2846 0 19:14 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.5G 541M 1.8G 24% /imgpart /dev/loop0 473M 473M 0 100% /static overlay 27G 78M 25G 1% / devtmpfs 1.9G 0 1.9G 0% /dev tmpfs 1.9G 0 1.9G 0% /dev/shm tmpfs 1.9G 9.0M 1.9G 1% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 1.9G 0 1.9G 0% /sys/fs/cgroup tmpfs 1.9G 0 1.9G 0% /var/spool/cups tmpfs 1.9G 68K 1.9G 1% /tmp tmpfs 20M 28K 20M 1% /var/log tmpfs 1.9G 0 1.9G 0% /var/spool/cups/tmp /dev/mmcblk0p1 92M 61M 31M 67% /boot //192.168.86.52/MOBEKO/MUSIQUE/FLAC 2.7T 2.5T 193G 93% /mnt/NAS/MUSIQUE # 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=1937316k,nr_inodes=141267,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/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) 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/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/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=35,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) //192.168.86.52/MOBEKO/MUSIQUE/FLAC on /mnt/NAS/MUSIQUE type cifs (ro,relatime,vers=3.1.1,cache=strict,username=mpratte,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.86.52,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:139 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:111 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49149 0.0.0.0:* LISTEN 984/upmpdcli tcp 0 0 0.0.0.0:41693 0.0.0.0:* LISTEN 1106/./vollibrespot tcp 0 0 0.0.0.0:445 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49152 0.0.0.0:* LISTEN 984/upmpdcli 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 1145/pulseaudio tcp 0 0 127.0.0.1:34862 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 127.0.0.1:33896 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 127.0.0.1:35218 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 127.0.0.1:34174 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 192.168.86.53:39110 172.67.141.32:443 ESTABLISHED 1007/streaming-daem tcp 0 0 192.168.86.53:50374 192.168.86.54:3000 ESTABLISHED 762/node tcp 0 0 192.168.86.53:49152 192.168.86.249:51405 TIME_WAIT - tcp 0 0 192.168.86.53:39108 172.67.141.32:443 ESTABLISHED 1007/streaming-daem tcp 0 0 127.0.0.1:33902 127.0.0.1:3000 ESTABLISHED 439/volumio-remote- tcp 0 0 127.0.0.1:35886 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 127.0.0.1:35626 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 127.0.0.1:35298 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 127.0.0.1:34946 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 192.168.86.53:59888 35.201.97.85:443 ESTABLISHED 762/node tcp 0 0 127.0.0.1:35444 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 192.168.86.53:47034 162.243.167.188:2222 ESTABLISHED 2815/ssh tcp 0 0 127.0.0.1:35782 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 127.0.0.1:34674 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 127.0.0.1:57704 127.0.0.1:6599 ESTABLISHED 984/upmpdcli tcp 95 0 192.168.86.53:50094 13.33.160.126:443 CLOSE_WAIT 1124/vtcs tcp 0 0 192.168.86.53:49152 192.168.86.249:51330 TIME_WAIT - tcp 0 0 127.0.0.1:35044 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 127.0.0.1:35548 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 192.168.86.53:39088 172.67.141.32:443 ESTABLISHED 1007/streaming-daem tcp 0 0 192.168.86.53:48716 192.168.86.52:445 ESTABLISHED - tcp 0 0 192.168.86.53:58332 84.247.14.230:80 ESTABLISHED 762/node tcp 0 0 127.0.0.1:35134 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 127.0.0.1:34038 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 192.168.86.53:49152 192.168.86.249:51246 TIME_WAIT - tcp 0 0 127.0.0.1:34586 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 127.0.0.1:33916 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 127.0.0.1:34284 127.0.0.1:3000 ESTABLISHED 762/node tcp 0 0 127.0.0.1:57700 127.0.0.1:6599 ESTABLISHED 984/upmpdcli tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 762/node tcp6 0 0 :::3001 :::* LISTEN 851/node tcp6 0 0 :::3005 :::* LISTEN 762/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::7777 :::* LISTEN 1007/streaming-daem tcp6 0 0 :::2019 :::* LISTEN 1124/vtcs tcp6 0 0 :::6599 :::* LISTEN 762/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::4713 :::* LISTEN 1145/pulseaudio tcp6 0 0 127.0.0.1:3000 127.0.0.1:34862 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:33916 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:33896 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:34586 ESTABLISHED 762/node tcp6 0 0 192.168.86.53:3000 192.168.86.46:39724 ESTABLISHED 762/node tcp6 0 0 192.168.86.53:3000 192.168.86.46:39782 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:35782 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:35444 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:34946 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:35298 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:35044 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:57700 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:35548 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:34038 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:33902 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:57704 ESTABLISHED 762/node tcp6 0 0 192.168.86.53:2019 192.168.86.22:39354 ESTABLISHED 1124/vtcs tcp6 0 0 127.0.0.1:3000 127.0.0.1:35626 ESTABLISHED 762/node tcp6 0 0 192.168.86.53:3000 192.168.86.42:52936 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:34674 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:35886 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:35218 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:34174 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:35134 ESTABLISHED 762/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:34284 ESTABLISHED 762/node # cat /etc/asound.conf --------------- pcm.!default { type empty slave.pcm "volumio" } pcm.volumio { type empty slave.pcm "volumioMultiRoomServer" } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/server/switch.target pcm.volumioMultiRoomServer { type volumioswitch target_file "/tmp/multiroom/server/switch.target" control_fifo "/tmp/multiroom/server/switch.fifo" } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/client/switch.target pcm.volumioMultiRoomClient { type volumioswitch target_file "/tmp/multiroom/client/switch.target" control_fifo "/tmp/multiroom/client/switch.fifo" } # This PCM performs any necessary rate/format/channel conversion before sending on to the Server FIFO pcm.volumioMultiRoom { type plug slave { pcm "volumioMultiRoomFIFO" rate 48000 format "S16_LE" channels 2 } rate_converter "speexrate_medium" } # Write to the FIFO watched by the Server pcm.volumioMultiRoomFIFO { type volumiofifo fifo "/tmp/multiroom/server/fifo" clear_on_drop false lead_in_frames 16384 } # This pcm is used by pcm.volumioMultiRoomServer and pcm.volumioMultiRoomClient to play audio on the local output pcm.volumioLocalPlayback { type empty slave.pcm "postMultiRoom" } # This pcm is used by pcm.volumioMultiRoomClient to discard audio and allow for faster switching pcm.volumioDiscard { type null } pcm.postMultiRoom { type empty slave.pcm "volumioOutput" } # There is always a plug before the hardware to be safe pcm.volumioOutput { type plug slave.pcm "volumioHw" } pcm.volumioHw { type hw card "S112" } # cat /var/log/mpd.log --------------- Nov 08 18:10 : zeroconf: No global port, disabling zeroconf Nov 08 18:10 : zeroconf: No global port, disabling zeroconf Nov 08 18:10 : client: [0] opened from local Nov 08 18:10 : exception: No such directory Nov 08 18:10 : client: [1] opened from local Nov 08 18:11 : zeroconf: No global port, disabling zeroconf Nov 08 18:11 : client: [0] opened from local Nov 08 18:11 : client: [1] opened from 127.0.0.1:34938 Nov 08 18:11 : client: [2] opened from 127.0.0.1:34942 Nov 08 18:11 : exception: Failed to read mixer for 'alsa': no such mixer control: PCM Nov 08 18:11 : zeroconf: No global port, disabling zeroconf Nov 08 18:11 : client: [0] opened from local # cat /data/configuration/plugins.json --------------- { "music_service": { "airplay_emulation": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "inputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpd": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "webradio": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "example_plugin": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "last_100": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp_browser": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "streaming_services": { "status": { "type": "string", "value": "STARTING" } }, "radio_paradise": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volspotconnect2": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "cd_controller": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "smart_inputs": { "status": { "type": "string", "value": "STARTED" } }, "hi_res_audio": { "status": { "type": "string", "value": "STARTED" } }, "tidal": { "status": { "type": "string", "value": "STARTED" } }, "qobuz": { "status": { "type": "string", "value": "STARTED" } }, "tidalconnect": { "status": { "type": "string", "value": "STARTED" }, "enabled": { "type": "boolean", "value": true } } }, "audio_interface": { "outputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "alsa_controller": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "bluetooth": { "enabled": { "type": "boolean", "value": true }, "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" } } }, "user_interface": { "websocket": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpdemulation": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STARTED" } }, "rest_api": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "system_controller": { "updater_comm": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "network": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "networkfs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "services": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "system": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "i2s_dacs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumiodiscovery": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumio_command_line_client": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_volumio": { "status": { "type": "string", "value": "STARTED" } } } } # cat /etc/mpd.conf --------------- # Volumio MPD Configuration File # Files and directories ####################################################### music_directory "/var/lib/mpd/music" playlist_directory "/var/lib/mpd/playlists" db_file "/var/lib/mpd/tag_cache" log_file "/var/log/mpd.log" #pid_file "/var/run/mpd/pid" #state_file "/var/lib/mpd/state" #sticker_file "/var/lib/mpd/sticker.sql" ############################################################################### # General music daemon options ################################################ user "mpd" group "audio" bind_to_address "any" #port "6600" #log_level "default" #save_absolute_paths_in_playlists "no" #metadata_to_use "artist,album,title,track,name,genre,date,composer,performer,disc" auto_update "no" #auto_update_depth "3" ############################################################################### # Symbolic link behavior ###################################################### follow_outside_symlinks "yes" follow_inside_symlinks "yes" ############################################################################### # Input ####################################################################### # input { plugin "curl" # proxy "proxy.isp.com:8080" # proxy_user "user" # proxy_password "password" } ############################################################################### # Decoder ################################################################ ############################################################################### # Audio Output ################################################################ resampler { plugin "soxr" quality "very high" threads "1" } audio_output { type "alsa" name "alsa" device "volumio" dop "no" format "384000:32:2" } audio_output { type "fifo" enabled "no" name "multiroom" path "/tmp/snapfifo" format "44100:16:2" } #replaygain "album" #replaygain_preamp "0" volume_normalization "no" ############################################################################### # MPD Internal Buffering ###################################################### audio_buffer_size "2048" ############################################################################### # Resource Limitations ######################################################## #connection_timeout "60" max_connections "20" max_playlist_length "81920" max_command_list_size "81920" max_output_buffer_size "81920" ############################################################################### # Character Encoding ########################################################## filesystem_charset "UTF-8" ############################################################################### # volumio endpointstest --------------- TESTING REMOTE ENDPOINTS https://google.com, 808 ms: OK https://www.googleapis.com, 224 ms: OK https://securetoken.googleapis.com, 235 ms: OK https://myvolumio.firebaseio.com, 369 ms: OK https://functions.volumio.cloud, 512 ms: OK https://oauth-performer.dfs.volumio.org, 556 ms: OK https://browsing-performer.dfs.volumio.org, 933 ms: OK http://cddb.volumio.org, 1552 ms: OK https://functions.volumio.cloud, 542 ms: OK http://pushupdates.volumio.org, 479 ms: OK http://plugins.volumio.org, 1609 ms: OK https://database.volumio.cloud, 520 ms: OK https://radio-directory.firebaseapp.com, 403 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # sudo journalctl -p 7 --------------- -- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Mon 2021-11-08 19:14:19 UTC. -- Feb 14 10:11:59 bureau kernel: Booting Linux on physical CPU 0x0 Feb 14 10:11:59 bureau kernel: Linux version 5.4.83-v7l+ (dom@buildbot) (gcc version 8.4.0 (Ubuntu/Linaro 8.4.0-3ubuntu1)) #1379 SMP Mon Dec 14 13:11:54 GMT 2020 Feb 14 10:11:59 bureau kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Feb 14 10:11:59 bureau kernel: CPU: div instructions available: patching division code Feb 14 10:11:59 bureau kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Feb 14 10:11:59 bureau kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.2 Feb 14 10:11:59 bureau kernel: Memory policy: Data cache writealloc Feb 14 10:11:59 bureau kernel: Reserved memory: created CMA memory pool at 0x0000000029800000, size 64 MiB Feb 14 10:11:59 bureau kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 14 10:11:59 bureau kernel: On node 0 totalpages: 1024000 Feb 14 10:11:59 bureau kernel: DMA zone: 2304 pages used for memmap Feb 14 10:11:59 bureau kernel: DMA zone: 0 pages reserved Feb 14 10:11:59 bureau kernel: DMA zone: 196608 pages, LIFO batch:63 Feb 14 10:11:59 bureau kernel: HighMem zone: 827392 pages, LIFO batch:63 Feb 14 10:11:59 bureau kernel: percpu: Embedded 20 pages/cpu s49804 r8192 d23924 u81920 Feb 14 10:11:59 bureau kernel: pcpu-alloc: s49804 r8192 d23924 u81920 alloc=20*4096 Feb 14 10:11:59 bureau kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 14 10:11:59 bureau kernel: Built 1 zonelists, mobility grouping on. Total pages: 1021696 Feb 14 10:11:59 bureau kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:6D:3A:89 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Feb 14 10:11:59 bureau kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Feb 14 10:11:59 bureau kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 10:11:59 bureau kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 14 10:11:59 bureau kernel: software IO TLB: mapped [mem 0x228c0000-0x268c0000] (64MB) Feb 14 10:11:59 bureau kernel: Memory: 3874636K/4096000K available (10240K kernel code, 739K rwdata, 2816K rodata, 2048K init, 854K bss, 155828K reserved, 65536K cma-reserved, 3309568K highmem) Feb 14 10:11:59 bureau kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 14 10:11:59 bureau kernel: ftrace: allocating 31115 entries in 61 pages Feb 14 10:11:59 bureau kernel: rcu: Hierarchical RCU implementation. Feb 14 10:11:59 bureau kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Feb 14 10:11:59 bureau kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Feb 14 10:11:59 bureau kernel: GIC: Using split EOI/Deactivate mode Feb 14 10:11:59 bureau kernel: random: get_random_bytes called from start_kernel+0x344/0x518 with crng_init=0 Feb 14 10:11:59 bureau kernel: sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns Feb 14 10:11:59 bureau kernel: clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns Feb 14 10:11:59 bureau kernel: bcm2835: system timer (irq = 17) Feb 14 10:11:59 bureau kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Feb 14 10:11:59 bureau kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Feb 14 10:11:59 bureau kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Feb 14 10:11:59 bureau kernel: Switching to timer-based delay loop, resolution 18ns Feb 14 10:11:59 bureau kernel: Console: colour dummy device 80x30 Feb 14 10:11:59 bureau kernel: printk: console [tty1] enabled Feb 14 10:11:59 bureau kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Feb 14 10:11:59 bureau kernel: pid_max: default: 32768 minimum: 301 Feb 14 10:11:59 bureau kernel: LSM: Security Framework initializing Feb 14 10:11:59 bureau kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 bureau kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 bureau kernel: Disabling memory control group subsystem Feb 14 10:11:59 bureau kernel: CPU: Testing write buffer coherency: ok Feb 14 10:11:59 bureau kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Feb 14 10:11:59 bureau kernel: Setting up static identity map for 0x200000 - 0x20003c Feb 14 10:11:59 bureau kernel: rcu: Hierarchical SRCU implementation. Feb 14 10:11:59 bureau kernel: smp: Bringing up secondary CPUs ... Feb 14 10:11:59 bureau kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Feb 14 10:11:59 bureau kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Feb 14 10:11:59 bureau kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Feb 14 10:11:59 bureau kernel: smp: Brought up 1 node, 4 CPUs Feb 14 10:11:59 bureau kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Feb 14 10:11:59 bureau kernel: CPU: All CPU(s) started in HYP mode. Feb 14 10:11:59 bureau kernel: CPU: Virtualization extensions available. Feb 14 10:11:59 bureau kernel: devtmpfs: initialized Feb 14 10:11:59 bureau kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Feb 14 10:11:59 bureau kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 14 10:11:59 bureau kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 14 10:11:59 bureau kernel: pinctrl core: initialized pinctrl subsystem Feb 14 10:11:59 bureau kernel: NET: Registered protocol family 16 Feb 14 10:11:59 bureau kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 14 10:11:59 bureau kernel: audit: initializing netlink subsys (disabled) Feb 14 10:11:59 bureau kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Feb 14 10:11:59 bureau kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 14 10:11:59 bureau kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 14 10:11:59 bureau kernel: Serial: AMBA PL011 UART driver Feb 14 10:11:59 bureau kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Feb 14 10:11:59 bureau kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2021-01-08 14:31, variant start Feb 14 10:11:59 bureau kernel: raspberrypi-firmware soc:firmware: Firmware hash is 194a85abd768c7334bbadc3f1911c10a7d18ed14 Feb 14 10:11:59 bureau kernel: bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 Feb 14 10:11:59 bureau kernel: vgaarb: loaded Feb 14 10:11:59 bureau kernel: SCSI subsystem initialized Feb 14 10:11:59 bureau kernel: usbcore: registered new interface driver usbfs Feb 14 10:11:59 bureau kernel: usbcore: registered new interface driver hub Feb 14 10:11:59 bureau kernel: usbcore: registered new device driver usb Feb 14 10:11:59 bureau kernel: usb_phy_generic phy: phy supply vcc not found, using dummy regulator Feb 14 10:11:59 bureau kernel: clocksource: Switched to clocksource arch_sys_counter Feb 14 10:11:59 bureau kernel: VFS: Disk quotas dquot_6.6.0 Feb 14 10:11:59 bureau kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 14 10:11:59 bureau kernel: FS-Cache: Loaded Feb 14 10:11:59 bureau kernel: CacheFiles: Loaded Feb 14 10:11:59 bureau kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 14 10:11:59 bureau kernel: NET: Registered protocol family 2 Feb 14 10:11:59 bureau kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Feb 14 10:11:59 bureau kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Feb 14 10:11:59 bureau kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 14 10:11:59 bureau kernel: TCP: Hash tables configured (established 8192 bind 8192) Feb 14 10:11:59 bureau kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:11:59 bureau kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:11:59 bureau kernel: NET: Registered protocol family 1 Feb 14 10:11:59 bureau kernel: RPC: Registered named UNIX socket transport module. Feb 14 10:11:59 bureau kernel: RPC: Registered udp transport module. Feb 14 10:11:59 bureau kernel: RPC: Registered tcp transport module. Feb 14 10:11:59 bureau kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 14 10:11:59 bureau kernel: PCI: CLS 0 bytes, default 64 Feb 14 10:11:59 bureau kernel: Trying to unpack rootfs image as initramfs... Feb 14 10:11:59 bureau kernel: Freeing initrd memory: 22488K Feb 14 10:11:59 bureau kernel: hw perfevents: enabled with armv7_cortex_a15 PMU driver, 7 counters available Feb 14 10:11:59 bureau kernel: Initialise system trusted keyrings Feb 14 10:11:59 bureau kernel: workingset: timestamp_bits=14 max_order=20 bucket_order=6 Feb 14 10:11:59 bureau kernel: FS-Cache: Netfs 'nfs' registered for caching Feb 14 10:11:59 bureau kernel: NFS: Registering the id_resolver key type Feb 14 10:11:59 bureau kernel: Key type id_resolver registered Feb 14 10:11:59 bureau kernel: Key type id_legacy registered Feb 14 10:11:59 bureau kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 14 10:11:59 bureau kernel: Key type asymmetric registered Feb 14 10:11:59 bureau kernel: Asymmetric key parser 'x509' registered Feb 14 10:11:59 bureau kernel: bounce: pool size: 64 pages Feb 14 10:11:59 bureau kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Feb 14 10:11:59 bureau kernel: io scheduler mq-deadline registered Feb 14 10:11:59 bureau kernel: io scheduler kyber registered Feb 14 10:11:59 bureau kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Feb 14 10:11:59 bureau kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Feb 14 10:11:59 bureau kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x0603ffffff -> 0x00f8000000 Feb 14 10:11:59 bureau kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000 Feb 14 10:11:59 bureau kernel: brcm-pcie fd500000.pcie: link up, 5 GT/s x1 (SSC) Feb 14 10:11:59 bureau kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Feb 14 10:11:59 bureau kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Feb 14 10:11:59 bureau kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x603ffffff] (bus address [0xf8000000-0xfbffffff]) Feb 14 10:11:59 bureau kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Feb 14 10:11:59 bureau kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Feb 14 10:11:59 bureau kernel: PCI: bus0: Fast back to back transfers disabled Feb 14 10:11:59 bureau kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Feb 14 10:11:59 bureau kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Feb 14 10:11:59 bureau kernel: pci 0000:01:00.0: PME# supported from D0 D3cold Feb 14 10:11:59 bureau kernel: PCI: bus1: Fast back to back transfers disabled Feb 14 10:11:59 bureau kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Feb 14 10:11:59 bureau kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Feb 14 10:11:59 bureau kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Feb 14 10:11:59 bureau kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Feb 14 10:11:59 bureau kernel: pcieport 0000:00:00.0: enabling device (0140 -> 0142) Feb 14 10:11:59 bureau kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 54 Feb 14 10:11:59 bureau kernel: pcieport 0000:00:00.0: AER: enabled with IRQ 54 Feb 14 10:11:59 bureau kernel: pci 0000:01:00.0: enabling device (0140 -> 0142) Feb 14 10:11:59 bureau kernel: bcm2708_fb soc:fb: FB found 1 display(s) Feb 14 10:11:59 bureau kernel: Console: switching to colour frame buffer device 80x30 Feb 14 10:11:59 bureau kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Feb 14 10:11:59 bureau kernel: iproc-rng200 fe104000.rng: hwrng registered Feb 14 10:11:59 bureau kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Feb 14 10:11:59 bureau kernel: vc-sm: Videocore shared memory driver Feb 14 10:11:59 bureau kernel: gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000 Feb 14 10:11:59 bureau kernel: brd: module loaded Feb 14 10:11:59 bureau kernel: loop: module loaded Feb 14 10:11:59 bureau kernel: Loading iSCSI transport class v2.0-870. Feb 14 10:11:59 bureau kernel: libphy: Fixed MDIO Bus: probed Feb 14 10:11:59 bureau kernel: bcmgenet fd580000.ethernet: failed to get enet clock Feb 14 10:11:59 bureau kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Feb 14 10:11:59 bureau kernel: bcmgenet fd580000.ethernet: failed to get enet-wol clock Feb 14 10:11:59 bureau kernel: bcmgenet fd580000.ethernet: failed to get enet-eee clock Feb 14 10:11:59 bureau kernel: bcmgenet: Skipping UMAC reset Feb 14 10:11:59 bureau kernel: libphy: bcmgenet MII bus: probed Feb 14 10:11:59 bureau kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Feb 14 10:11:59 bureau kernel: usbcore: registered new interface driver r8152 Feb 14 10:11:59 bureau kernel: usbcore: registered new interface driver lan78xx Feb 14 10:11:59 bureau kernel: usbcore: registered new interface driver smsc95xx Feb 14 10:11:59 bureau kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Feb 14 10:11:59 bureau kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Feb 14 10:11:59 bureau kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x0000003000000890 Feb 14 10:11:59 bureau kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.04 Feb 14 10:11:59 bureau kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 14 10:11:59 bureau kernel: usb usb1: Product: xHCI Host Controller Feb 14 10:11:59 bureau kernel: usb usb1: Manufacturer: Linux 5.4.83-v7l+ xhci-hcd Feb 14 10:11:59 bureau kernel: usb usb1: SerialNumber: 0000:01:00.0 Feb 14 10:11:59 bureau kernel: hub 1-0:1.0: USB hub found Feb 14 10:11:59 bureau kernel: hub 1-0:1.0: 1 port detected Feb 14 10:11:59 bureau kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Feb 14 10:11:59 bureau kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Feb 14 10:11:59 bureau kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Feb 14 10:11:59 bureau kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.04 Feb 14 10:11:59 bureau kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Nov 08 18:10:31 bureau kernel: usb usb2: Product: xHCI Host Controller Nov 08 18:10:31 bureau kernel: usb usb2: Manufacturer: Linux 5.4.83-v7l+ xhci-hcd Nov 08 18:10:31 bureau kernel: usb usb2: SerialNumber: 0000:01:00.0 Nov 08 18:10:31 bureau kernel: hub 2-0:1.0: USB hub found Nov 08 18:10:31 bureau kernel: hub 2-0:1.0: 4 ports detected Nov 08 18:10:31 bureau kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Nov 08 18:10:31 bureau kernel: dwc_otg: FIQ enabled Nov 08 18:10:31 bureau kernel: dwc_otg: NAK holdoff enabled Nov 08 18:10:31 bureau kernel: dwc_otg: FIQ split-transaction FSM enabled Nov 08 18:10:31 bureau kernel: Module dwc_common_port init Nov 08 18:10:31 bureau kernel: usbcore: registered new interface driver uas Nov 08 18:10:31 bureau kernel: usbcore: registered new interface driver usb-storage Nov 08 18:10:31 bureau kernel: mousedev: PS/2 mouse device common for all mice Nov 08 18:10:31 bureau kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Nov 08 18:10:31 bureau kernel: sdhci: Secure Digital Host Controller Interface driver Nov 08 18:10:31 bureau kernel: sdhci: Copyright(c) Pierre Ossman Nov 08 18:10:31 bureau kernel: mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe Nov 08 18:10:31 bureau kernel: sdhci-pltfm: SDHCI platform and OF driver helper Nov 08 18:10:31 bureau kernel: ledtrig-cpu: registered to indicate activity on CPUs Nov 08 18:10:31 bureau kernel: hidraw: raw HID events driver (C) Jiri Kosina Nov 08 18:10:31 bureau kernel: usbcore: registered new interface driver usbhid Nov 08 18:10:31 bureau kernel: usbhid: USB HID core driver Nov 08 18:10:31 bureau kernel: vchiq: vchiq_init_state: slot_zero = (ptrval) Nov 08 18:10:31 bureau kernel: [vc_sm_connected_init]: start Nov 08 18:10:31 bureau kernel: [vc_sm_connected_init]: end - returning 0 Nov 08 18:10:31 bureau kernel: Initializing XFRM netlink socket Nov 08 18:10:31 bureau kernel: NET: Registered protocol family 17 Nov 08 18:10:31 bureau kernel: Key type dns_resolver registered Nov 08 18:10:31 bureau kernel: Registering SWP/SWPB emulation handler Nov 08 18:10:31 bureau kernel: registered taskstats version 1 Nov 08 18:10:31 bureau kernel: Loading compiled-in X.509 certificates Nov 08 18:10:31 bureau kernel: Key type ._fscrypt registered Nov 08 18:10:31 bureau kernel: Key type .fscrypt registered Nov 08 18:10:31 bureau kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Nov 08 18:10:31 bureau kernel: fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 29, base_baud = 0) is a PL011 rev2 Nov 08 18:10:31 bureau kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Nov 08 18:10:31 bureau kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Nov 08 18:10:31 bureau kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Nov 08 18:10:31 bureau kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Nov 08 18:10:31 bureau kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Nov 08 18:10:31 bureau kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Nov 08 18:10:31 bureau kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Nov 08 18:10:31 bureau kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Nov 08 18:10:31 bureau kernel: mmc0: SDHCI controller on fe340000.emmc2 [fe340000.emmc2] using ADMA Nov 08 18:10:31 bureau kernel: of_cfs_init Nov 08 18:10:31 bureau kernel: of_cfs_init: OK Nov 08 18:10:31 bureau kernel: Freeing unused kernel memory: 2048K Nov 08 18:10:31 bureau kernel: Run /init as init process Nov 08 18:10:31 bureau kernel: random: fast init done Nov 08 18:10:31 bureau kernel: mmc1: new high speed SDIO card at address 0001 Nov 08 18:10:31 bureau kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Nov 08 18:10:31 bureau kernel: mmcblk0: mmc0:aaaa SC32G 29.7 GiB Nov 08 18:10:31 bureau kernel: mmcblk0: p1 p2 p3 Nov 08 18:10:31 bureau initramfs: Booting Volumio for BCM2711 Nov 08 18:10:31 bureau initramfs: This script mounts rootfs RO with an overlay RW layer. Nov 08 18:10:31 bureau kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Nov 08 18:10:31 bureau initramfs: IMGPART=/dev/mmcblk0p2 Nov 08 18:10:31 bureau initramfs: IMGFILE=/volumio_current.sqsh Nov 08 18:10:31 bureau initramfs: Boot delay (except first time) will be 5 seconds Nov 08 18:10:31 bureau initramfs: /dev/mmcblk0p2 not detected,retrying mdev in 5 seconds Nov 08 18:10:31 bureau kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Nov 08 18:10:31 bureau kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Nov 08 18:10:31 bureau kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Nov 08 18:10:31 bureau kernel: usb 1-1: Product: USB2.0 Hub Nov 08 18:10:31 bureau kernel: hub 1-1:1.0: USB hub found Nov 08 18:10:31 bureau kernel: hub 1-1:1.0: 4 ports detected Nov 08 18:10:31 bureau kernel: usb 1-1.1: new high-speed USB device number 3 using xhci_hcd Nov 08 18:10:31 bureau initramfs: /dev/mmcblk0p2: Nov 08 18:10:31 bureau kernel: usb 1-1.1: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 6.f2 Nov 08 18:10:31 bureau kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Nov 08 18:10:31 bureau kernel: usb 1-1.1: Product: SMSL M8A 1.1.2 Nov 08 18:10:31 bureau kernel: usb 1-1.1: Manufacturer: SMSL Nov 08 18:10:31 bureau kernel: EXT4-fs (mmcblk0p2): recovery complete Nov 08 18:10:31 bureau kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Nov 08 18:10:31 bureau initramfs: Doing a 5 second delay here to give kernel load a headstart Nov 08 18:10:31 bureau initramfs: Checking for USB updates Nov 08 18:10:31 bureau initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Nov 08 18:10:31 bureau kernel: EXT4-fs (mmcblk0p3): recovery complete Nov 08 18:10:31 bureau kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Nov 08 18:10:31 bureau initramfs: With Option: Nov 08 18:10:31 bureau initramfs: VOLUMIO_VERSION="3.129" Nov 08 18:10:31 bureau initramfs: Finish initramfs, continue booting Volumio Nov 08 18:10:31 bureau systemd[1]: System time before build time, advancing clock. Nov 08 18:10:31 bureau kernel: NET: Registered protocol family 10 Nov 08 18:10:31 bureau kernel: Segment Routing with IPv6 Nov 08 18:10:31 bureau 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) Nov 08 18:10:31 bureau systemd[1]: Detected architecture arm. Nov 08 18:10:31 bureau systemd[1]: Set hostname to . Nov 08 18:10:31 bureau 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. Nov 08 18:10:31 bureau kernel: random: systemd: uninitialized urandom read (16 bytes read) Nov 08 18:10:31 bureau kernel: random: systemd: uninitialized urandom read (16 bytes read) Nov 08 18:10:31 bureau kernel: random: systemd: uninitialized urandom read (16 bytes read) Nov 08 18:10:31 bureau systemd[1]: Listening on Journal Socket (/dev/log). Nov 08 18:10:31 bureau systemd[1]: Listening on RPCbind Server Activation Socket. Nov 08 18:10:31 bureau systemd[1]: Reached target System Time Synchronized. Nov 08 18:10:31 bureau systemd[1]: Started Forward Password Requests to Wall Directory Watch. Nov 08 18:10:31 bureau systemd[1]: Created slice system-systemd\x2dfsck.slice. Nov 08 18:10:31 bureau systemd[1]: Created slice User and Session Slice. Nov 08 18:10:31 bureau kernel: i2c /dev entries driver Nov 08 18:10:31 bureau systemd-journald[195]: Journal started Nov 08 18:10:31 bureau systemd-journald[195]: Runtime journal (/run/log/journal/510261faaece7a0c78916f67617bf954) is 7.5M, max 30.0M, 22.5M free. Nov 08 18:10:31 bureau systemd-modules-load[183]: Inserted module 'i2c_dev' Nov 08 18:10:31 bureau fake-hwclock[187]: Mon Nov 8 18:10:31 UTC 2021 Nov 08 18:10:31 bureau systemd[1]: Started Load/Save Random Seed. Nov 08 18:10:31 bureau systemd[1]: dynamicswap.service: Succeeded. Nov 08 18:10:31 bureau systemd[1]: Started Create System Users. Nov 08 18:10:31 bureau systemd[1]: Starting Create Static Device Nodes in /dev... Nov 08 18:10:31 bureau systemd[1]: Started Create Static Device Nodes in /dev. Nov 08 18:10:31 bureau systemd[1]: Reached target Local File Systems (Pre). Nov 08 18:10:31 bureau systemd[1]: Mounting /var/spool/cups... Nov 08 18:10:31 bureau systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Nov 08 18:10:31 bureau systemd[1]: Mounting /var/log... Nov 08 18:10:31 bureau systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Nov 08 18:10:31 bureau systemd[1]: Mounting /tmp... Nov 08 18:10:31 bureau systemd[1]: Starting udev Kernel Device Manager... Nov 08 18:10:31 bureau systemd[1]: Started udev Coldplug all Devices. Nov 08 18:10:31 bureau systemd[1]: Mounted /var/spool/cups. Nov 08 18:10:31 bureau systemd[1]: Mounted /var/log. Nov 08 18:10:31 bureau systemd[1]: Mounted /tmp. Nov 08 18:10:31 bureau systemd[1]: Starting Flush Journal to Persistent Storage... Nov 08 18:10:31 bureau systemd[1]: Mounting /var/spool/cups/tmp... Nov 08 18:10:31 bureau systemd[1]: Starting Helper to synchronize boot up for ifupdown... Nov 08 18:10:31 bureau systemd[1]: Mounted /var/spool/cups/tmp. Nov 08 18:10:31 bureau systemd-udevd[216]: Network interface NamePolicy= disabled on kernel command line, ignoring. Nov 08 18:10:31 bureau systemd[1]: Started Helper to synchronize boot up for ifupdown. Nov 08 18:10:31 bureau systemd-journald[195]: Runtime journal (/run/log/journal/510261faaece7a0c78916f67617bf954) is 7.5M, max 30.0M, 22.5M free. Nov 08 18:10:31 bureau systemd[1]: Started Flush Journal to Persistent Storage. Nov 08 18:10:31 bureau systemd[1]: Started udev Kernel Device Manager. Nov 08 18:10:31 bureau systemd[1]: Starting Show Plymouth Boot Screen... Nov 08 18:10:31 bureau systemd[1]: Received SIGRTMIN+20 from PID 233 (plymouthd). Nov 08 18:10:31 bureau systemd[1]: Started Show Plymouth Boot Screen. Nov 08 18:10:31 bureau systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Nov 08 18:10:31 bureau systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Nov 08 18:10:31 bureau systemd[1]: Reached target Paths. Nov 08 18:10:31 bureau systemd[1]: Reached target Local Encrypted Volumes. Nov 08 18:10:31 bureau kernel: rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000 Nov 08 18:10:31 bureau kernel: rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000 Nov 08 18:10:31 bureau kernel: rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000 Nov 08 18:10:31 bureau kernel: rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000 Nov 08 18:10:31 bureau systemd-udevd[222]: Using default interface naming scheme 'v240'. Nov 08 18:10:31 bureau kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Nov 08 18:10:31 bureau kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Nov 08 18:10:31 bureau kernel: [vc_sm_connected_init]: start Nov 08 18:10:31 bureau kernel: [vc_sm_connected_init]: installed successfully Nov 08 18:10:31 bureau kernel: mc: Linux media interface: v0.10 Nov 08 18:10:31 bureau systemd-udevd[222]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 08 18:10:31 bureau kernel: videodev: Linux video capture interface: v2.00 Nov 08 18:10:32 bureau kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Nov 08 18:10:32 bureau kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Nov 08 18:10:32 bureau kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Nov 08 18:10:32 bureau kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Nov 08 18:10:32 bureau kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Nov 08 18:10:32 bureau kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Nov 08 18:10:32 bureau kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Nov 08 18:10:32 bureau kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Nov 08 18:10:32 bureau kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Nov 08 18:10:32 bureau kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Nov 08 18:10:32 bureau kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Nov 08 18:10:32 bureau kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Nov 08 18:10:32 bureau kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Nov 08 18:10:32 bureau kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Nov 08 18:10:32 bureau kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Nov 08 18:10:32 bureau kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Nov 08 18:10:32 bureau kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Nov 08 18:10:32 bureau kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Nov 08 18:10:32 bureau kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Nov 08 18:10:32 bureau kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Nov 08 18:10:32 bureau systemd-udevd[221]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 08 18:10:32 bureau kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Nov 08 18:10:32 bureau kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Nov 08 18:10:32 bureau kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Nov 08 18:10:32 bureau kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Nov 08 18:10:32 bureau kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Nov 08 18:10:32 bureau kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Nov 08 18:10:32 bureau kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Nov 08 18:10:32 bureau kernel: usbcore: registered new interface driver brcmfmac Nov 08 18:10:32 bureau kernel: snd_usb_audio: unknown parameter 'nrpacks' ignored Nov 08 18:10:32 bureau kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.txt failed with error -2 Nov 08 18:10:33 bureau systemd[1]: Found device /dev/mmcblk0p1. Nov 08 18:10:33 bureau kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Nov 08 18:10:33 bureau 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 Nov 08 18:10:33 bureau systemd-udevd[252]: Using default interface naming scheme 'v240'. Nov 08 18:10:33 bureau systemd-udevd[252]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Nov 08 18:10:33 bureau systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Nov 08 18:10:33 bureau systemd[1]: Condition check resulted in /sys/subsystem/net/devices/wlan0 being skipped. Nov 08 18:10:33 bureau systemd[1]: Condition check resulted in Huge Pages File System being skipped. Nov 08 18:10:33 bureau systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Nov 08 18:10:33 bureau systemd[1]: Condition check resulted in FUSE Control File System being skipped. Nov 08 18:10:33 bureau systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Nov 08 18:10:33 bureau systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Nov 08 18:10:33 bureau systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Nov 08 18:10:33 bureau systemd[1]: Starting File System Check on /dev/mmcblk0p1... Nov 08 18:10:33 bureau systemd[1]: Starting Load/Save RF Kill Switch Status... Nov 08 18:10:33 bureau systemd[1]: Started File System Check Daemon to report status. Nov 08 18:10:33 bureau systemd[1]: Started Load/Save RF Kill Switch Status. Nov 08 18:10:34 bureau systemd-fsck[335]: fsck.fat 4.1 (2017-01-24) Nov 08 18:10:34 bureau systemd-fsck[335]: /dev/mmcblk0p1: 257 files, 31121/46774 clusters Nov 08 18:10:34 bureau systemd[1]: Started File System Check on /dev/mmcblk0p1. Nov 08 18:10:34 bureau systemd[1]: Mounting /boot... Nov 08 18:10:34 bureau kernel: usb 1-1.1: 1:3 : unsupported format bits 0x100000000 Nov 08 18:10:34 bureau systemd[1]: Mounted /boot. Nov 08 18:10:34 bureau systemd[1]: Reached target Local File Systems. Nov 08 18:10:34 bureau systemd[1]: Starting Raise network interfaces... Nov 08 18:10:34 bureau systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Nov 08 18:10:34 bureau systemd[1]: Started ifup for eth0. Nov 08 18:10:34 bureau systemd[1]: Starting Preprocess NFS configuration... Nov 08 18:10:34 bureau systemd[1]: Started ifup for wlan0. Nov 08 18:10:34 bureau systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Nov 08 18:10:34 bureau systemd[1]: Starting Create Volatile Files and Directories... Nov 08 18:10:34 bureau systemd[1]: plymouth-read-write.service: Succeeded. Nov 08 18:10:34 bureau systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Nov 08 18:10:34 bureau systemd[1]: Received SIGRTMIN+20 from PID 233 (plymouthd). Nov 08 18:10:34 bureau systemd[1]: nfs-config.service: Succeeded. Nov 08 18:10:34 bureau systemd[1]: Started Preprocess NFS configuration. Nov 08 18:10:34 bureau systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Nov 08 18:10:34 bureau systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Nov 08 18:10:34 bureau systemd[1]: Reached target NFS client services. Nov 08 18:10:34 bureau systemd[1]: Started Create Volatile Files and Directories. Nov 08 18:10:34 bureau systemd[1]: Starting RPC bind portmap service... Nov 08 18:10:34 bureau systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Nov 08 18:10:34 bureau systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Nov 08 18:10:34 bureau systemd[1]: Starting Update UTMP about System Boot/Shutdown... Nov 08 18:10:34 bureau systemd[1]: Started Update UTMP about System Boot/Shutdown. Nov 08 18:10:34 bureau systemd[1]: Reached target System Initialization. Nov 08 18:10:34 bureau kernel: usbcore: registered new interface driver snd-usb-audio Nov 08 18:10:34 bureau systemd[1]: Listening on mpd.socket. Nov 08 18:10:34 bureau systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Nov 08 18:10:34 bureau systemd[1]: Listening on triggerhappy.socket. Nov 08 18:10:34 bureau kernel: 8021q: 802.1Q VLAN Support v1.8 Nov 08 18:10:34 bureau systemd[1]: Started Daily apt download activities. Nov 08 18:10:34 bureau systemd[1]: Started Daily Cleanup of Temporary Directories. Nov 08 18:10:34 bureau systemd[1]: Listening on D-Bus System Message Bus Socket. Nov 08 18:10:34 bureau systemd[1]: Reached target Sockets. Nov 08 18:10:34 bureau systemd[1]: Started Daily apt upgrade and clean activities. Nov 08 18:10:34 bureau systemd[1]: Reached target Timers. Nov 08 18:10:34 bureau systemd[1]: Reached target Basic System. Nov 08 18:10:34 bureau systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Nov 08 18:10:34 bureau kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 08 18:10:34 bureau systemd[1]: Starting Configure Bluetooth Modems connected by UART... Nov 08 18:10:34 bureau systemd[1]: Starting Wireless Services... Nov 08 18:10:34 bureau systemd[1]: Started Manage Sound Card State (restore and store). Nov 08 18:10:34 bureau systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Nov 08 18:10:34 bureau systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Nov 08 18:10:34 bureau alsactl[424]: alsactl 1.1.8 daemon started Nov 08 18:10:34 bureau systemd[1]: Starting triggerhappy global hotkey daemon... Nov 08 18:10:34 bureau systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Nov 08 18:10:34 bureau systemd[1]: Starting dhcpcd on all interfaces... Nov 08 18:10:34 bureau systemd[1]: Started volumio-remote-updater.service. Nov 08 18:10:34 bureau thd[433]: Unable to parse trigger line: Nov 08 18:10:34 bureau thd[433]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Nov 08 18:10:34 bureau thd[433]: Unable to parse trigger line: Nov 08 18:10:34 bureau thd[433]: Found socket passed from systemd Nov 08 18:10:34 bureau systemd[1]: Started UPnP Renderer front-end to MPD. Nov 08 18:10:34 bureau systemd[1]: Started D-Bus System Message Bus. Nov 08 18:10:34 bureau dhcpcd[437]: Not running dhcpcd because /etc/network/interfaces Nov 08 18:10:34 bureau dhcpcd[437]: defines some interfaces that will use a Nov 08 18:10:34 bureau dhcpcd[437]: DHCP client or static address Nov 08 18:10:34 bureau avahi-daemon[430]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Nov 08 18:10:34 bureau avahi-daemon[430]: Successfully dropped root privileges. Nov 08 18:10:34 bureau avahi-daemon[430]: avahi-daemon 0.7 starting up. Nov 08 18:10:34 bureau systemd[1]: Starting WPA supplicant... Nov 08 18:10:34 bureau systemd[1]: Starting Save/Restore Sound Card State... Nov 08 18:10:34 bureau systemd[1]: Starting Login Service... Nov 08 18:10:34 bureau systemd[1]: Started Volumio Iptables Module. Nov 08 18:10:34 bureau systemd[1]: Started RPC bind portmap service. Nov 08 18:10:34 bureau systemd[1]: Started triggerhappy global hotkey daemon. Nov 08 18:10:35 bureau systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Nov 08 18:10:35 bureau systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Nov 08 18:10:35 bureau systemd[1]: Failed to start dhcpcd on all interfaces. Nov 08 18:10:35 bureau kernel: uart-pl011 fe201000.serial: no DMA platform data Nov 08 18:10:35 bureau kernel: bcmgenet: Skipping UMAC reset Nov 08 18:10:35 bureau sh[344]: eth0: waiting for carrier Nov 08 18:10:35 bureau dhcpcd[391]: eth0: waiting for carrier Nov 08 18:10:35 bureau avahi-daemon[430]: Successfully called chroot(). Nov 08 18:10:35 bureau avahi-daemon[430]: Successfully dropped remaining capabilities. Nov 08 18:10:35 bureau kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII Nov 08 18:10:35 bureau kernel: bcmgenet fd580000.ethernet eth0: Link is Down Nov 08 18:10:35 bureau avahi-daemon[430]: Loading service file /services/volumio.service. Nov 08 18:10:35 bureau avahi-daemon[430]: Network interface enumeration completed. Nov 08 18:10:35 bureau avahi-daemon[430]: Server startup complete. Host name is bureau.local. Local service cookie is 3860657800. Nov 08 18:10:35 bureau avahi-daemon[430]: Service "Bureau" (/services/volumio.service) successfully established. Nov 08 18:10:35 bureau systemd[1]: Started Save/Restore Sound Card State. Nov 08 18:10:35 bureau systemd[1]: Reached target Sound Card. Nov 08 18:10:35 bureau systemd[1]: Reached target Remote File Systems (Pre). Nov 08 18:10:35 bureau systemd[1]: Reached target Remote File Systems. Nov 08 18:10:35 bureau systemd-logind[464]: New seat seat0. Nov 08 18:10:35 bureau systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Nov 08 18:10:35 bureau sh[348]: wlan0=wlan0 Nov 08 18:10:35 bureau systemd[1]: Reached target RPC Port Mapper. Nov 08 18:10:35 bureau systemd[1]: Started Login Service. Nov 08 18:10:35 bureau systemd[1]: Started Avahi mDNS/DNS-SD Stack. Nov 08 18:10:35 bureau systemd[1]: Started Raise network interfaces. Nov 08 18:10:35 bureau loadcpufreq[486]: Loading cpufreq kernel modules...done (none). Nov 08 18:10:35 bureau systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Nov 08 18:10:35 bureau systemd[1]: Starting LSB: set CPUFreq kernel parameters... Nov 08 18:10:35 bureau systemd[1]: Started WPA supplicant. Nov 08 18:10:35 bureau wpa_supplicant[457]: Successfully initialized wpa_supplicant Nov 08 18:10:35 bureau systemd[1]: Reached target Network. Nov 08 18:10:35 bureau systemd[1]: Starting Permit User Sessions... Nov 08 18:10:35 bureau systemd[1]: Reached target Network is Online. Nov 08 18:10:35 bureau systemd[1]: Starting LSB: Brings up/down network automatically... Nov 08 18:10:35 bureau systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 08 18:10:35 bureau systemd[1]: Starting Samba NMB Daemon... Nov 08 18:10:35 bureau systemd[1]: Starting Network Time Service... Nov 08 18:10:35 bureau systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Nov 08 18:10:35 bureau systemd[1]: Starting /etc/rc.local Compatibility... Nov 08 18:10:35 bureau systemd[1]: Starting Music Player Daemon... Nov 08 18:10:35 bureau systemd[1]: Started Permit User Sessions. Nov 08 18:10:35 bureau systemd[1]: Started /etc/rc.local Compatibility. Nov 08 18:10:36 bureau systemd[1]: Starting Terminate Plymouth Boot Screen... Nov 08 18:10:36 bureau systemd[1]: Starting Hold until boot process finishes up... Nov 08 18:10:36 bureau systemd[1]: plymouth-quit.service: Succeeded. Nov 08 18:10:36 bureau systemd[1]: Started Terminate Plymouth Boot Screen. Nov 08 18:10:36 bureau systemd[1]: plymouth-start.service: Succeeded. Nov 08 18:10:36 bureau systemd[1]: plymouth-quit-wait.service: Succeeded. Nov 08 18:10:36 bureau systemd[1]: Started Hold until boot process finishes up. Nov 08 18:10:36 bureau systemd[1]: Received SIGRTMIN+21 from PID 233 (n/a). Nov 08 18:10:36 bureau systemd[1]: Received SIGRTMIN+21 from PID 233 (n/a). Nov 08 18:10:36 bureau systemd[1]: Started Getty on tty1. Nov 08 18:10:36 bureau systemd[1]: Reached target Login Prompts. Nov 08 18:10:36 bureau ntpd[551]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 08 18:10:36 bureau ntpd[551]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Nov 08 18:10:36 bureau systemd[1]: Started Network Time Service. Nov 08 18:10:36 bureau ifplugd(eth0)[588]: ifplugd 0.28 initializing. Nov 08 18:10:36 bureau ifplugd(eth0)[588]: Using interface eth0/DC:A6:32:6D:3A:89 with driver (version: v2.0) Nov 08 18:10:36 bureau ifplugd(eth0)[588]: Using detection mode: SIOCETHTOOL Nov 08 18:10:36 bureau ifplugd(eth0)[588]: Initialization complete, link beat not detected. Nov 08 18:10:36 bureau ntpd[578]: proto: precision = 0.777 usec (-20) Nov 08 18:10:36 bureau cpufrequtils[541]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Nov 08 18:10:36 bureau ntpd[578]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 08 18:10:36 bureau ntpd[578]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 08 18:10:36 bureau systemd[1]: Started LSB: set CPUFreq kernel parameters. Nov 08 18:10:36 bureau ntpd[578]: Listen and drop on 0 v6wildcard [::]:123 Nov 08 18:10:36 bureau ntpd[578]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 08 18:10:36 bureau ntpd[578]: Listen normally on 2 lo 127.0.0.1:123 Nov 08 18:10:36 bureau ntpd[578]: Listening on routing socket on fd #19 for interface updates Nov 08 18:10:36 bureau ntpd[578]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 08 18:10:36 bureau ntpd[578]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 08 18:10:36 bureau systemd[1]: iptables.service: Succeeded. Nov 08 18:10:36 bureau ifplugd[545]: Network Interface Plugging Daemon...start eth0...done. Nov 08 18:10:36 bureau systemd[1]: Started LSB: Brings up/down network automatically. Nov 08 18:10:36 bureau volumio-remote-updater[439]: Error: No active session Nov 08 18:10:36 bureau volumio-remote-updater[439]: [2021-11-08 18:10:36] [info] asio async_connect error: system:111 (Connection refused) Nov 08 18:10:36 bureau volumio-remote-updater[439]: [2021-11-08 18:10:36] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 08 18:10:36 bureau volumio-remote-updater[439]: [2021-11-08 18:10:36] [error] handle_connect error: Underlying Transport Error Nov 08 18:10:36 bureau volumio[442]: Could not open config: /tmp/upmpdcli.conf Nov 08 18:10:36 bureau systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Nov 08 18:10:36 bureau systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Nov 08 18:10:37 bureau haveged[394]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Nov 08 18:10:37 bureau haveged[394]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Nov 08 18:10:37 bureau haveged[394]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99901 Nov 08 18:10:37 bureau haveged[394]: haveged: fills: 0, generated: 0 Nov 08 18:10:37 bureau kernel: random: crng init done Nov 08 18:10:37 bureau kernel: random: 7 urandom warning(s) missed due to ratelimiting Nov 08 18:10:37 bureau nmbd[556]: [2021/11/08 18:10:37.121962, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Nov 08 18:10:37 bureau nmbd[556]: started asyncdns process 599 Nov 08 18:10:37 bureau nmbd[556]: [2021/11/08 18:10:37.123772, 0] ../lib/util/become_daemon.c:149(daemon_status) Nov 08 18:10:37 bureau nmbd[556]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Nov 08 18:10:37 bureau nmbd[556]: [2021/11/08 18:10:37.123886, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Nov 08 18:10:37 bureau nmbd[556]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Nov 08 18:10:37 bureau ntpd[578]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 08 18:10:38 bureau wireless.js[420]: WIRELESS DAEMON: start Nov 08 18:10:38 bureau wireless.js[420]: WIRELESS: Loaded configuration Nov 08 18:10:38 bureau wireless.js[420]: Cleaning previous... Nov 08 18:10:38 bureau ntpd[578]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 08 18:10:38 bureau sudo[609]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 08 18:10:38 bureau sudo[609]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:38 bureau sudo[609]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:38 bureau sudo[611]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 08 18:10:38 bureau sudo[611]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:38 bureau mpd[596]: Nov 08 18:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 08 18:10:38 bureau mpd[596]: Nov 08 18:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 08 18:10:38 bureau mpd[596]: Nov 08 18:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 08 18:10:38 bureau sudo[611]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:38 bureau systemd[1]: Started Music Player Daemon. Nov 08 18:10:38 bureau systemd[1]: systemd-rfkill.service: Succeeded. Nov 08 18:10:39 bureau wireless.js[420]: Stopped aP Nov 08 18:10:39 bureau wireless.js[420]: Start wireless flow Nov 08 18:10:39 bureau wireless.js[420]: Stopped hotspot (if there).. Nov 08 18:10:39 bureau sudo[620]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Nov 08 18:10:39 bureau sudo[620]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:39 bureau sudo[620]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:39 bureau sudo[622]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Nov 08 18:10:39 bureau sudo[622]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:39 bureau sudo[622]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:39 bureau wireless.js[420]: DHCP IP Nov 08 18:10:39 bureau wireless.js[420]: Start ap Nov 08 18:10:39 bureau wpa_supplicant[624]: Successfully initialized wpa_supplicant Nov 08 18:10:39 bureau sudo[625]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Nov 08 18:10:39 bureau sudo[625]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:39 bureau dhcpcd[626]: dev: loaded udev Nov 08 18:10:39 bureau kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Nov 08 18:10:39 bureau dhcpcd-run-hooks[645]: wlan0: starting wpa_supplicant Nov 08 18:10:39 bureau ntpd[578]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 08 18:10:39 bureau dhcpcd-run-hooks[650]: wlan0: failed to start wpa_supplicant Nov 08 18:10:39 bureau dhcpcd-run-hooks[651]: wlan0: Successfully initialized wpa_supplicant ctrl_iface exists and seems to be in use - cannot override it Delete '/var/run/wpa_supplicant/wlan0' manually if it is not used anymore Failed to initialize control interface '/var/run/wpa_supplicant'. You may have another wpa_supplicant process already running or the file was left by an unclean termination of wpa_supplicant in which case you will need to manually remove this file before starting wpa_supplicant again. nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Nov 08 18:10:39 bureau dhcpcd[626]: wlan0: connected to Access Point `' Nov 08 18:10:39 bureau dhcpcd[626]: no interfaces have a carrier Nov 08 18:10:39 bureau dhcpcd[661]: eth0: waiting for carrier Nov 08 18:10:39 bureau dhcpcd[661]: wlan0: waiting for carrier Nov 08 18:10:39 bureau dhcpcd[626]: forked to background, child pid 661 Nov 08 18:10:39 bureau sudo[625]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:40 bureau wireless.js[420]: trying... Nov 08 18:10:40 bureau sudo[663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 08 18:10:40 bureau sudo[663]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:40 bureau sudo[663]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:40 bureau ntpd[578]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 08 18:10:41 bureau wireless.js[420]: trying... Nov 08 18:10:41 bureau sudo[666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 08 18:10:41 bureau sudo[666]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:41 bureau sudo[666]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:41 bureau volumio-remote-updater[439]: [2021-11-08 18:10:41] [info] asio async_connect error: system:111 (Connection refused) Nov 08 18:10:41 bureau volumio-remote-updater[439]: [2021-11-08 18:10:41] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 08 18:10:41 bureau volumio-remote-updater[439]: [2021-11-08 18:10:41] [error] handle_connect error: Underlying Transport Error Nov 08 18:10:41 bureau wpa_supplicant[649]: wlan0: Trying to associate with SSID 'RM' Nov 08 18:10:41 bureau kernel: Bluetooth: Core ver 2.22 Nov 08 18:10:41 bureau kernel: NET: Registered protocol family 31 Nov 08 18:10:41 bureau kernel: Bluetooth: HCI device and connection manager initialized Nov 08 18:10:41 bureau kernel: Bluetooth: HCI socket layer initialized Nov 08 18:10:41 bureau kernel: Bluetooth: L2CAP socket layer initialized Nov 08 18:10:41 bureau kernel: Bluetooth: SCO socket layer initialized Nov 08 18:10:41 bureau btuart[415]: bcm43xx_init Nov 08 18:10:41 bureau btuart[415]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Nov 08 18:10:41 bureau btuart[415]: Set Controller UART speed to 3000000 bit/s Nov 08 18:10:41 bureau btuart[415]: Device setup complete Nov 08 18:10:41 bureau kernel: Bluetooth: HCI UART driver ver 2.3 Nov 08 18:10:41 bureau kernel: Bluetooth: HCI UART protocol H4 registered Nov 08 18:10:41 bureau kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Nov 08 18:10:41 bureau kernel: Bluetooth: HCI UART protocol Broadcom registered Nov 08 18:10:41 bureau systemd-udevd[252]: Process '/usr/bin/hciconfig hci0 up' failed with exit code 1. Nov 08 18:10:41 bureau systemd[1]: Starting Load/Save RF Kill Switch Status... Nov 08 18:10:41 bureau systemd[1]: Started Configure Bluetooth Modems connected by UART. Nov 08 18:10:41 bureau systemd[1]: Started Load/Save RF Kill Switch Status. Nov 08 18:10:41 bureau systemd[1]: Created slice system-bthelper.slice. Nov 08 18:10:41 bureau systemd[1]: Starting Raspberry Pi bluetooth helper... Nov 08 18:10:41 bureau bthelper[675]: Raspberry Pi BDADDR already set Nov 08 18:10:41 bureau systemd[1]: Started Raspberry Pi bluetooth helper. Nov 08 18:10:41 bureau systemd[1]: Starting Bluetooth service... Nov 08 18:10:42 bureau bluetoothd[685]: Bluetooth daemon 5.50 Nov 08 18:10:42 bureau bluetoothd[685]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Nov 08 18:10:42 bureau systemd[1]: Started Bluetooth service. Nov 08 18:10:42 bureau systemd[1]: Reached target Bluetooth. Nov 08 18:10:42 bureau bluetoothd[685]: Starting SDP server Nov 08 18:10:42 bureau bluetoothd[685]: Excluding (cli) sap Nov 08 18:10:42 bureau bluetoothd[685]: Bluetooth management interface 1.14 initialized Nov 08 18:10:42 bureau dbus-daemon[449]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=685 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Nov 08 18:10:42 bureau kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Nov 08 18:10:42 bureau kernel: Bluetooth: BNEP filters: protocol multicast Nov 08 18:10:42 bureau kernel: Bluetooth: BNEP socket layer initialized Nov 08 18:10:42 bureau systemd[1]: Starting Hostname Service... Nov 08 18:10:42 bureau wireless.js[420]: trying... Nov 08 18:10:42 bureau sudo[689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 08 18:10:42 bureau sudo[689]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:42 bureau sudo[689]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:42 bureau dbus-daemon[449]: [system] Successfully activated service 'org.freedesktop.hostname1' Nov 08 18:10:42 bureau systemd[1]: Started Hostname Service. Nov 08 18:10:42 bureau bluetoothd[685]: Failed to set privacy: Rejected (0x0b) Nov 08 18:10:43 bureau wireless.js[420]: trying... Nov 08 18:10:43 bureau sudo[692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 08 18:10:43 bureau sudo[692]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:43 bureau sudo[692]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:44 bureau wireless.js[420]: trying... Nov 08 18:10:44 bureau sudo[695]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 08 18:10:44 bureau sudo[695]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:44 bureau sudo[695]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:44 bureau dhcpcd[661]: wlan0: carrier acquired Nov 08 18:10:44 bureau dhcpcd[661]: wlan0: connected to Access Point `RM' Nov 08 18:10:44 bureau wpa_supplicant[649]: wlan0: Associated with e4:f0:42:cf:fc:78 Nov 08 18:10:44 bureau wpa_supplicant[649]: wlan0: CTRL-EVENT-CONNECTED - Connection to e4:f0:42:cf:fc:78 completed [id=0 id_str=] Nov 08 18:10:44 bureau wpa_supplicant[649]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Nov 08 18:10:44 bureau wpa_supplicant[649]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Nov 08 18:10:44 bureau dhcpcd[661]: DUID 00:01:00:01:29:0e:b7:81:dc:a6:32:6d:3a:89 Nov 08 18:10:44 bureau dhcpcd[661]: wlan0: IAID 32:6d:3a:8b Nov 08 18:10:44 bureau dhcpcd[661]: wlan0: adding address fe80::5369:18bb:94fc:bf47 Nov 08 18:10:44 bureau dhcpcd[661]: ipv6_addaddr1: Permission denied Nov 08 18:10:44 bureau dhcpcd[661]: wlan0: carrier lost Nov 08 18:10:44 bureau dhcpcd[661]: wlan0: carrier acquired Nov 08 18:10:44 bureau dhcpcd[661]: wlan0: IAID 32:6d:3a:8b Nov 08 18:10:44 bureau dhcpcd[661]: wlan0: soliciting an IPv6 router Nov 08 18:10:45 bureau dhcpcd[661]: wlan0: soliciting a DHCP lease Nov 08 18:10:45 bureau dhcpcd[661]: wlan0: offered 192.168.86.53 from 192.168.86.1 Nov 08 18:10:45 bureau wireless.js[420]: trying... Nov 08 18:10:45 bureau sudo[710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 08 18:10:45 bureau sudo[710]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:45 bureau sudo[710]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:45 bureau dhcpcd[661]: wlan0: probing address 192.168.86.53/24 Nov 08 18:10:46 bureau wireless.js[420]: trying... Nov 08 18:10:46 bureau sudo[713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 08 18:10:46 bureau sudo[713]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:46 bureau sudo[713]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:46 bureau systemd[1]: systemd-rfkill.service: Succeeded. Nov 08 18:10:46 bureau bthelper[675]: Changing power off succeeded Nov 08 18:10:47 bureau wireless.js[420]: trying... Nov 08 18:10:47 bureau sudo[717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 08 18:10:47 bureau sudo[717]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:47 bureau sudo[717]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:47 bureau bthelper[675]: [CHG] Controller DC:A6:32:6D:3A:8C Class: 0x0000041c Nov 08 18:10:47 bureau bthelper[675]: Changing power on succeeded Nov 08 18:10:48 bureau wireless.js[420]: trying... Nov 08 18:10:48 bureau sudo[725]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 08 18:10:48 bureau sudo[725]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:48 bureau sudo[725]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:48 bureau volumio-remote-updater[439]: [2021-11-08 18:10:48] [info] asio async_connect error: system:111 (Connection refused) Nov 08 18:10:48 bureau volumio-remote-updater[439]: [2021-11-08 18:10:48] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 08 18:10:48 bureau volumio-remote-updater[439]: [2021-11-08 18:10:48] [error] handle_connect error: Underlying Transport Error Nov 08 18:10:49 bureau wireless.js[420]: trying... Nov 08 18:10:49 bureau sudo[728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 08 18:10:49 bureau sudo[728]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:49 bureau sudo[728]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:50 bureau wireless.js[420]: trying... Nov 08 18:10:50 bureau sudo[731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 08 18:10:50 bureau sudo[731]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:50 bureau sudo[731]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:50 bureau dhcpcd[661]: wlan0: leased 192.168.86.53 for 86400 seconds Nov 08 18:10:50 bureau avahi-daemon[430]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.86.53. Nov 08 18:10:50 bureau avahi-daemon[430]: New relevant interface wlan0.IPv4 for mDNS. Nov 08 18:10:50 bureau dhcpcd[661]: wlan0: adding route to 192.168.86.0/24 Nov 08 18:10:50 bureau avahi-daemon[430]: Registering new address record for 192.168.86.53 on wlan0.IPv4. Nov 08 18:10:50 bureau dhcpcd[661]: wlan0: adding default route via 192.168.86.1 Nov 08 18:10:51 bureau wireless.js[420]: trying... Nov 08 18:10:51 bureau sudo[755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 08 18:10:51 bureau sudo[755]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:51 bureau sudo[755]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:51 bureau wireless.js[420]: Connected to: ----RM Nov 08 18:10:51 bureau wireless.js[420]: ---- Nov 08 18:10:51 bureau sudo[758]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 08 18:10:51 bureau sudo[758]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:51 bureau sudo[758]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:51 bureau wireless.js[420]: ... joined AP, wlan0 IPv4 is 192.168.86.53, ipV6 is undefined Nov 08 18:10:51 bureau wireless.js[420]: It's done! AP Nov 08 18:10:51 bureau systemd[1]: Started Wireless Services. Nov 08 18:10:51 bureau systemd[1]: Started Volumio Backend Module. Nov 08 18:10:51 bureau systemd[1]: Started Volumio Streaming Daemon. Nov 08 18:10:51 bureau volumio-streaming-daemon[763]: ############################ Nov 08 18:10:51 bureau volumio-streaming-daemon[763]: # Volumio Streaming Daemon # Nov 08 18:10:51 bureau volumio-streaming-daemon[763]: # Running on port 7777 # Nov 08 18:10:51 bureau volumio-streaming-daemon[763]: ############################ Nov 08 18:10:51 bureau volumio-streaming-daemon[763]: Environment: production Nov 08 18:10:52 bureau nmbd[556]: [2021/11/08 18:10:52.129944, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 08 18:10:52 bureau systemd[1]: Started Samba NMB Daemon. Nov 08 18:10:52 bureau nmbd[556]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Nov 08 18:10:52 bureau systemd[1]: Starting Samba Winbind Daemon... Nov 08 18:10:52 bureau ntpd[578]: Listen normally on 3 wlan0 192.168.86.53:123 Nov 08 18:10:52 bureau ntpd[578]: new interface(s) found: waking up resolver Nov 08 18:10:52 bureau winbindd[775]: [2021/11/08 18:10:52.343825, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 08 18:10:52 bureau winbindd[775]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 08 18:10:52 bureau winbindd[775]: [2021/11/08 18:10:52.371557, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 08 18:10:52 bureau systemd[1]: Started Samba Winbind Daemon. Nov 08 18:10:52 bureau winbindd[775]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 08 18:10:52 bureau systemd[1]: Starting Samba SMB Daemon... Nov 08 18:10:52 bureau systemd[1]: Started Samba SMB Daemon. Nov 08 18:10:52 bureau smbd[780]: [2021/11/08 18:10:52.804165, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 08 18:10:52 bureau systemd[1]: Reached target Multi-User System. Nov 08 18:10:52 bureau smbd[780]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 08 18:10:52 bureau systemd[1]: Reached target Graphical Interface. Nov 08 18:10:52 bureau systemd[1]: Starting Update UTMP about System Runlevel Changes... Nov 08 18:10:52 bureau systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Nov 08 18:10:52 bureau systemd[1]: Started Update UTMP about System Runlevel Changes. Nov 08 18:10:52 bureau systemd[1]: Startup finished in 10.608s (kernel) + 23.393s (userspace) = 34.002s. Nov 08 18:10:53 bureau volumio[762]: info: ------------------------------------------- Nov 08 18:10:53 bureau volumio[762]: info: ----- Volumio2 ---- Nov 08 18:10:53 bureau volumio[762]: info: ------------------------------------------- Nov 08 18:10:53 bureau volumio[762]: info: ----- System startup ---- Nov 08 18:10:53 bureau volumio[762]: info: ------------------------------------------- Nov 08 18:10:53 bureau volumio[762]: info: MYVOLUMIO Environment detected Nov 08 18:10:53 bureau volumio[762]: info: Plugin folders cleanup Nov 08 18:10:53 bureau volumio[762]: info: Scanning into folder /volumio/app/plugins/ Nov 08 18:10:53 bureau volumio[762]: info: Scanning category audio_interface Nov 08 18:10:53 bureau volumio[762]: info: Scanning category miscellanea Nov 08 18:10:53 bureau volumio[762]: info: Scanning category music_service Nov 08 18:10:53 bureau volumio[762]: info: Scanning category plugins.json Nov 08 18:10:53 bureau volumio[762]: info: Scanning category system_controller Nov 08 18:10:53 bureau volumio[762]: info: Scanning category user_interface Nov 08 18:10:53 bureau volumio[762]: info: Scanning into folder /data/plugins/ Nov 08 18:10:53 bureau volumio[762]: info: Scanning category music_service Nov 08 18:10:53 bureau volumio[762]: info: Plugin folders cleanup completed Nov 08 18:10:53 bureau volumio[762]: info: ------------------------------------------- Nov 08 18:10:53 bureau volumio[762]: info: ----- Core plugins startup ---- Nov 08 18:10:53 bureau volumio[762]: info: ------------------------------------------- Nov 08 18:10:53 bureau volumio[762]: info: Loading plugins from folder /volumio/app/plugins/ Nov 08 18:10:53 bureau volumio[762]: info: Adding plugin upnp to MyMusic Plugins Nov 08 18:10:53 bureau volumio[762]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 08 18:10:53 bureau volumio[762]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 08 18:10:53 bureau volumio[762]: info: Loading plugins from folder /data/plugins/ Nov 08 18:10:53 bureau volumio[762]: info: Loading plugin "system"... Nov 08 18:10:53 bureau volumio[762]: info: Loading plugin "appearance"... Nov 08 18:10:54 bureau volumio[762]: info: Loading plugin "network"... Nov 08 18:10:54 bureau volumio[762]: info: Refreshing Cached IP Addresses Nov 08 18:10:54 bureau sudo[800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 08 18:10:54 bureau sudo[800]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:54 bureau volumio[762]: info: Loading plugin "services"... Nov 08 18:10:54 bureau volumio[762]: info: Loading plugin "alsa_controller"... Nov 08 18:10:54 bureau sudo[800]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:54 bureau sudo[802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 08 18:10:54 bureau sudo[802]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:54 bureau sudo[802]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:55 bureau volumio[762]: info: Loading plugin "wizard"... Nov 08 18:10:55 bureau volumio[762]: info: Loading plugin "volumio_command_line_client"... Nov 08 18:10:55 bureau volumio[762]: info: Loading plugin "upnp"... Nov 08 18:10:55 bureau volumio[762]: info: [1636395055141] Starting Upmpd Daemon Nov 08 18:10:55 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 08 18:10:55 bureau volumio[762]: info: Loading plugin "my_music"... Nov 08 18:10:55 bureau volumio[762]: info: Loading plugin "mpd"... Nov 08 18:10:55 bureau volumio[762]: info: Creating MPD Configuration file Nov 08 18:10:55 bureau volumio[762]: info: Loading plugin "upnp_browser"... Nov 08 18:10:55 bureau sudo[810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 08 18:10:55 bureau sudo[810]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:55 bureau sudo[810]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:55 bureau sudo[812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 08 18:10:55 bureau sudo[812]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:55 bureau systemd[1]: Stopping Music Player Daemon... Nov 08 18:10:55 bureau systemd[1]: mpd.service: Succeeded. Nov 08 18:10:55 bureau systemd[1]: Stopped Music Player Daemon. Nov 08 18:10:55 bureau systemd[1]: Starting Music Player Daemon... Nov 08 18:10:55 bureau volumio[762]: info: Loading plugin "networkfs"... Nov 08 18:10:56 bureau volumio[762]: info: Starting Udev Watcher for removable devices Nov 08 18:10:56 bureau sudo[820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=,password=,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.86.52/MOBEKO/MUSIQUE/FLAC /mnt/NAS/MUSIQUE Nov 08 18:10:56 bureau sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:56 bureau kernel: FS-Cache: Netfs 'cifs' registered for caching Nov 08 18:10:56 bureau kernel: Key type cifs.spnego registered Nov 08 18:10:56 bureau kernel: Key type cifs.idmap registered Nov 08 18:10:56 bureau kernel: CIFS: Attempting to mount //192.168.86.52/MOBEKO/MUSIQUE/FLAC Nov 08 18:10:56 bureau kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Nov 08 18:10:56 bureau volumio[762]: info: Ignoring mount for partition: boot Nov 08 18:10:56 bureau volumio[762]: info: Ignoring mount for partition: volumio Nov 08 18:10:56 bureau volumio[762]: info: Ignoring mount for partition: volumio_data Nov 08 18:10:56 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 08 18:10:56 bureau volumio[762]: info: Loading plugin "alarm-clock"... Nov 08 18:10:56 bureau mpd[817]: Nov 08 18:10 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 08 18:10:56 bureau volumio[762]: info: Loading plugin "airplay_emulation"... Nov 08 18:10:56 bureau volumio[762]: info: Starting Shairport Sync Nov 08 18:10:56 bureau volumio[762]: info: Loading plugin "last_100"... Nov 08 18:10:56 bureau volumio[762]: info: Loading plugin "webradio"... Nov 08 18:10:56 bureau mpd[817]: Nov 08 18:10 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 08 18:10:56 bureau mpd[817]: Nov 08 18:10 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 08 18:10:56 bureau systemd[1]: Started Music Player Daemon. Nov 08 18:10:56 bureau sudo[812]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:56 bureau volumio[762]: info: Loading plugin "i2s_dacs"... Nov 08 18:10:56 bureau volumio[762]: info: I2S DAC not set, start Auto-detection Nov 08 18:10:56 bureau volumio[762]: info: Loading plugin "volumiodiscovery"... Nov 08 18:10:56 bureau volumio[762]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 08 18:10:56 bureau node[762]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 08 18:10:56 bureau volumio[762]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 08 18:10:56 bureau node[762]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 08 18:10:56 bureau volumio[762]: *** WARNING *** For more information see Nov 08 18:10:56 bureau node[762]: *** WARNING *** For more information see Nov 08 18:10:56 bureau volumio[762]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 08 18:10:56 bureau node[762]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 08 18:10:56 bureau volumio[762]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 08 18:10:56 bureau node[762]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 08 18:10:56 bureau volumio[762]: *** WARNING *** For more information see Nov 08 18:10:56 bureau node[762]: *** WARNING *** For more information see Nov 08 18:10:56 bureau volumio[762]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 08 18:10:56 bureau volumio[762]: info: Discovery: Started advertising with name: Bureau Nov 08 18:10:56 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 08 18:10:56 bureau volumio[762]: info: Loading plugin "outputs"... Nov 08 18:10:56 bureau volumio[762]: info: Loading plugin "albumart"... Nov 08 18:10:56 bureau volumio[762]: info: Plugin example_plugin is not enabled Nov 08 18:10:56 bureau volumio[762]: info: Loading plugin "inputs"... Nov 08 18:10:56 bureau volumio[762]: info: Loading plugin "updater_comm"... Nov 08 18:10:56 bureau sudo[820]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:56 bureau volumio[762]: info: Plugin mpdemulation is not enabled Nov 08 18:10:56 bureau volumio[762]: info: Loading plugin "rest_api"... Nov 08 18:10:56 bureau volumio[762]: info: Loading plugin "websocket"... Nov 08 18:10:56 bureau volumio[762]: info: Loading plugin "radio_paradise"... Nov 08 18:10:56 bureau volumio[762]: Forking 3 albumart workers Nov 08 18:10:57 bureau volumio[762]: info: Applying required configuration parameters for plugin radio_paradise Nov 08 18:10:57 bureau volumio[762]: info: [1636395057312] [RadioParadise] API delay: 5 Nov 08 18:10:57 bureau volumio[762]: info: Loading plugin "volspotconnect2"... Nov 08 18:10:57 bureau volumio[762]: Starting albumart workers Nov 08 18:10:57 bureau volumio[762]: Starting albumart workers Nov 08 18:10:57 bureau volumio[762]: Starting albumart workers Nov 08 18:10:58 bureau volumio[762]: info: Loading i18n strings for locale fr Nov 08 18:10:58 bureau volumio[762]: Updating browse sources language Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::initPlayerControls Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 08 18:10:58 bureau volumio[762]: Express server listening on port 3000 Nov 08 18:10:58 bureau volumio[762]: [Metrics] WebUI: 5s 723.02ms Nov 08 18:10:58 bureau volumio[762]: Volumio Calling Home Nov 08 18:10:58 bureau volumio[762]: info: CoreStateMachine::resetVolumioState Nov 08 18:10:58 bureau volumio[762]: info: CoreStateMachine::getcurrentVolume Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::volumioRetrievevolume Nov 08 18:10:58 bureau sudo[911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Nov 08 18:10:58 bureau sudo[911]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:58 bureau volumio[762]: info: MPD Permissions set Nov 08 18:10:58 bureau sudo[911]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:58 bureau volumio[762]: info: MPD running with PID817 Nov 08 18:10:58 bureau volumio[762]: ,establishing connection Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:58 bureau volumio[762]: info: Reloading queue from file Nov 08 18:10:58 bureau volumio[762]: error: updateQueue error: null Nov 08 18:10:58 bureau volumio[762]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 08 18:10:58 bureau volumio[762]: info: VolumeController:: Volume=30 Mute =false Nov 08 18:10:58 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:10:58 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:10:58 bureau volumio[762]: info: CoreStateMachine::updateTrackBlock Nov 08 18:10:58 bureau volumio[762]: info: CorePlayQueue::getTrackBlock Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::volumioRetrievevolume Nov 08 18:10:58 bureau volumio[762]: info: Setting Device type: Raspberry PI Nov 08 18:10:58 bureau volumio[762]: info: CoreStateMachine::setRepeat null single undefined Nov 08 18:10:58 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:10:58 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:10:58 bureau volumio[762]: info: CoreStateMachine::setRandom null Nov 08 18:10:58 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:10:58 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:10:58 bureau volumio[762]: info: Completed loading Core Plugins Nov 08 18:10:58 bureau volumio[762]: info: Preparing to generate the ALSA configuration file Nov 08 18:10:58 bureau volumio[762]: info: Discovery: adding 221d04ff-49e6-4903-b8c4-19c0e7a1f5db Nov 08 18:10:58 bureau volumio[762]: info: mDNS: Found device Bureau Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:10:58 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:10:58 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:10:58 bureau volumio[762]: info: VolumeController:: Volume=30 Mute =false Nov 08 18:10:58 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:10:58 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:10:58 bureau volumio[762]: info: Volumio called home Nov 08 18:10:58 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:10:58 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:10:58 bureau volumio[762]: info: Discovery: adding 42a9680b-2995-4fc5-a0fa-2ba741838420 Nov 08 18:10:58 bureau volumio[762]: info: mDNS: Found device Salon Nov 08 18:10:58 bureau volumio-remote-updater[439]: [2021-11-08 18:10:58] [connect] Successful connection Nov 08 18:10:58 bureau volumio[762]: error: updateQueue error: null Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::volumioGetQueue Nov 08 18:10:59 bureau volumio[762]: info: CoreStateMachine::getQueue Nov 08 18:10:59 bureau volumio[762]: info: CorePlayQueue::getQueue Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Nov 08 18:10:59 bureau volumio-remote-updater[439]: [2021-11-08 18:10:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1636395058 101 Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::volumioGetVisibleSources Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 08 18:10:59 bureau volumio[762]: info: Listing playlists Nov 08 18:10:59 bureau volumio[762]: info: Asound.conf file written Nov 08 18:10:59 bureau sudo[929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 08 18:10:59 bureau sudo[929]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:59 bureau sudo[929]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:59 bureau volumio[762]: info: Output device has changed, restarting MPD Nov 08 18:10:59 bureau sudo[934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 08 18:10:59 bureau sudo[934]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:59 bureau volumio[762]: info: Output device has changed, restarting Shairport Sync Nov 08 18:10:59 bureau sudo[934]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:59 bureau sudo[937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 08 18:10:59 bureau sudo[937]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:59 bureau systemd[1]: Stopping Music Player Daemon... Nov 08 18:10:59 bureau volumio[762]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 08 18:10:59 bureau volumio[762]: info: ___________ START PLUGINS ___________ Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 08 18:10:59 bureau volumio[762]: info: [1636395059499] CoreMusicLibrary::Adding element Media Servers Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 08 18:10:59 bureau volumio[762]: Cannot find translation for sourceMedia Servers Nov 08 18:10:59 bureau systemd[1]: mpd.service: Succeeded. Nov 08 18:10:59 bureau systemd[1]: Stopped Music Player Daemon. Nov 08 18:10:59 bureau systemd[1]: Starting Music Player Daemon... Nov 08 18:10:59 bureau volumio[762]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 08 18:10:59 bureau volumio[762]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 08 18:10:59 bureau volumio[762]: info: [1636395059602] CoreMusicLibrary::Adding element Last_100 Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 08 18:10:59 bureau volumio[762]: Cannot find translation for sourceMedia Servers Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 08 18:10:59 bureau volumio[762]: info: [1636395059605] CoreMusicLibrary::Adding element Webradio Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 08 18:10:59 bureau volumio[762]: Cannot find translation for sourceMedia Servers Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 08 18:10:59 bureau volumio[762]: info: [1636395059620] CoreMusicLibrary::Adding element Radio Paradise Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 08 18:10:59 bureau volumio[762]: Cannot find translation for sourceMedia Servers Nov 08 18:10:59 bureau volumio[762]: Cannot find translation for sourceRadio Paradise Nov 08 18:10:59 bureau volumio[762]: [SpotifyConnect] Creating VLS config file Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:59 bureau volumio[762]: [SpotifyConnect] Starting metadata listener Nov 08 18:10:59 bureau sudo[952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Nov 08 18:10:59 bureau sudo[952]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:10:59 bureau systemd[1]: Started Volspotconnect2 Daemon. Nov 08 18:10:59 bureau sudo[952]: pam_unix(sudo:session): session closed for user root Nov 08 18:10:59 bureau volumio[954]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 Nov 08 18:10:59 bureau volumio[954]: Reading Config from "volspotify.toml" Nov 08 18:10:59 bureau volumio[954]: [Vollibrespot] : Using Alsa backend with device: volumio Nov 08 18:10:59 bureau volumio[762]: [SpotifyConnect] Vollibrespot Daemon service started! Nov 08 18:10:59 bureau volumio[762]: [Metrics] SpotifyConnect: 0s 233.99ms Nov 08 18:10:59 bureau volumio[762]: info: Completed starting Core Plugins Nov 08 18:10:59 bureau volumio[762]: info: ------------------------------------------- Nov 08 18:10:59 bureau volumio[762]: info: ----- MyVolumio plugins startup ---- Nov 08 18:10:59 bureau volumio[762]: info: ------------------------------------------- Nov 08 18:10:59 bureau volumio[762]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 08 18:10:59 bureau volumio[762]: info: MPD Permissions set Nov 08 18:10:59 bureau volumio[954]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:10:59 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:10:59 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:10:59 bureau volumio[762]: info: Starting Shairport Sync Nov 08 18:10:59 bureau volumio[762]: info: Starting Shairport Sync Nov 08 18:11:00 bureau sudo[961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 08 18:11:00 bureau sudo[961]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:00 bureau volumio[762]: info: Starting Shairport Sync Nov 08 18:11:00 bureau sudo[963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 08 18:11:00 bureau sudo[966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 08 18:11:00 bureau sudo[963]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:00 bureau sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:00 bureau systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 08 18:11:00 bureau systemd[1]: shairport-sync.service: Succeeded. Nov 08 18:11:00 bureau systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 08 18:11:00 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:11:00 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:11:00 bureau systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 08 18:11:00 bureau sudo[961]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:00 bureau sudo[966]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:00 bureau sudo[963]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:00 bureau volumio[762]: info: Shairport-Sync Started Nov 08 18:11:00 bureau volumio[762]: Error adding Membership: Error: addMembership EINVAL Nov 08 18:11:00 bureau volumio[762]: info: Shairport-Sync Started Nov 08 18:11:00 bureau volumio[762]: info: Shairport-Sync Started Nov 08 18:11:00 bureau mpd[951]: Nov 08 18:11 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 08 18:11:00 bureau mpd[951]: Nov 08 18:11 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 08 18:11:00 bureau mpd[951]: Nov 08 18:11 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 08 18:11:00 bureau systemd[1]: Started Music Player Daemon. Nov 08 18:11:00 bureau sudo[937]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:00 bureau volumio[762]: error: updateQueue error: null Nov 08 18:11:04 bureau systemd[1]: systemd-fsckd.service: Succeeded. Nov 08 18:11:05 bureau sh[344]: timed out Nov 08 18:11:05 bureau dhcpcd[391]: timed out Nov 08 18:11:05 bureau sh[344]: dhcpcd exited Nov 08 18:11:05 bureau dhcpcd[391]: dhcpcd exited Nov 08 18:11:05 bureau sh[344]: ifup: failed to bring up eth0 Nov 08 18:11:05 bureau systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Nov 08 18:11:05 bureau systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Nov 08 18:11:05 bureau sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 08 18:11:05 bureau sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:05 bureau sudo[976]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:05 bureau sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 08 18:11:05 bureau sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:05 bureau sudo[978]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:05 bureau sudo[982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 08 18:11:05 bureau sudo[982]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:05 bureau systemd[1]: Started UPnP Renderer front-end to MPD. Nov 08 18:11:05 bureau sudo[982]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:05 bureau volumio[762]: info: Upmpdcli Daemon Started Nov 08 18:11:05 bureau volumio[984]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 08 18:11:08 bureau volumio[762]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 08 18:11:08 bureau volumio[762]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 08 18:11:08 bureau volumio[762]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 08 18:11:08 bureau volumio[762]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 08 18:11:08 bureau volumio[762]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 08 18:11:08 bureau volumio[762]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 08 18:11:08 bureau volumio[762]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 08 18:11:08 bureau volumio[762]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 08 18:11:08 bureau volumio[762]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 08 18:11:08 bureau volumio[762]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 08 18:11:08 bureau volumio[762]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 08 18:11:08 bureau volumio[762]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 08 18:11:08 bureau volumio[762]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 08 18:11:08 bureau volumio[762]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 08 18:11:08 bureau volumio[762]: info: Adding plugin bluetooth to MyMusic Plugins Nov 08 18:11:08 bureau volumio[762]: info: Adding plugin cd_controller to MyMusic Plugins Nov 08 18:11:08 bureau volumio[762]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 08 18:11:08 bureau volumio[762]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 08 18:11:08 bureau volumio[762]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 08 18:11:09 bureau volumio[762]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 08 18:11:09 bureau volumio[762]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 08 18:11:09 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:11:09 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:11:09 bureau volumio[762]: info: Starting MyVolumio Remote Streaming Endpoints Nov 08 18:11:09 bureau volumio[762]: info: MyVolumio login type: Token Nov 08 18:11:09 bureau volumio[762]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 08 18:11:09 bureau volumio[762]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 08 18:11:09 bureau volumio[762]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 08 18:11:09 bureau volumio[762]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 08 18:11:09 bureau volumio[762]: info: Streaming services startup Nov 08 18:11:09 bureau volumio[762]: info: Starting Streaming Daemon Nov 08 18:11:09 bureau sudo[1001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 08 18:11:09 bureau sudo[1001]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:09 bureau systemd[1]: Stopping Volumio Streaming Daemon... Nov 08 18:11:09 bureau systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Nov 08 18:11:09 bureau systemd[1]: volumio-streaming-daemon.service: Succeeded. Nov 08 18:11:09 bureau systemd[1]: Stopped Volumio Streaming Daemon. Nov 08 18:11:09 bureau systemd[1]: Started Volumio Streaming Daemon. Nov 08 18:11:09 bureau sudo[1001]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:09 bureau volumio-streaming-daemon[1007]: ############################ Nov 08 18:11:09 bureau volumio-streaming-daemon[1007]: # Volumio Streaming Daemon # Nov 08 18:11:09 bureau volumio-streaming-daemon[1007]: # Running on port 7777 # Nov 08 18:11:09 bureau volumio-streaming-daemon[1007]: ############################ Nov 08 18:11:09 bureau volumio-streaming-daemon[1007]: Environment: production Nov 08 18:11:11 bureau volumio-streaming-daemon[1007]: Environment: production Nov 08 18:11:11 bureau volumio[762]: info: Fetching Streaming Services browse cache Nov 08 18:11:12 bureau systemd[1]: systemd-hostnamed.service: Succeeded. Nov 08 18:11:12 bureau volumio[762]: error: MyVolumio Custom Token format not valid, refreshing it Nov 08 18:11:13 bureau volumio[762]: info: MyVolumio login type: Token Nov 08 18:11:13 bureau volumio[762]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 08 18:11:13 bureau volumio[762]: info: MYVOLUMIO: Adding device Nov 08 18:11:13 bureau volumio[762]: info: MYVOLUMIO: Evaluating Server Nov 08 18:11:14 bureau volumio[762]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Nov 08 18:11:14 bureau volumio[762]: info: Adding audio output: Nov 08 18:11:14 bureau volumio[762]: info: MYVOLUMIO: Adding device Nov 08 18:11:14 bureau volumio[762]: info: MYVOLUMIO: Evaluating Server Nov 08 18:11:14 bureau volumio[762]: info: Remote config written successfully Nov 08 18:11:14 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 18:11:14 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 18:11:14 bureau volumio[762]: info: Completed starting MyVolumio Plugin Nov 08 18:11:14 bureau sudo[1029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 18:11:14 bureau sudo[1029]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:14 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 18:11:14 bureau sudo[1029]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:14 bureau volumio[762]: info: Remote SSH Started Nov 08 18:11:14 bureau autossh[1032]: port set to 0, monitoring disabled Nov 08 18:11:14 bureau autossh[1032]: starting ssh (count 1) Nov 08 18:11:14 bureau autossh[1032]: ssh child pid is 1035 Nov 08 18:11:14 bureau volumio[762]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Nov 08 18:11:14 bureau volumio[762]: info: MyVolumio status changed Nov 08 18:11:14 bureau volumio[762]: info: Streaming services startup Nov 08 18:11:14 bureau volumio[762]: info: Re-Starting Streaming Daemon Nov 08 18:11:14 bureau volumio-streaming-daemon[1007]: Restarting daemon Nov 08 18:11:14 bureau volumio-streaming-daemon[1007]: Environment: production Nov 08 18:11:14 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 08 18:11:14 bureau volumio[762]: error: [MyVolumio PluginManager] Cache data is invalid! Nov 08 18:11:14 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:11:14 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:11:15 bureau volumio[762]: info: Setting Geolocation for MyVolumio to us1 Nov 08 18:11:15 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:11:15 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:11:15 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:11:15 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:11:15 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:11:15 bureau volumio[762]: info: CoreCommandRouter::volumioGetQueue Nov 08 18:11:15 bureau volumio[762]: info: CoreStateMachine::getQueue Nov 08 18:11:15 bureau volumio[762]: info: CorePlayQueue::getQueue Nov 08 18:11:15 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Nov 08 18:11:15 bureau volumio-streaming-daemon[1007]: Enabled services: Nov 08 18:11:15 bureau volumio-streaming-daemon[1007]: 1) tidal Nov 08 18:11:15 bureau volumio-streaming-daemon[1007]: 2) qobuz Nov 08 18:11:16 bureau volumio[762]: info: Updating MyVolumio device info Nov 08 18:11:16 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:11:16 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:11:16 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:11:16 bureau volumio-streaming-daemon[1007]: Environment: production Nov 08 18:11:16 bureau volumio[762]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Nov 08 18:11:16 bureau volumio[762]: info: [1636395076938] Starting BluetoothController Nov 08 18:11:17 bureau volumio[762]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Nov 08 18:11:18 bureau volumio[762]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Nov 08 18:11:18 bureau sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast Nov 08 18:11:18 bureau sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:18 bureau sudo[1045]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:18 bureau sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Nov 08 18:11:18 bureau sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:18 bureau sudo[1048]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:18 bureau volumio[762]: info: MultiRoom plugin initialized Nov 08 18:11:18 bureau volumio[762]: STOPPING SNAPCLIENT Nov 08 18:11:18 bureau volumio[762]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Nov 08 18:11:18 bureau sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Nov 08 18:11:18 bureau sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:18 bureau volumio[762]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Nov 08 18:11:18 bureau volumio[984]: ...............................................................................................................................................++++ Nov 08 18:11:18 bureau sudo[1064]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:18 bureau volumio[762]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Nov 08 18:11:18 bureau volumio[762]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Nov 08 18:11:18 bureau volumio[762]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Nov 08 18:11:18 bureau volumio[762]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Nov 08 18:11:18 bureau volumio[762]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Nov 08 18:11:18 bureau volumio[762]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Nov 08 18:11:18 bureau volumio[762]: info: Preparing to generate the ALSA configuration file Nov 08 18:11:18 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Nov 08 18:11:18 bureau volumio[762]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 08 18:11:18 bureau volumio[762]: info: Reading ALSA contributions from plugins. Nov 08 18:11:18 bureau volumio[762]: info: MRS: SNAPCLIENT STOPPED Nov 08 18:11:18 bureau volumio[762]: info: Snap server and client installed Nov 08 18:11:18 bureau volumio[762]: info: Fetching Streaming Services browse cache Nov 08 18:11:18 bureau volumio[762]: info: Asound.conf file written Nov 08 18:11:18 bureau sudo[1067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 08 18:11:18 bureau sudo[1067]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:18 bureau sudo[1067]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:18 bureau volumio[762]: info: Output device has changed, restarting MPD Nov 08 18:11:18 bureau sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 08 18:11:18 bureau sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:18 bureau volumio[762]: info: Output device has changed, restarting Shairport Sync Nov 08 18:11:18 bureau sudo[1072]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:18 bureau sudo[1075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 08 18:11:18 bureau sudo[1075]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:18 bureau volumio[762]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 08 18:11:18 bureau systemd[1]: Stopping Music Player Daemon... Nov 08 18:11:18 bureau volumio[762]: [SpotifyConnect] Creating VLS config file Nov 08 18:11:18 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:11:18 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:11:18 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:11:18 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:11:18 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:11:18 bureau volumio[762]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Nov 08 18:11:18 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 08 18:11:18 bureau volumio[762]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Nov 08 18:11:18 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Nov 08 18:11:18 bureau volumio[762]: info: MRS: Pushing multiroomSync output for this device Nov 08 18:11:18 bureau volumio[762]: info: MRS: Pushing multiroomSync output Nov 08 18:11:18 bureau volumio[762]: info: Adding audio output: Nov 08 18:11:18 bureau systemd[1]: mpd.service: Succeeded. Nov 08 18:11:18 bureau volumio[762]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Nov 08 18:11:18 bureau volumio[762]: info: Adding METAVOLUMIO REST API Endpoints Nov 08 18:11:18 bureau volumio[762]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 08 18:11:18 bureau volumio[762]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Nov 08 18:11:18 bureau volumio[762]: info: Preparing CD Folders Nov 08 18:11:18 bureau systemd[1]: Stopped Music Player Daemon. Nov 08 18:11:18 bureau volumio[762]: info: Adding CD REST API Endpoints Nov 08 18:11:18 bureau volumio[762]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 08 18:11:18 bureau volumio[762]: info: Starting UDEV Watcher for CD Nov 08 18:11:18 bureau systemd[1]: Starting Music Player Daemon... Nov 08 18:11:18 bureau sudo[1081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Bureau Nov 08 18:11:18 bureau sudo[1081]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:18 bureau volumio[762]: info: Detecting CD presence with UDEV Nov 08 18:11:18 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 08 18:11:18 bureau sudo[1081]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:19 bureau volumio[762]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Nov 08 18:11:19 bureau volumio[762]: info: Adding inputs REST Endpoints Nov 08 18:11:19 bureau volumio[762]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 08 18:11:19 bureau volumio[762]: info: Scanning Audio Inputs Nov 08 18:11:19 bureau volumio[762]: info: Checking against Known Cards name Nov 08 18:11:19 bureau volumio[762]: info: Adding Server instance for streaming Nov 08 18:11:19 bureau volumio[762]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Nov 08 18:11:19 bureau volumio[762]: error: Hi Res Audio Failed Login: Missing Login Data Nov 08 18:11:19 bureau volumio[762]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Nov 08 18:11:19 bureau volumio[762]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Nov 08 18:11:19 bureau volumio[762]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Nov 08 18:11:19 bureau sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 08 18:11:19 bureau sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:19 bureau volumio[762]: info: Stopping AccessToken refresher cron Nov 08 18:11:19 bureau sudo[1089]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:19 bureau volumio[762]: info: AccessToken refresher cron started Nov 08 18:11:19 bureau volumio[762]: info: Stopping AccessToken refresher cron for QOBUZ Nov 08 18:11:19 bureau volumio[762]: info: AccessToken refresher cron started for QOBUZ Nov 08 18:11:19 bureau volumio[762]: info: Bluetooth name changed to Bureau Nov 08 18:11:19 bureau volumio[762]: info: MPD Permissions set Nov 08 18:11:19 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:11:19 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:11:19 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:11:19 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:11:19 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:11:19 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:11:19 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:11:19 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:11:19 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:11:19 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 08 18:11:19 bureau volumio[762]: info: MRS: Adding multiroomSync output Nov 08 18:11:19 bureau volumio[762]: info: Adding audio output: Nov 08 18:11:19 bureau volumio[762]: info: MRS: Pushing multiroomSync output Nov 08 18:11:19 bureau volumio[762]: info: Setting Geolocation for MyVolumio to us1 Nov 08 18:11:19 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:11:19 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:11:19 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:11:19 bureau volumio[762]: info: Starting Shairport Sync Nov 08 18:11:19 bureau mpd[1085]: Nov 08 18:11 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 08 18:11:19 bureau volumio[762]: [SpotifyConnect] Restarting Vollibrespot Daemon Nov 08 18:11:19 bureau sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 08 18:11:19 bureau sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:19 bureau systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 08 18:11:19 bureau systemd[1]: shairport-sync.service: Succeeded. Nov 08 18:11:19 bureau systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 08 18:11:19 bureau systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 08 18:11:19 bureau sudo[1097]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:19 bureau volumio[762]: info: Shairport-Sync Started Nov 08 18:11:19 bureau sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Nov 08 18:11:19 bureau sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:19 bureau systemd[1]: Stopping Volspotconnect2 Daemon... Nov 08 18:11:19 bureau systemd[1]: volspotconnect2.service: Main process exited, code=killed, status=15/TERM Nov 08 18:11:19 bureau systemd[1]: volspotconnect2.service: Succeeded. Nov 08 18:11:19 bureau systemd[1]: Stopped Volspotconnect2 Daemon. Nov 08 18:11:19 bureau systemd[1]: Started Volspotconnect2 Daemon. Nov 08 18:11:19 bureau sudo[1100]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:19 bureau volumio[762]: [SpotifyConnect] Vollibrespot Daemon service restarted! Nov 08 18:11:19 bureau volumio[1105]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 Nov 08 18:11:19 bureau volumio[1105]: Reading Config from "volspotify.toml" Nov 08 18:11:19 bureau volumio[1105]: [Vollibrespot] : Using Alsa backend with device: volumio Nov 08 18:11:19 bureau volumio[1105]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Nov 08 18:11:19 bureau mpd[1085]: Nov 08 18:11 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Nov 08 18:11:19 bureau mpd[1085]: Nov 08 18:11 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Nov 08 18:11:19 bureau systemd[1]: Started Music Player Daemon. Nov 08 18:11:19 bureau sudo[1075]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:19 bureau volumio[762]: error: updateQueue error: null Nov 08 18:11:20 bureau volumio[762]: info: Updating MyVolumio device info Nov 08 18:11:20 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:11:21 bureau sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Nov 08 18:11:21 bureau sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:21 bureau systemd[1]: Started Volumio Bluetooth Module. Nov 08 18:11:21 bureau sudo[1110]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:21 bureau volumio[762]: info: Volumio BT Module successfully started Nov 08 18:11:21 bureau sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Nov 08 18:11:21 bureau sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:21 bureau volumiobt[1112]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Nov 08 18:11:21 bureau sudo[1113]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:22 bureau volumio[762]: info: TidalConnect service stoped! Nov 08 18:11:22 bureau volumio[762]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 08 18:11:22 bureau volumio[762]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 08 18:11:22 bureau sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 08 18:11:22 bureau sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:22 bureau systemd[1]: Started Volumio Tidal Connect Service. Nov 08 18:11:22 bureau sudo[1121]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:22 bureau volumio[762]: info: Executing endpoint tc_getconfig Nov 08 18:11:22 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 08 18:11:22 bureau vtcs[1123]: STARTING TidalConnect services, version: 1.1.7.5 Nov 08 18:11:22 bureau vtcs[1123]: STARTED TidalConnect services. Nov 08 18:11:22 bureau volumio[762]: info: Executing endpoint tc_connect Nov 08 18:11:22 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 08 18:11:22 bureau volumio[762]: Connecting to TidalConnect. Nov 08 18:11:22 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:11:22 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:11:22 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:11:22 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:11:22 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:11:22 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:11:22 bureau volumio[762]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 08 18:11:22 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:11:22 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:11:22 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:11:22 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:11:22 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:11:22 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:11:22 bureau volumio[762]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 08 18:11:22 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:11:22 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:11:23 bureau sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Nov 08 18:11:23 bureau sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:11:23 bureau volumiobt[1112]: pulseaudio: no process found Nov 08 18:11:23 bureau sudo[1141]: pam_unix(sudo:session): session closed for user root Nov 08 18:11:23 bureau dbus-daemon[449]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.19' (uid=1000 pid=1145 comm="/usr/bin/pulseaudio ") Nov 08 18:11:23 bureau systemd[1]: Starting RealtimeKit Scheduling Policy Service... Nov 08 18:11:23 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 18:11:23 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:11:23 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 08 18:11:23 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:11:23 bureau dbus-daemon[449]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Nov 08 18:11:23 bureau systemd[1]: Started RealtimeKit Scheduling Policy Service. Nov 08 18:11:23 bureau rtkit-daemon[1146]: Successfully called chroot. Nov 08 18:11:23 bureau rtkit-daemon[1146]: Successfully dropped privileges. Nov 08 18:11:23 bureau rtkit-daemon[1146]: Successfully limited resources. Nov 08 18:11:23 bureau rtkit-daemon[1146]: Running. Nov 08 18:11:23 bureau rtkit-daemon[1146]: Canary thread running. Nov 08 18:11:23 bureau rtkit-daemon[1146]: Watchdog thread running. Nov 08 18:11:23 bureau dbus-daemon[449]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.20' (uid=0 pid=1146 comm="/usr/lib/rtkit/rtkit-daemon ") Nov 08 18:11:23 bureau systemd[1]: Starting Authorization Manager... Nov 08 18:11:23 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 18:11:23 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:11:23 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 08 18:11:23 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:11:23 bureau polkitd[1149]: started daemon version 0.105 using authority implementation `local' version `0.105' Nov 08 18:11:23 bureau dbus-daemon[449]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Nov 08 18:11:23 bureau systemd[1]: Started Authorization Manager. Nov 08 18:11:23 bureau pulseaudio[1145]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 08 18:11:23 bureau pulseaudio[1145]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Nov 08 18:11:23 bureau volumiobt[1112]: Applying permissions Nov 08 18:11:23 bureau volumiobt[1112]: Setting BT discoverable and pairable Nov 08 18:11:23 bureau bluetoothd[685]: Endpoint registered: sender=:1.40 path=/MediaEndpoint/A2DPSource Nov 08 18:11:23 bureau bluetoothd[685]: Endpoint registered: sender=:1.40 path=/MediaEndpoint/A2DPSink Nov 08 18:11:23 bureau pulseaudio[1145]: 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 Nov 08 18:11:23 bureau kernel: Bluetooth: RFCOMM TTY layer initialized Nov 08 18:11:23 bureau kernel: Bluetooth: RFCOMM socket layer initialized Nov 08 18:11:23 bureau kernel: Bluetooth: RFCOMM ver 1.11 Nov 08 18:11:23 bureau volumiobt[1112]: [CHG] Controller DC:A6:32:6D:3A:8C UUIDs: 00001112-0000-1000-8000-00805f9b34fb Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [117B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [55B blob data] Nov 08 18:11:23 bureau volumiobt[1112]: [bluetooth]# -e power on Nov 08 18:11:23 bureau volumiobt[1112]: Invalid command in menu main: -e Nov 08 18:11:23 bureau volumiobt[1112]: Nov 08 18:11:23 bureau volumiobt[1112]: Use "help" for a list of available commands in a menu. Nov 08 18:11:23 bureau volumiobt[1112]: Use "menu " if you want to enter any submenu. Nov 08 18:11:23 bureau volumiobt[1112]: Use "back" if you want to return to menu main. Nov 08 18:11:23 bureau volumiobt[1112]: [bluetooth]# agent on Nov 08 18:11:23 bureau volumiobt[1112]: Agent is already registered Nov 08 18:11:23 bureau volumiobt[1112]: [bluetooth]# discoverable on Nov 08 18:11:23 bureau volumiobt[1112]: [bluetooth]# pairable on Nov 08 18:11:23 bureau volumiobt[1112]: [bluetooth]# agent NoInputNoOutput Nov 08 18:11:23 bureau volumiobt[1112]: Agent is already registered Nov 08 18:11:23 bureau volumiobt[1112]: [bluetooth]# default-agent Nov 08 18:11:23 bureau volumiobt[1112]: [bluetooth]# quit Nov 08 18:11:23 bureau volumiobt[1112]: [59B blob data] Nov 08 18:11:23 bureau volumio[762]: info: MRS: Getting audio outputs on start Nov 08 18:11:23 bureau volumio[762]: info: MRS: Requesting all other devices output Nov 08 18:11:23 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:11:25 bureau volumio[762]: info: TidalConnect service started! Nov 08 18:11:25 bureau volumio[762]: info: BOOT COMPLETED Nov 08 18:11:25 bureau volumio[762]: [Metrics] CommandRouter: 32s 305.43ms Nov 08 18:11:25 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:11:31 bureau volumio[762]: xcb_connection_has_error() returned true Nov 08 18:11:31 bureau volumio[762]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Nov 08 18:11:31 bureau volumio[762]: info: CoreCommandRouter::Close All Modals sent Nov 08 18:11:31 bureau volumio[762]: info: CoreCommandRouter::Close All Modals sent Nov 08 18:11:31 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 08 18:11:31 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 08 18:11:32 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 08 18:11:32 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 08 18:11:32 bureau volumio[762]: xcb_connection_has_error() returned true Nov 08 18:11:32 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 08 18:11:34 bureau systemd-udevd[225]: Spawned process '/bin/bash -c '/usr/local/bin/volumio usbattach'' [410] is taking longer than 59s to complete Nov 08 18:11:35 bureau systemd-udevd[216]: pcmC5D0p: Worker [225] processing SEQNUM=1554 is taking a long time Nov 08 18:11:37 bureau volumio[984]: ................................................................................................................................................................................................................++++ Nov 08 18:11:37 bureau volumio[984]: e is 65537 (0x010001) Nov 08 18:11:37 bureau volumio[984]: writing RSA key Nov 08 18:11:38 bureau volumiossh-tunnel[1031]: Warning: Permanently added '[eu1.myvolumio.org]:2222,[188.166.162.121]:2222' (RSA) to the list of known hosts. Nov 08 18:11:41 bureau ntpd[578]: Soliciting pool server 203.57.115.181 Nov 08 18:11:42 bureau ntpd[578]: Soliciting pool server 84.245.9.254 Nov 08 18:11:42 bureau ntpd[578]: Soliciting pool server 194.25.134.196 Nov 08 18:11:43 bureau ntpd[578]: Soliciting pool server 149.56.121.17 Nov 08 18:11:43 bureau ntpd[578]: Soliciting pool server 37.187.5.167 Nov 08 18:11:44 bureau ntpd[578]: Soliciting pool server 54.39.23.64 Nov 08 18:11:44 bureau ntpd[578]: Soliciting pool server 112.213.34.20 Nov 08 18:11:44 bureau ntpd[578]: Soliciting pool server 199.182.221.110 Nov 08 18:11:45 bureau ntpd[578]: Soliciting pool server 205.206.70.7 Nov 08 18:11:45 bureau ntpd[578]: Soliciting pool server 5.199.135.170 Nov 08 18:11:45 bureau ntpd[578]: Soliciting pool server 194.0.5.123 Nov 08 18:11:46 bureau ntpd[578]: Soliciting pool server 205.206.70.40 Nov 08 18:11:46 bureau ntpd[578]: Soliciting pool server 206.75.147.25 Nov 08 18:11:46 bureau ntpd[578]: Soliciting pool server 159.89.86.140 Nov 08 18:11:46 bureau ntpd[578]: Soliciting pool server 200.160.7.186 Nov 08 18:11:47 bureau ntpd[578]: Soliciting pool server 51.79.69.205 Nov 08 18:11:47 bureau ntpd[578]: Soliciting pool server 151.80.211.8 Nov 08 18:11:47 bureau ntpd[578]: Soliciting pool server 209.115.181.102 Nov 08 18:13:20 bureau systemd-udevd[225]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Nov 08 18:14:54 bureau volumio[762]: info: CALLMETHOD: system_controller system enableLiveLog true Nov 08 18:14:54 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Nov 08 18:14:54 bureau volumio[762]: info: Launching a new LiveLog session Nov 08 18:15:07 bureau volumio[762]: info: CALLMETHOD: system_controller system enableLiveLog false Nov 08 18:15:07 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Nov 08 18:15:07 bureau volumio[762]: info: Launching a new LiveLog session Nov 08 18:15:07 bureau volumio[762]: info: Live Log process terminated: null Nov 08 18:15:44 bureau volumio[762]: info: Tunnel connection is inactive, restarting it Nov 08 18:15:44 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 18:15:44 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 18:15:44 bureau sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 18:15:44 bureau sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:15:44 bureau autossh[1032]: received signal to exit (15) Nov 08 18:15:44 bureau systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 08 18:15:44 bureau systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 08 18:15:44 bureau systemd[1]: sshtunnel.service: Succeeded. Nov 08 18:15:44 bureau systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 08 18:15:44 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 18:15:44 bureau sudo[1279]: pam_unix(sudo:session): session closed for user root Nov 08 18:15:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 18:15:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:15:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 08 18:15:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:15:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 08 18:15:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:15:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: undefined Nov 08 18:15:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:15:44 bureau autossh[1282]: port set to 0, monitoring disabled Nov 08 18:15:44 bureau autossh[1282]: starting ssh (count 1) Nov 08 18:15:44 bureau autossh[1282]: ssh child pid is 1285 Nov 08 18:15:44 bureau volumio[762]: info: Remote SSH Started Nov 08 18:15:44 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:15:44 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:16:01 bureau volumio[762]: info: CALLMETHOD: system_controller system enableLiveLog true Nov 08 18:16:01 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Nov 08 18:16:01 bureau volumio[762]: info: Launching a new LiveLog session Nov 08 18:16:02 bureau volumiossh-tunnel[1281]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 08 18:16:32 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:16:32 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:16:32 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:16:32 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:16:32 bureau volumio[762]: info: Currently active: mpd Nov 08 18:16:32 bureau volumio[762]: info: Stopping currently active service Nov 08 18:16:32 bureau volumio[762]: info: CoreCommandRouter::volumioStop Nov 08 18:16:32 bureau volumio[762]: info: CoreStateMachine::stop Nov 08 18:16:32 bureau volumio[762]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 08 18:16:32 bureau volumio[762]: verbose: UNSET VOLATILE Nov 08 18:16:32 bureau volumio[762]: info: TidalConnect Active Nov 08 18:16:32 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:16:32 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:16:32 bureau volumio[762]: info: Currently active: mpd Nov 08 18:16:32 bureau volumio[762]: info: Setting Volatile state to tidalconnect Nov 08 18:16:32 bureau volumio[762]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 08 18:16:32 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:32 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:32 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:32 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:32 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:16:32 bureau volumio[762]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 08 18:16:32 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:32 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:32 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:32 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:32 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:16:32 bureau volumio[762]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 08 18:16:32 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:32 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:33 bureau vtcs[1123]: [2021-11-08 18:16:32.997] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:1 Nov 08 18:16:33 bureau volumio[762]: info: Pushing metadata Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:33 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:33 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:16:33 bureau volumio[762]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:33 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:33 bureau volumio[762]: info: CorePlayQueue::getTrack 0 Nov 08 18:16:33 bureau volumio[762]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:33 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:33 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:33 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:33 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:33 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:33 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:33 bureau volumio[762]: info: Pushing metadata Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:33 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:33 bureau vtcs[1123]: [2021-11-08 18:16:33.488] [tisoc] [warning] [logger.cpp:22] [audio_worker.cpp:326] Attempting to initialize a decoder without initial data! Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:33 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:33 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:33 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:33 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:33 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:33 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:33 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:33 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:33 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:33 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:33 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:33 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:34 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:34 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:34 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:34 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:34 bureau vtcs[1123]: VTCS: PCM: volumioMultiRoomServer RATE: 44100 FORMAT: S16_LE CHANNELS: 2 PERIODSIZE: 4096 BUFFERSIZE: 32768 Nov 08 18:16:34 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:34 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:34 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:34 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:34 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:34 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:34 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:34 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:34 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:34 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:34 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:36 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:36 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:36 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:36 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:36 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:36 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:36 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:36 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:36 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:16:36 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:16:36 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:16:36 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:16:36 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:36 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:36 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:16:42 bureau vtcs[1123]: [2021-11-08 18:16:42.203] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5000 milliseconds Nov 08 18:16:42 bureau vtcs[1123]: [2021-11-08 18:16:42.203] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 18:17:29 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:17:29 bureau volumio[762]: info: VolumeController::SetAlsaVolume26 Nov 08 18:17:29 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:17:29 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:17:29 bureau volumio[762]: info: VolumeController::SetAlsaVolume25 Nov 08 18:17:29 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:17:29 bureau volumio[762]: info: VolumeController::SetAlsaVolume24 Nov 08 18:17:29 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:17:29 bureau volumio[762]: info: VolumeController::SetAlsaVolume23 Nov 08 18:17:29 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:17:29 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:17:29 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:17:29 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:17:29 bureau volumio[762]: info: VolumeController::SetAlsaVolume22 Nov 08 18:17:29 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:17:29 bureau volumio[762]: info: VolumeController::SetAlsaVolume21 Nov 08 18:17:29 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:17:29 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:17:29 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:17:29 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:17:29 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:17:29 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:17:29 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:17:29 bureau volumio[762]: info: VolumeController::SetAlsaVolume20 Nov 08 18:17:29 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:17:29 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:17:29 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:17:29 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:17:29 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:17:29 bureau volumio[762]: info: VolumeController::SetAlsaVolume19 Nov 08 18:17:29 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:17:29 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:17:29 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:18:44 bureau volumio[762]: info: Tunnel connection is inactive, restarting it Nov 08 18:18:44 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 18:18:44 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 18:18:44 bureau sudo[1365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 18:18:44 bureau sudo[1365]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:18:44 bureau autossh[1282]: received signal to exit (15) Nov 08 18:18:44 bureau systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 08 18:18:44 bureau systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 08 18:18:44 bureau systemd[1]: sshtunnel.service: Succeeded. Nov 08 18:18:44 bureau systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 08 18:18:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 18:18:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:18:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:18:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:18:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 08 18:18:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:18:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:18:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:18:44 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 18:18:44 bureau sudo[1365]: pam_unix(sudo:session): session closed for user root Nov 08 18:18:44 bureau volumio[762]: info: Remote SSH Started Nov 08 18:18:44 bureau autossh[1368]: port set to 0, monitoring disabled Nov 08 18:18:44 bureau autossh[1368]: starting ssh (count 1) Nov 08 18:18:44 bureau autossh[1368]: ssh child pid is 1371 Nov 08 18:18:44 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:19:03 bureau volumiossh-tunnel[1367]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 08 18:20:31 bureau volumio[762]: info: Pushing metadata Nov 08 18:20:31 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:20:31 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:20:31 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:20:31 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:20:31 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:20:31 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:20:31 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:20:31 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:20:31 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:20:31 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:20:31 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:20:31 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:20:31 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:20:31 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:20:31 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:24:09 bureau volumio[762]: info: Pushing metadata Nov 08 18:24:09 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:24:09 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:24:09 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:24:09 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:24:09 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:24:09 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:24:09 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:24:09 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:24:09 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:24:09 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:24:09 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:24:09 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:24:09 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:24:09 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:24:09 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:25:54 bureau ntpd[578]: 37.187.5.167 local addr 192.168.86.53 -> Nov 08 18:26:38 bureau systemd[1]: Starting Cleanup of Temporary Directories... Nov 08 18:26:38 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 18:26:38 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:26:38 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:26:38 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:26:38 bureau systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Nov 08 18:26:38 bureau systemd[1]: Started Cleanup of Temporary Directories. Nov 08 18:26:52 bureau vtcs[1123]: [2021-11-08 18:26:52.445] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5000 milliseconds Nov 08 18:26:52 bureau vtcs[1123]: [2021-11-08 18:26:52.445] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 18:27:18 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 08 18:27:22 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:27:22 bureau volumio[762]: info: VolumeController::SetAlsaVolume26 Nov 08 18:27:22 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:27:22 bureau volumio[762]: info: VolumeController::SetAlsaVolume25 Nov 08 18:27:22 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:27:22 bureau volumio[762]: info: VolumeController::SetAlsaVolume24 Nov 08 18:27:22 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:27:22 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:27:22 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:27:22 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:27:22 bureau volumio[762]: info: VolumeController::SetAlsaVolume23 Nov 08 18:27:22 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:27:22 bureau volumio[762]: info: VolumeController::SetAlsaVolume22 Nov 08 18:27:22 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:27:22 bureau volumio[762]: info: VolumeController::SetAlsaVolume21 Nov 08 18:27:22 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:27:22 bureau volumio[762]: info: VolumeController::SetAlsaVolume20 Nov 08 18:27:22 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:27:22 bureau volumio[762]: info: VolumeController::SetAlsaVolume19 Nov 08 18:27:22 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:27:22 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:27:22 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:27:22 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:27:22 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:27:22 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:27:22 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:27:22 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:27:22 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:27:22 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:27:22 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:27:22 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:27:22 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:27:22 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:27:26 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:27:26 bureau volumio[762]: info: VolumeController::SetAlsaVolume16 Nov 08 18:27:26 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:27:26 bureau volumio[762]: info: VolumeController::SetAlsaVolume15 Nov 08 18:27:26 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:27:26 bureau volumio[762]: info: VolumeController::SetAlsaVolume14 Nov 08 18:27:26 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:27:26 bureau volumio[762]: info: VolumeController::SetAlsaVolume13 Nov 08 18:27:26 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:27:26 bureau volumio[762]: info: VolumeController::SetAlsaVolume12 Nov 08 18:27:26 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:27:26 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:27:26 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:27:26 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:27:26 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:27:26 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:27:26 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:27:26 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:27:26 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:27:26 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:27:26 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:27:26 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:27:26 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:27:26 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:27:26 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:27:26 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:27:26 bureau volumio[762]: info: VolumeController::SetAlsaVolume11 Nov 08 18:27:26 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:27:26 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:27:26 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:27:26 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:27:26 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:27:26 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:27:26 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:27:26 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:27:26 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:28:06 bureau volumio[762]: info: Pushing metadata Nov 08 18:28:06 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:28:06 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:28:06 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:28:06 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:28:06 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:28:06 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:28:06 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:28:06 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:28:06 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:28:06 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:28:07 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:28:07 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:28:07 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:28:07 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:28:07 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:28:44 bureau volumio[762]: info: Tunnel connection is inactive, restarting it Nov 08 18:28:44 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 18:28:44 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 18:28:44 bureau sudo[1592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 18:28:44 bureau sudo[1592]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:28:44 bureau autossh[1368]: received signal to exit (15) Nov 08 18:28:44 bureau systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 08 18:28:44 bureau systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 08 18:28:44 bureau systemd[1]: sshtunnel.service: Succeeded. Nov 08 18:28:44 bureau systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 08 18:28:44 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 18:28:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 18:28:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:28:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:28:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:28:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 08 18:28:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:28:44 bureau sudo[1592]: pam_unix(sudo:session): session closed for user root Nov 08 18:28:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:28:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:28:44 bureau autossh[1595]: port set to 0, monitoring disabled Nov 08 18:28:44 bureau autossh[1595]: starting ssh (count 1) Nov 08 18:28:44 bureau autossh[1595]: ssh child pid is 1598 Nov 08 18:28:44 bureau volumio[762]: info: Remote SSH Started Nov 08 18:28:44 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:29:00 bureau volumiossh-tunnel[1594]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 08 18:31:44 bureau volumio[762]: info: Tunnel connection is inactive, restarting it Nov 08 18:31:44 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 18:31:44 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 18:31:44 bureau sudo[1660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 18:31:44 bureau sudo[1660]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:31:44 bureau autossh[1595]: received signal to exit (15) Nov 08 18:31:44 bureau systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 08 18:31:44 bureau systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 08 18:31:44 bureau systemd[1]: sshtunnel.service: Succeeded. Nov 08 18:31:44 bureau systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 08 18:31:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 18:31:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:31:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:31:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:31:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 08 18:31:44 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 18:31:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:31:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:31:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:31:44 bureau sudo[1660]: pam_unix(sudo:session): session closed for user root Nov 08 18:31:44 bureau volumio[762]: info: Remote SSH Started Nov 08 18:31:44 bureau autossh[1663]: port set to 0, monitoring disabled Nov 08 18:31:44 bureau autossh[1663]: starting ssh (count 1) Nov 08 18:31:44 bureau autossh[1663]: ssh child pid is 1666 Nov 08 18:31:44 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:32:00 bureau volumiossh-tunnel[1662]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 08 18:32:08 bureau volumio[762]: info: Pushing metadata Nov 08 18:32:08 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:32:08 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:08 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:08 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:08 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:32:08 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:08 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:08 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:08 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:08 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:08 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:32:08 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:08 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:08 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:08 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:24 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:24 bureau volumio[762]: info: VolumeController::SetAlsaVolume34 Nov 08 18:32:24 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:24 bureau volumio[762]: info: VolumeController::SetAlsaVolume35 Nov 08 18:32:25 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:25 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:25 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:25 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:25 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:25 bureau volumio[762]: info: VolumeController::SetAlsaVolume36 Nov 08 18:32:25 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:25 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:25 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:25 bureau volumio[762]: info: VolumeController::SetAlsaVolume35 Nov 08 18:32:25 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:25 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:25 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:25 bureau volumio[762]: info: VolumeController::SetAlsaVolume34 Nov 08 18:32:25 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:25 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:25 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:25 bureau volumio[762]: info: VolumeController::SetAlsaVolume33 Nov 08 18:32:25 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:25 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:25 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:25 bureau volumio[762]: info: VolumeController::SetAlsaVolume32 Nov 08 18:32:25 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:25 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:25 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:25 bureau volumio[762]: info: VolumeController::SetAlsaVolume31 Nov 08 18:32:25 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:25 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:25 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:25 bureau volumio[762]: info: VolumeController::SetAlsaVolume30 Nov 08 18:32:25 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:25 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:25 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:27 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:27 bureau volumio[762]: info: VolumeController::SetAlsaVolume27 Nov 08 18:32:27 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:27 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:27 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:27 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:27 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:27 bureau volumio[762]: info: VolumeController::SetAlsaVolume26 Nov 08 18:32:27 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:27 bureau volumio[762]: info: VolumeController::SetAlsaVolume27 Nov 08 18:32:27 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:27 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:27 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:27 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:27 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:27 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:27 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:27 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:28 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:28 bureau volumio[762]: info: VolumeController::SetAlsaVolume24 Nov 08 18:32:28 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:28 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:28 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:29 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:29 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:29 bureau volumio[762]: info: VolumeController::SetAlsaVolume23 Nov 08 18:32:29 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:29 bureau volumio[762]: info: VolumeController::SetAlsaVolume24 Nov 08 18:32:29 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:29 bureau volumio[762]: info: VolumeController::SetAlsaVolume23 Nov 08 18:32:29 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:29 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:29 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:29 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:29 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:29 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:29 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:29 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:29 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:29 bureau volumio[762]: info: Volume: {"mute":false} Nov 08 18:32:29 bureau volumio[762]: info: VolumeController::SetAlsaVolume24 Nov 08 18:32:29 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:32:29 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:32:29 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:32:29 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:29 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:29 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:32:29 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:33:01 bureau vtcs[1123]: [2021-11-08 18:33:01.346] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5000 milliseconds Nov 08 18:33:01 bureau vtcs[1123]: [2021-11-08 18:33:01.346] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 18:34:04 bureau vtcs[1123]: [2021-11-08 18:34:04.504] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5000 milliseconds Nov 08 18:34:04 bureau vtcs[1123]: [2021-11-08 18:34:04.504] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 18:35:51 bureau volumio[762]: info: Pushing metadata Nov 08 18:35:51 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:35:51 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:35:51 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:35:51 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:35:51 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:35:51 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:35:51 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:35:51 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:35:51 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:35:51 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:35:51 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:35:51 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:35:51 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:35:51 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:35:51 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:36:01 bureau ntpd[578]: 5.199.135.170 local addr 192.168.86.53 -> Nov 08 18:36:44 bureau volumio[762]: info: Tunnel connection is inactive, restarting it Nov 08 18:36:44 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 18:36:44 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 18:36:44 bureau sudo[1803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 18:36:44 bureau sudo[1803]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:36:44 bureau autossh[1663]: received signal to exit (15) Nov 08 18:36:44 bureau systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 08 18:36:44 bureau systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 08 18:36:44 bureau systemd[1]: sshtunnel.service: Succeeded. Nov 08 18:36:44 bureau systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 08 18:36:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 18:36:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:36:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:36:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:36:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 08 18:36:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:36:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:36:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:36:44 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 18:36:44 bureau sudo[1803]: pam_unix(sudo:session): session closed for user root Nov 08 18:36:44 bureau volumio[762]: info: Remote SSH Started Nov 08 18:36:44 bureau autossh[1806]: port set to 0, monitoring disabled Nov 08 18:36:44 bureau autossh[1806]: starting ssh (count 1) Nov 08 18:36:44 bureau autossh[1806]: ssh child pid is 1809 Nov 08 18:36:44 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:37:03 bureau volumiossh-tunnel[1805]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 08 18:38:30 bureau vtcs[1123]: [2021-11-08 18:38:30.324] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5000 milliseconds Nov 08 18:38:30 bureau vtcs[1123]: [2021-11-08 18:38:30.324] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 18:39:32 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:39:32 bureau volumio[762]: info: VolumeController::SetAlsaVolume20 Nov 08 18:39:32 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:39:32 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:39:32 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:39:33 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:39:39 bureau volumio[762]: info: Pushing metadata Nov 08 18:39:39 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:39:39 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:39:39 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:39:39 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:39:39 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:39:39 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:39:39 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:39:39 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:39:39 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:39:39 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:39:39 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:39:39 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:39:39 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:39:39 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:39:39 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:39:44 bureau volumio[762]: info: Tunnel connection is inactive, restarting it Nov 08 18:39:44 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 18:39:44 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 18:39:44 bureau sudo[1875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 18:39:44 bureau sudo[1875]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:39:44 bureau autossh[1806]: received signal to exit (15) Nov 08 18:39:44 bureau systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 08 18:39:44 bureau systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 08 18:39:44 bureau systemd[1]: sshtunnel.service: Succeeded. Nov 08 18:39:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 18:39:44 bureau systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 08 18:39:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:39:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:39:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:39:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 08 18:39:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:39:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:39:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:39:44 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 18:39:44 bureau sudo[1875]: pam_unix(sudo:session): session closed for user root Nov 08 18:39:44 bureau volumio[762]: info: Remote SSH Started Nov 08 18:39:44 bureau autossh[1878]: port set to 0, monitoring disabled Nov 08 18:39:44 bureau autossh[1878]: starting ssh (count 1) Nov 08 18:39:44 bureau autossh[1878]: ssh child pid is 1881 Nov 08 18:39:44 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:40:02 bureau volumiossh-tunnel[1877]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 08 18:40:26 bureau vtcs[1123]: [2021-11-08 18:40:26.403] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5000 milliseconds Nov 08 18:40:26 bureau vtcs[1123]: [2021-11-08 18:40:26.403] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 18:42:44 bureau volumio[762]: info: Tunnel connection is inactive, restarting it Nov 08 18:42:44 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 18:42:44 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 18:42:44 bureau sudo[2035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 18:42:44 bureau sudo[2035]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:42:44 bureau autossh[1878]: received signal to exit (15) Nov 08 18:42:44 bureau systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 08 18:42:44 bureau systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 08 18:42:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 18:42:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:42:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:42:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:42:44 bureau systemd[1]: sshtunnel.service: Succeeded. Nov 08 18:42:44 bureau systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 08 18:42:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 08 18:42:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:42:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:42:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:42:44 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 18:42:44 bureau sudo[2035]: pam_unix(sudo:session): session closed for user root Nov 08 18:42:44 bureau volumio[762]: info: Remote SSH Started Nov 08 18:42:44 bureau autossh[2038]: port set to 0, monitoring disabled Nov 08 18:42:44 bureau autossh[2038]: starting ssh (count 1) Nov 08 18:42:44 bureau autossh[2038]: ssh child pid is 2041 Nov 08 18:42:44 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:43:02 bureau volumiossh-tunnel[2037]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 08 18:43:36 bureau vtcs[1123]: [2021-11-08 18:43:36.308] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5000 milliseconds Nov 08 18:43:36 bureau vtcs[1123]: [2021-11-08 18:43:36.308] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 18:44:39 bureau volumio[762]: info: Pushing metadata Nov 08 18:44:39 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:44:39 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:44:39 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:44:39 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:44:39 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:44:39 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:44:39 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:44:39 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:44:39 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:44:39 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:44:40 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:44:40 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:44:40 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:44:40 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:44:40 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:45:44 bureau volumio[762]: info: Tunnel connection is inactive, restarting it Nov 08 18:45:44 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 18:45:44 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 18:45:44 bureau sudo[2104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 18:45:44 bureau sudo[2104]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:45:44 bureau autossh[2038]: received signal to exit (15) Nov 08 18:45:44 bureau systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 08 18:45:44 bureau systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 08 18:45:44 bureau systemd[1]: sshtunnel.service: Succeeded. Nov 08 18:45:44 bureau systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 08 18:45:44 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 18:45:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 18:45:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:45:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:45:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:45:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 08 18:45:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:45:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:45:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:45:44 bureau sudo[2104]: pam_unix(sudo:session): session closed for user root Nov 08 18:45:44 bureau volumio[762]: info: Remote SSH Started Nov 08 18:45:44 bureau autossh[2107]: port set to 0, monitoring disabled Nov 08 18:45:44 bureau autossh[2107]: starting ssh (count 1) Nov 08 18:45:44 bureau autossh[2107]: ssh child pid is 2110 Nov 08 18:45:44 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:46:01 bureau volumiossh-tunnel[2106]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 08 18:48:34 bureau volumio[762]: info: Pushing metadata Nov 08 18:48:34 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:48:34 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:48:34 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:48:34 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:48:34 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:48:34 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:48:34 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:48:34 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:48:34 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:48:34 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:48:35 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:48:35 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:48:35 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:48:35 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:48:35 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:48:44 bureau volumio[762]: info: Tunnel connection is inactive, restarting it Nov 08 18:48:44 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 18:48:44 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 18:48:44 bureau sudo[2176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 18:48:44 bureau sudo[2176]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:48:44 bureau autossh[2107]: received signal to exit (15) Nov 08 18:48:44 bureau systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 08 18:48:44 bureau systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 08 18:48:44 bureau systemd[1]: sshtunnel.service: Succeeded. Nov 08 18:48:44 bureau systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 08 18:48:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 18:48:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:48:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:48:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:48:44 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 18:48:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 08 18:48:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:48:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:48:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:48:44 bureau sudo[2176]: pam_unix(sudo:session): session closed for user root Nov 08 18:48:44 bureau volumio[762]: info: Remote SSH Started Nov 08 18:48:44 bureau autossh[2179]: port set to 0, monitoring disabled Nov 08 18:48:44 bureau autossh[2179]: starting ssh (count 1) Nov 08 18:48:44 bureau autossh[2179]: ssh child pid is 2182 Nov 08 18:48:44 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:49:03 bureau volumiossh-tunnel[2178]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 08 18:50:13 bureau vtcs[1123]: [2021-11-08 18:50:13.331] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5000 milliseconds Nov 08 18:50:13 bureau vtcs[1123]: [2021-11-08 18:50:13.332] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 18:51:44 bureau volumio[762]: info: Tunnel connection is inactive, restarting it Nov 08 18:51:44 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 18:51:44 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 18:51:44 bureau sudo[2236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 18:51:44 bureau sudo[2236]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:51:44 bureau autossh[2179]: received signal to exit (15) Nov 08 18:51:44 bureau systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 08 18:51:44 bureau systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 08 18:51:44 bureau systemd[1]: sshtunnel.service: Succeeded. Nov 08 18:51:44 bureau systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 08 18:51:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 18:51:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:51:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:51:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:51:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 08 18:51:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:51:44 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 18:51:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:51:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:51:44 bureau sudo[2236]: pam_unix(sudo:session): session closed for user root Nov 08 18:51:44 bureau volumio[762]: info: Remote SSH Started Nov 08 18:51:44 bureau autossh[2239]: port set to 0, monitoring disabled Nov 08 18:51:44 bureau autossh[2239]: starting ssh (count 1) Nov 08 18:51:44 bureau autossh[2239]: ssh child pid is 2242 Nov 08 18:51:44 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:51:52 bureau volumio[762]: info: Pushing metadata Nov 08 18:51:52 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:51:52 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:51:52 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:51:52 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:51:52 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:51:52 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:51:52 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:51:52 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:51:52 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:51:52 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:51:53 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:51:53 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:51:53 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:51:53 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:51:53 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:52:04 bureau volumiossh-tunnel[2238]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 08 18:53:24 bureau vtcs[1123]: [2021-11-08 18:53:24.121] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5000 milliseconds Nov 08 18:53:24 bureau vtcs[1123]: [2021-11-08 18:53:24.121] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 18:53:54 bureau vtcs[1123]: [2021-11-08 18:53:54.496] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5000 milliseconds Nov 08 18:53:54 bureau vtcs[1123]: [2021-11-08 18:53:54.496] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 18:56:56 bureau vtcs[1123]: [2021-11-08 18:56:56.926] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5000 milliseconds Nov 08 18:56:56 bureau vtcs[1123]: [2021-11-08 18:56:56.926] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 18:57:44 bureau volumio[762]: info: Tunnel connection is inactive, restarting it Nov 08 18:57:44 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 18:57:44 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 18:57:44 bureau sudo[2356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 18:57:44 bureau sudo[2356]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 18:57:44 bureau autossh[2239]: received signal to exit (15) Nov 08 18:57:44 bureau systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 08 18:57:44 bureau systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 08 18:57:44 bureau systemd[1]: sshtunnel.service: Succeeded. Nov 08 18:57:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 18:57:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:57:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:57:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:57:44 bureau systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 08 18:57:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 08 18:57:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 18:57:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 18:57:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 18:57:44 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 18:57:44 bureau sudo[2356]: pam_unix(sudo:session): session closed for user root Nov 08 18:57:44 bureau volumio[762]: info: Remote SSH Started Nov 08 18:57:44 bureau autossh[2359]: port set to 0, monitoring disabled Nov 08 18:57:44 bureau autossh[2359]: starting ssh (count 1) Nov 08 18:57:44 bureau autossh[2359]: ssh child pid is 2362 Nov 08 18:57:44 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 18:57:49 bureau volumio[762]: info: Pushing metadata Nov 08 18:57:49 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:57:49 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:57:49 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:57:49 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:57:49 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:57:49 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:57:49 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:57:49 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:57:49 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:57:49 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:57:50 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 18:57:50 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 18:57:50 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 18:57:50 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 18:57:50 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 18:58:01 bureau volumiossh-tunnel[2358]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 08 19:00:44 bureau volumio[762]: info: Tunnel connection is inactive, restarting it Nov 08 19:00:44 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 19:00:44 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 19:00:44 bureau sudo[2438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 19:00:44 bureau sudo[2438]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 19:00:44 bureau autossh[2359]: received signal to exit (15) Nov 08 19:00:44 bureau systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 08 19:00:44 bureau systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 08 19:00:44 bureau systemd[1]: sshtunnel.service: Succeeded. Nov 08 19:00:44 bureau systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 08 19:00:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 19:00:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 19:00:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 19:00:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 19:00:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 08 19:00:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 19:00:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 19:00:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 19:00:44 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 19:00:44 bureau sudo[2438]: pam_unix(sudo:session): session closed for user root Nov 08 19:00:44 bureau volumio[762]: info: Remote SSH Started Nov 08 19:00:44 bureau autossh[2441]: port set to 0, monitoring disabled Nov 08 19:00:44 bureau autossh[2441]: starting ssh (count 1) Nov 08 19:00:44 bureau autossh[2441]: ssh child pid is 2444 Nov 08 19:00:44 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 19:01:02 bureau volumiossh-tunnel[2440]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 08 19:02:23 bureau volumio[762]: info: Pushing metadata Nov 08 19:02:23 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 19:02:23 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 19:02:23 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 19:02:23 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 19:02:23 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 19:02:23 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 19:02:23 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 19:02:23 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 19:02:23 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 19:02:23 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 19:02:23 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 19:02:23 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 19:02:23 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 19:02:23 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 19:02:23 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 19:03:32 bureau vtcs[1123]: [2021-11-08 19:03:32.603] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5000 milliseconds Nov 08 19:03:32 bureau vtcs[1123]: [2021-11-08 19:03:32.603] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 19:03:44 bureau volumio[762]: info: Tunnel connection is inactive, restarting it Nov 08 19:03:44 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 19:03:44 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 19:03:44 bureau sudo[2510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 19:03:44 bureau sudo[2510]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 19:03:44 bureau autossh[2441]: received signal to exit (15) Nov 08 19:03:44 bureau systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 08 19:03:44 bureau systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 08 19:03:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 19:03:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 19:03:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 19:03:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 19:03:44 bureau systemd[1]: sshtunnel.service: Succeeded. Nov 08 19:03:44 bureau systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 08 19:03:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 08 19:03:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 19:03:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 19:03:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 19:03:44 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 19:03:44 bureau sudo[2510]: pam_unix(sudo:session): session closed for user root Nov 08 19:03:44 bureau volumio[762]: info: Remote SSH Started Nov 08 19:03:44 bureau autossh[2513]: port set to 0, monitoring disabled Nov 08 19:03:44 bureau autossh[2513]: starting ssh (count 1) Nov 08 19:03:44 bureau autossh[2513]: ssh child pid is 2516 Nov 08 19:03:44 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 19:04:01 bureau volumiossh-tunnel[2512]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 08 19:05:02 bureau vtcs[1123]: [2021-11-08 19:05:02.465] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Timeout was reached - Resolving timed out after 5000 milliseconds Nov 08 19:05:02 bureau vtcs[1123]: [2021-11-08 19:05:02.466] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 19:06:34 bureau volumio[762]: info: Pushing metadata Nov 08 19:06:34 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 19:06:34 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 19:06:34 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 19:06:34 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 19:06:34 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 19:06:34 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 19:06:34 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 19:06:34 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 19:06:35 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 19:06:35 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 19:06:35 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 19:06:35 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 19:06:35 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 19:06:35 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 19:06:35 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 19:09:44 bureau volumio[762]: info: Tunnel connection is inactive, restarting it Nov 08 19:09:44 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 19:09:44 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 19:09:44 bureau sudo[2629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 19:09:44 bureau sudo[2629]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 19:09:44 bureau autossh[2513]: received signal to exit (15) Nov 08 19:09:44 bureau systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 08 19:09:44 bureau systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 08 19:09:44 bureau systemd[1]: sshtunnel.service: Succeeded. Nov 08 19:09:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 19:09:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 19:09:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 19:09:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 19:09:44 bureau systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 08 19:09:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 08 19:09:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 19:09:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 19:09:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 19:09:44 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 19:09:44 bureau sudo[2629]: pam_unix(sudo:session): session closed for user root Nov 08 19:09:44 bureau volumio[762]: info: Remote SSH Started Nov 08 19:09:44 bureau autossh[2632]: port set to 0, monitoring disabled Nov 08 19:09:44 bureau autossh[2632]: starting ssh (count 1) Nov 08 19:09:44 bureau autossh[2632]: ssh child pid is 2635 Nov 08 19:09:44 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 19:09:52 bureau volumio[762]: info: Pushing metadata Nov 08 19:09:52 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 19:09:52 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 19:09:52 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 19:09:52 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 19:09:52 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 19:09:52 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 19:09:52 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 19:09:52 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 19:09:52 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 19:09:52 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 19:09:52 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 19:09:52 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 19:09:52 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 19:09:52 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 19:09:52 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 19:10:03 bureau volumiossh-tunnel[2631]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 08 19:11:04 bureau vtcs[1123]: [2021-11-08 19:11:04.645] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Stream error in the HTTP/2 framing layer - HTTP/2 stream 0 was not closed cleanly: INTERNAL_ERROR (err 2) Nov 08 19:11:04 bureau vtcs[1123]: [2021-11-08 19:11:04.646] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 19:11:12 bureau vtcs[1123]: [2021-11-08 19:11:12.570] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Stream error in the HTTP/2 framing layer - HTTP/2 stream 0 was not closed cleanly: INTERNAL_ERROR (err 2) Nov 08 19:11:12 bureau vtcs[1123]: [2021-11-08 19:11:12.570] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 19:11:20 bureau vtcs[1123]: [2021-11-08 19:11:20.396] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Stream error in the HTTP/2 framing layer - HTTP/2 stream 0 was not closed cleanly: INTERNAL_ERROR (err 2) Nov 08 19:11:20 bureau vtcs[1123]: [2021-11-08 19:11:20.397] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 19:11:43 bureau vtcs[1123]: [2021-11-08 19:11:43.528] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Stream error in the HTTP/2 framing layer - HTTP/2 stream 0 was not closed cleanly: INTERNAL_ERROR (err 2) Nov 08 19:11:43 bureau vtcs[1123]: [2021-11-08 19:11:43.528] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 19:11:51 bureau vtcs[1123]: [2021-11-08 19:11:51.395] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Stream error in the HTTP/2 framing layer - HTTP/2 stream 0 was not closed cleanly: INTERNAL_ERROR (err 2) Nov 08 19:11:51 bureau vtcs[1123]: [2021-11-08 19:11:51.395] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 19:11:51 bureau vtcs[1123]: [2021-11-08 19:11:51.959] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Stream error in the HTTP/2 framing layer - HTTP/2 stream 0 was not closed cleanly: INTERNAL_ERROR (err 2) Nov 08 19:11:51 bureau vtcs[1123]: [2021-11-08 19:11:51.960] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 19:11:52 bureau vtcs[1123]: [2021-11-08 19:11:52.431] [tisoc] [error] [logger.cpp:25] [operation_curl.cpp:257] curl_easy_perform failed: Stream error in the HTTP/2 framing layer - HTTP/2 stream 0 was not closed cleanly: INTERNAL_ERROR (err 2) Nov 08 19:11:52 bureau vtcs[1123]: [2021-11-08 19:11:52.431] [tisoc] [error] [logger.cpp:25] [http_stream_fetcher.cpp:162] Content-Range is missing from response! Nov 08 19:11:52 bureau vtcs[1123]: [2021-11-08 19:11:52.434] [tisoc] [warning] [logger.cpp:22] [audio_worker.cpp:293] Max connection attempts reached! Nov 08 19:11:52 bureau volumio[762]: info: Unknown command: notifyPlaybackError Nov 08 19:11:52 bureau volumio[762]: info: CoreCommandRouter::servicePushState Nov 08 19:11:52 bureau volumio[762]: info: CoreStateMachine::pushState Nov 08 19:11:52 bureau volumio[762]: info: CoreCommandRouter::volumioPushState Nov 08 19:11:52 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 08 19:11:52 bureau volumio[762]: info: MRS: Updating multiroomSync output Nov 08 19:12:44 bureau volumio[762]: info: Tunnel connection is inactive, restarting it Nov 08 19:12:44 bureau volumio[762]: info: Starting Tunnel 1 Nov 08 19:12:44 bureau volumio[762]: info: Starting Tunnel Connection Checker Nov 08 19:12:44 bureau sudo[2809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 08 19:12:44 bureau sudo[2809]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 19:12:44 bureau autossh[2632]: received signal to exit (15) Nov 08 19:12:44 bureau systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 08 19:12:44 bureau systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 08 19:12:44 bureau systemd[1]: sshtunnel.service: Succeeded. Nov 08 19:12:44 bureau systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 08 19:12:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 08 19:12:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 19:12:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 19:12:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 19:12:44 bureau volumio[762]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 08 19:12:44 bureau volumio[762]: BT PLUGIN MESSAGE: Playing: false Nov 08 19:12:44 bureau volumio[762]: BT PLUGIN MESSAGE: VOLATILE: tidalconnect Nov 08 19:12:44 bureau volumio[762]: BT PLUGIN MESSAGE: STATE:undefined Nov 08 19:12:44 bureau systemd[1]: Started MyVolumio SSH Tunnel. Nov 08 19:12:44 bureau sudo[2809]: pam_unix(sudo:session): session closed for user root Nov 08 19:12:44 bureau volumio[762]: info: Remote SSH Started Nov 08 19:12:44 bureau autossh[2812]: port set to 0, monitoring disabled Nov 08 19:12:44 bureau autossh[2812]: starting ssh (count 1) Nov 08 19:12:44 bureau autossh[2812]: ssh child pid is 2815 Nov 08 19:12:44 bureau volumio[762]: info: CoreCommandRouter::volumioGetState Nov 08 19:13:02 bureau volumiossh-tunnel[2811]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Nov 08 19:14:16 bureau volumio[762]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Nov 08 19:14:16 bureau volumio[762]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Nov 08 19:14:16 bureau sudo[2842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Nov 08 19:14:16 bureau sudo[2842]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 08 19:14:16 bureau sudo[2842]: pam_unix(sudo:session): session closed for user root Nov 08 19:14:19 bureau sudo[2876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Nov 08 19:14:19 bureau sudo[2876]: pam_unix(sudo:session): session opened for user root by (uid=0)