Wed Mar 8 18:59:39 GMT 2023 Description="'Upgraded from Spotify 3.0.7 to 3.0.8. Uninstalled. Restart. Installed 3.0.8. Restart. Logged in to Spotify. Select Spotify from main source menu, no typical Features Playlists, Whats new, Genres & Moods, etc. My Playlist appears to fuction. My albims produces '\''Error No Results'\'' and '\''WebapiError: Bad OAuth request'\''. A search results in '\''WebapiError: API rate limit exceeded'\'' and sometimes '\''WebapiError: Bad OAuth request'\''. Contiuned testing got some results once. Hope this helps. Thank for all the hard work.'" # cat /proc/version --------------- Linux version 5.10.92-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1514 SMP Mon Jan 17 17:38:03 GMT 2022 # cat /etc/os-release --------------- PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="1d63a568b7d5e4a317f16be7d0961cbb2d82e282" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="4f8978e0148ab5e1610548b80abf1b4b5e03ea0a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 07 Feb 2023 02:43:13 PM CET" VOLUMIO_VERSION="3.429" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3223bbb74b9427313a173f7908c19cfa" # ifconfig --------------- eth0: flags=4099 mtu 1500 ether dc:a6:32:ca:e1:52 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 3629 bytes 825245 (805.9 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 3629 bytes 825245 (805.9 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 wlan0: flags=4163 mtu 1500 inet 192.168.1.44 netmask 255.255.255.0 broadcast 192.168.1.255 ether dc:a6:32:ca:e1:53 txqueuelen 1000 (Ethernet) RX packets 8156 bytes 2210728 (2.1 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 8849 bytes 3903794 (3.7 MiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- lo no wireless extensions. eth0 no wireless extensions. wlan0 IEEE 802.11 ESSID:"Bernard" Mode:Managed Frequency:5.22 GHz Access Point: EC:6C:9A:92:AA:DA Bit Rate=390 Mb/s Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off Link Quality=63/70 Signal level=-47 dBm Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0 Tx excessive retries:1 Invalid misc:0 Missed beacon:0 # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 0: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones] Subdevices: 8/8 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 Subdevice #4: subdevice #4 Subdevice #5: subdevice #5 Subdevice #6: subdevice #6 Subdevice #7: subdevice #7 card 1: sndrpihifiberry [snd_rpi_hifiberry_dac], device 0: HifiBerry DAC HiFi pcm5102a-hifi-0 [HifiBerry DAC HiFi pcm5102a-hifi-0] Subdevices: 1/1 Subdevice #0: subdevice #0 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 0 18:33 ? 00:00:08 /sbin/init root 2 0 0 18:33 ? 00:00:00 [kthreadd] root 3 2 0 18:33 ? 00:00:00 [rcu_gp] root 4 2 0 18:33 ? 00:00:00 [rcu_par_gp] root 8 2 0 18:33 ? 00:00:00 [mm_percpu_wq] root 9 2 0 18:33 ? 00:00:00 [rcu_tasks_rude_] root 10 2 0 18:33 ? 00:00:00 [rcu_tasks_trace] root 11 2 0 18:33 ? 00:00:00 [ksoftirqd/0] root 12 2 0 18:33 ? 00:00:00 [rcu_sched] root 13 2 0 18:33 ? 00:00:00 [migration/0] root 14 2 0 18:33 ? 00:00:00 [cpuhp/0] root 15 2 0 18:33 ? 00:00:00 [cpuhp/1] root 16 2 0 18:33 ? 00:00:00 [migration/1] root 17 2 0 18:33 ? 00:00:00 [ksoftirqd/1] root 20 2 0 18:33 ? 00:00:00 [cpuhp/2] root 21 2 0 18:33 ? 00:00:00 [migration/2] root 22 2 0 18:33 ? 00:00:00 [ksoftirqd/2] root 25 2 0 18:33 ? 00:00:00 [cpuhp/3] root 26 2 0 18:33 ? 00:00:00 [migration/3] root 27 2 0 18:33 ? 00:00:00 [ksoftirqd/3] root 30 2 0 18:33 ? 00:00:00 [kdevtmpfs] root 31 2 0 18:33 ? 00:00:00 [netns] root 33 2 0 18:33 ? 00:00:00 [kworker/1:1-mm_percpu_wq] root 34 2 0 18:33 ? 00:00:00 [kauditd] root 35 2 0 18:33 ? 00:00:00 [kworker/0:2-mm_percpu_wq] root 36 2 0 18:33 ? 00:00:00 [khungtaskd] root 37 2 0 18:33 ? 00:00:00 [oom_reaper] root 38 2 0 18:33 ? 00:00:00 [writeback] root 39 2 0 18:33 ? 00:00:00 [kcompactd0] root 59 2 0 18:33 ? 00:00:00 [kblockd] root 60 2 0 18:33 ? 00:00:00 [blkcg_punt_bio] root 61 2 0 18:33 ? 00:00:00 [watchdogd] root 64 2 0 18:33 ? 00:00:00 [kworker/1:1H-kblockd] root 65 2 0 18:33 ? 00:00:00 [rpciod] root 66 2 0 18:33 ? 00:00:00 [kworker/u9:0-hci0] root 67 2 0 18:33 ? 00:00:00 [xprtiod] root 68 2 0 18:33 ? 00:00:00 [kswapd0] root 69 2 0 18:33 ? 00:00:00 [nfsiod] root 70 2 0 18:33 ? 00:00:00 [kthrotld] root 71 2 0 18:33 ? 00:00:00 [iscsi_eh] root 72 2 0 18:33 ? 00:00:00 [iscsi_destroy] root 73 2 0 18:33 ? 00:00:00 [nvme-wq] root 74 2 0 18:33 ? 00:00:00 [nvme-reset-wq] root 75 2 0 18:33 ? 00:00:00 [nvme-delete-wq] root 76 2 0 18:33 ? 00:00:00 [kworker/u8:1-brcmf_wq/mmc1:0001:1] root 78 2 0 18:33 ? 00:00:00 [DWC Notificatio] root 79 2 0 18:33 ? 00:00:00 [uas] root 80 2 0 18:33 ? 00:00:00 [vchiq-slot/0] root 81 2 0 18:33 ? 00:00:00 [vchiq-recy/0] root 82 2 0 18:33 ? 00:00:00 [vchiq-sync/0] root 83 2 0 18:33 ? 00:00:00 [zswap-shrink] root 87 2 0 18:33 ? 00:00:00 [sdhci] root 88 2 0 18:33 ? 00:00:00 [irq/48-mmc0] root 97 2 0 18:33 ? 00:00:00 [mmc_complete] root 98 2 0 18:33 ? 00:00:01 [kworker/0:1H-kblockd] root 123 2 0 18:33 ? 00:00:00 [kworker/2:1H-kblockd] root 125 2 0 18:33 ? 00:00:00 [jbd2/mmcblk0p2-] root 126 2 0 18:33 ? 00:00:00 [ext4-rsv-conver] root 127 2 0 18:33 ? 00:00:00 [kworker/3:1H-kblockd] root 129 2 0 18:34 ? 00:00:00 [scsi_eh_0] root 130 2 0 18:34 ? 00:00:00 [scsi_tmf_0] root 131 2 0 18:34 ? 00:00:00 [usb-storage] root 137 2 0 18:34 ? 00:00:01 [loop0] root 163 2 0 18:34 ? 00:00:00 [jbd2/mmcblk0p3-] root 164 2 0 18:34 ? 00:00:00 [ext4-rsv-conver] root 172 2 0 18:34 ? 00:00:00 [kworker/3:2H-kblockd] root 173 2 0 18:34 ? 00:00:00 [ipv6_addrconf] root 207 1 0 18:34 ? 00:00:01 /lib/systemd/systemd-journald root 220 1 0 18:34 ? 00:00:01 /lib/systemd/systemd-udevd root 251 2 0 18:34 ? 00:00:00 [rc0] root 256 2 0 18:34 ? 00:00:00 [vchiq-keep/0] root 258 2 0 18:34 ? 00:00:00 [SMIO] root 296 2 0 18:34 ? 00:00:00 [mmal-vchiq] root 300 2 0 18:34 ? 00:00:00 [mmal-vchiq] root 302 2 0 18:34 ? 00:00:00 [mmal-vchiq] root 303 2 0 18:34 ? 00:00:00 [mmal-vchiq] root 306 2 0 18:34 ? 00:00:00 [mmal-vchiq] root 307 2 0 18:34 ? 00:00:00 [mmal-vchiq] root 347 2 0 18:34 ? 00:00:00 [cfg80211] root 359 2 0 18:34 ? 00:00:00 [brcmf_wq/mmc1:0] root 360 2 0 18:34 ? 00:00:00 [brcmf_wdog/mmc1] _rpc 458 1 0 18:34 ? 00:00:00 /sbin/rpcbind -f -w root 470 1 0 18:34 ? 00:00:01 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 root 502 1 0 18:34 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon message+ 507 1 0 18:34 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 518 1 0 18:34 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant avahi 522 1 0 18:34 ? 00:00:00 avahi-daemon: running [maud.local] root 524 1 0 18:34 ? 00:00:00 /lib/systemd/systemd-logind nobody 530 1 0 18:34 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* volumio 534 1 0 18:34 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 541 2 0 18:34 ? 00:00:01 [kworker/u8:3-brcmf_wq/mmc1:0001:1] root 544 1 0 18:34 ? 00:00:00 /usr/sbin/cron -f avahi 567 522 0 18:34 ? 00:00:00 avahi-daemon: chroot helper root 614 2 0 18:34 ? 00:00:00 [kworker/3:3-events_freezable] shairpo+ 635 1 0 18:34 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf root 646 1 0 18:34 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 660 1 0 18:34 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I root 668 1 0 18:34 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux ntp 749 1 0 18:34 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 902 1 0 18:34 ? 00:00:00 /usr/sbin/sshd -D root 928 646 0 18:34 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 967 1 0 18:34 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 3000000 flow - root 970 2 0 18:34 ? 00:00:00 [kworker/u9:1-hci0] root 982 1 0 18:34 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap root 1014 1 0 18:34 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 1026 1 0 18:34 ? 00:00:00 /sbin/dhcpcd volumio 1108 1 1 18:34 ? 00:00:22 /usr/bin/node /volumio/index.js root 1125 1 0 18:34 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 1129 1125 0 18:34 ? 00:00:00 winbindd: domain child [MAUD] root 1130 1 0 18:34 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1140 1130 0 18:34 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1141 1130 0 18:34 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1143 1125 0 18:34 ? 00:00:00 winbindd: idmap child root 1146 1125 0 18:34 ? 00:00:00 winbindd: domain child [BUILTIN] root 1148 1130 0 18:34 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group volumio 1149 1 0 18:34 ? 00:00:05 /usr/bin/python /home/volumio/power.py root 1168 2 0 18:34 ? 00:00:00 [cifsiod] root 1169 2 0 18:34 ? 00:00:00 [smb3decryptd] root 1170 2 0 18:34 ? 00:00:00 [cifsfileinfoput] root 1171 2 0 18:34 ? 00:00:00 [cifsoplockd] root 1172 2 0 18:34 ? 00:00:00 [cifs-dfscache] root 1174 2 0 18:34 ? 00:00:00 [cifsd] volumio 1193 1108 0 18:34 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1200 1193 0 18:34 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1201 1193 0 18:34 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1202 1193 0 18:34 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 1318 1 0 18:34 ? 00:00:00 /bin/sh /usr/bin/startx /etc/X11/Xsession /opt/volumiokiosk.sh -- -nocursor volumio 1345 1318 0 18:34 ? 00:00:00 xinit /etc/X11/Xsession /opt/volumiokiosk.sh -- /usr/bin/X :0 -nocursor -auth /tmp/serverauth.jmyAAcMAMH root 1348 1345 0 18:34 tty2 00:00:03 /usr/lib/xorg/Xorg :0 -nocursor -auth /tmp/serverauth.jmyAAcMAMH volumio 1369 1345 0 18:34 ? 00:00:00 /bin/bash /opt/volumiokiosk.sh volumio 1395 1369 0 18:34 ? 00:00:00 /usr/bin/ssh-agent /opt/volumiokiosk.sh volumio 1403 1369 0 18:34 ? 00:00:00 /usr/bin/openbox --startup /usr/lib/arm-linux-gnueabihf/openbox-autostart OPENBOX volumio 1404 1369 0 18:34 ? 00:00:04 /usr/lib/chromium-browser/chromium-browser-v7 --enable-pinch --simulate-outdated-no-au=Tue, 31 Dec 2099 23:59:59 GMT --force-device-scale-factor=1 --disable-pinch --kiosk --no-first-run --noerrdialogs --disable-3d-apis --disable-breakpad --disable-crash-reporter --disable-infobars --disable-session-crashed-bubble --disable-translate --user-data-dir=/data/volumiokiosk http://localhost:3000 volumio 1455 1 0 18:34 ? 00:00:00 /usr/lib/chromium-browser/crashpad_handler --monitor-self --monitor-self-annotation=ptype=crashpad-handler --database=/home/volumio/.config/chromium/Crash Reports --metrics-dir=/data/volumiokiosk --annotation=channel=Built on Raspbian , running on Raspbian 10 --annotation=lsb-release=Raspbian GNU/Linux 10 (buster) --annotation=plat=Linux --annotation=prod=Chrome_Linux --annotation=ver=92.0.4515.98 --initial-client-fd=7 --shared-client-connection volumio 1457 1 0 18:34 ? 00:00:00 /usr/lib/chromium-browser/crashpad_handler --no-periodic-tasks --monitor-self-annotation=ptype=crashpad-handler --database=/home/volumio/.config/chromium/Crash Reports --annotation=channel=Built on Raspbian , running on Raspbian 10 --annotation=lsb-release=Raspbian GNU/Linux 10 (buster) --annotation=plat=Linux --annotation=prod=Chrome_Linux --annotation=ver=92.0.4515.98 --initial-client-fd=4 --shared-client-connection volumio 1461 1404 0 18:34 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=zygote --no-zygote-sandbox --force-device-scale-factor=1 --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --enable-crashpad volumio 1462 1404 0 18:34 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=zygote --force-device-scale-factor=1 --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --enable-crashpad volumio 1464 1462 0 18:34 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=zygote --force-device-scale-factor=1 --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --enable-crashpad volumio 1502 1404 0 18:34 ? 00:00:02 /usr/lib/chromium-browser/chromium-browser-v7 --type=utility --utility-sub-type=network.mojom.NetworkService --field-trial-handle=11100133634671589528,14482378439284565369,131072 --lang=en-US --service-sandbox-type=none --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --shared-files=v8_context_snapshot_data:100 --enable-crashpad volumio 1517 1461 0 18:34 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=gpu-process --field-trial-handle=11100133634671589528,14482378439284565369,131072 --disable-breakpad --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --gpu-preferences=UAAAAAAAAAAgAAAQAAAAAAAAAAAAAAAAAABgAAAAAAAwAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAQAAANgAAAAAAAAA2AAAAAAAAABIAAAACAAAAEAAAAAAAAAASAAAAAAAAABQAAAAAAAAAFgAAAAAAAAAYAAAAAAAAABoAAAAAAAAAHAAAAAAAAAAeAAAAAAAAAAQAAAAAAAAAAAAAAAMAAAAEAAAAAAAAAAAAAAADQAAABAAAAAAAAAAAQAAAAwAAAAQAAAAAAAAAAEAAAANAAAAEAAAAAAAAAAEAAAADAAAABAAAAAAAAAABAAAAA0AAAAQAAAAAAAAAAcAAAAMAAAAEAAAAAAAAAAHAAAADQAAAAgAAAAAAAAACAAAAAAAAAA= --use-gl=swiftshader-webgl --shared-files volumio 1520 1464 0 18:34 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=utility --utility-sub-type=storage.mojom.StorageService --field-trial-handle=11100133634671589528,14482378439284565369,131072 --lang=en-US --service-sandbox-type=utility --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --shared-files=v8_context_snapshot_data:100 volumio 1525 1517 0 18:34 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=broker volumio 1549 1464 0 18:34 ? 00:00:10 /usr/lib/chromium-browser/chromium-browser-v7 --type=renderer --disable-breakpad --force-device-scale-factor=1 --field-trial-handle=11100133634671589528,14482378439284565369,131072 --disable-gpu-compositing --lang=en-US --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --disable-3d-apis --num-raster-threads=2 --enable-main-frame-before-activation --renderer-client-id=6 --no-v8-untrusted-code-mitigations --shared-files=v8_context_snapshot_data:100 volumio 1584 1108 0 18:34 ? 00:00:00 /usr/bin/node /myvolumio/plugins/music_service/streaming_services/streamingProxy.js mpd 1700 1 0 18:35 ? 00:00:01 /usr/bin/mpd --no-daemon volumio 1705 1 0 18:35 ? 00:00:00 /bin/bash /usr/lib/startconnect.sh volumio 1708 1705 0 18:35 ? 00:00:00 ./usr/bin/vollibrespot -c /tmp/volspotify.toml - volumio 1740 1 0 18:35 ? 00:00:00 /bin/sh /bin/bt/btstart.sh start volumio 1747 1 0 18:35 ? 00:00:00 /bin/sh /tmp/sshtunnel.sh volumio 1748 1747 0 18:35 ? 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 GL1tmP53M1XYcv2f3tpC8dfupRv1-2292dcedb137a5c0a5895dbc6aaa8da2.eu3.myvolumio.org:8091:localhost:3000 eu3.myvolumio.org volumio 1751 1748 0 18:35 ? 00:00:00 /usr/bin/ssh -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R GL1tmP53M1XYcv2f3tpC8dfupRv1-2292dcedb137a5c0a5895dbc6aaa8da2.eu3.myvolumio.org:8091:localhost:3000 eu3.myvolumio.org volumio 1765 1 0 18:35 ? 00:00:00 /usr/bin/pulseaudio --high-priority=true rtkit 1766 1 0 18:35 ? 00:00:00 /usr/lib/rtkit/rtkit-daemon root 1769 1 0 18:35 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 1780 2 0 18:35 ? 00:00:00 [krfcommd] volumio 1782 1740 0 18:35 ? 00:00:00 /usr/bin/python /bin/bt/simple-agent -c NoInputNoOutput volumio 1786 1464 0 18:35 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=renderer --disable-breakpad --force-device-scale-factor=1 --field-trial-handle=11100133634671589528,14482378439284565369,131072 --disable-gpu-compositing --lang=en-US --enable-crashpad --crashpad-handler-pid=0 --enable-crash-reporter=,Built on Raspbian , running on Raspbian 10 --noerrdialogs --user-data-dir=/data/volumiokiosk --disable-3d-apis --num-raster-threads=2 --enable-main-frame-before-activation --renderer-client-id=10 --no-v8-untrusted-code-mitigations --shared-files=v8_context_snapshot_data:100 root 2746 2 0 18:39 ? 00:00:00 [kworker/1:0H] root 3161 2 0 18:41 ? 00:00:00 [kworker/0:0H] root 3860 2 0 18:44 ? 00:00:00 [kworker/2:0H] root 4101 2 0 18:46 ? 00:00:00 [kworker/2:1-mm_percpu_wq] root 4714 2 0 18:49 ? 00:00:00 [kworker/1:0-mm_percpu_wq] root 4872 2 0 18:50 ? 00:00:00 [kworker/0:0-mm_percpu_wq] root 5374 2 0 18:53 ? 00:00:00 [kworker/2:0-mm_percpu_wq] root 5647 2 0 18:54 ? 00:00:00 [kworker/3:1-events_freezable] root 5688 2 0 18:54 ? 00:00:00 [kworker/1:2-mm_percpu_wq] root 5769 2 0 18:55 ? 00:00:00 [kworker/3:0H] root 5847 2 0 18:55 ? 00:00:00 [kworker/1:2H] root 5854 2 0 18:55 ? 00:00:00 [kworker/0:1-events] root 5910 2 0 18:55 ? 00:00:00 [kworker/0:2H] root 6539 2 0 18:59 ? 00:00:00 [kworker/2:2-mm_percpu_wq] root 6601 2 0 18:59 ? 00:00:00 [kworker/3:0] volumio 6617 1108 1 18:59 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'Upgraded from Spotify 3.0.7 to 3.0.8. Uninstalled. Restart. Installed 3.0.8. Restart. Logged in to Spotify. Select Spotify from main source menu, no typical Features Playlists, Whats new, Genres & Moods, etc. My Playlist appears to fuction. My albims produces '\''Error No Results'\'' and '\''WebapiError: Bad OAuth request'\''. A search results in '\''WebapiError: API rate limit exceeded'\'' and sometimes '\''WebapiError: Bad OAuth request'\''. Contiuned testing got some results once. Hope this helps. Thank for all the hard work.' volumio 6618 6617 21 18:59 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js Upgraded from Spotify 3.0.7 to 3.0.8. Uninstalled. Restart. Installed 3.0.8. Restart. Logged in to Spotify. Select Spotify from main source menu, no typical Features Playlists, Whats new, Genres & Moods, etc. My Playlist appears to fuction. My albims produces 'Error No Results' and 'WebapiError: Bad OAuth request'. A search results in 'WebapiError: API rate limit exceeded' and sometimes 'WebapiError: Bad OAuth request'. Contiuned testing got some results once. Hope this helps. Thank for all the hard work. volumio 6635 1149 0 18:59 ? 00:00:00 [sh] volumio 6641 6618 0 18:59 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 6642 6641 0 18:59 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.5G 935M 1.4G 41% /imgpart /dev/loop0 410M 410M 0 100% /static overlay 12G 2.0G 9.3G 18% / devtmpfs 919M 0 919M 0% /dev tmpfs 959M 12M 947M 2% /dev/shm tmpfs 959M 5.3M 953M 1% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 959M 0 959M 0% /sys/fs/cgroup tmpfs 959M 1.6M 957M 1% /tmp tmpfs 959M 0 959M 0% /var/spool/cups tmpfs 20M 44K 20M 1% /var/log tmpfs 959M 0 959M 0% /var/spool/cups/tmp /dev/mmcblk0p1 92M 54M 38M 59% /boot //192.168.1.102/Bertie/Music/ 5.3T 2.9T 2.5T 54% /mnt/NAS/Bertie # 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=940828k,nr_inodes=150927,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/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) cgroup on /sys/fs/cgroup/net_cls,net_prio type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls,net_prio) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) cgroup on /sys/fs/cgroup/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/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=40,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) debugfs on /sys/kernel/debug type debugfs (rw,relatime) sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) mqueue on /dev/mqueue type mqueue (rw,relatime) configfs on /sys/kernel/config type configfs (rw,relatime) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) tmpfs on /var/spool/cups/tmp type tmpfs (rw,noatime,mode=755) /dev/mmcblk0p1 on /boot type vfat (rw,nosuid,nodev,noexec,relatime,fmask=0111,dmask=0000,allow_utime=0022,codepage=437,iocharset=ascii,shortname=mixed,utf8,errors=remount-ro,user) //192.168.1.102/Bertie/Music/ on /mnt/NAS/Bertie type cifs (ro,relatime,vers=3.1.1,cache=strict,username=Colin,uid=0,noforceuid,gid=0,noforcegid,addr=192.168.1.102,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=4194304,wsize=4194304,bsize=1048576,echo_interval=60,actimeo=1) # netstat -natp --------------- (Not all processes could be identified, non-owned process info will not be shown, you would have to be root to see it all.) Active Internet connections (servers and established) Proto Recv-Q Send-Q Local Address Foreign Address State PID/Program name tcp 0 0 0.0.0.0:445 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:38819 0.0.0.0:* LISTEN 1708/./usr/bin/voll 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 1765/pulseaudio tcp 0 0 0.0.0.0:139 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:111 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:22 0.0.0.0:* LISTEN - tcp 0 0 192.168.1.44:43862 192.168.1.87:3000 ESTABLISHED 1108/node tcp 0 0 192.168.1.44:37940 34.120.160.131:443 ESTABLISHED 1108/node tcp 0 0 127.0.0.1:45294 127.0.0.1:3000 ESTABLISHED 1502/volumiokiosk - tcp 0 0 127.0.0.1:45278 127.0.0.1:3000 ESTABLISHED 1108/node tcp 0 0 192.168.1.44:45600 192.168.1.136:8009 ESTABLISHED 1502/volumiokiosk - tcp 130 0 192.168.1.44:41400 142.250.180.10:443 CLOSE_WAIT 1502/volumiokiosk - tcp 0 0 127.0.0.1:45580 127.0.0.1:3000 TIME_WAIT - tcp 0 0 127.0.0.1:45162 127.0.0.1:3000 ESTABLISHED 1108/node tcp 1 0 127.0.0.1:45156 127.0.0.1:3000 CLOSE_WAIT 1149/python tcp 0 0 192.168.1.44:33494 84.247.14.230:80 ESTABLISHED 1108/node tcp 0 0 192.168.1.44:51518 104.199.65.124:443 ESTABLISHED 1708/./usr/bin/voll tcp 0 0 127.0.0.1:45160 127.0.0.1:3000 ESTABLISHED 1149/python tcp 0 0 192.168.1.44:57966 192.168.1.102:445 ESTABLISHED - tcp 0 0 192.168.1.44:50470 192.168.1.101:8009 ESTABLISHED 1502/volumiokiosk - tcp 0 0 127.0.0.1:45182 127.0.0.1:3000 ESTABLISHED 1108/node tcp 0 0 127.0.0.1:45150 127.0.0.1:3000 ESTABLISHED 534/volumio-remote- tcp 0 0 192.168.1.44:37742 192.168.1.38:3000 ESTABLISHED 1108/node tcp 0 0 127.0.0.1:45178 127.0.0.1:3000 ESTABLISHED 1108/node tcp 0 0 192.168.1.44:39482 167.71.136.233:2222 ESTABLISHED 1751/ssh tcp 0 0 192.168.1.44:59496 192.168.1.179:8009 ESTABLISHED 1502/volumiokiosk - tcp 0 0 192.168.1.44:38020 34.120.160.131:443 ESTABLISHED 1502/volumiokiosk - tcp 0 0 127.0.0.1:45322 127.0.0.1:3000 ESTABLISHED 1108/node tcp6 0 0 :::3005 :::* LISTEN 1108/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::4713 :::* LISTEN 1765/pulseaudio tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::3245 :::* LISTEN 1584/node tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::22 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 1108/node tcp6 0 0 :::3001 :::* LISTEN 1193/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:45178 ESTABLISHED 1108/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:45182 ESTABLISHED 1108/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:45322 ESTABLISHED 1108/node tcp6 0 0 192.168.1.44:3000 192.168.1.87:57068 ESTABLISHED 1108/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:45278 ESTABLISHED 1108/node tcp6 0 0 192.168.1.44:3000 192.168.1.163:57476 ESTABLISHED 1108/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:45162 ESTABLISHED 1108/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:45150 ESTABLISHED 1108/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:45294 ESTABLISHED 1108/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:45160 ESTABLISHED 1108/node tcp6 0 0 192.168.1.44:3000 192.168.1.163:57631 ESTABLISHED 1108/node tcp6 0 0 192.168.1.44:3000 192.168.1.38:38758 ESTABLISHED 1108/node # cat /etc/asound.conf --------------- pcm.!default { type empty slave.pcm "volumio" } pcm.volumio { type empty slave.pcm "volumioMultiRoomServer" } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/server/switch.target pcm.volumioMultiRoomServer { type volumioswitch target_file "/tmp/multiroom/server/switch.target" control_fifo "/tmp/multiroom/server/switch.fifo" } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/client/switch.target pcm.volumioMultiRoomClient { type volumioswitch target_file "/tmp/multiroom/client/switch.target" control_fifo "/tmp/multiroom/client/switch.fifo" } # This PCM performs any necessary rate/format/channel conversion before sending on to the Server FIFO pcm.volumioMultiRoom { type plug slave { pcm "volumioMultiRoomFIFO" rate 48000 format "S16_LE" channels 2 } rate_converter "speexrate_medium" } # Write to the FIFO watched by the Server pcm.volumioMultiRoomFIFO { type volumiofifo fifo "/tmp/multiroom/server/fifo" clear_on_drop false lead_in_frames 16384 } # This pcm is used by pcm.volumioMultiRoomServer and pcm.volumioMultiRoomClient to play audio on the local output pcm.volumioLocalPlayback { type empty slave.pcm "postMultiRoom" } # This pcm is used by pcm.volumioMultiRoomClient to discard audio and allow for faster switching pcm.volumioDiscard { type null } pcm.postMultiRoom { type empty slave.pcm "volumioMultiRoomServer" } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/server/switch.target pcm.volumioMultiRoomServer { type volumioswitch target_file "/tmp/multiroom/server/switch.target" control_fifo "/tmp/multiroom/server/switch.fifo" } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/client/switch.target pcm.volumioMultiRoomClient { type volumioswitch target_file "/tmp/multiroom/client/switch.target" control_fifo "/tmp/multiroom/client/switch.fifo" } # This PCM performs any necessary rate/format/channel conversion before sending on to the Server FIFO pcm.volumioMultiRoom { type plug slave { pcm "volumioMultiRoomFIFO" rate 48000 format "S16_LE" channels 2 } rate_converter "speexrate_medium" } # Write to the FIFO watched by the Server pcm.volumioMultiRoomFIFO { type volumiofifo fifo "/tmp/multiroom/server/fifo" clear_on_drop false lead_in_frames 16384 } # This pcm is used by pcm.volumioMultiRoomServer and pcm.volumioMultiRoomClient to play audio on the local output pcm.volumioLocalPlayback { type empty slave.pcm "postMultiRoom" } # This pcm is used by pcm.volumioMultiRoomClient to discard audio and allow for faster switching pcm.volumioDiscard { type null } pcm.postMultiRoom { type empty slave.pcm "softvolume" } # Convert to 24 bit to avoid unnecessary quality loss for 16 bit audio pcm.softvolume { type plug slave { pcm "volumioSoftVol" format "S24_3LE" } } pcm.volumioSoftVol { type softvol slave { pcm "postVolume" } control { name "SoftMaster" card 1 device 0 } max_dB 0.0 min_dB -50.0 resolution 100 } pcm.postVolume { type empty slave.pcm "volumioOutput" } # There is always a plug before the hardware to be safe pcm.volumioOutput { type plug slave.pcm "volumioHw" } pcm.volumioHw { type hw card "sndrpihifiberry" } # cat /var/log/mpd.log --------------- Mar 08 18:33 : zeroconf: No global port, disabling zeroconf Mar 08 18:34 : zeroconf: No global port, disabling zeroconf Mar 08 18:34 : client: [0] opened from local Mar 08 18:34 : exception: No such directory Mar 08 18:34 : client: [1] opened from local Mar 08 18:34 : zeroconf: No global port, disabling zeroconf Mar 08 18:34 : client: [0] opened from local Mar 08 18:34 : zeroconf: No global port, disabling zeroconf Mar 08 18:34 : client: [0] opened from local Mar 08 18:34 : exception: No such playlist ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:294:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 22050, but target_pcm softvolume has buffer size 22048 ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:305:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 22050, but target_pcm softvolume has buffer size 22048 Mar 08 18:34 : exception: Failed to open mixer for 'alsa': no such mixer control: PCM Mar 08 18:34 : player: played "https://stream-mz.planetradio.co.uk/wave105.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933" # volumio plugin list --------------- This command will list installed plugins on your device ----------------------- Installed Plugins: [ { prettyName: 'Contemporary advanced', name: 'contemporary_advanced', category: 'miscellanea', version: '1.0.6', icon: 'fa-lightbulb-o', isManuallyInstalled: true, enabled: false, active: false }, { prettyName: 'PeppyMeter Screensaver', name: 'peppy_screensaver', category: 'miscellanea', version: '1.3.0', icon: 'fa-lightbulb-o', isManuallyInstalled: true, enabled: false, active: false }, { prettyName: 'Spotify', name: 'spop', category: 'music_service', version: '3.0.8', icon: 'fa-spotify', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'Touch Display', name: 'touch_display', category: 'user_interface', version: '3.3.5', icon: 'fa-hand-pointer-o', isManuallyInstalled: false, enabled: true, active: true } ] ------------------------------------------ # cat /data/configuration/plugins.json --------------- { "music_service": { "airplay_emulation": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "inputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpd": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "webradio": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "example_plugin": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "last_100": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp_browser": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "streaming_services": { "status": { "type": "string", "value": "STARTED" } }, "cd_controller": { "status": { "type": "string", "value": "STARTED" }, "enabled": { "type": "boolean", "value": true } }, "smart_inputs": { "status": { "type": "string", "value": "STOPPED" }, "enabled": { "type": "boolean", "value": false } }, "hi_res_audio": { "status": { "type": "string", "value": "STARTED" } }, "tidal": { "status": { "type": "string", "value": "STARTED" } }, "qobuz": { "status": { "type": "string", "value": "STARTED" } }, "tidalconnect": { "status": { "type": "string", "value": "STOPPED" }, "enabled": { "type": "boolean", "value": false } }, "spop": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "audio_interface": { "outputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "alsa_controller": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "bluetooth": { "status": { "type": "string", "value": "STARTED" }, "enabled": { "type": "boolean", "value": true } }, "multiroom": { "status": { "type": "string", "value": "STARTED" }, "enabled": { "type": "boolean", "value": true } } }, "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" } }, "contemporary_advanced": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "peppy_screensaver": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "metavolumio": { "status": { "type": "string", "value": "STARTED" } }, "manifestui": { "status": { "type": "string", "value": "STARTED" } } }, "user_interface": { "websocket": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpdemulation": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STARTED" } }, "rest_api": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "touch_display": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "system_controller": { "updater_comm": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "network": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "networkfs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "services": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "system": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "i2s_dacs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumiodiscovery": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumio_command_line_client": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_volumio": { "status": { "type": "string", "value": "STARTED" } } } } # cat /etc/mpd.conf --------------- # Volumio MPD Configuration File # Files and directories ####################################################### music_directory "/var/lib/mpd/music" playlist_directory "/var/lib/mpd/playlists" db_file "/var/lib/mpd/tag_cache" log_file "/var/log/mpd.log" #pid_file "/var/run/mpd/pid" #state_file "/var/lib/mpd/state" #sticker_file "/var/lib/mpd/sticker.sql" ############################################################################### # General music daemon options ################################################ user "mpd" group "audio" bind_to_address "any" #port "6600" #log_level "default" #save_absolute_paths_in_playlists "no" #metadata_to_use "artist,album,title,track,name,genre,date,composer,performer,disc" auto_update "no" #auto_update_depth "3" ############################################################################### # Symbolic link behavior ###################################################### follow_outside_symlinks "yes" follow_inside_symlinks "yes" ############################################################################### # Input ####################################################################### # input { plugin "curl" # proxy "proxy.isp.com:8080" # proxy_user "user" # proxy_password "password" } ############################################################################### # Decoder ################################################################ decoder { plugin "faad" enabled "no" } ############################################################################### # Audio Output ################################################################ resampler { plugin "soxr" quality "high" threads "1" } audio_output { type "alsa" name "alsa" device "volumio" dop "no" } audio_output { type "fifo" enabled "no" name "multiroom" path "/tmp/snapfifo" format "44100:16:2" } #replaygain "album" #replaygain_preamp "0" volume_normalization "no" ############################################################################### # MPD Internal Buffering ###################################################### audio_buffer_size "2048" ############################################################################### # Resource Limitations ######################################################## #connection_timeout "60" max_connections "20" max_playlist_length "81920" max_command_list_size "81920" max_output_buffer_size "81920" ############################################################################### # Character Encoding ########################################################## filesystem_charset "UTF-8" ############################################################################### # volumio endpointstest --------------- TESTING REMOTE ENDPOINTS https://google.com, 298 ms: OK https://www.googleapis.com, 237 ms: OK https://securetoken.googleapis.com, 251 ms: OK https://myvolumio.firebaseio.com, 340 ms: OK https://functions.volumio.cloud, 743 ms: OK https://oauth-performer.dfs.volumio.org, 269 ms: OK https://browsing-performer.dfs.volumio.org, 262 ms: OK http://cddb.volumio.org, 569 ms: OK https://functions.volumio.cloud, 668 ms: OK http://pushupdates.volumio.org, 191 ms: OK http://plugins.volumio.org, 1063 ms: OK https://database.volumio.cloud, 423 ms: OK https://radio-directory.firebaseapp.com, 196 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # sudo journalctl -p 7 --------------- -- Logs begin at Wed 2023-03-08 18:33:43 GMT, end at Wed 2023-03-08 18:59:42 GMT. -- Mar 08 18:33:43 maud kernel: Booting Linux on physical CPU 0x0 Mar 08 18:33:43 maud kernel: Linux version 5.10.92-v7l+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1514 SMP Mon Jan 17 17:38:03 GMT 2022 Mar 08 18:33:43 maud kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Mar 08 18:33:43 maud kernel: CPU: div instructions available: patching division code Mar 08 18:33:43 maud kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Mar 08 18:33:43 maud kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.2 Mar 08 18:33:43 maud kernel: random: fast init done Mar 08 18:33:43 maud kernel: Memory policy: Data cache writealloc Mar 08 18:33:43 maud kernel: Reserved memory: created CMA memory pool at 0x000000002a000000, size 64 MiB Mar 08 18:33:43 maud kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Mar 08 18:33:43 maud kernel: Zone ranges: Mar 08 18:33:43 maud kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Mar 08 18:33:43 maud kernel: Normal empty Mar 08 18:33:43 maud kernel: HighMem [mem 0x0000000030000000-0x000000007fffffff] Mar 08 18:33:43 maud kernel: Movable zone start for each node Mar 08 18:33:43 maud kernel: Early memory node ranges Mar 08 18:33:43 maud kernel: node 0: [mem 0x0000000000000000-0x000000003dffffff] Mar 08 18:33:43 maud kernel: node 0: [mem 0x0000000040000000-0x000000007fffffff] Mar 08 18:33:43 maud kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000007fffffff] Mar 08 18:33:43 maud kernel: On node 0 totalpages: 516096 Mar 08 18:33:43 maud kernel: DMA zone: 1728 pages used for memmap Mar 08 18:33:43 maud kernel: DMA zone: 0 pages reserved Mar 08 18:33:43 maud kernel: DMA zone: 196608 pages, LIFO batch:63 Mar 08 18:33:43 maud kernel: HighMem zone: 319488 pages, LIFO batch:63 Mar 08 18:33:43 maud kernel: percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920 Mar 08 18:33:43 maud kernel: pcpu-alloc: s50828 r8192 d22900 u81920 alloc=20*4096 Mar 08 18:33:43 maud kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Mar 08 18:33:43 maud kernel: Built 1 zonelists, mobility grouping on. Total pages: 514368 Mar 08 18:33:43 maud 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=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:CA:E1:52 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Mar 08 18:33:43 maud kernel: Kernel parameter elevator= does not have any effect anymore. Please use sysfs to set IO scheduler for individual devices. Mar 08 18:33:43 maud kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Mar 08 18:33:43 maud kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 08 18:33:43 maud kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 08 18:33:43 maud kernel: software IO TLB: mapped [mem 0x0000000024e00000-0x0000000028e00000] (64MB) Mar 08 18:33:43 maud kernel: Memory: 1881660K/2064384K available (10240K kernel code, 1366K rwdata, 3196K rodata, 2048K init, 882K bss, 117188K reserved, 65536K cma-reserved, 1277952K highmem) Mar 08 18:33:43 maud kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Mar 08 18:33:43 maud kernel: ftrace: allocating 34360 entries in 101 pages Mar 08 18:33:43 maud kernel: ftrace: allocated 101 pages with 4 groups Mar 08 18:33:43 maud kernel: rcu: Hierarchical RCU implementation. Mar 08 18:33:43 maud kernel: Rude variant of Tasks RCU enabled. Mar 08 18:33:43 maud kernel: Tracing variant of Tasks RCU enabled. Mar 08 18:33:43 maud kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Mar 08 18:33:43 maud kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Mar 08 18:33:43 maud kernel: GIC: Using split EOI/Deactivate mode Mar 08 18:33:43 maud kernel: random: get_random_bytes called from start_kernel+0x3c8/0x59c with crng_init=1 Mar 08 18:33:43 maud kernel: sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns Mar 08 18:33:43 maud kernel: clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns Mar 08 18:33:43 maud kernel: bcm2835: system timer (irq = 25) Mar 08 18:33:43 maud kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Mar 08 18:33:43 maud kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Mar 08 18:33:43 maud kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Mar 08 18:33:43 maud kernel: Switching to timer-based delay loop, resolution 18ns Mar 08 18:33:43 maud kernel: Console: colour dummy device 80x30 Mar 08 18:33:43 maud kernel: printk: console [tty1] enabled Mar 08 18:33:43 maud kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Mar 08 18:33:43 maud kernel: pid_max: default: 32768 minimum: 301 Mar 08 18:33:43 maud kernel: LSM: Security Framework initializing Mar 08 18:33:43 maud kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Mar 08 18:33:43 maud kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Mar 08 18:33:43 maud kernel: cgroup: Disabling memory control group subsystem Mar 08 18:33:43 maud kernel: CPU: Testing write buffer coherency: ok Mar 08 18:33:43 maud kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Mar 08 18:33:43 maud kernel: Setting up static identity map for 0x200000 - 0x20003c Mar 08 18:33:43 maud kernel: rcu: Hierarchical SRCU implementation. Mar 08 18:33:43 maud kernel: smp: Bringing up secondary CPUs ... Mar 08 18:33:43 maud kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Mar 08 18:33:43 maud kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Mar 08 18:33:43 maud kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Mar 08 18:33:43 maud kernel: smp: Brought up 1 node, 4 CPUs Mar 08 18:33:43 maud kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Mar 08 18:33:43 maud kernel: CPU: All CPU(s) started in HYP mode. Mar 08 18:33:43 maud kernel: CPU: Virtualization extensions available. Mar 08 18:33:43 maud kernel: devtmpfs: initialized Mar 08 18:33:43 maud kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Mar 08 18:33:43 maud kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Mar 08 18:33:43 maud kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Mar 08 18:33:43 maud kernel: pinctrl core: initialized pinctrl subsystem Mar 08 18:33:43 maud kernel: NET: Registered protocol family 16 Mar 08 18:33:43 maud kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Mar 08 18:33:43 maud kernel: audit: initializing netlink subsys (disabled) Mar 08 18:33:43 maud kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Mar 08 18:33:43 maud kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 08 18:33:43 maud kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Mar 08 18:33:43 maud kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Mar 08 18:33:43 maud kernel: Serial: AMBA PL011 UART driver Mar 08 18:33:43 maud kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Mar 08 18:33:43 maud kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2022-01-20T13:56:48, variant start Mar 08 18:33:43 maud kernel: raspberrypi-firmware soc:firmware: Firmware hash is bd88f66f8952d34e4e0613a85c7a6d3da49e13e2 Mar 08 18:33:43 maud kernel: Kprobes globally optimized Mar 08 18:33:43 maud kernel: bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 Mar 08 18:33:43 maud kernel: vgaarb: loaded Mar 08 18:33:43 maud kernel: SCSI subsystem initialized Mar 08 18:33:43 maud kernel: usbcore: registered new interface driver usbfs Mar 08 18:33:43 maud kernel: usbcore: registered new interface driver hub Mar 08 18:33:43 maud kernel: usbcore: registered new device driver usb Mar 08 18:33:43 maud kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Mar 08 18:33:43 maud kernel: clocksource: Switched to clocksource arch_sys_counter Mar 08 18:33:43 maud kernel: VFS: Disk quotas dquot_6.6.0 Mar 08 18:33:43 maud kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Mar 08 18:33:43 maud kernel: FS-Cache: Loaded Mar 08 18:33:43 maud kernel: CacheFiles: Loaded Mar 08 18:33:43 maud kernel: NET: Registered protocol family 2 Mar 08 18:33:43 maud kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Mar 08 18:33:43 maud kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Mar 08 18:33:43 maud kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Mar 08 18:33:43 maud kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 08 18:33:43 maud kernel: TCP: Hash tables configured (established 8192 bind 8192) Mar 08 18:33:43 maud kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Mar 08 18:33:43 maud kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Mar 08 18:33:43 maud kernel: NET: Registered protocol family 1 Mar 08 18:33:43 maud kernel: RPC: Registered named UNIX socket transport module. Mar 08 18:33:43 maud kernel: RPC: Registered udp transport module. Mar 08 18:33:43 maud kernel: RPC: Registered tcp transport module. Mar 08 18:33:43 maud kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Mar 08 18:33:43 maud kernel: PCI: CLS 0 bytes, default 64 Mar 08 18:33:43 maud kernel: Trying to unpack rootfs image as initramfs... Mar 08 18:33:43 maud kernel: Freeing initrd memory: 13028K Mar 08 18:33:43 maud kernel: Initialise system trusted keyrings Mar 08 18:33:43 maud kernel: workingset: timestamp_bits=14 max_order=19 bucket_order=5 Mar 08 18:33:43 maud kernel: zbud: loaded Mar 08 18:33:43 maud kernel: FS-Cache: Netfs 'nfs' registered for caching Mar 08 18:33:43 maud kernel: NFS: Registering the id_resolver key type Mar 08 18:33:43 maud kernel: Key type id_resolver registered Mar 08 18:33:43 maud kernel: Key type id_legacy registered Mar 08 18:33:43 maud kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Mar 08 18:33:43 maud kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Mar 08 18:33:43 maud kernel: Key type asymmetric registered Mar 08 18:33:43 maud kernel: Asymmetric key parser 'x509' registered Mar 08 18:33:43 maud kernel: bounce: pool size: 64 pages Mar 08 18:33:43 maud kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Mar 08 18:33:43 maud kernel: io scheduler mq-deadline registered Mar 08 18:33:43 maud kernel: io scheduler kyber registered Mar 08 18:33:43 maud kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Mar 08 18:33:43 maud kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Mar 08 18:33:43 maud kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Mar 08 18:33:43 maud kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x007fffffff -> 0x0400000000 Mar 08 18:33:43 maud kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Mar 08 18:33:43 maud kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Mar 08 18:33:43 maud kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Mar 08 18:33:43 maud kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Mar 08 18:33:43 maud kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Mar 08 18:33:43 maud kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Mar 08 18:33:43 maud kernel: PCI: bus0: Fast back to back transfers disabled Mar 08 18:33:43 maud kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Mar 08 18:33:43 maud kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Mar 08 18:33:43 maud kernel: pci 0000:01:00.0: PME# supported from D0 D3cold Mar 08 18:33:43 maud kernel: PCI: bus1: Fast back to back transfers disabled Mar 08 18:33:43 maud kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Mar 08 18:33:43 maud kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Mar 08 18:33:43 maud kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Mar 08 18:33:43 maud kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Mar 08 18:33:43 maud kernel: pcieport 0000:00:00.0: enabling device (0140 -> 0142) Mar 08 18:33:43 maud kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 62 Mar 08 18:33:43 maud kernel: bcm2708_fb soc:fb: FB found 1 display(s) Mar 08 18:33:43 maud kernel: Console: switching to colour frame buffer device 100x30 Mar 08 18:33:43 maud kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 800x480 Mar 08 18:33:43 maud kernel: iproc-rng200 fe104000.rng: hwrng registered Mar 08 18:33:43 maud kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Mar 08 18:33:43 maud kernel: gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000 Mar 08 18:33:43 maud kernel: brd: module loaded Mar 08 18:33:43 maud kernel: loop: module loaded Mar 08 18:33:43 maud kernel: Loading iSCSI transport class v2.0-870. Mar 08 18:33:43 maud kernel: libphy: Fixed MDIO Bus: probed Mar 08 18:33:43 maud kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Mar 08 18:33:43 maud kernel: libphy: bcmgenet MII bus: probed Mar 08 18:33:43 maud kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Mar 08 18:33:43 maud kernel: usbcore: registered new interface driver r8152 Mar 08 18:33:43 maud kernel: usbcore: registered new interface driver lan78xx Mar 08 18:33:43 maud kernel: usbcore: registered new interface driver smsc95xx Mar 08 18:33:43 maud kernel: xhci_hcd 0000:01:00.0: enabling device (0140 -> 0142) Mar 08 18:33:43 maud kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Mar 08 18:33:43 maud kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Mar 08 18:33:43 maud kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00000e0000000890 Mar 08 18:33:43 maud kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Mar 08 18:33:43 maud kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Mar 08 18:33:43 maud kernel: usb usb1: Product: xHCI Host Controller Mar 08 18:33:43 maud kernel: usb usb1: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Mar 08 18:33:43 maud kernel: usb usb1: SerialNumber: 0000:01:00.0 Mar 08 18:33:43 maud kernel: hub 1-0:1.0: USB hub found Mar 08 18:33:43 maud kernel: hub 1-0:1.0: 1 port detected Mar 08 18:33:43 maud kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Mar 08 18:33:43 maud kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Mar 08 18:33:43 maud kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Mar 08 18:33:43 maud kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 Mar 08 18:33:43 maud kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Mar 08 18:33:43 maud kernel: usb usb2: Product: xHCI Host Controller Mar 08 18:33:43 maud kernel: usb usb2: Manufacturer: Linux 5.10.92-v7l+ xhci-hcd Mar 08 18:33:43 maud kernel: usb usb2: SerialNumber: 0000:01:00.0 Mar 08 18:33:43 maud kernel: hub 2-0:1.0: USB hub found Mar 08 18:33:43 maud kernel: hub 2-0:1.0: 4 ports detected Mar 08 18:33:43 maud kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Mar 08 18:33:43 maud kernel: dwc_otg: FIQ enabled Mar 08 18:33:43 maud kernel: dwc_otg: NAK holdoff enabled Mar 08 18:33:43 maud kernel: dwc_otg: FIQ split-transaction FSM enabled Mar 08 18:33:43 maud kernel: Module dwc_common_port init Mar 08 18:33:43 maud kernel: usbcore: registered new interface driver uas Mar 08 18:33:43 maud kernel: usbcore: registered new interface driver usb-storage Mar 08 18:33:43 maud kernel: mousedev: PS/2 mouse device common for all mice Mar 08 18:33:43 maud kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Mar 08 18:33:43 maud kernel: sdhci: Secure Digital Host Controller Interface driver Mar 08 18:33:43 maud kernel: sdhci: Copyright(c) Pierre Ossman Mar 08 18:33:43 maud kernel: mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe Mar 08 18:33:43 maud kernel: sdhci-pltfm: SDHCI platform and OF driver helper Mar 08 18:33:43 maud kernel: ledtrig-cpu: registered to indicate activity on CPUs Mar 08 18:33:43 maud kernel: hid: raw HID events driver (C) Jiri Kosina Mar 08 18:33:43 maud kernel: usbcore: registered new interface driver usbhid Mar 08 18:33:43 maud kernel: usbhid: USB HID core driver Mar 08 18:33:43 maud kernel: Initializing XFRM netlink socket Mar 08 18:33:43 maud kernel: NET: Registered protocol family 17 Mar 08 18:33:43 maud kernel: Key type dns_resolver registered Mar 08 18:33:43 maud kernel: Registering SWP/SWPB emulation handler Mar 08 18:33:43 maud kernel: registered taskstats version 1 Mar 08 18:33:43 maud kernel: Loading compiled-in X.509 certificates Mar 08 18:33:43 maud kernel: Key type ._fscrypt registered Mar 08 18:33:43 maud kernel: Key type .fscrypt registered Mar 08 18:33:43 maud kernel: Key type fscrypt-provisioning registered Mar 08 18:33:43 maud kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Mar 08 18:33:43 maud kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Mar 08 18:33:43 maud kernel: fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2 Mar 08 18:33:43 maud kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Mar 08 18:33:43 maud kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Mar 08 18:33:43 maud kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Mar 08 18:33:43 maud kernel: of_cfs_init Mar 08 18:33:43 maud kernel: of_cfs_init: OK Mar 08 18:33:43 maud kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Mar 08 18:33:43 maud kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Mar 08 18:33:43 maud kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Mar 08 18:33:43 maud kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Mar 08 18:33:43 maud kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Mar 08 18:33:43 maud kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Mar 08 18:33:43 maud kernel: Freeing unused kernel memory: 2048K Mar 08 18:33:43 maud kernel: Run /init as init process Mar 08 18:33:43 maud kernel: with arguments: Mar 08 18:33:43 maud kernel: /init Mar 08 18:33:43 maud kernel: splash Mar 08 18:33:43 maud kernel: with environment: Mar 08 18:33:43 maud kernel: HOME=/ Mar 08 18:33:43 maud kernel: TERM=linux Mar 08 18:33:43 maud kernel: imgpart=/dev/mmcblk0p2 Mar 08 18:33:43 maud kernel: imgfile=/volumio_current.sqsh Mar 08 18:33:43 maud kernel: bootdelay=5 Mar 08 18:33:43 maud kernel: mmc1: new high speed SDIO card at address 0001 Mar 08 18:33:43 maud kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Mar 08 18:33:43 maud kernel: mmcblk0: mmc0:aaaa SL16G 14.8 GiB Mar 08 18:33:43 maud kernel: mmcblk0: p1 p2 p3 Mar 08 18:33:43 maud kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Mar 08 18:33:43 maud initramfs: Booting Volumio for BCM2711 Mar 08 18:33:43 maud initramfs: This script mounts rootfs RO with an overlay RW layer. Mar 08 18:33:43 maud kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 08 18:33:43 maud initramfs: IMGPART=/dev/mmcblk0p2 Mar 08 18:33:43 maud initramfs: IMGFILE=/volumio_current.sqsh Mar 08 18:33:43 maud initramfs: Boot delay (except first time) will be 5 seconds Mar 08 18:33:43 maud initramfs: /dev/mmcblk0p2: Mar 08 18:33:43 maud kernel: EXT4-fs (mmcblk0p2): recovery complete Mar 08 18:33:43 maud kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Mar 08 18:33:43 maud initramfs: Doing a 5 second delay here to give kernel load a headstart Mar 08 18:33:43 maud kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Mar 08 18:33:43 maud kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Mar 08 18:33:43 maud kernel: usb 1-1: Product: USB2.0 Hub Mar 08 18:33:43 maud kernel: hub 1-1:1.0: USB hub found Mar 08 18:33:43 maud kernel: hub 1-1:1.0: 4 ports detected Mar 08 18:33:43 maud kernel: usb 1-1.1: new high-speed USB device number 3 using xhci_hcd Mar 08 18:33:43 maud kernel: usb 1-1.1: New USB device found, idVendor=05ac, idProduct=1500, bcdDevice= 2.03 Mar 08 18:33:43 maud kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 08 18:33:43 maud kernel: usb 1-1.1: Product: MacBook Air SuperDrive Mar 08 18:33:43 maud kernel: usb 1-1.1: Manufacturer: Apple Inc. Mar 08 18:33:43 maud kernel: usb 1-1.1: SerialNumber: KX5E6GB2715 Mar 08 18:33:43 maud kernel: usb-storage 1-1.1:1.0: USB Mass Storage device detected Mar 08 18:33:43 maud kernel: scsi host0: usb-storage 1-1.1:1.0 Mar 08 18:33:43 maud kernel: scsi 0:0:0:0: CD-ROM Apple SuperDrive 2.03 PQ: 0 ANSI: 0 Mar 08 18:33:43 maud initramfs: Checking for USB updates Mar 08 18:33:43 maud initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Mar 08 18:33:43 maud kernel: EXT4-fs (mmcblk0p3): recovery complete Mar 08 18:33:43 maud kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Mar 08 18:33:43 maud initramfs: With Option: Mar 08 18:33:43 maud initramfs: VOLUMIO_VERSION="3.429" Mar 08 18:33:43 maud initramfs: Finish initramfs, continue booting Volumio Mar 08 18:33:43 maud systemd[1]: System time before build time, advancing clock. Mar 08 18:33:43 maud kernel: NET: Registered protocol family 10 Mar 08 18:33:43 maud kernel: Segment Routing with IPv6 Mar 08 18:33:43 maud systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Mar 08 18:33:43 maud systemd[1]: Detected architecture arm. Mar 08 18:33:43 maud systemd[1]: Set hostname to . Mar 08 18:33:43 maud systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 08 18:33:43 maud systemd[1]: /lib/systemd/system/power.service:6: Invalid environment assignment, ignoring: XAUTHORITY/home/volumio/.Xauthority Mar 08 18:33:43 maud systemd[1]: /lib/systemd/system/power.service:9: Failed to parse service type, ignoring: idlepower Service Mar 08 18:33:43 maud kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 08 18:33:43 maud kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 08 18:33:43 maud kernel: random: systemd: uninitialized urandom read (16 bytes read) Mar 08 18:33:43 maud systemd[1]: Created slice system-getty.slice. Mar 08 18:33:43 maud systemd[1]: Listening on Journal Audit Socket. Mar 08 18:33:43 maud systemd[1]: Reached target System Time Synchronized. Mar 08 18:33:43 maud systemd[1]: Listening on Journal Socket (/dev/log). Mar 08 18:33:43 maud kernel: i2c /dev entries driver Mar 08 18:33:43 maud systemd-journald[207]: Journal started Mar 08 18:33:43 maud systemd-journald[207]: Runtime journal (/run/log/journal/8ae31a5258af32ecbc8d1a0d62a2178b) is 3.7M, max 30.0M, 26.2M free. Mar 08 18:33:43 maud systemd-modules-load[204]: Inserted module 'i2c_dev' Mar 08 18:33:43 maud fake-hwclock[189]: Wed Mar 8 18:33:43 UTC 2023 Mar 08 18:33:43 maud systemd[1]: Started Create Static Device Nodes in /dev. Mar 08 18:33:43 maud systemd[1]: Starting udev Kernel Device Manager... Mar 08 18:33:43 maud systemd[1]: Reached target Local File Systems (Pre). Mar 08 18:33:43 maud systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Mar 08 18:33:43 maud systemd[1]: Mounting /tmp... Mar 08 18:33:43 maud systemd[1]: Mounting /var/spool/cups... Mar 08 18:33:43 maud systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Mar 08 18:33:43 maud systemd[1]: Mounting /var/log... Mar 08 18:33:43 maud systemd[1]: Mounted /tmp. Mar 08 18:33:43 maud systemd[1]: Mounted /var/spool/cups. Mar 08 18:33:43 maud systemd[1]: Mounted /var/log. Mar 08 18:33:43 maud systemd-udevd[220]: Network interface NamePolicy= disabled on kernel command line, ignoring. Mar 08 18:33:43 maud systemd[1]: Starting Flush Journal to Persistent Storage... Mar 08 18:33:43 maud systemd[1]: Mounting /var/spool/cups/tmp... Mar 08 18:33:43 maud systemd[1]: Mounted /var/spool/cups/tmp. Mar 08 18:33:43 maud systemd[1]: Started udev Kernel Device Manager. Mar 08 18:33:43 maud systemd-journald[207]: Runtime journal (/run/log/journal/8ae31a5258af32ecbc8d1a0d62a2178b) is 3.7M, max 30.0M, 26.2M free. Mar 08 18:33:43 maud systemd[1]: Started Flush Journal to Persistent Storage. Mar 08 18:33:43 maud systemd[1]: Started udev Coldplug all Devices. Mar 08 18:33:43 maud systemd[1]: Starting Show Plymouth Boot Screen... Mar 08 18:33:43 maud systemd[1]: Starting Helper to synchronize boot up for ifupdown... Mar 08 18:33:43 maud systemd[1]: Received SIGRTMIN+20 from PID 243 (plymouthd). Mar 08 18:33:43 maud systemd[1]: Started Show Plymouth Boot Screen. Mar 08 18:33:43 maud systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Mar 08 18:33:43 maud systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 08 18:33:43 maud systemd[1]: Reached target Paths. Mar 08 18:33:43 maud systemd[1]: Reached target Local Encrypted Volumes. Mar 08 18:33:43 maud kernel: Registered IR keymap rc-rc6-mce Mar 08 18:33:43 maud kernel: IR RC6 protocol handler initialized Mar 08 18:33:43 maud systemd-udevd[236]: Using default interface naming scheme 'v240'. Mar 08 18:33:44 maud kernel: rc rc0: gpio_ir_recv as /devices/platform/ir-receiver@e/rc/rc0 Mar 08 18:33:44 maud kernel: rc rc0: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter Mar 08 18:33:44 maud kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@e/rc/rc0/input0 Mar 08 18:33:44 maud kernel: rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000 Mar 08 18:33:44 maud kernel: rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000 Mar 08 18:33:44 maud kernel: rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000 Mar 08 18:33:44 maud kernel: rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000 Mar 08 18:33:44 maud kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 18:33:44 maud kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Mar 08 18:33:44 maud kernel: [vc_sm_connected_init]: start Mar 08 18:33:44 maud kernel: [vc_sm_connected_init]: installed successfully Mar 08 18:33:44 maud kernel: mc: Linux media interface: v0.10 Mar 08 18:33:44 maud systemd-udevd[236]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 08 18:33:44 maud kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 18:33:44 maud kernel: bcm2835_audio bcm2835_audio: card created with 8 channels Mar 08 18:33:44 maud kernel: videodev: Linux video capture interface: v2.00 Mar 08 18:33:44 maud kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 18:33:44 maud kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 18:33:44 maud kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 18:33:44 maud kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 18:33:44 maud kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 18:33:44 maud kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Mar 08 18:33:44 maud kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Mar 08 18:33:44 maud kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Mar 08 18:33:44 maud kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Mar 08 18:33:44 maud kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 08 18:33:44 maud kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 08 18:33:44 maud kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Mar 08 18:33:44 maud kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Mar 08 18:33:44 maud kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Mar 08 18:33:44 maud kernel: input: raspberrypi-ts as /devices/platform/soc/soc:firmware/soc:firmware:touchscreen/input/input1 Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Mar 08 18:33:44 maud kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Mar 08 18:33:45 maud systemd-udevd[229]: Process '/usr/bin/sg_raw /dev/1-1.1 EA 00 00 00 00 00 01' failed with exit code 52. Mar 08 18:33:45 maud systemd-udevd[234]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 08 18:33:45 maud systemd[1]: Found device /dev/mmcblk0p1. Mar 08 18:33:45 maud systemd-udevd[229]: Process '/usr/bin/sg_raw /dev/1-1.1:1.0 EA 00 00 00 00 00 01' failed with exit code 52. Mar 08 18:33:45 maud systemd-udevd[229]: Process '/usr/bin/sg_raw /dev/host0 EA 00 00 00 00 00 01' failed with exit code 52. Mar 08 18:33:45 maud systemd-udevd[229]: Process '/usr/bin/sg_raw /dev/host0 EA 00 00 00 00 00 01' failed with exit code 52. Mar 08 18:33:45 maud kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Mar 08 18:33:45 maud systemd-udevd[237]: Process '/usr/bin/sg_raw /dev/target0:0:0 EA 00 00 00 00 00 01' failed with exit code 52. Mar 08 18:33:45 maud kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Mar 08 18:33:45 maud kernel: scsi 0:0:0:0: Attached scsi generic sg0 type 5 Mar 08 18:33:45 maud kernel: sr 0:0:0:0: Power-on or device reset occurred Mar 08 18:33:45 maud kernel: sr 0:0:0:0: [sr0] scsi3-mmc drive: 24x/24x writer cd/rw xa/form2 cdda caddy Mar 08 18:33:45 maud kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 08 18:33:45 maud kernel: sr 0:0:0:0: Attached scsi CD-ROM sr0 Mar 08 18:33:45 maud systemd-udevd[229]: Process '/usr/bin/sg_raw /dev/0:0:0:0 EA 00 00 00 00 00 01' failed with exit code 52. Mar 08 18:33:45 maud systemd-udevd[229]: Process '/usr/bin/sg_raw /dev/0:0:0:0 EA 00 00 00 00 00 01' failed with exit code 52. Mar 08 18:33:45 maud systemd-udevd[230]: Process '/usr/bin/sg_raw /dev/0:0:0:0 EA 00 00 00 00 00 01' failed with exit code 52. Mar 08 18:33:45 maud kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Mar 08 18:33:45 maud kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Mar 08 18:33:45 maud kernel: usbcore: registered new interface driver brcmfmac Mar 08 18:33:45 maud systemd-udevd[237]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 08 18:33:46 maud systemd-udevd[233]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 08 18:33:46 maud kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Mar 08 18:33:46 maud kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Mar 08 18:33:46 maud kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan 4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e Mar 08 18:33:46 maud systemd-udevd[226]: Using default interface naming scheme 'v240'. Mar 08 18:33:46 maud systemd-udevd[226]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Mar 08 18:33:47 maud systemd-udevd[239]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 08 18:33:47 maud systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Mar 08 18:33:47 maud systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Mar 08 18:33:47 maud systemd[1]: Condition check resulted in FUSE Control File System being skipped. Mar 08 18:33:47 maud systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Mar 08 18:33:47 maud systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Mar 08 18:33:47 maud systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Mar 08 18:33:47 maud systemd[1]: Condition check resulted in Huge Pages File System being skipped. Mar 08 18:33:47 maud systemd[1]: Created slice system-systemd\x2dbacklight.slice. Mar 08 18:33:47 maud systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:rpi_backlight... Mar 08 18:33:47 maud systemd[1]: Starting File System Check on /dev/mmcblk0p1... Mar 08 18:33:47 maud systemd[1]: Starting Load/Save RF Kill Switch Status... Mar 08 18:33:47 maud systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Mar 08 18:33:47 maud systemd[1]: Started Load/Save RF Kill Switch Status. Mar 08 18:33:47 maud systemd[1]: Started Helper to synchronize boot up for ifupdown. Mar 08 18:33:47 maud systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:rpi_backlight. Mar 08 18:33:47 maud systemd[1]: Started File System Check Daemon to report status. Mar 08 18:33:47 maud systemd-fsck[407]: fsck.fat 4.1 (2017-01-24) Mar 08 18:33:47 maud systemd-fsck[407]: /dev/mmcblk0p1: 297 files, 27486/46774 clusters Mar 08 18:33:47 maud systemd[1]: Started File System Check on /dev/mmcblk0p1. Mar 08 18:33:47 maud systemd[1]: Mounting /boot... Mar 08 18:33:48 maud systemd[1]: Mounted /boot. Mar 08 18:33:48 maud systemd[1]: Reached target Local File Systems. Mar 08 18:33:48 maud systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Mar 08 18:33:48 maud systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Mar 08 18:33:48 maud systemd[1]: Starting Raise network interfaces... Mar 08 18:33:48 maud systemd[1]: Starting Create Volatile Files and Directories... Mar 08 18:33:48 maud systemd[1]: Started ifup for wlan0. Mar 08 18:33:48 maud systemd[1]: Starting Preprocess NFS configuration... Mar 08 18:33:48 maud systemd[1]: Started ifup for eth0. Mar 08 18:33:48 maud systemd[1]: plymouth-read-write.service: Succeeded. Mar 08 18:33:48 maud systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Mar 08 18:33:48 maud systemd[1]: Received SIGRTMIN+20 from PID 243 (plymouthd). Mar 08 18:33:48 maud systemd[1]: nfs-config.service: Succeeded. Mar 08 18:33:48 maud systemd[1]: Started Preprocess NFS configuration. Mar 08 18:33:48 maud systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Mar 08 18:33:48 maud systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Mar 08 18:33:48 maud systemd[1]: Reached target NFS client services. Mar 08 18:33:48 maud systemd[1]: Started Create Volatile Files and Directories. Mar 08 18:33:48 maud systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Mar 08 18:33:48 maud systemd[1]: Starting RPC bind portmap service... Mar 08 18:33:48 maud systemd[1]: Starting Update UTMP about System Boot/Shutdown... Mar 08 18:33:48 maud systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Mar 08 18:33:48 maud systemd[1]: Started Update UTMP about System Boot/Shutdown. Mar 08 18:33:48 maud systemd[1]: Reached target System Initialization. Mar 08 18:33:48 maud systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Mar 08 18:33:48 maud systemd[1]: Listening on mpd.socket. Mar 08 18:33:48 maud systemd[1]: Started Daily apt download activities. Mar 08 18:33:48 maud systemd[1]: Started Daily Cleanup of Temporary Directories. Mar 08 18:33:48 maud kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 08 18:33:48 maud kernel: 8021q: 802.1Q VLAN Support v1.8 Mar 08 18:33:48 maud systemd[1]: Listening on D-Bus System Message Bus Socket. Mar 08 18:33:48 maud systemd[1]: Started Daily apt upgrade and clean activities. Mar 08 18:33:48 maud systemd[1]: Started Daily man-db regeneration. Mar 08 18:33:48 maud systemd[1]: Reached target Timers. Mar 08 18:33:48 maud systemd[1]: Listening on triggerhappy.socket. Mar 08 18:33:48 maud systemd[1]: Reached target Sockets. Mar 08 18:33:48 maud systemd[1]: Reached target Basic System. Mar 08 18:33:48 maud systemd[1]: Started Volumio Iptables Module. Mar 08 18:33:48 maud systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Mar 08 18:33:48 maud systemd[1]: Started UPnP Renderer front-end to MPD. Mar 08 18:33:48 maud systemd[1]: Started Manage Sound Card State (restore and store). Mar 08 18:33:48 maud alsactl[502]: alsactl 1.1.8 daemon started Mar 08 18:33:48 maud systemd[1]: Starting Configure Bluetooth Modems connected by UART... Mar 08 18:33:48 maud systemd[1]: Starting Wireless Services... Mar 08 18:33:48 maud systemd[1]: Started D-Bus System Message Bus. Mar 08 18:33:48 maud systemd[1]: Starting WPA supplicant... Mar 08 18:33:48 maud systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Mar 08 18:33:48 maud systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Mar 08 18:33:48 maud systemd[1]: Starting Login Service... Mar 08 18:33:48 maud systemd[1]: Starting dhcpcd on all interfaces... Mar 08 18:33:48 maud systemd[1]: Starting Save/Restore Sound Card State... Mar 08 18:33:48 maud systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Mar 08 18:33:48 maud systemd[1]: Starting triggerhappy global hotkey daemon... Mar 08 18:33:48 maud systemd[1]: Started volumio-remote-updater.service. Mar 08 18:33:48 maud systemd[1]: Started RPC bind portmap service. Mar 08 18:33:48 maud dhcpcd[526]: Not running dhcpcd because /etc/network/interfaces Mar 08 18:33:48 maud dhcpcd[526]: defines some interfaces that will use a Mar 08 18:33:48 maud dhcpcd[526]: DHCP client or static address Mar 08 18:33:48 maud systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Mar 08 18:33:48 maud systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Mar 08 18:33:48 maud systemd[1]: Failed to start dhcpcd on all interfaces. Mar 08 18:33:48 maud thd[530]: Unable to parse trigger line: Mar 08 18:33:48 maud systemd[1]: Started Save/Restore Sound Card State. Mar 08 18:33:48 maud thd[530]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Mar 08 18:33:48 maud thd[530]: Unable to parse trigger line: Mar 08 18:33:48 maud thd[530]: Unable to parse trigger line: Mar 08 18:33:48 maud thd[530]: Unable to parse trigger line: Mar 08 18:33:48 maud thd[530]: Unable to parse trigger line: Mar 08 18:33:48 maud thd[530]: Unable to parse trigger line: Mar 08 18:33:48 maud thd[530]: Unable to parse trigger line: Mar 08 18:33:48 maud thd[530]: Found socket passed from systemd Mar 08 18:33:48 maud systemd[1]: Started triggerhappy global hotkey daemon. Mar 08 18:33:48 maud systemd[1]: Reached target Sound Card. Mar 08 18:33:48 maud systemd[1]: Reached target Remote File Systems (Pre). Mar 08 18:33:48 maud systemd[1]: Reached target Remote File Systems. Mar 08 18:33:48 maud systemd[1]: Started Regular background program processing daemon. Mar 08 18:33:49 maud ifup[416]: ifup: waiting for lock on /run/network/ifstate.wlan0 Mar 08 18:33:49 maud systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Mar 08 18:33:49 maud cron[544]: (CRON) INFO (pidfile fd = 3) Mar 08 18:33:49 maud systemd[1]: Reached target RPC Port Mapper. Mar 08 18:33:49 maud sh[423]: eth0: waiting for carrier Mar 08 18:33:49 maud kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Mar 08 18:33:49 maud kernel: bcmgenet fd580000.ethernet eth0: Link is Down Mar 08 18:33:49 maud dhcpcd[467]: eth0: waiting for carrier Mar 08 18:33:49 maud cron[544]: (CRON) INFO (Running @reboot jobs) Mar 08 18:33:49 maud sh[419]: wlan0=wlan0 Mar 08 18:33:49 maud avahi-daemon[522]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Mar 08 18:33:49 maud avahi-daemon[522]: Successfully dropped root privileges. Mar 08 18:33:49 maud avahi-daemon[522]: avahi-daemon 0.7 starting up. Mar 08 18:33:49 maud kernel: uart-pl011 fe201000.serial: no DMA platform data Mar 08 18:33:49 maud avahi-daemon[522]: Successfully called chroot(). Mar 08 18:33:49 maud avahi-daemon[522]: Successfully dropped remaining capabilities. Mar 08 18:33:49 maud avahi-daemon[522]: Loading service file /services/volumio.service. Mar 08 18:33:49 maud avahi-daemon[522]: Network interface enumeration completed. Mar 08 18:33:49 maud avahi-daemon[522]: Server startup complete. Host name is maud.local. Local service cookie is 1982110856. Mar 08 18:33:49 maud avahi-daemon[522]: Service "Maud" (/services/volumio.service) successfully established. Mar 08 18:33:49 maud systemd-logind[524]: Watching system buttons on /dev/input/event0 (gpio_ir_recv) Mar 08 18:33:49 maud systemd-logind[524]: New seat seat0. Mar 08 18:33:49 maud systemd[1]: Started Avahi mDNS/DNS-SD Stack. Mar 08 18:33:49 maud systemd[1]: Started Login Service. Mar 08 18:33:49 maud systemd[1]: Started Raise network interfaces. Mar 08 18:33:49 maud loadcpufreq[547]: Loading cpufreq kernel modules...done (none). Mar 08 18:33:49 maud systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Mar 08 18:33:49 maud systemd[1]: Starting LSB: set CPUFreq kernel parameters... Mar 08 18:33:50 maud cpufrequtils[608]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Mar 08 18:33:50 maud systemd[1]: Started LSB: set CPUFreq kernel parameters. Mar 08 18:33:50 maud systemd[1]: Started WPA supplicant. Mar 08 18:33:50 maud systemd[1]: Reached target Network. Mar 08 18:33:50 maud wpa_supplicant[518]: Successfully initialized wpa_supplicant Mar 08 18:33:50 maud systemd[1]: Starting Network Time Service... Mar 08 18:33:50 maud systemd[1]: Starting Permit User Sessions... Mar 08 18:33:50 maud systemd[1]: Starting OpenBSD Secure Shell server... Mar 08 18:33:50 maud systemd[1]: Reached target Network is Online. Mar 08 18:33:50 maud systemd[1]: Starting LSB: Brings up/down network automatically... Mar 08 18:33:50 maud systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 08 18:33:50 maud systemd[1]: Starting LSB: exim Mail Transport Agent... Mar 08 18:33:50 maud systemd[1]: Starting Samba NMB Daemon... Mar 08 18:33:50 maud systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Mar 08 18:33:50 maud systemd[1]: Starting /etc/rc.local Compatibility... Mar 08 18:33:50 maud systemd[1]: Starting Music Player Daemon... Mar 08 18:33:50 maud systemd[1]: Started Permit User Sessions. Mar 08 18:33:50 maud rc.local[641]: no crontab for volumio Mar 08 18:33:50 maud systemd[1]: rc-local.service: Control process exited, code=exited, status=1/FAILURE Mar 08 18:33:50 maud systemd[1]: rc-local.service: Failed with result 'exit-code'. Mar 08 18:33:50 maud systemd[1]: Failed to start /etc/rc.local Compatibility. Mar 08 18:33:50 maud systemd[1]: iptables.service: Succeeded. Mar 08 18:33:50 maud ifplugd(eth0)[660]: ifplugd 0.28 initializing. Mar 08 18:33:50 maud ifplugd(eth0)[660]: Using interface eth0/DC:A6:32:CA:E1:52 with driver (version: 5.10.92-v7l+) Mar 08 18:33:50 maud ifplugd(eth0)[660]: Using detection mode: SIOCETHTOOL Mar 08 18:33:50 maud ifplugd(eth0)[660]: Initialization complete, link beat not detected. Mar 08 18:33:50 maud systemd[1]: Starting Terminate Plymouth Boot Screen... Mar 08 18:33:50 maud systemd[1]: Starting Hold until boot process finishes up... Mar 08 18:33:50 maud systemd[1]: Received SIGRTMIN+21 from PID 243 (plymouthd). Mar 08 18:33:50 maud systemd[1]: plymouth-quit.service: Succeeded. Mar 08 18:33:50 maud systemd[1]: Started Terminate Plymouth Boot Screen. Mar 08 18:33:50 maud systemd[1]: plymouth-start.service: Succeeded. Mar 08 18:33:50 maud systemd[1]: plymouth-quit-wait.service: Succeeded. Mar 08 18:33:50 maud systemd[1]: Started Hold until boot process finishes up. Mar 08 18:33:50 maud systemd[1]: Received SIGRTMIN+21 from PID 243 (n/a). Mar 08 18:33:50 maud systemd[1]: Started Getty on tty1. Mar 08 18:33:50 maud systemd[1]: Reached target Login Prompts. Mar 08 18:33:50 maud ifplugd[633]: Network Interface Plugging Daemon...start eth0...done. Mar 08 18:33:50 maud systemd[1]: Started LSB: Brings up/down network automatically. Mar 08 18:33:51 maud volumio-remote-updater[534]: Error: No active session Mar 08 18:33:51 maud volumio-remote-updater[534]: [2023-03-08 18:33:51] [info] asio async_connect error: system:111 (Connection refused) Mar 08 18:33:51 maud volumio-remote-updater[534]: [2023-03-08 18:33:51] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 08 18:33:51 maud volumio-remote-updater[534]: [2023-03-08 18:33:51] [error] handle_connect error: Underlying Transport Error Mar 08 18:33:51 maud ntpd[626]: ntpd 4.2.8p12@1.3728-o (1): Starting Mar 08 18:33:51 maud ntpd[626]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Mar 08 18:33:51 maud systemd[1]: Started Network Time Service. Mar 08 18:33:51 maud haveged[470]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Mar 08 18:33:51 maud haveged[470]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Mar 08 18:33:51 maud haveged[470]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 7.99864 Mar 08 18:33:51 maud haveged[470]: haveged: fills: 0, generated: 0 Mar 08 18:33:51 maud ntpd[749]: proto: precision = 0.778 usec (-20) Mar 08 18:33:51 maud ntpd[749]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Mar 08 18:33:51 maud ntpd[749]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2023-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Mar 08 18:33:51 maud ntpd[749]: Listen and drop on 0 v6wildcard [::]:123 Mar 08 18:33:51 maud ntpd[749]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Mar 08 18:33:51 maud ntpd[749]: Listen normally on 2 lo 127.0.0.1:123 Mar 08 18:33:51 maud ntpd[749]: Listening on routing socket on fd #19 for interface updates Mar 08 18:33:51 maud ntpd[749]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 08 18:33:51 maud ntpd[749]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Mar 08 18:33:51 maud kernel: random: crng init done Mar 08 18:33:51 maud kernel: random: 7 urandom warning(s) missed due to ratelimiting Mar 08 18:33:51 maud sshd[902]: Server listening on 0.0.0.0 port 22. Mar 08 18:33:51 maud sshd[902]: Server listening on :: port 22. Mar 08 18:33:51 maud systemd[1]: Started OpenBSD Secure Shell server. Mar 08 18:33:51 maud volumio[501]: Could not open config: /tmp/upmpdcli.conf Mar 08 18:33:51 maud systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Mar 08 18:33:51 maud systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Mar 08 18:33:52 maud exim4[636]: Starting MTA: exim4. Mar 08 18:33:52 maud systemd[1]: Started LSB: exim Mail Transport Agent. Mar 08 18:33:52 maud exim[920]: 2023-03-08 18:33:52 socket bind() to port 25 for address ::1 failed: Cannot assign requested address: waiting 30s before trying again (9 more tries) Mar 08 18:33:52 maud exim[920]: 2023-03-08 18:33:52 Cannot open main log file "/var/log/exim4/mainlog": Permission denied: euid=0 egid=125 Mar 08 18:33:52 maud exim[920]: exim: could not open panic log - aborting: see message(s) above Mar 08 18:33:52 maud ntpd[749]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Mar 08 18:33:52 maud systemd[1]: systemd-rfkill.service: Succeeded. Mar 08 18:33:52 maud nmbd[646]: [2023/03/08 18:33:52.681279, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Mar 08 18:33:52 maud nmbd[646]: started asyncdns process 928 Mar 08 18:33:52 maud nmbd[646]: [2023/03/08 18:33:52.690611, 0] ../lib/util/become_daemon.c:149(daemon_status) Mar 08 18:33:52 maud nmbd[646]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Mar 08 18:33:52 maud nmbd[646]: [2023/03/08 18:33:52.690759, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Mar 08 18:33:52 maud nmbd[646]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Mar 08 18:33:53 maud ntpd[749]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Mar 08 18:33:53 maud wireless.js[505]: Cleaning previous... Mar 08 18:33:53 maud sudo[937]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 08 18:33:53 maud sudo[937]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:33:53 maud sudo[937]: pam_unix(sudo:session): session closed for user root Mar 08 18:33:53 maud sudo[939]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 08 18:33:53 maud sudo[939]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:33:53 maud mpd[669]: Mar 08 18:33 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 08 18:33:54 maud mpd[669]: Mar 08 18:33 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 08 18:33:54 maud mpd[669]: Mar 08 18:33 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 08 18:33:54 maud sudo[939]: pam_unix(sudo:session): session closed for user root Mar 08 18:33:54 maud systemd[1]: Started Music Player Daemon. Mar 08 18:33:54 maud wireless.js[505]: Stopped aP Mar 08 18:33:54 maud sudo[948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 08 18:33:54 maud sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:33:54 maud sudo[948]: pam_unix(sudo:session): session closed for user root Mar 08 18:33:54 maud kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 08 18:33:54 maud ntpd[749]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Mar 08 18:33:54 maud sudo[950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 08 18:33:54 maud sudo[950]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:33:54 maud sudo[950]: pam_unix(sudo:session): session closed for user root Mar 08 18:33:54 maud sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 08 18:33:54 maud sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:33:54 maud sudo[957]: pam_unix(sudo:session): session closed for user root Mar 08 18:33:54 maud sudo[959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 08 18:33:54 maud sudo[959]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:33:55 maud ntpd[749]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Mar 08 18:33:56 maud kernel: Bluetooth: Core ver 2.22 Mar 08 18:33:56 maud kernel: NET: Registered protocol family 31 Mar 08 18:33:56 maud kernel: Bluetooth: HCI device and connection manager initialized Mar 08 18:33:56 maud kernel: Bluetooth: HCI socket layer initialized Mar 08 18:33:56 maud kernel: Bluetooth: L2CAP socket layer initialized Mar 08 18:33:56 maud kernel: Bluetooth: SCO socket layer initialized Mar 08 18:33:56 maud btuart[504]: bcm43xx_init Mar 08 18:33:56 maud btuart[504]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Mar 08 18:33:56 maud btuart[504]: Set Controller UART speed to 3000000 bit/s Mar 08 18:33:56 maud btuart[504]: Device setup complete Mar 08 18:33:56 maud systemd[1]: Starting Load/Save RF Kill Switch Status... Mar 08 18:33:56 maud kernel: Bluetooth: HCI UART driver ver 2.3 Mar 08 18:33:56 maud kernel: Bluetooth: HCI UART protocol H4 registered Mar 08 18:33:56 maud kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Mar 08 18:33:56 maud kernel: Bluetooth: HCI UART protocol Broadcom registered Mar 08 18:33:56 maud systemd[1]: Started Configure Bluetooth Modems connected by UART. Mar 08 18:33:56 maud volumio-remote-updater[534]: [2023-03-08 18:33:56] [info] asio async_connect error: system:111 (Connection refused) Mar 08 18:33:56 maud volumio-remote-updater[534]: [2023-03-08 18:33:56] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 08 18:33:56 maud volumio-remote-updater[534]: [2023-03-08 18:33:56] [error] handle_connect error: Underlying Transport Error Mar 08 18:33:56 maud systemd[1]: Started Load/Save RF Kill Switch Status. Mar 08 18:33:56 maud systemd[1]: Created slice system-bthelper.slice. Mar 08 18:33:56 maud systemd[1]: Starting Raspberry Pi bluetooth helper... Mar 08 18:33:56 maud bthelper[972]: Raspberry Pi BDADDR already set Mar 08 18:33:56 maud systemd[1]: Started Raspberry Pi bluetooth helper. Mar 08 18:33:56 maud systemd[1]: Starting Bluetooth service... Mar 08 18:33:56 maud bluetoothd[982]: Bluetooth daemon 5.50 Mar 08 18:33:56 maud bluetoothd[982]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Mar 08 18:33:56 maud systemd[1]: Started Bluetooth service. Mar 08 18:33:56 maud systemd[1]: Reached target Bluetooth. Mar 08 18:33:56 maud bluetoothd[982]: Starting SDP server Mar 08 18:33:56 maud bluetoothd[982]: Excluding (cli) sap Mar 08 18:33:56 maud kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Mar 08 18:33:56 maud kernel: Bluetooth: BNEP filters: protocol multicast Mar 08 18:33:56 maud kernel: Bluetooth: BNEP socket layer initialized Mar 08 18:33:56 maud bluetoothd[982]: Bluetooth management interface 1.18 initialized Mar 08 18:33:56 maud dbus-daemon[507]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=982 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Mar 08 18:33:56 maud systemd[1]: Starting Hostname Service... Mar 08 18:33:56 maud dbus-daemon[507]: [system] Successfully activated service 'org.freedesktop.hostname1' Mar 08 18:33:56 maud systemd[1]: Started Hostname Service. Mar 08 18:33:56 maud bluetoothd[982]: Failed to set privacy: Rejected (0x0b) Mar 08 18:33:56 maud sudo[959]: pam_unix(sudo:session): session closed for user root Mar 08 18:33:56 maud wireless.js[505]: Start wireless flow Mar 08 18:33:56 maud wireless.js[505]: Stopped hotspot (if there).. Mar 08 18:33:56 maud sudo[986]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 08 18:33:56 maud sudo[986]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:33:56 maud sudo[986]: pam_unix(sudo:session): session closed for user root Mar 08 18:33:56 maud sudo[988]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 08 18:33:56 maud sudo[988]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:33:57 maud sudo[988]: pam_unix(sudo:session): session closed for user root Mar 08 18:33:57 maud wireless.js[505]: DHCP IP Mar 08 18:33:57 maud wireless.js[505]: Start ap Mar 08 18:33:57 maud wpa_supplicant[990]: Successfully initialized wpa_supplicant Mar 08 18:33:57 maud sudo[991]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Mar 08 18:33:57 maud sudo[991]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:33:57 maud kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 08 18:33:57 maud dhcpcd[992]: dev: loaded udev Mar 08 18:33:57 maud dhcpcd-run-hooks[1010]: wlan0: starting wpa_supplicant Mar 08 18:33:57 maud dhcpcd-run-hooks[1015]: wlan0: failed to start wpa_supplicant Mar 08 18:33:57 maud dhcpcd-run-hooks[1016]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Mar 08 18:33:57 maud dhcpcd[992]: wlan0: connected to Access Point `' Mar 08 18:33:57 maud dhcpcd[992]: no interfaces have a carrier Mar 08 18:33:57 maud dhcpcd[992]: forked to background, child pid 1026 Mar 08 18:33:57 maud dhcpcd[1026]: eth0: waiting for carrier Mar 08 18:33:57 maud dhcpcd[1026]: wlan0: waiting for carrier Mar 08 18:33:57 maud sudo[991]: pam_unix(sudo:session): session closed for user root Mar 08 18:33:58 maud wireless.js[505]: trying... Mar 08 18:33:58 maud sudo[1028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 18:33:58 maud sudo[1028]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:33:58 maud sudo[1028]: pam_unix(sudo:session): session closed for user root Mar 08 18:33:59 maud wireless.js[505]: trying... Mar 08 18:33:59 maud sudo[1031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 18:33:59 maud sudo[1031]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:33:59 maud sudo[1031]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:00 maud wpa_supplicant[1014]: wlan0: Trying to associate with SSID 'Bernard' Mar 08 18:34:00 maud wireless.js[505]: trying... Mar 08 18:34:00 maud sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 18:34:00 maud sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:00 maud sudo[1034]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:01 maud systemd[1]: systemd-rfkill.service: Succeeded. Mar 08 18:34:01 maud bthelper[972]: Changing power off succeeded Mar 08 18:34:01 maud bthelper[972]: [CHG] Controller DC:A6:32:CA:E1:54 Class: 0x0000041c Mar 08 18:34:01 maud bthelper[972]: Changing power on succeeded Mar 08 18:34:01 maud wireless.js[505]: trying... Mar 08 18:34:01 maud sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 18:34:01 maud sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:01 maud sudo[1044]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:02 maud wireless.js[505]: trying... Mar 08 18:34:02 maud sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 18:34:02 maud sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:02 maud sudo[1047]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:02 maud dhcpcd[1026]: wlan0: carrier acquired Mar 08 18:34:02 maud dhcpcd[1026]: wlan0: connected to Access Point `Bernard' Mar 08 18:34:02 maud wpa_supplicant[1014]: wlan0: Associated with ec:6c:9a:92:aa:da Mar 08 18:34:02 maud wpa_supplicant[1014]: wlan0: CTRL-EVENT-CONNECTED - Connection to ec:6c:9a:92:aa:da completed [id=0 id_str=] Mar 08 18:34:02 maud wpa_supplicant[1014]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Mar 08 18:34:02 maud wpa_supplicant[1014]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=GB Mar 08 18:34:02 maud dhcpcd[1026]: DUID 00:01:00:01:2a:34:d6:91:dc:a6:32:ca:e1:53 Mar 08 18:34:02 maud dhcpcd[1026]: wlan0: IAID 32:ca:e1:53 Mar 08 18:34:02 maud dhcpcd[1026]: wlan0: adding address fe80::dea6:32ff:feca:e153 Mar 08 18:34:02 maud dhcpcd[1026]: ipv6_addaddr1: Permission denied Mar 08 18:34:02 maud dhcpcd[1026]: wlan0: carrier lost Mar 08 18:34:02 maud dhcpcd[1026]: wlan0: carrier acquired Mar 08 18:34:02 maud dhcpcd[1026]: wlan0: IAID 32:ca:e1:53 Mar 08 18:34:03 maud dhcpcd[1026]: wlan0: soliciting an IPv6 router Mar 08 18:34:03 maud wireless.js[505]: trying... Mar 08 18:34:03 maud sudo[1062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 18:34:03 maud sudo[1062]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:03 maud sudo[1062]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:03 maud volumio-remote-updater[534]: [2023-03-08 18:34:03] [info] asio async_connect error: system:111 (Connection refused) Mar 08 18:34:03 maud volumio-remote-updater[534]: [2023-03-08 18:34:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 08 18:34:03 maud volumio-remote-updater[534]: [2023-03-08 18:34:03] [error] handle_connect error: Underlying Transport Error Mar 08 18:34:03 maud dhcpcd[1026]: wlan0: rebinding lease of 192.168.1.44 Mar 08 18:34:04 maud dhcpcd[1026]: wlan0: probing address 192.168.1.44/24 Mar 08 18:34:04 maud wireless.js[505]: trying... Mar 08 18:34:04 maud sudo[1065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 18:34:04 maud sudo[1065]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:04 maud sudo[1065]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:05 maud kernel: cam-dummy-reg: disabling Mar 08 18:34:05 maud kernel: cam1-reg: disabling Mar 08 18:34:05 maud wireless.js[505]: trying... Mar 08 18:34:05 maud sudo[1068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 18:34:05 maud sudo[1068]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:05 maud sudo[1068]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:06 maud wireless.js[505]: trying... Mar 08 18:34:06 maud sudo[1071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 18:34:06 maud sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:06 maud sudo[1071]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:07 maud wireless.js[505]: trying... Mar 08 18:34:07 maud sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 18:34:07 maud sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:07 maud sudo[1074]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:08 maud wireless.js[505]: trying... Mar 08 18:34:08 maud sudo[1077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 18:34:08 maud sudo[1077]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:08 maud sudo[1077]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:08 maud dhcpcd[1026]: wlan0: leased 192.168.1.44 for 86400 seconds Mar 08 18:34:08 maud avahi-daemon[522]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.44. Mar 08 18:34:08 maud avahi-daemon[522]: New relevant interface wlan0.IPv4 for mDNS. Mar 08 18:34:08 maud dhcpcd[1026]: wlan0: adding route to 192.168.1.0/24 Mar 08 18:34:08 maud dhcpcd[1026]: wlan0: adding default route via 192.168.1.254 Mar 08 18:34:08 maud avahi-daemon[522]: Registering new address record for 192.168.1.44 on wlan0.IPv4. Mar 08 18:34:09 maud wireless.js[505]: trying... Mar 08 18:34:09 maud sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 08 18:34:09 maud sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:09 maud sudo[1101]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:09 maud wireless.js[505]: Connected to: ----Bernard Mar 08 18:34:09 maud wireless.js[505]: ---- Mar 08 18:34:09 maud sudo[1104]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 18:34:09 maud sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:09 maud sudo[1104]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:09 maud wireless.js[505]: ... joined AP, wlan0 IPv4 is 192.168.1.44, ipV6 is undefined Mar 08 18:34:09 maud wireless.js[505]: It's done! AP Mar 08 18:34:09 maud systemd[1]: Started Wireless Services. Mar 08 18:34:09 maud systemd[1]: Started Volumio Backend Module. Mar 08 18:34:10 maud ntpd[749]: Listen normally on 3 wlan0 192.168.1.44:123 Mar 08 18:34:10 maud ntpd[749]: new interface(s) found: waking up resolver Mar 08 18:34:11 maud volumio[1108]: info: ------------------------------------------- Mar 08 18:34:11 maud volumio[1108]: info: ----- Volumio3 ---- Mar 08 18:34:11 maud volumio[1108]: info: ------------------------------------------- Mar 08 18:34:11 maud volumio[1108]: info: ----- System startup ---- Mar 08 18:34:11 maud volumio[1108]: info: ------------------------------------------- Mar 08 18:34:11 maud volumio[1108]: info: MYVOLUMIO Environment detected Mar 08 18:34:12 maud volumio[1108]: info: Plugin folders cleanup Mar 08 18:34:12 maud volumio[1108]: info: Scanning into folder /volumio/app/plugins/ Mar 08 18:34:12 maud volumio[1108]: info: Scanning category audio_interface Mar 08 18:34:12 maud volumio[1108]: info: Scanning category miscellanea Mar 08 18:34:12 maud volumio[1108]: info: Scanning category music_service Mar 08 18:34:12 maud volumio[1108]: info: Scanning category plugins.json Mar 08 18:34:12 maud volumio[1108]: info: Scanning category system_controller Mar 08 18:34:12 maud volumio[1108]: info: Scanning category user_interface Mar 08 18:34:12 maud volumio[1108]: info: Scanning into folder /data/plugins/ Mar 08 18:34:12 maud volumio[1108]: info: Scanning category miscellanea Mar 08 18:34:12 maud volumio[1108]: info: Scanning category music_service Mar 08 18:34:12 maud volumio[1108]: info: Scanning category user_interface Mar 08 18:34:12 maud volumio[1108]: info: Plugin folders cleanup completed Mar 08 18:34:12 maud volumio[1108]: info: ------------------------------------------- Mar 08 18:34:12 maud volumio[1108]: info: ----- Core plugins startup ---- Mar 08 18:34:12 maud volumio[1108]: info: ------------------------------------------- Mar 08 18:34:12 maud volumio[1108]: info: Loading plugins from folder /volumio/app/plugins/ Mar 08 18:34:12 maud volumio[1108]: info: Adding plugin upnp to MyMusic Plugins Mar 08 18:34:12 maud volumio[1108]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 08 18:34:12 maud volumio[1108]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 08 18:34:12 maud volumio[1108]: info: Loading plugins from folder /data/plugins/ Mar 08 18:34:12 maud volumio[1108]: info: Loading plugin "system"... Mar 08 18:34:12 maud volumio[1108]: info: Loading plugin "appearance"... Mar 08 18:34:12 maud nmbd[646]: [2023/03/08 18:34:12.698515, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 08 18:34:12 maud nmbd[646]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Mar 08 18:34:12 maud systemd[1]: Started Samba NMB Daemon. Mar 08 18:34:12 maud systemd[1]: Starting Samba Winbind Daemon... Mar 08 18:34:12 maud winbindd[1125]: [2023/03/08 18:34:12.920574, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Mar 08 18:34:12 maud winbindd[1125]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Mar 08 18:34:12 maud winbindd[1125]: [2023/03/08 18:34:12.949673, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 08 18:34:12 maud winbindd[1125]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Mar 08 18:34:12 maud systemd[1]: Started Samba Winbind Daemon. Mar 08 18:34:12 maud systemd[1]: Starting Samba SMB Daemon... Mar 08 18:34:13 maud volumio[1108]: info: Loading plugin "network"... Mar 08 18:34:13 maud volumio[1108]: info: Refreshing Cached IP Addresses Mar 08 18:34:13 maud sudo[1132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 08 18:34:13 maud sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:13 maud sudo[1132]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:13 maud sudo[1135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 18:34:13 maud sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:13 maud volumio[1108]: info: Plugin peppy_screensaver is not enabled Mar 08 18:34:13 maud volumio[1108]: info: Loading plugin "services"... Mar 08 18:34:13 maud sudo[1135]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:13 maud sudo[1138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 08 18:34:13 maud volumio[1108]: info: Loading plugin "alsa_controller"... Mar 08 18:34:13 maud sudo[1138]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:13 maud volumio-remote-updater[534]: [2023-03-08 18:34:13] [connect] Successful connection Mar 08 18:34:13 maud smbd[1130]: [2023/03/08 18:34:13.957580, 0] ../lib/util/become_daemon.c:138(daemon_ready) Mar 08 18:34:13 maud systemd[1]: Started Samba SMB Daemon. Mar 08 18:34:13 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 18:34:13 maud volumio[1108]: info: Loading plugin "wizard"... Mar 08 18:34:13 maud smbd[1130]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Mar 08 18:34:13 maud systemd[1]: Reached target Multi-User System. Mar 08 18:34:13 maud systemd[1]: Reached target Graphical Interface. Mar 08 18:34:13 maud systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 08 18:34:13 maud systemd[1]: Started My Power Service. Mar 08 18:34:13 maud volumio[1108]: info: Loading plugin "volumio_command_line_client"... Mar 08 18:34:13 maud volumio[1108]: info: Plugin upnp is not enabled Mar 08 18:34:13 maud volumio[1108]: info: Loading plugin "my_music"... Mar 08 18:34:14 maud volumio[1108]: info: Loading plugin "mpd"... Mar 08 18:34:14 maud systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 08 18:34:14 maud systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 08 18:34:14 maud systemd[1]: Startup finished in 9.403s (kernel) + 32.574s (userspace) = 41.977s. Mar 08 18:34:14 maud volumio[1108]: info: Creating MPD Configuration file Mar 08 18:34:14 maud volumio[1108]: info: Plugin upnp_browser is not enabled Mar 08 18:34:14 maud volumio[1108]: info: Loading plugin "networkfs"... Mar 08 18:34:14 maud sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 08 18:34:14 maud sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:14 maud sudo[1152]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:14 maud sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 08 18:34:14 maud sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:14 maud systemd[1]: Stopping Music Player Daemon... Mar 08 18:34:14 maud systemd[1]: mpd.service: Succeeded. Mar 08 18:34:14 maud systemd[1]: Stopped Music Player Daemon. Mar 08 18:34:14 maud systemd[1]: Starting Music Player Daemon... Mar 08 18:34:14 maud volumio[1108]: info: Starting Udev Watcher for removable devices Mar 08 18:34:14 maud sudo[1162]: 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.1.102/Bertie/Music/ /mnt/NAS/Bertie Mar 08 18:34:14 maud sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:15 maud kernel: FS-Cache: Netfs 'cifs' registered for caching Mar 08 18:34:15 maud kernel: Key type cifs.spnego registered Mar 08 18:34:15 maud kernel: Key type cifs.idmap registered Mar 08 18:34:15 maud kernel: CIFS: Attempting to mount //192.168.1.102/Bertie/Music/ Mar 08 18:34:15 maud kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Mar 08 18:34:15 maud volumio[1108]: info: Ignoring mount for partition: boot Mar 08 18:34:15 maud volumio[1108]: info: Ignoring mount for partition: volumio Mar 08 18:34:15 maud volumio[1108]: info: Ignoring mount for partition: volumio_data Mar 08 18:34:15 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 18:34:15 maud volumio[1108]: info: Loading plugin "alarm-clock"... Mar 08 18:34:15 maud volumio[1108]: info: Plugin airplay_emulation is not enabled Mar 08 18:34:15 maud volumio[1108]: info: Loading plugin "last_100"... Mar 08 18:34:15 maud volumio[1108]: info: Loading plugin "webradio"... Mar 08 18:34:15 maud mpd[1159]: Mar 08 18:34 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 08 18:34:15 maud mpd[1159]: Mar 08 18:34 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 08 18:34:15 maud mpd[1159]: Mar 08 18:34 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 08 18:34:15 maud volumio[1108]: info: Loading plugin "i2s_dacs"... Mar 08 18:34:15 maud systemd[1]: Started Music Player Daemon. Mar 08 18:34:15 maud sudo[1154]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:15 maud volumio[1108]: info: Loading plugin "volumiodiscovery"... Mar 08 18:34:15 maud sudo[1162]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:15 maud volumio[1108]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 08 18:34:15 maud volumio[1108]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 18:34:15 maud volumio[1108]: *** WARNING *** For more information see Mar 08 18:34:15 maud node[1108]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 08 18:34:15 maud volumio[1108]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 08 18:34:15 maud volumio[1108]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 18:34:15 maud volumio[1108]: *** WARNING *** For more information see Mar 08 18:34:15 maud node[1108]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 18:34:15 maud node[1108]: *** WARNING *** For more information see Mar 08 18:34:15 maud node[1108]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 08 18:34:15 maud node[1108]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 08 18:34:15 maud node[1108]: *** WARNING *** For more information see Mar 08 18:34:15 maud volumio[1108]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 08 18:34:15 maud volumio[1108]: info: Discovery: Started advertising with name: Maud Mar 08 18:34:15 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 18:34:15 maud volumio[1108]: info: Loading plugin "spop"... Mar 08 18:34:16 maud volumio[1108]: info: Loading plugin "outputs"... Mar 08 18:34:16 maud volumio[1108]: info: Loading plugin "albumart"... Mar 08 18:34:16 maud volumio[1108]: info: Plugin example_plugin is not enabled Mar 08 18:34:16 maud volumio[1108]: info: Loading plugin "inputs"... Mar 08 18:34:16 maud volumio[1108]: info: Loading plugin "updater_comm"... Mar 08 18:34:17 maud volumio[1108]: info: Plugin mpdemulation is not enabled Mar 08 18:34:17 maud volumio[1108]: info: Loading plugin "rest_api"... Mar 08 18:34:17 maud volumio[1108]: info: Loading plugin "websocket"... Mar 08 18:34:17 maud volumio[1108]: info: Plugin contemporary_advanced is not enabled Mar 08 18:34:17 maud volumio[1108]: info: Loading plugin "touch_display"... Mar 08 18:34:17 maud sudo[1138]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:17 maud volumio[1108]: Forking 3 albumart workers Mar 08 18:34:17 maud volumio[1108]: info: Applying required configuration parameters for plugin touch_display Mar 08 18:34:17 maud volumio[1108]: info: Loading i18n strings for locale en Mar 08 18:34:17 maud volumio[1108]: Updating browse sources language Mar 08 18:34:17 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 18:34:17 maud volumio[1108]: Starting albumart workers Mar 08 18:34:17 maud volumio[1108]: Starting albumart workers Mar 08 18:34:17 maud volumio[1108]: Starting albumart workers Mar 08 18:34:17 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 18:34:17 maud volumio[1108]: info: CoreCommandRouter::initPlayerControls Mar 08 18:34:17 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:17 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:17 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:17 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:17 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:17 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:17 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:17 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:17 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 18:34:17 maud systemd[1]: systemd-fsckd.service: Succeeded. Mar 08 18:34:17 maud volumio[1108]: Express server listening on port 3000 Mar 08 18:34:17 maud volumio[1108]: [Metrics] WebUI: 7s 130.67ms Mar 08 18:34:17 maud volumio[1108]: info: CoreStateMachine::resetVolumioState Mar 08 18:34:17 maud volumio[1108]: info: CoreStateMachine::getcurrentVolume Mar 08 18:34:17 maud volumio[1108]: info: CoreCommandRouter::volumioRetrievevolume Mar 08 18:34:17 maud volumio[1108]: info: MPD Permissions set Mar 08 18:34:17 maud volumio-remote-updater[534]: [2023-03-08 18:34:17] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1678300453 101 Mar 08 18:34:18 maud volumio[1108]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Mar 08 18:34:18 maud volumio[1108]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.27.1 Total Clients: 2 Mar 08 18:34:18 maud volumio[1108]: verbose: New Socket.io Connection to 192.168.1.44:3000 from 192.168.1.38 UA: node-XMLHttpRequest Total Clients: 3 Mar 08 18:34:18 maud volumio[1108]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Mar 08 18:34:18 maud volumio[1108]: info: MPD running with PID1159 Mar 08 18:34:18 maud volumio[1108]: ,establishing connection Mar 08 18:34:18 maud volumio[1108]: info: CoreStateMachine::pushState Mar 08 18:34:18 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::volumioPushState Mar 08 18:34:18 maud volumio[1108]: info: CoreStateMachine::updateTrackBlock Mar 08 18:34:18 maud volumio[1108]: info: CorePlayQueue::getTrackBlock Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::volumioRetrievevolume Mar 08 18:34:18 maud volumio[1108]: info: Reloading queue from file Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:18 maud volumio[1108]: error: updateQueue error: null Mar 08 18:34:18 maud volumio[1108]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 08 18:34:18 maud volumio[1108]: info: CoreStateMachine::pushState Mar 08 18:34:18 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::volumioPushState Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:18 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 18:34:18 maud volumio[1108]: info: Setting Device type: Raspberry PI Mar 08 18:34:18 maud volumio[1108]: info: Completed loading Core Plugins Mar 08 18:34:18 maud volumio[1108]: info: Preparing to generate the ALSA configuration file Mar 08 18:34:18 maud volumio[1108]: info: CoreStateMachine::setRepeat false single undefined Mar 08 18:34:18 maud volumio[1108]: info: CoreStateMachine::pushState Mar 08 18:34:18 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::volumioPushState Mar 08 18:34:18 maud volumio[1108]: info: CoreStateMachine::setRandom null Mar 08 18:34:18 maud volumio[1108]: info: CoreStateMachine::pushState Mar 08 18:34:18 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::volumioPushState Mar 08 18:34:18 maud volumio[1108]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 08 18:34:18 maud volumio[1108]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 08 18:34:18 maud volumio[1108]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 08 18:34:18 maud volumio[1108]: info: Reading ALSA contributions from plugins. Mar 08 18:34:18 maud volumio[1108]: info: Discovery: adding a5ebfa80-0971-49f3-bf34-6379d7cff227 Mar 08 18:34:18 maud volumio[1108]: info: Discovery: Found device Maud Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:18 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:18 maud volumio[1108]: error: updateQueue error: null Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:18 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:18 maud volumio[1108]: info: Asound.conf file unchanged, so no further update is needed Mar 08 18:34:18 maud volumio[1108]: info: Output device has changed, restarting MPD Mar 08 18:34:18 maud sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 08 18:34:18 maud sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:18 maud volumio[1108]: info: ___________ START PLUGINS ___________ Mar 08 18:34:18 maud sudo[1273]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 08 18:34:18 maud volumio[1108]: info: [1678300458967] CoreMusicLibrary::Adding element Last_100 Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 18:34:18 maud sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 08 18:34:18 maud sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 08 18:34:18 maud volumio[1108]: info: [1678300458977] CoreMusicLibrary::Adding element Webradio Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 18:34:18 maud systemd[1]: Stopping Music Player Daemon... Mar 08 18:34:18 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 18:34:18 maud volumio[1108]: info: Checking for old spotify connect plugin installed Mar 08 18:34:18 maud volumio[1108]: info: Loading i18n strings for locale en Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 18:34:19 maud volumio[1108]: info: Volumio Calling Home Mar 08 18:34:19 maud systemd[1]: mpd.service: Succeeded. Mar 08 18:34:19 maud systemd[1]: Stopped Music Player Daemon. Mar 08 18:34:19 maud systemd[1]: Starting Music Player Daemon... Mar 08 18:34:19 maud sh[423]: timed out Mar 08 18:34:19 maud dhcpcd[467]: timed out Mar 08 18:34:19 maud sh[423]: dhcpcd exited Mar 08 18:34:19 maud dhcpcd[467]: dhcpcd exited Mar 08 18:34:19 maud sh[423]: ifup: failed to bring up eth0 Mar 08 18:34:19 maud systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Mar 08 18:34:19 maud systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 18:34:19 maud volumio[1108]: [SpotifyConnect] Creating VLS config file Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:19 maud volumio[1108]: [SpotifyConnect] Starting metadata listener Mar 08 18:34:19 maud sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Mar 08 18:34:19 maud sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:19 maud sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Mar 08 18:34:19 maud sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:19 maud systemd[1]: Started Volspotconnect2 Daemon. Mar 08 18:34:19 maud sudo[1285]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:19 maud systemd[1]: Reloading. Mar 08 18:34:19 maud volumio[1289]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Mar 08 18:34:19 maud volumio[1289]: Reading Config from "/tmp/volspotify.toml" Mar 08 18:34:19 maud volumio[1289]: Malformed config key: missing field `Authentication` Mar 08 18:34:19 maud volumio[1108]: [SpotifyConnect] Vollibrespot Daemon service started! Mar 08 18:34:19 maud volumio[1108]: [Metrics] SpotifyConnect: 0s 367.76ms Mar 08 18:34:19 maud volumio[1108]: info: MPD Permissions set Mar 08 18:34:19 maud volumio[1108]: info: Volumio called home Mar 08 18:34:19 maud volumio[1108]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Mar 08 18:34:19 maud volumio[1108]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:19 maud systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Mar 08 18:34:19 maud systemd[1]: /lib/systemd/system/power.service:6: Invalid environment assignment, ignoring: XAUTHORITY/home/volumio/.Xauthority Mar 08 18:34:19 maud systemd[1]: /lib/systemd/system/power.service:9: Failed to parse service type, ignoring: idlepower Service Mar 08 18:34:19 maud volumio[1108]: info: touch_display: Raspberry Pi Foundation touch screen detected. Mar 08 18:34:19 maud volumio[1108]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. Mar 08 18:34:19 maud sudo[1311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:19 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:19 maud sudo[1311]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:19 maud sudo[1311]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:19 maud volumio[1108]: info: touch_display: File permissions for backlight brightness control set. Mar 08 18:34:19 maud systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 08 18:34:19 maud mpd[1291]: Mar 08 18:34 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 08 18:34:19 maud systemd[1]: volspotconnect.service: Main process exited, code=exited, status=1/FAILURE Mar 08 18:34:19 maud systemd[1]: volspotconnect.service: Failed with result 'exit-code'. Mar 08 18:34:19 maud sudo[1288]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:19 maud volumio[1108]: info: touch_display: systemctl daemon-reload succeeded. Mar 08 18:34:19 maud sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Mar 08 18:34:19 maud sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:19 maud systemd[1]: Started Volumio Kiosk. Mar 08 18:34:19 maud sudo[1316]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:19 maud volumio[1108]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Mar 08 18:34:19 maud volumio[1108]: info: touch_display: Volumio Kiosk started Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:19 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:19 maud volumio[1108]: info: Completed starting Core Plugins Mar 08 18:34:19 maud volumio[1108]: info: ------------------------------------------- Mar 08 18:34:19 maud volumio[1108]: info: ----- MyVolumio plugins startup ---- Mar 08 18:34:19 maud volumio[1108]: info: ------------------------------------------- Mar 08 18:34:19 maud volumio[1108]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 08 18:34:19 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:19 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:20 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Mar 08 18:34:20 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:20 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:20 maud python[1149]: xset: unable to open display ":0" Mar 08 18:34:20 maud mpd[1291]: Mar 08 18:34 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 08 18:34:20 maud mpd[1291]: Mar 08 18:34 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 08 18:34:20 maud systemd[1]: Started Music Player Daemon. Mar 08 18:34:20 maud sudo[1276]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:20 maud volumio[1108]: error: updateQueue error: null Mar 08 18:34:20 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:34:20 maud startx[1318]: X.Org X Server 1.20.4 Mar 08 18:34:20 maud startx[1318]: X Protocol Version 11, Revision 0 Mar 08 18:34:20 maud startx[1318]: Build Operating System: Linux 5.4.0-72-generic armv8l Raspbian Mar 08 18:34:20 maud startx[1318]: Current Operating System: Linux maud 5.10.92-v7l+ #1514 SMP Mon Jan 17 17:38:03 GMT 2022 armv7l Mar 08 18:34:20 maud startx[1318]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:CA:E1:52 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Mar 08 18:34:20 maud startx[1318]: Build Date: 04 January 2022 10:05:34AM Mar 08 18:34:20 maud startx[1318]: xorg-server 2:1.20.4-1+rpt4+deb10u4 (https://www.debian.org/support) Mar 08 18:34:20 maud startx[1318]: Current version of pixman: 0.36.0 Mar 08 18:34:20 maud startx[1318]: Before reporting problems, check http://wiki.x.org Mar 08 18:34:20 maud startx[1318]: to make sure that you have the latest version. Mar 08 18:34:20 maud startx[1318]: Markers: (--) probed, (**) from config file, (==) default setting, Mar 08 18:34:20 maud startx[1318]: (++) from command line, (!!) notice, (II) informational, Mar 08 18:34:20 maud startx[1318]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Mar 08 18:34:20 maud startx[1318]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Mar 8 18:34:20 2023 Mar 08 18:34:20 maud startx[1318]: (==) Using config directory: "/etc/X11/xorg.conf.d" Mar 08 18:34:20 maud startx[1318]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Mar 08 18:34:20 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:20 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:20 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:34:21 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:34:22 maud systemd[1]: volspotconnect.service: Service RestartSec=2s expired, scheduling restart. Mar 08 18:34:22 maud systemd[1]: volspotconnect.service: Scheduled restart job, restart counter is at 1. Mar 08 18:34:22 maud systemd[1]: Stopped Volspotconnect2 Daemon. Mar 08 18:34:22 maud systemd[1]: Started Volspotconnect2 Daemon. Mar 08 18:34:22 maud volumio[1405]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Mar 08 18:34:22 maud volumio[1405]: Reading Config from "/tmp/volspotify.toml" Mar 08 18:34:22 maud volumio[1405]: [Vollibrespot] : Using Alsa backend with device: volumio Mar 08 18:34:22 maud volumio[1405]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Mar 08 18:34:22 maud volumio[1405]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Mar 08 18:34:22 maud volumio[1405]: [Vollibrespot] : Authenticated as "1137932189" ! Mar 08 18:34:22 maud volumio[1405]: [Vollibrespot] : Using alsa sink Mar 08 18:34:22 maud volumio[1405]: [Vollibrespot] : Metadata pipe established Mar 08 18:34:22 maud volumio[1405]: [Vollibrespot] : Country: "GB" Mar 08 18:34:22 maud volumio[1405]: [Vollibrespot] : Event: Volume { volume_to_mixer: 32768 } Mar 08 18:34:22 maud volumio[1108]: [SpotifyConnect] Volume Spotify: 50.000762951094835 Volumio: 50 Mar 08 18:34:23 maud volumio[1108]: info: Checking Spotify Web API Mar 08 18:34:23 maud volumio[1405]: [Vollibrespot] : ReqToken Mar 08 18:34:23 maud volumio[1108]: info: New Spotify Access Token Received Mar 08 18:34:23 maud volumio[1108]: info: Initializing Spotify Web API Mar 08 18:34:23 maud volumio[1108]: info: Initliazing Spotify Browsing Facility Mar 08 18:34:23 maud volumio[1108]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 08 18:34:23 maud volumio[1108]: info: [1678300463876] CoreMusicLibrary::Adding element Spotify Mar 08 18:34:23 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 18:34:23 maud volumio[1108]: Cannot find translation for sourceSpotify Mar 08 18:34:23 maud volumio[1108]: error: Failed to retrieve spotify devices lists: WebapiError: Bad OAuth request Mar 08 18:34:23 maud volumio[1108]: error: Failed to retrieve user informations: WebapiError: Bad OAuth request Mar 08 18:34:23 maud volumio[1108]: (node:1108) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 4) Mar 08 18:34:23 maud volumio[1108]: (Use `node --trace-warnings ...` to show where the warning was created) Mar 08 18:34:23 maud volumio[1108]: (node:1108) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Mar 08 18:34:26 maud systemd[1]: systemd-hostnamed.service: Succeeded. Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 08 18:34:28 maud volumio[1108]: info: Adding plugin bluetooth to MyMusic Plugins Mar 08 18:34:28 maud volumio[1108]: info: Adding plugin multiroom to MyMusic Plugins Mar 08 18:34:28 maud volumio[1108]: info: Adding plugin metavolumio to MyMusic Plugins Mar 08 18:34:28 maud volumio[1108]: info: Adding plugin cd_controller to MyMusic Plugins Mar 08 18:34:28 maud volumio[1108]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 08 18:34:28 maud volumio[1108]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 08 18:34:28 maud volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 08 18:34:29 maud volumio[1108]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 08 18:34:29 maud volumio[1108]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 08 18:34:29 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:29 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:29 maud volumio[1108]: info: Starting MyVolumio Remote Streaming Endpoints Mar 08 18:34:29 maud volumio[1108]: info: MyVolumio login type: Token Mar 08 18:34:29 maud volumio[1108]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 08 18:34:29 maud volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 08 18:34:30 maud volumio[1108]: info: Starting Streaming Service Transparent Proxy Mar 08 18:34:30 maud volumio[1108]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 08 18:34:30 maud volumio[1108]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 08 18:34:30 maud volumio[1108]: info: Streaming services startup Mar 08 18:34:30 maud volumio[1108]: info: Starting Streaming Daemon Mar 08 18:34:30 maud sudo[1591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 08 18:34:30 maud sudo[1591]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:30 maud volumio[1108]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 08 18:34:30 maud sudo[1591]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:30 maud volumio[1108]: error: Cannot start Volumio Streaming Daemon Mar 08 18:34:30 maud volumio[1108]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 08 18:34:30 maud volumio[1108]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 08 18:34:30 maud volumio[1108]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 08 18:34:30 maud volumio[1108]: info: MYVOLUMIO: Adding device Mar 08 18:34:30 maud volumio[1108]: info: MYVOLUMIO: Evaluating Server Mar 08 18:34:31 maud volumio[1108]: STREAMING PROXY: Starting server on port 3245 Mar 08 18:34:31 maud volumio[1108]: Node JS runtime: 14 Mar 08 18:34:31 maud volumio[1108]: info: MyVolumio Plan changed: premium Mar 08 18:34:31 maud volumio[1108]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 08 18:34:31 maud volumio[1108]: info: Removing browser output: myVolumio user plan is not superstar Mar 08 18:34:31 maud volumio[1108]: info: Removing audio output: Mar 08 18:34:31 maud volumio[1108]: info: MYVOLUMIO: Adding device Mar 08 18:34:31 maud volumio[1108]: info: MYVOLUMIO: Evaluating Server Mar 08 18:34:31 maud volumio[1108]: info: Remote config written successfully Mar 08 18:34:31 maud volumio[1108]: info: Starting Tunnel 1 Mar 08 18:34:31 maud volumio[1108]: info: Starting Tunnel Connection Checker Mar 08 18:34:31 maud volumio[1108]: info: Completed starting MyVolumio Plugin Mar 08 18:34:31 maud volumio[1108]: info: MYVolumio Device enabled Mar 08 18:34:31 maud volumio[1108]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 08 18:34:31 maud volumio[1108]: info: MyVolumio status changed Mar 08 18:34:31 maud volumio[1108]: info: Streaming services startup Mar 08 18:34:31 maud volumio[1108]: info: Starting Streaming Daemon Mar 08 18:34:31 maud sudo[1641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 08 18:34:31 maud sudo[1641]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:31 maud volumio[1108]: info: Setting Geolocation for MyVolumio to eu3 Mar 08 18:34:31 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:31 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:31 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:31 maud sudo[1641]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:32 maud volumio[1108]: error: Cannot start Volumio Streaming Daemon Mar 08 18:34:32 maud volumio[1108]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 08 18:34:32 maud volumio[1108]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 08 18:34:33 maud volumio[1108]: info: Updating MyVolumio device info Mar 08 18:34:33 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:33 maud volumio[1108]: info: Setting Geolocation for MyVolumio to eu3 Mar 08 18:34:33 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:33 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:33 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:33 maud volumio[1108]: info: Successfully Added MyVolumio device Mar 08 18:34:33 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 08 18:34:33 maud volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 08 18:34:33 maud volumio[1108]: info: [1678300473273] Starting BluetoothController Mar 08 18:34:33 maud volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 08 18:34:34 maud volumio[1108]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 08 18:34:34 maud sudo[1650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast Mar 08 18:34:34 maud sudo[1650]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:34 maud sudo[1650]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:34 maud sudo[1653]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 08 18:34:34 maud sudo[1653]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:34 maud sudo[1653]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:34 maud volumio[1108]: info: MRS: MultiRoom plugin initialized Mar 08 18:34:34 maud volumio[1108]: info: MRS: STOPPING SNAPCLIENT Mar 08 18:34:34 maud volumio[1108]: info: MRS: Snap server stop Mar 08 18:34:34 maud sudo[1670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 08 18:34:34 maud sudo[1670]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:34 maud volumio[1108]: info: MRS: STOPPING volumioStreaming Mar 08 18:34:34 maud sudo[1672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 08 18:34:34 maud sudo[1672]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:34 maud sudo[1677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 08 18:34:34 maud sudo[1677]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:34 maud volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 08 18:34:34 maud sudo[1670]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:34 maud sudo[1672]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:34 maud sudo[1682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 08 18:34:34 maud sudo[1682]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:34 maud sudo[1682]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:34 maud volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 08 18:34:34 maud sudo[1677]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:34 maud volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 08 18:34:34 maud volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 08 18:34:34 maud volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 08 18:34:34 maud volumio[1108]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 08 18:34:34 maud volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 08 18:34:34 maud volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 08 18:34:34 maud volumio[1108]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 08 18:34:34 maud volumio[1108]: info: Preparing to generate the ALSA configuration file Mar 08 18:34:34 maud volumio[1108]: info: Updating MyVolumio device info Mar 08 18:34:34 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:34 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:34 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:34 maud volumio[1108]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 08 18:34:34 maud volumio[1108]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 08 18:34:34 maud volumio[1108]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 08 18:34:34 maud volumio[1108]: info: Reading ALSA contributions from plugins. Mar 08 18:34:34 maud volumio[1108]: info: Successfully Added MyVolumio device Mar 08 18:34:34 maud volumio[1108]: info: Successfully Updated MyVolumio device Mar 08 18:34:34 maud volumio[1108]: info: MRS: Removed streaming files Mar 08 18:34:34 maud volumio[1108]: info: MRS: volumioStreaming STOPPED Mar 08 18:34:34 maud volumio[1108]: info: MRS: SNAPSERVER STOPPED Mar 08 18:34:34 maud volumio[1108]: info: MRS: SNAPCLIENT STOPPED Mar 08 18:34:34 maud volumio[1108]: info: Snap server and client installed Mar 08 18:34:34 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:34:34 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:34:34 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:34:34 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:34 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:34 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:34:34 maud volumio[1108]: verbose: New Socket.io Connection to 192.168.1.44 from 192.168.1.118 UA: okhttp/3.12.12 Total Clients: 7 Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:35 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 08 18:34:35 maud volumio[1108]: info: Asound.conf file unchanged, so no further update is needed Mar 08 18:34:35 maud volumio[1108]: info: Output device has changed, restarting MPD Mar 08 18:34:35 maud volumio[1108]: [SpotifyConnect] Creating VLS config file Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud volumio[1108]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 08 18:34:35 maud sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:35 maud sudo[1685]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:35 maud sudo[1687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 08 18:34:35 maud sudo[1687]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:35 maud volumio[1108]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 08 18:34:35 maud systemd[1]: Stopping Music Player Daemon... Mar 08 18:34:35 maud volumio[1108]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 08 18:34:35 maud volumio[1108]: info: MRS: Pushing multiroomSync output for this device Mar 08 18:34:35 maud volumio[1108]: info: MRS: Pushing multiroomSync output Mar 08 18:34:35 maud volumio[1108]: info: Adding audio output: Mar 08 18:34:35 maud volumio[1108]: info: Adding audio output: Mar 08 18:34:35 maud sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Maud Mar 08 18:34:35 maud sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:35 maud systemd[1]: mpd.service: Succeeded. Mar 08 18:34:35 maud systemd[1]: Stopped Music Player Daemon. Mar 08 18:34:35 maud volumio[1108]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud systemd[1]: Starting Music Player Daemon... Mar 08 18:34:35 maud sudo[1693]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:35 maud volumio[1108]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Mar 08 18:34:35 maud volumio[1108]: info: Adding METAVOLUMIO REST API Endpoints Mar 08 18:34:35 maud volumio[1108]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 08 18:34:35 maud volumio[1108]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 08 18:34:35 maud volumio[1108]: info: Adding Manifest REST API Endpoints Mar 08 18:34:35 maud volumio[1108]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Mar 08 18:34:35 maud volumio[1108]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 08 18:34:35 maud volumio[1108]: info: Preparing CD Folders Mar 08 18:34:35 maud volumio[1108]: info: Adding CD REST API Endpoints Mar 08 18:34:35 maud volumio[1108]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Mar 08 18:34:35 maud volumio[1108]: info: Starting UDEV Watcher for CD Mar 08 18:34:35 maud volumio[1108]: info: Detecting CD presence with UDEV Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Mar 08 18:34:35 maud volumio[1108]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Mar 08 18:34:35 maud volumio[1108]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Mar 08 18:34:35 maud volumio[1108]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Mar 08 18:34:35 maud volumio[1108]: error: Hi Res Audio Failed Login: Missing Login Data Mar 08 18:34:35 maud volumio[1108]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 08 18:34:35 maud volumio[1108]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 08 18:34:35 maud volumio[1108]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 08 18:34:35 maud volumio[1108]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 08 18:34:35 maud volumio[1108]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 08 18:34:35 maud volumio[1108]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Mar 08 18:34:35 maud volumio[1108]: info: Stopping AccessToken refresher cron Mar 08 18:34:35 maud volumio[1108]: info: AccessToken refresher cron started Mar 08 18:34:35 maud volumio[1108]: info: Stopping AccessToken refresher cron for QOBUZ Mar 08 18:34:35 maud volumio[1108]: info: AccessToken refresher cron started for QOBUZ Mar 08 18:34:35 maud volumio[1108]: info: Adding TIDAL REST API Endpoints Mar 08 18:34:35 maud volumio[1108]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 08 18:34:35 maud volumio[1108]: info: Adding QOBUZ REST API Endpoints Mar 08 18:34:35 maud volumio[1108]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 08 18:34:35 maud volumio[1108]: [SpotifyConnect] Restarting Vollibrespot Daemon Mar 08 18:34:35 maud sudo[1702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Mar 08 18:34:35 maud sudo[1702]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:35 maud systemd[1]: Stopping Volspotconnect2 Daemon... Mar 08 18:34:35 maud systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Mar 08 18:34:35 maud systemd[1]: volspotconnect.service: Succeeded. Mar 08 18:34:35 maud systemd[1]: Stopped Volspotconnect2 Daemon. Mar 08 18:34:35 maud volumio[1108]: info: Successfully Updated MyVolumio device Mar 08 18:34:35 maud systemd[1]: Started Volspotconnect2 Daemon. Mar 08 18:34:35 maud sudo[1702]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:35 maud volumio[1108]: info: Bluetooth name changed to Maud Mar 08 18:34:35 maud volumio[1108]: info: MPD Permissions set Mar 08 18:34:35 maud volumio[1705]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Mar 08 18:34:35 maud volumio[1705]: Reading Config from "/tmp/volspotify.toml" Mar 08 18:34:35 maud volumio[1705]: [Vollibrespot] : Using Alsa backend with device: volumio Mar 08 18:34:35 maud volumio[1108]: [SpotifyConnect] Vollibrespot Daemon service restarted! Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud volumio[1705]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud volumio[1108]: info: BOOT COMPLETED Mar 08 18:34:35 maud volumio[1108]: [Metrics] CommandRouter: 24s 450.56ms Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::volumiosetStartupVolume Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 18:34:35 maud volumio[1108]: info: VolumeController:: Setting startup Volume 100 Mar 08 18:34:35 maud volumio[1108]: info: VolumeController::SetAlsaVolume100 Mar 08 18:34:35 maud volumio[1705]: [Vollibrespot] : Connecting to AP "ap-gew1.spotify.com:443" Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::Close All Modals sent Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::Close All Modals sent Mar 08 18:34:35 maud volumio[1108]: info: CoreStateMachine::pushState Mar 08 18:34:35 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 18:34:35 maud volumio[1108]: info: CoreCommandRouter::volumioPushState Mar 08 18:34:35 maud volumio[1108]: info: MRS: Pushing multiroomSync output update for this device Mar 08 18:34:35 maud volumio[1108]: info: MRS: Pushing multiroomSync output Mar 08 18:34:35 maud volumio[1108]: info: MRS: Found cast device: Chromecast-450032fd7b915bbc48c30f45f75e7241 Mar 08 18:34:35 maud volumio[1108]: info: Adding audio output: Mar 08 18:34:35 maud volumio[1108]: info: MRS: Found cast device: Google-Home-Mini-9bda9fe47891d83e8cbba3a3122e1fa3 Mar 08 18:34:35 maud volumio[1108]: info: Adding audio output: Mar 08 18:34:35 maud volumio[1108]: info: MRS: Found cast device: Google-Home-Mini-fb62328b4506c6620de45e58c93af032 Mar 08 18:34:35 maud volumio[1108]: info: Adding audio output: Mar 08 18:34:35 maud volumio[1705]: [Vollibrespot] : Authenticated as "1137932189" ! Mar 08 18:34:35 maud volumio[1705]: [Vollibrespot] : Metadata pipe established Mar 08 18:34:35 maud volumio[1705]: [Vollibrespot] : Country: "GB" Mar 08 18:34:35 maud volumio[1705]: [Vollibrespot] : Event: Volume { volume_to_mixer: 32768 } Mar 08 18:34:35 maud volumio[1705]: [Vollibrespot] : Using alsa sink Mar 08 18:34:35 maud volumio[1108]: [SpotifyConnect] Volume Spotify: 50.000762951094835 Volumio: 50 Mar 08 18:34:35 maud mpd[1700]: Mar 08 18:34 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 08 18:34:36 maud mpd[1700]: Mar 08 18:34 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 08 18:34:36 maud mpd[1700]: Mar 08 18:34 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 08 18:34:36 maud systemd[1]: Started Music Player Daemon. Mar 08 18:34:36 maud sudo[1687]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:36 maud volumio[1108]: error: updateQueue error: null Mar 08 18:34:36 maud volumio[1108]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 8 Mar 08 18:34:36 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 08 18:34:36 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 08 18:34:36 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 08 18:34:38 maud sudo[1738]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 08 18:34:38 maud sudo[1738]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:38 maud systemd[1]: Started Volumio Bluetooth Module. Mar 08 18:34:38 maud sudo[1738]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:38 maud volumio[1108]: info: Volumio BT Module successfully started Mar 08 18:34:38 maud sudo[1743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 08 18:34:38 maud sudo[1743]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:38 maud sudo[1742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Mar 08 18:34:38 maud sudo[1742]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:38 maud volumiobt[1740]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Mar 08 18:34:38 maud sudo[1742]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:38 maud systemd[1]: Started MyVolumio SSH Tunnel. Mar 08 18:34:38 maud sudo[1743]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:38 maud volumio[1108]: info: Remote SSH Started Mar 08 18:34:38 maud autossh[1748]: port set to 0, monitoring disabled Mar 08 18:34:38 maud autossh[1748]: starting ssh (count 1) Mar 08 18:34:38 maud autossh[1748]: ssh child pid is 1751 Mar 08 18:34:38 maud volumiossh-tunnel[1747]: Warning: Permanently added '[eu3.myvolumio.org]:2222,[167.71.136.233]:2222' (RSA) to the list of known hosts. Mar 08 18:34:38 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 18:34:38 maud volumio[1108]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Mar 08 18:34:38 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:38 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:39 maud sudo[1755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Mar 08 18:34:39 maud sudo[1755]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:39 maud volumiobt[1740]: pulseaudio: no process found Mar 08 18:34:39 maud sudo[1755]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:39 maud sudo[1758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 08 18:34:39 maud sudo[1758]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:39 maud sudo[1758]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:39 maud sudo[1761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 18:34:39 maud sudo[1761]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:34:39 maud sudo[1761]: pam_unix(sudo:session): session closed for user root Mar 08 18:34:39 maud dbus-daemon[507]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.25' (uid=1000 pid=1765 comm="/usr/bin/pulseaudio --high-priority=true ") Mar 08 18:34:39 maud systemd[1]: Starting RealtimeKit Scheduling Policy Service... Mar 08 18:34:39 maud volumio[1108]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 18:34:39 maud dbus-daemon[507]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Mar 08 18:34:39 maud systemd[1]: Started RealtimeKit Scheduling Policy Service. Mar 08 18:34:39 maud rtkit-daemon[1766]: Successfully called chroot. Mar 08 18:34:39 maud rtkit-daemon[1766]: Successfully dropped privileges. Mar 08 18:34:39 maud rtkit-daemon[1766]: Successfully limited resources. Mar 08 18:34:39 maud rtkit-daemon[1766]: Running. Mar 08 18:34:39 maud rtkit-daemon[1766]: Canary thread running. Mar 08 18:34:39 maud rtkit-daemon[1766]: Watchdog thread running. Mar 08 18:34:39 maud dbus-daemon[507]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.26' (uid=0 pid=1766 comm="/usr/lib/rtkit/rtkit-daemon ") Mar 08 18:34:39 maud systemd[1]: Starting Authorization Manager... Mar 08 18:34:39 maud volumio[1108]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 18:34:39 maud polkitd[1769]: started daemon version 0.105 using authority implementation `local' version `0.105' Mar 08 18:34:39 maud volumio[1108]: verbose: New Socket.io Connection to 192.168.1.44 from 192.168.1.118 UA: okhttp/3.12.12 Total Clients: 10 Mar 08 18:34:39 maud dbus-daemon[507]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Mar 08 18:34:39 maud systemd[1]: Started Authorization Manager. Mar 08 18:34:39 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 18:34:39 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 18:34:39 maud volumio[1108]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 18:34:39 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 18:34:39 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:39 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:39 maud pulseaudio[1765]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 08 18:34:39 maud pulseaudio[1765]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 08 18:34:39 maud volumiobt[1740]: Applying permissions Mar 08 18:34:39 maud volumiobt[1740]: Setting BT discoverable and pairable Mar 08 18:34:39 maud bluetoothd[982]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSource Mar 08 18:34:39 maud bluetoothd[982]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSink Mar 08 18:34:39 maud pulseaudio[1765]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Mar 08 18:34:39 maud kernel: Bluetooth: RFCOMM TTY layer initialized Mar 08 18:34:39 maud kernel: Bluetooth: RFCOMM socket layer initialized Mar 08 18:34:39 maud kernel: Bluetooth: RFCOMM ver 1.11 Mar 08 18:34:39 maud volumiobt[1740]: [CHG] Controller DC:A6:32:CA:E1:54 Class: 0x0008041c Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [91B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [117B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [55B blob data] Mar 08 18:34:39 maud volumiobt[1740]: [bluetooth]# -e power on Mar 08 18:34:39 maud volumiobt[1740]: Invalid command in menu main: -e Mar 08 18:34:39 maud volumiobt[1740]: Mar 08 18:34:39 maud volumiobt[1740]: Use "help" for a list of available commands in a menu. Mar 08 18:34:39 maud volumiobt[1740]: Use "menu " if you want to enter any submenu. Mar 08 18:34:39 maud volumiobt[1740]: Use "back" if you want to return to menu main. Mar 08 18:34:39 maud volumiobt[1740]: [bluetooth]# agent on Mar 08 18:34:39 maud volumiobt[1740]: Agent is already registered Mar 08 18:34:39 maud volumiobt[1740]: [bluetooth]# discoverable on Mar 08 18:34:39 maud volumiobt[1740]: [bluetooth]# pairable on Mar 08 18:34:39 maud volumiobt[1740]: [bluetooth]# agent NoInputNoOutput Mar 08 18:34:39 maud volumiobt[1740]: Agent is already registered Mar 08 18:34:39 maud volumiobt[1740]: [bluetooth]# default-agent Mar 08 18:34:39 maud volumiobt[1740]: [bluetooth]# quit Mar 08 18:34:39 maud volumiobt[1740]: [59B blob data] Mar 08 18:34:39 maud volumio[1108]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 11 Mar 08 18:34:39 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 08 18:34:39 maud volumio[1108]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 08 18:34:40 maud volumio[1108]: info: Received Get System Info Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:34:40 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:40 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:40 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:40 maud volumio[1108]: info: Listing playlists Mar 08 18:34:40 maud volumio[1108]: info: MRS: Getting audio outputs on start Mar 08 18:34:40 maud volumio[1108]: info: MRS: Requesting all other devices output Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:40 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 08 18:34:40 maud volumio[1108]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 08 18:34:40 maud volumio[1108]: info: Received Get System Info Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:34:40 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:40 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:40 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:40 maud volumio[1108]: info: Listing playlists Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 08 18:34:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 18:34:41 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 08 18:34:41 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 08 18:34:41 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 18:34:41 maud volumio[1108]: info: Received Get System Info Mar 08 18:34:41 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:34:41 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:34:41 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:34:41 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:41 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:41 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:34:41 maud volumio[1108]: info: Checking Spotify Web API Mar 08 18:34:41 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 18:34:41 maud volumio[1108]: info: Received Get System Info Mar 08 18:34:41 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:34:41 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:34:41 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:34:41 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:41 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:41 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:34:41 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 08 18:34:43 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 18:34:43 maud volumio[1108]: info: Received Get System Info Mar 08 18:34:43 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:34:43 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:34:43 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:34:43 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:43 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:43 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:34:43 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 18:34:43 maud volumio[1108]: info: Received Get System Info Mar 08 18:34:43 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:34:43 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:34:43 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:34:43 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:43 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:43 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:34:44 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 18:34:44 maud volumio[1108]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12 Mar 08 18:34:44 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:44 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:44 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 18:34:44 maud volumio[1108]: info: Received Get System Info Mar 08 18:34:44 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:34:44 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:34:44 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:34:44 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:34:44 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:44 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:34:44 maud volumio[1108]: info: CoreCommandRouter::volumioGetQueue Mar 08 18:34:44 maud volumio[1108]: info: CoreStateMachine::getQueue Mar 08 18:34:44 maud volumio[1108]: info: CorePlayQueue::getQueue Mar 08 18:34:46 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 08 18:34:46 maud volumio[1108]: info: Preload queue cleared Mar 08 18:34:49 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 18:34:49 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 18:34:49 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 18:34:49 maud volumio-remote-updater[534]: No test mode Mar 08 18:34:49 maud volumio-remote-updater[534]: No alpha test mode Mar 08 18:34:49 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 08 18:34:49 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 08 18:34:49 maud volumio[1108]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 08 18:34:49 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 08 18:34:49 maud volumio[1108]: info: Preload queue cleared Mar 08 18:34:50 maud volumio[1108]: info: Preload queue cleared Mar 08 18:34:50 maud volumio[1108]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 08 18:34:50 maud volumio[1108]: info: CoreStateMachine::ClearQueue Mar 08 18:34:50 maud volumio[1108]: info: CoreStateMachine::stop Mar 08 18:34:50 maud volumio[1108]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 18:34:50 maud volumio[1108]: info: CorePlayQueue::clearPlayQueue Mar 08 18:34:50 maud volumio[1108]: info: CorePlayQueue::saveQueue Mar 08 18:34:50 maud volumio[1108]: info: CoreCommandRouter::volumioPushQueue Mar 08 18:34:50 maud volumio[1108]: info: CoreStateMachine::addQueueItems Mar 08 18:34:50 maud volumio[1108]: info: CorePlayQueue::addQueueItems Mar 08 18:34:50 maud volumio[1108]: info: Preload queue cleared Mar 08 18:34:50 maud volumio[1108]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s8774 Mar 08 18:34:50 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Mar 08 18:34:50 maud volumio[1108]: info: CoreCommandRouter::volumioPushQueue Mar 08 18:34:50 maud volumio[1108]: info: CorePlayQueue::saveQueue Mar 08 18:34:50 maud volumio[1108]: info: CoreStateMachine::updateTrackBlock Mar 08 18:34:50 maud volumio[1108]: info: CorePlayQueue::getTrackBlock Mar 08 18:34:50 maud volumio[1108]: info: CoreCommandRouter::volumioPlay Mar 08 18:34:50 maud volumio[1108]: info: CoreStateMachine::play index 0 Mar 08 18:34:50 maud volumio[1108]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 18:34:50 maud volumio[1108]: info: CoreStateMachine::stop Mar 08 18:34:50 maud volumio[1108]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 18:34:50 maud volumio[1108]: info: CoreStateMachine::play index undefined Mar 08 18:34:50 maud volumio[1108]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 18:34:50 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:50 maud volumio[1108]: info: CoreStateMachine::startPlaybackTimer Mar 08 18:34:50 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:50 maud volumio[1108]: info: [1678300490635] ControllerWebradio::clearAddPlayTrack Mar 08 18:34:50 maud volumio[1108]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 18:34:50 maud volumio[1108]: info: sendMpdCommand stop took 4 milliseconds Mar 08 18:34:50 maud volumio[1108]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 18:34:50 maud volumio[1108]: info: Mar 08 18:34:50 maud volumio[1108]: ---------------------------- MPD announces system playlist update Mar 08 18:34:50 maud volumio[1108]: info: Ignoring MPD Status Update Mar 08 18:34:50 maud volumio[1108]: info: sendMpdCommand clear took 2 milliseconds Mar 08 18:34:50 maud volumio[1108]: verbose: ControllerMpd::sendMpdCommand load "https://stream-mz.planetradio.co.uk/wave105.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933" Mar 08 18:34:50 maud volumio[1108]: error: updateQueue error: null Mar 08 18:34:50 maud volumio[1108]: info: ------------------------------ 3ms Mar 08 18:34:52 maud volumio[1108]: verbose: ControllerMpd::sendMpdCommand add "https://stream-mz.planetradio.co.uk/wave105.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933" Mar 08 18:34:52 maud volumio[1108]: info: Mar 08 18:34:52 maud volumio[1108]: ---------------------------- MPD announces system playlist update Mar 08 18:34:52 maud volumio[1108]: info: Ignoring MPD Status Update Mar 08 18:34:52 maud volumio[1108]: info: sendMpdCommand add "https://stream-mz.planetradio.co.uk/wave105.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933" took 1 milliseconds Mar 08 18:34:52 maud volumio[1108]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 18:34:52 maud volumio[1108]: verbose: ControllerMpd::sendMpdCommand play Mar 08 18:34:52 maud volumio[1108]: info: ------------------------------ 3ms Mar 08 18:34:52 maud volumio[1108]: info: sendMpdCommand play took 3 milliseconds Mar 08 18:34:54 maud volumio[1108]: info: Mar 08 18:34:54 maud volumio[1108]: ---------------------------- MPD announces state update: player Mar 08 18:34:54 maud volumio[1108]: info: ControllerMpd::getState Mar 08 18:34:54 maud volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:34:54 maud volumio[1108]: info: Mar 08 18:34:54 maud volumio[1108]: ---------------------------- MPD announces system playlist update Mar 08 18:34:54 maud volumio[1108]: info: Ignoring MPD Status Update Mar 08 18:34:54 maud volumio[1108]: info: Mar 08 18:34:54 maud volumio[1108]: ---------------------------- MPD announces state update: player Mar 08 18:34:54 maud volumio[1108]: info: sendMpdCommand status took 24 milliseconds Mar 08 18:34:54 maud volumio[1108]: info: ControllerMpd::getState Mar 08 18:34:54 maud volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:34:54 maud volumio[1108]: verbose: ControllerMpd::parseState Mar 08 18:34:54 maud volumio[1108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:34:54 maud volumio[1108]: info: ------------------------------ 5ms Mar 08 18:34:54 maud volumio[1108]: info: sendMpdCommand status took 4 milliseconds Mar 08 18:34:54 maud volumio[1108]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 08 18:34:54 maud volumio[1108]: verbose: ControllerMpd::parseState Mar 08 18:34:54 maud volumio[1108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:34:54 maud volumio[1108]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:34:54 maud volumio[1108]: info: ControllerMpd::pushState Mar 08 18:34:54 maud volumio[1108]: info: CoreCommandRouter::servicePushState Mar 08 18:34:54 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:54 maud volumio[1108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Sunday Girl - Blondie","artist":null,"album":null,"uri":"https://stream-mz.planetradio.co.uk/wave105.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933","trackType":""} Mar 08 18:34:54 maud volumio[1108]: verbose: CURRENT POSITION 0 Mar 08 18:34:54 maud volumio[1108]: info: CoreStateMachine::syncState stateService play Mar 08 18:34:54 maud volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Mar 08 18:34:54 maud volumio[1108]: info: ------------------------------ 36ms Mar 08 18:34:54 maud volumio[1108]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 08 18:34:54 maud volumio[1108]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:34:54 maud volumio[1108]: info: ControllerMpd::pushState Mar 08 18:34:54 maud volumio[1108]: info: CoreCommandRouter::servicePushState Mar 08 18:34:54 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:54 maud volumio[1108]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Sunday Girl - Blondie","artist":null,"album":null,"uri":"https://stream-mz.planetradio.co.uk/wave105.mp3?direct=true&aw_0_1st.playerid=BMUK_TuneIn&aw_0_1st.skey=7374499933","trackType":""} Mar 08 18:34:54 maud volumio[1108]: verbose: CURRENT POSITION 0 Mar 08 18:34:54 maud volumio[1108]: info: CoreStateMachine::syncState stateService play Mar 08 18:34:54 maud volumio[1108]: info: CoreStateMachine::syncState currentStatus play Mar 08 18:34:54 maud volumio[1108]: info: Received an update from plugin. extracting info from payload Mar 08 18:34:54 maud volumio[1108]: info: CoreStateMachine::pushState Mar 08 18:34:54 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 18:34:54 maud volumio[1108]: info: CoreCommandRouter::volumioPushState Mar 08 18:34:54 maud volumio[1108]: info: MRS: Pushing multiroomSync output update for this device Mar 08 18:34:54 maud volumio[1108]: info: MRS: Pushing multiroomSync output Mar 08 18:34:54 maud volumio[1108]: info: CoreStateMachine::pushState Mar 08 18:34:54 maud volumio[1108]: info: CoreCommandRouter::volumioPushState Mar 08 18:34:54 maud volumio[1108]: info: MRS: Pushing multiroomSync output update for this device Mar 08 18:34:54 maud volumio[1108]: info: MRS: Pushing multiroomSync output Mar 08 18:34:54 maud volumio[1108]: info: ------------------------------ 50ms Mar 08 18:34:54 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 08 18:34:56 maud ntpd[749]: Soliciting pool server 185.57.191.229 Mar 08 18:34:56 maud volumio[1108]: info: CoreCommandRouter::volumioStop Mar 08 18:34:56 maud volumio[1108]: info: CoreStateMachine::stop Mar 08 18:34:56 maud volumio[1108]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 18:34:56 maud volumio[1108]: info: CoreStateMachine::stPlaybackTimer Mar 08 18:34:56 maud volumio[1108]: info: CoreStateMachine::updateTrackBlock Mar 08 18:34:56 maud volumio[1108]: info: CorePlayQueue::getTrackBlock Mar 08 18:34:56 maud volumio[1108]: info: CoreStateMachine::pushState Mar 08 18:34:56 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:56 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 18:34:56 maud volumio[1108]: info: CoreCommandRouter::volumioPushState Mar 08 18:34:56 maud volumio[1108]: info: MRS: Pushing multiroomSync output update for this device Mar 08 18:34:56 maud volumio[1108]: info: MRS: Pushing multiroomSync output Mar 08 18:34:56 maud volumio[1108]: info: CoreStateMachine::serviceStop Mar 08 18:34:56 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:56 maud volumio[1108]: info: CoreCommandRouter::serviceStop Mar 08 18:34:56 maud volumio[1108]: info: [1678300496808] ControllerWebradio::stop Mar 08 18:34:56 maud volumio[1108]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 18:34:56 maud volumio[1108]: info: Mar 08 18:34:56 maud volumio[1108]: ---------------------------- MPD announces state update: player Mar 08 18:34:56 maud volumio[1108]: info: sendMpdCommand stop took 68 milliseconds Mar 08 18:34:56 maud volumio[1108]: info: ControllerMpd::getState Mar 08 18:34:56 maud volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:34:56 maud volumio[1108]: info: sendMpdCommand status took 10 milliseconds Mar 08 18:34:56 maud volumio[1108]: verbose: ControllerMpd::parseState Mar 08 18:34:56 maud volumio[1108]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:34:56 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:34:56 maud volumio[1108]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 08 18:34:56 maud volumio[1108]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:34:56 maud volumio[1108]: info: ControllerMpd::pushState Mar 08 18:34:56 maud volumio[1108]: info: CoreCommandRouter::servicePushState Mar 08 18:34:56 maud volumio[1108]: info: CoreStateMachine::pushState Mar 08 18:34:56 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:56 maud volumio[1108]: info: CoreCommandRouter::volumioPushState Mar 08 18:34:56 maud volumio[1108]: info: MRS: Pushing multiroomSync output update for this device Mar 08 18:34:56 maud volumio[1108]: info: MRS: Pushing multiroomSync output Mar 08 18:34:56 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:34:56 maud volumio[1108]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 08 18:34:56 maud volumio[1108]: info: ------------------------------ 48ms Mar 08 18:34:57 maud ntpd[749]: Soliciting pool server 81.130.79.209 Mar 08 18:34:57 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 18:34:57 maud volumio[1108]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 08 18:34:57 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 08 18:34:57 maud volumio[1108]: info: Received Get System Version Mar 08 18:34:57 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 18:34:57 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 18:34:57 maud volumio[1108]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 08 18:34:57 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 08 18:34:57 maud volumio[1108]: info: Received Get System Version Mar 08 18:34:57 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 18:34:58 maud ntpd[749]: Soliciting pool server 93.93.131.118 Mar 08 18:34:59 maud ntpd[749]: Soliciting pool server 81.179.123.61 Mar 08 18:34:59 maud ntpd[749]: Soliciting pool server 185.103.117.60 Mar 08 18:34:59 maud ntpd[749]: Soliciting pool server 139.143.5.30 Mar 08 18:35:00 maud ntpd[749]: Soliciting pool server 83.151.207.133 Mar 08 18:35:00 maud ntpd[749]: Soliciting pool server 185.177.149.33 Mar 08 18:35:01 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 08 18:35:01 maud volumio[1108]: info: In handleBrowseUri, curUri=spotify Mar 08 18:35:01 maud volumio[1108]: error: An error occurred while listing Spotify new albums WebapiError: Bad OAuth request Mar 08 18:35:01 maud volumio[1108]: error: An error occurred while listing Spotify categories WebapiError: Bad OAuth request Mar 08 18:35:01 maud volumio[1108]: info: An error occurred while getting Spotify ROOT Discover Folders: Mar 08 18:35:01 maud volumio[1108]: info: Preload queue cleared Mar 08 18:35:01 maud ntpd[749]: Soliciting pool server 51.155.16.62 Mar 08 18:35:01 maud ntpd[749]: Soliciting pool server 178.62.250.107 Mar 08 18:35:02 maud ntpd[749]: Soliciting pool server 109.74.206.120 Mar 08 18:35:02 maud ntpd[749]: Soliciting pool server 81.21.65.168 Mar 08 18:35:02 maud ntpd[749]: Soliciting pool server 129.250.35.250 Mar 08 18:35:03 maud ntpd[749]: Soliciting pool server 162.159.200.1 Mar 08 18:35:03 maud ntpd[749]: Soliciting pool server 2a01:7e00::f03c:91ff:fe96:f129 Mar 08 18:35:04 maud ntpd[749]: Soliciting pool server 85.199.214.99 Mar 08 18:35:04 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:35:04 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:35:05 maud ntpd[749]: Soliciting pool server 81.128.218.110 Mar 08 18:35:31 maud ntpd[749]: receive: Unexpected origin timestamp 0xe7b353da.2e6ca4a0 does not match aorg 0000000000.00000000 from server@185.57.191.229 xmt 0xe7b353f3.4e329a05 Mar 08 18:35:31 maud ntpd[749]: receive: Unexpected origin timestamp 0xe7b353da.2e6b2abd does not match aorg 0000000000.00000000 from server@81.130.79.209 xmt 0xe7b353f3.4db01580 Mar 08 18:35:31 maud ntpd[749]: receive: Unexpected origin timestamp 0xe7b353da.2e670bc8 does not match aorg 0000000000.00000000 from server@178.62.250.107 xmt 0xe7b353f3.4e0f4c4c Mar 08 18:35:41 maud volumio[1108]: Searching plugin music_service/spop Mar 08 18:35:41 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 18:35:41 maud volumio[1108]: error: An error occurred while searching WebapiError: Bad OAuth request Mar 08 18:35:47 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:35:47 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:35:47 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:35:47 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:35:58 maud volumio[1108]: verbose: New Socket.io Connection to 192.168.1.44:3000 from 192.168.1.87 UA: node-XMLHttpRequest Total Clients: 13 Mar 08 18:35:59 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 08 18:35:59 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:35:59 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:36:00 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:36:00 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:36:00 maud volumio[1108]: info: Discovery: adding ec23dde6-027d-4732-8bfb-c531d573fcd6 Mar 08 18:36:00 maud volumio[1108]: info: Discovery: Found device MargotPlus Mar 08 18:36:00 maud volumio[1108]: info: Discovery: Connecting to remote: 192.168.1.38 Mar 08 18:36:00 maud volumio[1108]: info: MRS: Pushing audio output on request Mar 08 18:36:00 maud volumio[1108]: info: MRS: Pushing multiroomSync output Mar 08 18:36:00 maud volumio[1108]: info: Discovery: Connected to remote: 192.168.1.38 Mar 08 18:36:00 maud volumio[1108]: info: Discovery: adding 4ddd45c5-6354-4bbc-b390-e974c234b963 Mar 08 18:36:00 maud volumio[1108]: info: Discovery: Found device SonyBerry Mar 08 18:36:01 maud volumio[1108]: info: Discovery: Connecting to remote: 192.168.1.87 Mar 08 18:36:01 maud volumio[1108]: info: MRS: Pushing audio output on request Mar 08 18:36:01 maud volumio[1108]: info: MRS: Pushing multiroomSync output Mar 08 18:36:01 maud volumio[1108]: info: Discovery: Connected to remote: 192.168.1.87 Mar 08 18:36:30 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:36:30 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:36:47 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:36:47 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:36:47 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:36:47 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:36:47 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:36:47 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:36:48 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:36:48 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:36:48 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:36:48 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:36:54 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 08 18:36:54 maud volumio[1108]: info: In handleBrowseUri, curUri=spotify Mar 08 18:36:54 maud volumio[1108]: info: Preload queue cleared Mar 08 18:36:58 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 08 18:36:58 maud volumio[1108]: info: In handleBrowseUri, curUri=spotify/myalbums Mar 08 18:36:58 maud volumio[1108]: error: An error occurred while listing Spotify my albums WebapiError: API rate limit exceeded Mar 08 18:36:58 maud volumio[1108]: error: Failed to execute browseSource: Mar 08 18:37:00 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:37:00 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:37:11 maud volumio[1108]: Searching plugin music_service/spop Mar 08 18:37:11 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 18:37:11 maud volumio[1108]: error: An error occurred while searching WebapiError: Bad OAuth request Mar 08 18:37:31 maud sudo[2438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 08 18:37:31 maud sudo[2438]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:37:31 maud sudo[2438]: pam_unix(sudo:session): session closed for user root Mar 08 18:37:31 maud sudo[2441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 18:37:31 maud sudo[2441]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:37:31 maud sudo[2441]: pam_unix(sudo:session): session closed for user root Mar 08 18:37:31 maud volumio[1108]: verbose: New Socket.io Connection to 192.168.1.44 from 192.168.1.118 UA: Mozilla/5.0 (Linux; Android 10; Mi A2 Lite) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/110.0.0.0 Mobile Safari/537.36 Total Clients: 11 Mar 08 18:37:32 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 18:37:32 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 18:37:32 maud volumio[1108]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 18:37:32 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 18:37:32 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:37:32 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:37:32 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 08 18:37:32 maud volumio[1108]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 08 18:37:32 maud volumio[1108]: info: Received Get System Info Mar 08 18:37:32 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:37:32 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:37:32 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:37:32 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:37:32 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:37:32 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:37:32 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:37:32 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:37:32 maud volumio[1108]: info: Listing playlists Mar 08 18:37:32 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 08 18:37:32 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 18:37:32 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 08 18:37:33 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 18:37:33 maud volumio[1108]: info: Received Get System Info Mar 08 18:37:33 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:37:33 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:37:33 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:37:33 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:37:33 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:37:33 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:37:34 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 18:37:34 maud volumio[1108]: info: Received Get System Info Mar 08 18:37:34 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:37:34 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:37:34 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:37:34 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:37:34 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:37:34 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:37:41 maud volumio[1108]: verbose: New Socket.io Connection to 192.168.1.44 from 192.168.1.118 UA: Mozilla/5.0 (Linux; Android 10; Mi A2 Lite) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/110.0.0.0 Mobile Safari/537.36 Total Clients: 11 Mar 08 18:37:41 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:37:41 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:37:41 maud volumio[1108]: info: CoreCommandRouter::volumioGetQueue Mar 08 18:37:41 maud volumio[1108]: info: CoreStateMachine::getQueue Mar 08 18:37:41 maud volumio[1108]: info: CorePlayQueue::getQueue Mar 08 18:37:41 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Mar 08 18:37:48 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:37:48 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:37:49 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:37:49 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:38:07 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 18:38:07 maud volumio[1108]: info: Received Get System Info Mar 08 18:38:07 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:38:07 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:38:07 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:38:07 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:38:07 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:38:07 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:38:49 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:38:49 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:38:49 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:38:49 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:39:50 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:39:50 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:39:50 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:39:50 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:40:07 maud sudo[2937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 08 18:40:07 maud sudo[2937]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:40:07 maud sudo[2937]: pam_unix(sudo:session): session closed for user root Mar 08 18:40:07 maud sudo[2939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 18:40:07 maud sudo[2939]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:40:07 maud sudo[2939]: pam_unix(sudo:session): session closed for user root Mar 08 18:40:07 maud volumio[1108]: verbose: New Socket.io Connection to 192.168.1.44 from 192.168.1.163 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/110.0.0.0 Safari/537.36 Total Clients: 11 Mar 08 18:40:08 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:40:09 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 08 18:40:09 maud volumio[1108]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 08 18:40:09 maud volumio[1108]: info: Received Get System Info Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:40:09 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:40:09 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:40:09 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:40:09 maud volumio[1108]: info: Listing playlists Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:40:09 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 18:40:09 maud volumio[1108]: info: Received Get System Info Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:40:09 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:40:09 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:40:09 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 08 18:40:11 maud volumio[1108]: info: Executing endpoint metavolumio Mar 08 18:40:11 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 18:40:11 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 18:40:11 maud volumio[1108]: info: Received Get System Info Mar 08 18:40:11 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:40:11 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:40:11 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:40:11 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:40:11 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:40:11 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:40:12 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 18:40:12 maud volumio[1108]: info: Received Get System Info Mar 08 18:40:12 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:40:12 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:40:12 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:40:12 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:40:12 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:40:12 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:40:14 maud volumio[1108]: verbose: New Socket.io Connection to 192.168.1.44 from 192.168.1.163 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/110.0.0.0 Safari/537.36 Total Clients: 11 Mar 08 18:40:14 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:40:14 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:40:14 maud volumio[1108]: info: CoreCommandRouter::volumioGetQueue Mar 08 18:40:14 maud volumio[1108]: info: CoreStateMachine::getQueue Mar 08 18:40:14 maud volumio[1108]: info: CorePlayQueue::getQueue Mar 08 18:40:14 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Mar 08 18:40:34 maud volumio[1108]: info: MRS: Found cast device: Google-Home-Mini-fb62328b4506c6620de45e58c93af032 Mar 08 18:40:34 maud sudo[3027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 08 18:40:34 maud sudo[3027]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:40:34 maud sudo[3027]: pam_unix(sudo:session): session closed for user root Mar 08 18:40:34 maud sudo[3030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 18:40:34 maud sudo[3030]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:40:34 maud sudo[3030]: pam_unix(sudo:session): session closed for user root Mar 08 18:40:34 maud volumio[1108]: verbose: New Socket.io Connection to 192.168.1.44 from 192.168.1.163 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/110.0.0.0 Safari/537.36 Total Clients: 12 Mar 08 18:40:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 18:40:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 18:40:35 maud volumio[1108]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 18:40:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 18:40:35 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:40:35 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:40:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 08 18:40:35 maud volumio[1108]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 08 18:40:35 maud volumio[1108]: info: Received Get System Info Mar 08 18:40:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:40:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:40:35 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:40:35 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:40:35 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:40:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:40:35 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:40:35 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:40:35 maud volumio[1108]: info: Listing playlists Mar 08 18:40:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 08 18:40:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 18:40:35 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 08 18:40:35 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:40:35 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:40:36 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 18:40:36 maud volumio[1108]: info: Received Get System Info Mar 08 18:40:36 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:40:36 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:40:36 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:40:36 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:40:36 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:40:36 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:40:37 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 18:40:37 maud volumio[1108]: info: Received Get System Info Mar 08 18:40:37 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 18:40:37 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 18:40:37 maud volumio[1108]: info: Discovery: Getting this device information Mar 08 18:40:37 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:40:37 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:40:37 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 18:40:37 maud volumio[1108]: info: Executing endpoint metavolumio Mar 08 18:40:37 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 18:40:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 18:40:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 08 18:40:51 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:40:51 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:40:51 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:40:51 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:40:53 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 18:40:53 maud volumio[1108]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 08 18:40:53 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 08 18:40:53 maud volumio[1108]: info: Received Get System Version Mar 08 18:40:53 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 18:41:52 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:41:52 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:41:52 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:41:52 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:42:40 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:42:40 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:42:42 maud volumio[1108]: info: Executing endpoint metavolumio Mar 08 18:42:42 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 08 18:42:42 maud volumio[1108]: info: CoreCommandRouter::volumioGetQueue Mar 08 18:42:42 maud volumio[1108]: info: CoreStateMachine::getQueue Mar 08 18:42:42 maud volumio[1108]: info: CorePlayQueue::getQueue Mar 08 18:42:44 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 08 18:42:44 maud volumio[1108]: info: In handleBrowseUri, curUri=spotify Mar 08 18:42:44 maud volumio[1108]: info: Preload queue cleared Mar 08 18:42:52 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:42:52 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:42:52 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:42:52 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:43:53 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:43:53 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:43:53 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:43:53 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:44:27 maud ntpd[749]: 185.103.117.60 local addr 192.168.1.44 -> Mar 08 18:44:32 maud ntpd[749]: 81.21.65.168 local addr 192.168.1.44 -> Mar 08 18:44:34 maud ntpd[749]: 109.74.206.120 local addr 192.168.1.44 -> Mar 08 18:44:54 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:44:54 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:44:54 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:44:54 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:45:36 maud ntpd[749]: 81.130.79.209 local addr 192.168.1.44 -> Mar 08 18:45:42 maud volumio[1108]: info: Discovery: Disconnected from remote: 192.168.1.38 Mar 08 18:45:45 maud volumio[1108]: info: Discovery: A device disappeared from network Mar 08 18:45:45 maud volumio[1108]: info: Discovery: Device margotplus disappeared from network Mar 08 18:45:47 maud ntpd[749]: 129.250.35.250 local addr 192.168.1.44 -> Mar 08 18:45:55 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:45:55 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:45:55 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:45:55 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:46:55 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:46:55 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:46:55 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:46:55 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:47:12 maud volumio[1108]: info: Discovery: adding ec23dde6-027d-4732-8bfb-c531d573fcd6 Mar 08 18:47:12 maud volumio[1108]: info: Discovery: Found device MargotPlus Mar 08 18:47:12 maud volumio[1108]: info: Discovery: Connecting to remote: 192.168.1.38 Mar 08 18:47:14 maud volumio[1108]: info: MRS: Pushing audio output on request Mar 08 18:47:14 maud volumio[1108]: info: MRS: Pushing multiroomSync output Mar 08 18:47:14 maud volumio[1108]: info: Discovery: Connected to remote: 192.168.1.38 Mar 08 18:47:14 maud volumio[1108]: info: MRS: Adding multiroomSync output Mar 08 18:47:14 maud volumio[1108]: info: Adding audio output: Mar 08 18:47:14 maud volumio[1108]: info: MRS: Pushing multiroomSync output Mar 08 18:47:15 maud volumio[1108]: verbose: New Socket.io Connection to 192.168.1.44:3000 from 192.168.1.38 UA: node-XMLHttpRequest Total Clients: 12 Mar 08 18:47:17 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 08 18:47:18 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:47:18 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:47:44 maud volumio[1108]: info: MRS: Adding multiroomSync output Mar 08 18:47:44 maud volumio[1108]: info: Adding audio output: Mar 08 18:47:44 maud volumio[1108]: info: MRS: Pushing multiroomSync output Mar 08 18:47:49 maud volumio[1108]: info: MRS: Pushing audio output on request Mar 08 18:47:49 maud volumio[1108]: info: MRS: Pushing multiroomSync output Mar 08 18:47:56 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:47:56 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:47:56 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:47:56 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:48:29 maud ntpd[749]: 139.143.5.30 local addr 192.168.1.44 -> Mar 08 18:48:29 maud ntpd[749]: 93.93.131.118 local addr 192.168.1.44 -> Mar 08 18:48:55 maud ntpd[749]: 178.62.250.107 local addr 192.168.1.44 -> Mar 08 18:48:57 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:48:57 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:48:57 maud systemd[1]: Starting Cleanup of Temporary Directories... Mar 08 18:48:57 maud volumio[1108]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 18:48:57 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:48:57 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:48:57 maud systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Mar 08 18:48:57 maud systemd[1]: Started Cleanup of Temporary Directories. Mar 08 18:49:58 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:49:58 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:49:58 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:49:58 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:50:07 maud ntpd[749]: 83.151.207.133 local addr 192.168.1.44 -> Mar 08 18:50:41 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 08 18:50:41 maud volumio[1108]: info: In handleBrowseUri, curUri=spotify Mar 08 18:50:41 maud volumio[1108]: info: Preload queue cleared Mar 08 18:50:43 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 08 18:50:43 maud volumio[1108]: info: In handleBrowseUri, curUri=spotify/playlists Mar 08 18:50:43 maud volumio[1108]: info: Preload queue cleared Mar 08 18:50:58 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:50:58 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:50:58 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:50:58 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:51:14 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 08 18:51:14 maud volumio[1108]: info: In handleBrowseUri, curUri=spotify/myalbums Mar 08 18:51:14 maud volumio[1108]: error: An error occurred while listing Spotify my albums WebapiError: API rate limit exceeded Mar 08 18:51:14 maud volumio[1108]: error: Failed to execute browseSource: Mar 08 18:51:29 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 08 18:51:29 maud volumio[1108]: info: In handleBrowseUri, curUri=spotify/myalbums Mar 08 18:51:29 maud volumio[1108]: error: An error occurred while listing Spotify my albums WebapiError: Bad OAuth request Mar 08 18:51:29 maud volumio[1108]: error: Failed to execute browseSource: Mar 08 18:51:53 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 08 18:51:53 maud volumio[1108]: info: In handleBrowseUri, curUri=spotify/myalbums Mar 08 18:51:53 maud volumio[1108]: error: An error occurred while listing Spotify my albums WebapiError: Bad OAuth request Mar 08 18:51:53 maud volumio[1108]: error: Failed to execute browseSource: Mar 08 18:51:59 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:51:59 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:51:59 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:51:59 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:53:00 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:53:00 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:53:00 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:53:00 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:54:01 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:54:01 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:54:01 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:54:01 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:54:58 maud volumio[1108]: Searching plugin music_service/spop Mar 08 18:54:58 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 18:54:58 maud volumio[1108]: error: An error occurred while searching WebapiError: Bad OAuth request Mar 08 18:55:01 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:55:01 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:55:01 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:55:01 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:55:06 maud volumio[1108]: Searching all installed plugins Mar 08 18:55:06 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 18:55:06 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 08 18:55:06 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 08 18:55:06 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 18:55:06 maud volumio[1108]: error: An error occurred while searching WebapiError: Bad OAuth request Mar 08 18:55:06 maud volumio[1108]: error: Failed search in plugin spop: Mar 08 18:55:07 maud volumio[1108]: info: All search sources collected, pushing search results Mar 08 18:55:32 maud volumio[1108]: Searching all installed plugins Mar 08 18:55:32 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 18:55:32 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , search Mar 08 18:55:32 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: webradio , search Mar 08 18:55:32 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 18:55:32 maud volumio[1108]: error: An error occurred while searching WebapiError: Bad OAuth request Mar 08 18:55:32 maud volumio[1108]: error: Failed search in plugin spop: Mar 08 18:55:32 maud volumio[1108]: info: All search sources collected, pushing search results Mar 08 18:55:36 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 08 18:55:36 maud volumio[1108]: info: In handleBrowseUri, curUri=spotify Mar 08 18:55:37 maud volumio[1108]: info: Preload queue cleared Mar 08 18:55:43 maud volumio[1108]: Searching plugin music_service/spop Mar 08 18:55:43 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 18:55:43 maud volumio[1108]: info: All search sources collected, pushing search results Mar 08 18:55:57 maud volumio[1108]: Searching plugin music_service/spop Mar 08 18:55:57 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 18:55:57 maud volumio[1108]: error: An error occurred while searching WebapiError: API rate limit exceeded Mar 08 18:56:02 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:56:02 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:56:02 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:56:02 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:57:03 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:57:03 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:57:03 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:57:03 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:57:06 maud volumio[1108]: Searching plugin music_service/spop Mar 08 18:57:06 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 18:57:06 maud volumio[1108]: error: An error occurred while searching WebapiError: Bad OAuth request Mar 08 18:57:21 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Mar 08 18:57:21 maud volumio[1108]: info: In handleBrowseUri, curUri=spotify Mar 08 18:57:21 maud volumio[1108]: info: Preload queue cleared Mar 08 18:57:26 maud volumio[1108]: Searching plugin music_service/spop Mar 08 18:57:26 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 18:57:26 maud volumio[1108]: error: An error occurred while searching WebapiError: API rate limit exceeded Mar 08 18:58:04 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:58:04 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:58:04 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:58:04 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:58:23 maud volumio[1108]: Searching plugin music_service/spop Mar 08 18:58:23 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 18:58:23 maud volumio[1108]: error: An error occurred while searching WebapiError: API rate limit exceeded Mar 08 18:58:40 maud volumio[1108]: Searching plugin music_service/spop Mar 08 18:58:40 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 18:58:40 maud volumio[1108]: error: An error occurred while searching WebapiError: Bad OAuth request Mar 08 18:58:41 maud volumio[1108]: Searching plugin music_service/spop Mar 08 18:58:41 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: spop , search Mar 08 18:58:41 maud volumio[1108]: error: An error occurred while searching WebapiError: Bad OAuth request Mar 08 18:59:04 maud volumio[1108]: info: CoreCommandRouter::volumioGetState Mar 08 18:59:04 maud volumio[1108]: info: CorePlayQueue::getTrack 0 Mar 08 18:59:04 maud volumio[1108]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Mar 08 18:59:04 maud volumio[1108]: info: touch_display: Setting screensaver timeout to 60 seconds. Mar 08 18:59:38 maud volumio[1108]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Mar 08 18:59:38 maud volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Mar 08 18:59:39 maud sudo[6634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Mar 08 18:59:39 maud sudo[6634]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 18:59:39 maud sudo[6634]: pam_unix(sudo:session): session closed for user root Mar 08 18:59:42 maud sudo[6690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Mar 08 18:59:42 maud sudo[6690]: pam_unix(sudo:session): session opened for user root by (uid=0)