Sat Feb 5 18:59:25 MST 2022 Description="'ALSA underrun'" # cat /proc/version --------------- Linux version 5.10.90-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) #1512 SMP Thu Jan 13 16:33:39 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="e5f8795c07ba36ff2b02d7ba0a21c7a0f29b897e" VOLUMIO_FE_VERSION="473d8facbed5f9754db84a81b093baace7aa4b64" VOLUMIO_FE3_VERSION="934cddf6d46478e7a53a8d3664cd346f4de7cb34" VOLUMIO_BE_VERSION="359b2717b79cfc72b7d1c918d0fc2885a93bca70" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 24 Jan 2022 10:51:57 AM CET" VOLUMIO_VERSION="3.198" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="74e6a9ad035e912732893c8d6b060b17" # ifconfig --------------- eth0: flags=4163 mtu 1500 inet 10.0.2.51 netmask 255.255.255.0 broadcast 10.0.2.255 ether e4:5f:01:5d:2b:b5 txqueuelen 1000 (Ethernet) RX packets 21127 bytes 23620294 (22.5 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 12446 bytes 3365830 (3.2 MiB) 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 1887 bytes 339024 (331.0 KiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 1887 bytes 339024 (331.0 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- lo no wireless extensions. eth0 no wireless extensions. wlan0 IEEE 802.11 ESSID:off/any Mode:Managed Access Point: Not-Associated Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off # 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: sndrpirpidac [snd_rpi_rpi_dac], device 0: RPi-DAC HiFi pcm1794a-codec-0 [RPi-DAC HiFi pcm1794a-codec-0] Subdevices: 1/1 Subdevice #0: subdevice #0 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 1 18:52 ? 00:00:07 /sbin/init root 2 0 0 18:52 ? 00:00:00 [kthreadd] root 3 2 0 18:52 ? 00:00:00 [rcu_gp] root 4 2 0 18:52 ? 00:00:00 [rcu_par_gp] root 8 2 0 18:52 ? 00:00:00 [mm_percpu_wq] root 9 2 0 18:52 ? 00:00:00 [rcu_tasks_rude_] root 10 2 0 18:52 ? 00:00:00 [rcu_tasks_trace] root 11 2 0 18:52 ? 00:00:00 [ksoftirqd/0] root 12 2 0 18:52 ? 00:00:00 [rcu_sched] root 13 2 0 18:52 ? 00:00:00 [migration/0] root 14 2 0 18:52 ? 00:00:00 [cpuhp/0] root 15 2 0 18:52 ? 00:00:00 [cpuhp/1] root 16 2 0 18:52 ? 00:00:00 [migration/1] root 17 2 0 18:52 ? 00:00:00 [ksoftirqd/1] root 19 2 0 18:52 ? 00:00:00 [kworker/1:0H-kblockd] root 20 2 0 18:52 ? 00:00:00 [cpuhp/2] root 21 2 0 18:52 ? 00:00:00 [migration/2] root 22 2 0 18:52 ? 00:00:00 [ksoftirqd/2] root 25 2 0 18:52 ? 00:00:00 [cpuhp/3] root 26 2 0 18:52 ? 00:00:00 [migration/3] root 27 2 0 18:52 ? 00:00:00 [ksoftirqd/3] root 30 2 0 18:52 ? 00:00:00 [kdevtmpfs] root 31 2 0 18:52 ? 00:00:00 [netns] root 33 2 0 18:52 ? 00:00:00 [kworker/1:1-events_freezable] root 34 2 0 18:52 ? 00:00:00 [kworker/2:1-mm_percpu_wq] root 35 2 0 18:52 ? 00:00:00 [kauditd] root 37 2 0 18:52 ? 00:00:00 [khungtaskd] root 38 2 0 18:52 ? 00:00:00 [oom_reaper] root 39 2 0 18:52 ? 00:00:00 [writeback] root 40 2 0 18:52 ? 00:00:00 [kcompactd0] root 60 2 0 18:52 ? 00:00:00 [kblockd] root 61 2 0 18:52 ? 00:00:00 [blkcg_punt_bio] root 62 2 0 18:52 ? 00:00:00 [watchdogd] root 63 2 0 18:52 ? 00:00:00 [kworker/3:1-mm_percpu_wq] root 64 2 0 18:52 ? 00:00:00 [kworker/3:1H-kblockd] root 65 2 0 18:52 ? 00:00:00 [rpciod] root 66 2 0 18:52 ? 00:00:00 [kworker/u9:0-hci0] root 67 2 0 18:52 ? 00:00:00 [xprtiod] root 68 2 0 18:52 ? 00:00:00 [kswapd0] root 69 2 0 18:52 ? 00:00:00 [nfsiod] root 70 2 0 18:52 ? 00:00:00 [kthrotld] root 71 2 0 18:52 ? 00:00:00 [iscsi_eh] root 72 2 0 18:52 ? 00:00:00 [iscsi_destroy] root 73 2 0 18:52 ? 00:00:00 [nvme-wq] root 74 2 0 18:52 ? 00:00:00 [nvme-reset-wq] root 75 2 0 18:52 ? 00:00:00 [nvme-delete-wq] root 76 2 0 18:52 ? 00:00:00 [kworker/u8:1-events_unbound] root 78 2 0 18:52 ? 00:00:00 [DWC Notificatio] root 79 2 0 18:52 ? 00:00:00 [uas] root 80 2 0 18:52 ? 00:00:00 [vchiq-slot/0] root 81 2 0 18:52 ? 00:00:00 [vchiq-recy/0] root 82 2 0 18:52 ? 00:00:00 [vchiq-sync/0] root 83 2 0 18:52 ? 00:00:00 [zswap-shrink] root 86 2 0 18:52 ? 00:00:00 [kworker/0:5-events] root 87 2 0 18:52 ? 00:00:00 [sdhci] root 88 2 0 18:52 ? 00:00:00 [irq/48-mmc0] root 89 2 0 18:52 ? 00:00:00 [kworker/0:6-events] root 97 2 0 18:52 ? 00:00:00 [mmc_complete] root 98 2 0 18:52 ? 00:00:00 [kworker/0:1H-kblockd] root 116 2 0 18:52 ? 00:00:00 [kworker/3:2-events] root 119 2 0 18:52 ? 00:00:00 [kworker/2:1H-kblockd] root 120 2 0 18:52 ? 00:00:00 [kworker/2:2H-kblockd] root 124 2 0 18:52 ? 00:00:00 [kworker/0:2H-kblockd] root 125 2 0 18:52 ? 00:00:00 [jbd2/mmcblk0p2-] root 126 2 0 18:52 ? 00:00:00 [ext4-rsv-conver] root 134 2 0 18:52 ? 00:00:01 [loop0] root 160 2 0 18:52 ? 00:00:00 [jbd2/mmcblk0p3-] root 161 2 0 18:52 ? 00:00:00 [ext4-rsv-conver] root 168 2 0 18:52 ? 00:00:00 [ipv6_addrconf] root 169 2 0 18:52 ? 00:00:00 [kworker/1:2-events_freezable] root 196 2 0 18:52 ? 00:00:00 [kworker/3:2H-kblockd] root 201 1 0 18:52 ? 00:00:00 /lib/systemd/systemd-journald root 217 1 0 18:52 ? 00:00:00 /lib/systemd/systemd-udevd root 256 2 0 18:52 ? 00:00:00 [vchiq-keep/0] root 258 2 0 18:52 ? 00:00:00 [SMIO] root 310 2 0 18:52 ? 00:00:00 [kworker/1:2H-kblockd] root 328 2 0 18:52 ? 00:00:00 [mmal-vchiq] root 329 2 0 18:52 ? 00:00:00 [mmal-vchiq] root 330 2 0 18:52 ? 00:00:00 [mmal-vchiq] root 332 2 0 18:52 ? 00:00:00 [mmal-vchiq] root 333 2 0 18:52 ? 00:00:00 [mmal-vchiq] root 335 2 0 18:52 ? 00:00:00 [mmal-vchiq] root 357 2 0 18:52 ? 00:00:00 [cfg80211] root 358 2 0 18:52 ? 00:00:00 [brcmf_wq/mmc1:0] root 359 2 0 18:52 ? 00:00:00 [brcmf_wdog/mmc1] _rpc 419 1 0 18:52 ? 00:00:00 /sbin/rpcbind -f -w root 430 1 0 18:52 ? 00:00:01 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 nobody 450 1 0 18:52 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* volumio 451 1 0 18:52 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 455 1 0 18:52 ? 00:00:00 /lib/systemd/systemd-logind avahi 461 1 0 18:52 ? 00:00:00 avahi-daemon: running [volumio-main.local] message+ 464 1 0 18:52 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 470 1 0 18:52 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant root 474 1 0 18:52 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon avahi 510 461 0 18:52 ? 00:00:00 avahi-daemon: chroot helper root 605 1 0 18:52 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 618 1 0 18:52 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I root 619 1 0 18:52 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux ntp 622 1 0 18:52 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 628 1 0 18:52 ? 00:00:00 /usr/sbin/sshd -D root 634 605 0 18:52 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group volumio 651 1 3 18:52 ? 00:00:13 /usr/bin/node /volumio/index.js root 681 2 0 18:52 ? 00:00:00 [kworker/u9:1-hci0] root 685 1 0 18:52 ? 00:00:00 /usr/bin/hciattach /dev/serial1 bcm43xx 3000000 flow - root 698 1 0 18:52 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap root 746 2 0 18:52 ? 00:00:00 [cifsiod] root 748 2 0 18:52 ? 00:00:00 [smb3decryptd] root 749 2 0 18:52 ? 00:00:00 [cifsfileinfoput] root 750 2 0 18:52 ? 00:00:00 [cifsoplockd] root 751 2 0 18:52 ? 00:00:00 [cifs-dfscache] root 752 2 0 18:52 ? 00:00:00 [kworker/u8:4-flush-179:0] root 788 1 0 18:52 ? 00:00:00 /sbin/dhcpcd eth0 volumio 814 651 0 18:52 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 821 814 0 18:52 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 822 814 0 18:52 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 823 814 0 18:52 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart root 897 1 0 18:53 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 902 897 0 18:53 ? 00:00:00 winbindd: domain child [VOLUMIO-MAIN] root 903 1 0 18:53 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 922 903 0 18:53 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 923 903 0 18:53 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 924 897 0 18:53 ? 00:00:00 winbindd: idmap child mpd 925 1 0 18:53 ? 00:00:00 /usr/bin/mpd --no-daemon root 949 897 0 18:53 ? 00:00:00 winbindd: domain child [BUILTIN] root 961 903 0 18:53 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group volumio 971 1 0 18:53 ? 00:00:00 /bin/bash /data/plugins/music_service/volspotconnect2/startconnect.sh volumio 972 971 1 18:53 ? 00:00:06 ./vollibrespot -c volspotify.toml shairpo+ 1004 1 0 18:53 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf volumio 1008 1 0 18:53 ? 00:00:00 /bin/sh /usr/bin/startx /etc/X11/Xsession /opt/volumiokiosk.sh -- -nocursor volumio 1038 1008 0 18:53 ? 00:00:00 xinit /etc/X11/Xsession /opt/volumiokiosk.sh -- /usr/bin/X :0 -nocursor -auth /tmp/serverauth.0GMDAuNfpo root 1039 1038 0 18:53 tty2 00:00:00 /usr/lib/xorg/Xorg :0 -nocursor -auth /tmp/serverauth.0GMDAuNfpo root 1045 2 0 18:53 ? 00:00:00 [kworker/2:3-mm_percpu_wq] volumio 1061 1038 0 18:53 ? 00:00:00 /bin/bash /opt/volumiokiosk.sh volumio 1087 1061 0 18:53 ? 00:00:00 /usr/bin/ssh-agent /opt/volumiokiosk.sh volumio 1094 1061 0 18:53 ? 00:00:00 /usr/bin/openbox --startup /usr/lib/arm-linux-gnueabihf/openbox-autostart OPENBOX volumio 1095 1061 0 18:53 ? 00:00:03 /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:4004 root 1135 2 0 18:53 ? 00:00:00 [cifsd] volumio 1174 1 0 18:53 ? 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 1184 1 0 18:53 ? 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 1188 1 0 18:53 ? 00:00:01 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf volumio 1190 1095 0 18:53 ? 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 1191 1095 0 18:53 ? 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 1208 1191 0 18:53 ? 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 1237 1095 0 18:53 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=utility --utility-sub-type=network.mojom.NetworkService --field-trial-handle=6268912173023263894,13790234595358450997,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 1252 1190 0 18:53 ? 00:00:01 /usr/lib/chromium-browser/chromium-browser-v7 --type=gpu-process --field-trial-handle=6268912173023263894,13790234595358450997,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 1255 1208 0 18:53 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=utility --utility-sub-type=storage.mojom.StorageService --field-trial-handle=6268912173023263894,13790234595358450997,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 1260 1252 0 18:53 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=broker volumio 1282 1208 0 18:53 ? 00:00:00 /usr/lib/chromium-browser/chromium-browser-v7 --type=renderer --disable-breakpad --force-device-scale-factor=1 --field-trial-handle=6268912173023263894,13790234595358450997,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=8 --no-v8-untrusted-code-mitigations --shared-files=v8_context_snapshot_data:100 volumio 1283 1208 3 18:53 ? 00:00:12 /usr/lib/chromium-browser/chromium-browser-v7 --type=renderer --disable-breakpad --force-device-scale-factor=1 --field-trial-handle=6268912173023263894,13790234595358450997,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=7 --no-v8-untrusted-code-mitigations --shared-files=v8_context_snapshot_data:100 volumio 1331 1 0 18:53 ? 00:00:00 /bin/streaming-daemon root 1639 2 0 18:57 ? 00:00:00 [kworker/0:0-events_freezable] root 1643 2 0 18:58 ? 00:00:00 [kworker/2:0-events_power_efficient] root 1654 2 0 18:59 ? 00:00:00 [kworker/u8:0] volumio 1656 651 0 18:59 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'ALSA underrun' volumio 1657 1656 0 18:59 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js ALSA underrun volumio 1677 1657 0 18:59 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 1678 1677 0 18:59 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on /dev/mmcblk0p2 2.5G 425M 1.9G 19% /imgpart /dev/loop0 364M 364M 0 100% /static overlay 56G 1.5G 51G 3% / devtmpfs 1.9G 0 1.9G 0% /dev tmpfs 1.9G 16M 1.9G 1% /dev/shm tmpfs 1.9G 5.2M 1.9G 1% /run tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 1.9G 0 1.9G 0% /sys/fs/cgroup tmpfs 1.9G 0 1.9G 0% /var/spool/cups tmpfs 1.9G 22M 1.9G 2% /tmp tmpfs 20M 40K 20M 1% /var/log tmpfs 1.9G 0 1.9G 0% /var/spool/cups/tmp /dev/mmcblk0p1 92M 54M 38M 59% /boot //10.0.2.15/Music/Classical 8.9T 7.9T 1019G 89% /mnt/NAS/Classical //10.0.2.15/Music/Various 8.9T 7.9T 1019G 89% /mnt/NAS/Various //10.0.2.15/Music/Society of Sound 8.9T 7.9T 1019G 89% /mnt/NAS/Society_of_Sound //10.0.2.15/Music/MP3 8.9T 7.9T 1019G 89% /mnt/NAS/MP3 //10.0.2.15/Music/FLAC 8.9T 7.9T 1019G 89% /mnt/NAS/FLAC //10.0.2.15/Music/Techno 8.9T 7.9T 1019G 89% /mnt/NAS/Techno # 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=1947708k,nr_inodes=146463,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/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) 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/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=38,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) mqueue on /dev/mqueue type mqueue (rw,relatime) sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) debugfs on /sys/kernel/debug type debugfs (rw,relatime) configfs on /sys/kernel/config type configfs (rw,relatime) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) tmpfs on /var/spool/cups/tmp type tmpfs (rw,noatime,mode=755) /dev/mmcblk0p1 on /boot type vfat (rw,nosuid,nodev,noexec,relatime,fmask=0111,dmask=0000,allow_utime=0022,codepage=437,iocharset=ascii,shortname=mixed,utf8,errors=remount-ro,user) //10.0.2.15/Music/Classical on /mnt/NAS/Classical type cifs (ro,relatime,vers=2.1,cache=strict,username=music,uid=0,noforceuid,gid=0,noforcegid,addr=10.0.2.15,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=1048576,wsize=1048576,bsize=1048576,echo_interval=60,actimeo=1) //10.0.2.15/Music/Various on /mnt/NAS/Various type cifs (ro,relatime,vers=2.1,cache=strict,username=music,uid=0,noforceuid,gid=0,noforcegid,addr=10.0.2.15,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=1048576,wsize=1048576,bsize=1048576,echo_interval=60,actimeo=1) //10.0.2.15/Music/Society of Sound on /mnt/NAS/Society_of_Sound type cifs (ro,relatime,vers=2.1,cache=strict,username=music,uid=0,noforceuid,gid=0,noforcegid,addr=10.0.2.15,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=1048576,wsize=1048576,bsize=1048576,echo_interval=60,actimeo=1) //10.0.2.15/Music/MP3 on /mnt/NAS/MP3 type cifs (ro,relatime,vers=2.1,cache=strict,username=music,uid=0,noforceuid,gid=0,noforcegid,addr=10.0.2.15,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=1048576,wsize=1048576,bsize=1048576,echo_interval=60,actimeo=1) //10.0.2.15/Music/FLAC on /mnt/NAS/FLAC type cifs (ro,relatime,vers=2.1,cache=strict,username=music,uid=0,noforceuid,gid=0,noforcegid,addr=10.0.2.15,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=1048576,wsize=1048576,bsize=1048576,echo_interval=60,actimeo=1) //10.0.2.15/Music/Techno on /mnt/NAS/Techno type cifs (ro,relatime,vers=2.1,cache=strict,username=music,uid=0,noforceuid,gid=0,noforcegid,addr=10.0.2.15,file_mode=0666,dir_mode=0777,soft,nounix,serverino,mapposix,rsize=1048576,wsize=1048576,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:34799 0.0.0.0:* LISTEN 972/./vollibrespot 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 0.0.0.0:49149 0.0.0.0:* LISTEN 1188/upmpdcli tcp 0 0 0.0.0.0:445 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49152 0.0.0.0:* LISTEN 1188/upmpdcli tcp 0 0 0.0.0.0:5000 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:139 0.0.0.0:* LISTEN - tcp 0 0 10.0.2.51:59734 10.0.2.71:8009 ESTABLISHED 1237/volumiokiosk - tcp 0 0 10.0.2.51:50004 104.154.127.126:443 ESTABLISHED 972/./vollibrespot tcp 0 0 127.0.0.1:36186 127.0.0.1:3000 ESTABLISHED 651/node tcp 0 0 10.0.2.51:50040 142.250.69.234:443 ESTABLISHED 1237/volumiokiosk - tcp 0 0 10.0.2.51:54922 35.201.97.85:443 ESTABLISHED 651/node tcp 0 0 10.0.2.51:53328 10.0.2.71:8008 ESTABLISHED 1237/volumiokiosk - tcp 0 0 127.0.0.1:57452 127.0.0.1:6599 ESTABLISHED 1188/upmpdcli tcp 0 0 127.0.0.1:57456 127.0.0.1:6599 ESTABLISHED 1188/upmpdcli tcp 0 0 127.0.0.1:36206 127.0.0.1:3000 ESTABLISHED 451/volumio-remote- tcp 0 0 10.0.2.51:40296 151.101.70.248:443 ESTABLISHED 1237/volumiokiosk - tcp 0 0 127.0.0.1:36160 127.0.0.1:3000 ESTABLISHED 651/node tcp 0 0 127.0.0.1:36234 127.0.0.1:3000 ESTABLISHED 1237/volumiokiosk - tcp 0 0 10.0.2.51:34670 10.0.2.15:445 ESTABLISHED - tcp 0 0 127.0.0.1:51300 127.0.0.1:6600 ESTABLISHED 651/node tcp 0 0 127.0.0.1:36164 127.0.0.1:3000 ESTABLISHED 651/node tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::22 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 651/node tcp6 0 0 :::3001 :::* LISTEN 814/node tcp6 0 0 :::3005 :::* LISTEN 651/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::7777 :::* LISTEN 1331/streaming-daem tcp6 0 0 :::4004 :::* LISTEN 651/node tcp6 0 0 :::6599 :::* LISTEN 651/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 127.0.0.1:3000 127.0.0.1:36164 ESTABLISHED 651/node tcp6 0 0 10.0.2.51:3000 10.0.2.6:50820 ESTABLISHED 651/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:57456 ESTABLISHED 651/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:57452 ESTABLISHED 651/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:36234 ESTABLISHED 651/node tcp6 0 0 127.0.0.1:6600 127.0.0.1:51300 ESTABLISHED - tcp6 0 0 127.0.0.1:3000 127.0.0.1:36160 ESTABLISHED 651/node tcp6 0 0 10.0.2.51:3000 10.0.2.6:50795 ESTABLISHED 651/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:36206 ESTABLISHED 651/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:36186 ESTABLISHED 651/node # cat /etc/asound.conf --------------- pcm.!default { type empty slave.pcm "volumio" } pcm.volumio { 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 "sndrpirpidac" } # cat /var/log/mpd.log --------------- Feb 05 12:46 : zeroconf: No global port, disabling zeroconf Feb 05 12:46 : zeroconf: No global port, disabling zeroconf Feb 05 12:46 : client: [0] opened from local Feb 05 12:46 : exception: No such directory Feb 05 12:46 : client: [1] opened from local Feb 05 12:46 : zeroconf: No global port, disabling zeroconf Feb 05 12:46 : client: [0] opened from local Feb 05 12:46 : client: [1] opened from 127.0.0.1:51296 Feb 05 12:46 : client: [2] opened from 127.0.0.1:51300 ALSA lib control.c:1375:(snd_ctl_open_noupdate) Invalid CTL SoftMaster Feb 05 12:46 : exception: Failed to read mixer for 'alsa': failed to attach to SoftMaster: No such file or directory Feb 05 18:54 : client: [1] closed # volumio plugin list --------------- This command will list installed plugins on your device ----------------------- Installed Plugins: [ { prettyName: 'FusionDsp', name: 'fusiondsp', category: 'audio_interface', version: '0.1.28', icon: 'fa-sliders fa-rotate-90', isManuallyInstalled: true, enabled: false, active: false }, { prettyName: 'Volumio Spotify Connect2', name: 'volspotconnect2', category: 'music_service', version: '3.0.7', icon: 'fa-spotify', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'GPIO Buttons', name: 'gpio-buttons', category: 'system_controller', version: '0.8.1', icon: 'fa-microchip', isManuallyInstalled: true, enabled: true, active: true }, { prettyName: 'Now Playing', name: 'now_playing', category: 'user_interface', version: '0.1.3', icon: 'fa-play-circle', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'Touch Display', name: 'touch_display', category: 'user_interface', version: '3.3.1', icon: 'fa-hand-pointer-o', isManuallyInstalled: false, enabled: true, active: true } ] ------------------------------------------ # cat /data/configuration/plugins.json --------------- { "music_service": { "airplay_emulation": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "inputs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpd": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "webradio": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "example_plugin": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } }, "last_100": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "upnp_browser": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "streaming_services": { "status": { "type": "string", "value": "STARTING" } }, "volspotconnect2": { "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": true }, "status": { "type": "string", "value": "STARTED" } }, "alsa_controller": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "fusiondsp": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STOPPED" } } }, "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" } } }, "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" } }, "now_playing": { "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" } }, "gpio-buttons": { "enabled": { "type": "boolean", "value": true }, "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" mixer_device "SoftMaster" mixer_control "SoftMaster" mixer_type "hardware" } 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 "8192" ############################################################################### # 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, 376 ms: OK https://www.googleapis.com, 164 ms: OK https://securetoken.googleapis.com, 180 ms: OK https://myvolumio.firebaseio.com, 258 ms: OK https://functions.volumio.cloud, 531 ms: OK https://oauth-performer.dfs.volumio.org, 615 ms: OK https://browsing-performer.dfs.volumio.org, 665 ms: OK http://cddb.volumio.org, 3274 ms: OK https://functions.volumio.cloud, 511 ms: OK http://pushupdates.volumio.org, 496 ms: OK http://plugins.volumio.org, 694 ms: OK https://database.volumio.cloud, 512 ms: OK https://radio-directory.firebaseapp.com, 436 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # sudo journalctl -p 7 --------------- -- Logs begin at Sat 2022-02-05 12:46:15 MST, end at Sat 2022-02-05 18:59:31 MST. -- Feb 05 12:46:15 volumio-main kernel: Booting Linux on physical CPU 0x0 Feb 05 12:46:15 volumio-main kernel: Linux version 5.10.90-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) #1512 SMP Thu Jan 13 16:33:39 GMT 2022 Feb 05 12:46:15 volumio-main kernel: CPU: ARMv7 Processor [410fd083] revision 3 (ARMv7), cr=30c5383d Feb 05 12:46:15 volumio-main kernel: CPU: div instructions available: patching division code Feb 05 12:46:15 volumio-main kernel: CPU: PIPT / VIPT nonaliasing data cache, PIPT instruction cache Feb 05 12:46:15 volumio-main kernel: OF: fdt: Machine model: Raspberry Pi 4 Model B Rev 1.4 Feb 05 12:46:15 volumio-main kernel: random: fast init done Feb 05 12:46:15 volumio-main kernel: Memory policy: Data cache writealloc Feb 05 12:46:15 volumio-main kernel: Reserved memory: created CMA memory pool at 0x000000002a000000, size 64 MiB Feb 05 12:46:15 volumio-main kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 05 12:46:15 volumio-main kernel: Zone ranges: Feb 05 12:46:15 volumio-main kernel: DMA [mem 0x0000000000000000-0x000000002fffffff] Feb 05 12:46:15 volumio-main kernel: Normal empty Feb 05 12:46:15 volumio-main kernel: HighMem [mem 0x0000000030000000-0x00000000fbffffff] Feb 05 12:46:15 volumio-main kernel: Movable zone start for each node Feb 05 12:46:15 volumio-main kernel: Early memory node ranges Feb 05 12:46:15 volumio-main kernel: node 0: [mem 0x0000000000000000-0x000000003dffffff] Feb 05 12:46:15 volumio-main kernel: node 0: [mem 0x0000000040000000-0x00000000fbffffff] Feb 05 12:46:15 volumio-main kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff] Feb 05 12:46:15 volumio-main kernel: On node 0 totalpages: 1024000 Feb 05 12:46:15 volumio-main kernel: DMA zone: 1728 pages used for memmap Feb 05 12:46:15 volumio-main kernel: DMA zone: 0 pages reserved Feb 05 12:46:15 volumio-main kernel: DMA zone: 196608 pages, LIFO batch:63 Feb 05 12:46:15 volumio-main kernel: HighMem zone: 827392 pages, LIFO batch:63 Feb 05 12:46:15 volumio-main kernel: percpu: Embedded 20 pages/cpu s50828 r8192 d22900 u81920 Feb 05 12:46:15 volumio-main kernel: pcpu-alloc: s50828 r8192 d22900 u81920 alloc=20*4096 Feb 05 12:46:15 volumio-main kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 05 12:46:15 volumio-main kernel: Built 1 zonelists, mobility grouping on. Total pages: 1022272 Feb 05 12:46:15 volumio-main 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=E4:5F:01:5D:2B:B5 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Feb 05 12:46:15 volumio-main kernel: Kernel parameter elevator= does not have any effect anymore. Please use sysfs to set IO scheduler for individual devices. Feb 05 12:46:15 volumio-main kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Feb 05 12:46:15 volumio-main kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 05 12:46:15 volumio-main kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 05 12:46:15 volumio-main kernel: software IO TLB: mapped [mem 0x0000000023c90000-0x0000000027c90000] (64MB) Feb 05 12:46:15 volumio-main kernel: Memory: 3895420K/4096000K available (10240K kernel code, 1366K rwdata, 3192K rodata, 2048K init, 882K bss, 135044K reserved, 65536K cma-reserved, 3309568K highmem) Feb 05 12:46:15 volumio-main kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 05 12:46:15 volumio-main kernel: ftrace: allocating 34359 entries in 101 pages Feb 05 12:46:15 volumio-main kernel: ftrace: allocated 101 pages with 4 groups Feb 05 12:46:15 volumio-main kernel: rcu: Hierarchical RCU implementation. Feb 05 12:46:15 volumio-main kernel: Rude variant of Tasks RCU enabled. Feb 05 12:46:15 volumio-main kernel: Tracing variant of Tasks RCU enabled. Feb 05 12:46:15 volumio-main kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Feb 05 12:46:15 volumio-main kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Feb 05 12:46:15 volumio-main kernel: GIC: Using split EOI/Deactivate mode Feb 05 12:46:15 volumio-main kernel: random: get_random_bytes called from start_kernel+0x3c8/0x59c with crng_init=1 Feb 05 12:46:15 volumio-main kernel: sched_clock: 32 bits at 1000kHz, resolution 1000ns, wraps every 2147483647500ns Feb 05 12:46:15 volumio-main kernel: clocksource: timer: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275 ns Feb 05 12:46:15 volumio-main kernel: bcm2835: system timer (irq = 25) Feb 05 12:46:15 volumio-main kernel: arch_timer: cp15 timer(s) running at 54.00MHz (phys). Feb 05 12:46:15 volumio-main kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns Feb 05 12:46:15 volumio-main kernel: sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns Feb 05 12:46:15 volumio-main kernel: Switching to timer-based delay loop, resolution 18ns Feb 05 12:46:15 volumio-main kernel: Console: colour dummy device 80x30 Feb 05 12:46:15 volumio-main kernel: printk: console [tty1] enabled Feb 05 12:46:15 volumio-main kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=540000) Feb 05 12:46:15 volumio-main kernel: pid_max: default: 32768 minimum: 301 Feb 05 12:46:15 volumio-main kernel: LSM: Security Framework initializing Feb 05 12:46:15 volumio-main kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 05 12:46:15 volumio-main kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 05 12:46:15 volumio-main kernel: cgroup: Disabling memory control group subsystem Feb 05 12:46:15 volumio-main kernel: CPU: Testing write buffer coherency: ok Feb 05 12:46:15 volumio-main kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Feb 05 12:46:15 volumio-main kernel: Setting up static identity map for 0x200000 - 0x20003c Feb 05 12:46:15 volumio-main kernel: rcu: Hierarchical SRCU implementation. Feb 05 12:46:15 volumio-main kernel: smp: Bringing up secondary CPUs ... Feb 05 12:46:15 volumio-main kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Feb 05 12:46:15 volumio-main kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Feb 05 12:46:15 volumio-main kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Feb 05 12:46:15 volumio-main kernel: smp: Brought up 1 node, 4 CPUs Feb 05 12:46:15 volumio-main kernel: SMP: Total of 4 processors activated (432.00 BogoMIPS). Feb 05 12:46:15 volumio-main kernel: CPU: All CPU(s) started in HYP mode. Feb 05 12:46:15 volumio-main kernel: CPU: Virtualization extensions available. Feb 05 12:46:15 volumio-main kernel: devtmpfs: initialized Feb 05 12:46:15 volumio-main kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 8 rev 0 Feb 05 12:46:15 volumio-main kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 05 12:46:15 volumio-main kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 05 12:46:15 volumio-main kernel: pinctrl core: initialized pinctrl subsystem Feb 05 12:46:15 volumio-main kernel: NET: Registered protocol family 16 Feb 05 12:46:15 volumio-main kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 05 12:46:15 volumio-main kernel: audit: initializing netlink subsys (disabled) Feb 05 12:46:15 volumio-main kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Feb 05 12:46:15 volumio-main kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 05 12:46:15 volumio-main kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 05 12:46:15 volumio-main kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 05 12:46:15 volumio-main kernel: Serial: AMBA PL011 UART driver Feb 05 12:46:15 volumio-main kernel: bcm2835-mbox fe00b880.mailbox: mailbox enabled Feb 05 12:46:15 volumio-main kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2022-01-06T15:39:30, variant start Feb 05 12:46:15 volumio-main kernel: raspberrypi-firmware soc:firmware: Firmware hash is 58e03c94953762222f2b838390dde54d46c38381 Feb 05 12:46:15 volumio-main kernel: Kprobes globally optimized Feb 05 12:46:15 volumio-main kernel: bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 Feb 05 12:46:15 volumio-main kernel: vgaarb: loaded Feb 05 12:46:15 volumio-main kernel: SCSI subsystem initialized Feb 05 12:46:15 volumio-main kernel: usbcore: registered new interface driver usbfs Feb 05 12:46:15 volumio-main kernel: usbcore: registered new interface driver hub Feb 05 12:46:15 volumio-main kernel: usbcore: registered new device driver usb Feb 05 12:46:15 volumio-main kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Feb 05 12:46:15 volumio-main kernel: clocksource: Switched to clocksource arch_sys_counter Feb 05 12:46:15 volumio-main kernel: VFS: Disk quotas dquot_6.6.0 Feb 05 12:46:15 volumio-main kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 05 12:46:15 volumio-main kernel: FS-Cache: Loaded Feb 05 12:46:15 volumio-main kernel: CacheFiles: Loaded Feb 05 12:46:15 volumio-main kernel: NET: Registered protocol family 2 Feb 05 12:46:15 volumio-main kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Feb 05 12:46:15 volumio-main kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 6144 bytes, linear) Feb 05 12:46:15 volumio-main kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Feb 05 12:46:15 volumio-main kernel: TCP bind hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 05 12:46:15 volumio-main kernel: TCP: Hash tables configured (established 8192 bind 8192) Feb 05 12:46:15 volumio-main kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 05 12:46:15 volumio-main kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 05 12:46:15 volumio-main kernel: NET: Registered protocol family 1 Feb 05 12:46:15 volumio-main kernel: RPC: Registered named UNIX socket transport module. Feb 05 12:46:15 volumio-main kernel: RPC: Registered udp transport module. Feb 05 12:46:15 volumio-main kernel: RPC: Registered tcp transport module. Feb 05 12:46:15 volumio-main kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 05 12:46:15 volumio-main kernel: PCI: CLS 0 bytes, default 64 Feb 05 12:46:15 volumio-main kernel: Trying to unpack rootfs image as initramfs... Feb 05 12:46:15 volumio-main kernel: Freeing initrd memory: 13028K Feb 05 12:46:15 volumio-main kernel: Initialise system trusted keyrings Feb 05 12:46:15 volumio-main kernel: workingset: timestamp_bits=14 max_order=20 bucket_order=6 Feb 05 12:46:15 volumio-main kernel: zbud: loaded Feb 05 12:46:15 volumio-main kernel: FS-Cache: Netfs 'nfs' registered for caching Feb 05 12:46:15 volumio-main kernel: NFS: Registering the id_resolver key type Feb 05 12:46:15 volumio-main kernel: Key type id_resolver registered Feb 05 12:46:15 volumio-main kernel: Key type id_legacy registered Feb 05 12:46:15 volumio-main kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 05 12:46:15 volumio-main kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Feb 05 12:46:15 volumio-main kernel: Key type asymmetric registered Feb 05 12:46:15 volumio-main kernel: Asymmetric key parser 'x509' registered Feb 05 12:46:15 volumio-main kernel: bounce: pool size: 64 pages Feb 05 12:46:15 volumio-main kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Feb 05 12:46:15 volumio-main kernel: io scheduler mq-deadline registered Feb 05 12:46:15 volumio-main kernel: io scheduler kyber registered Feb 05 12:46:15 volumio-main kernel: brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: Feb 05 12:46:15 volumio-main kernel: brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] Feb 05 12:46:15 volumio-main kernel: brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 Feb 05 12:46:15 volumio-main kernel: brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00ffffffff -> 0x0400000000 Feb 05 12:46:15 volumio-main kernel: brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) Feb 05 12:46:15 volumio-main kernel: brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 Feb 05 12:46:15 volumio-main kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Feb 05 12:46:15 volumio-main kernel: pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) Feb 05 12:46:15 volumio-main kernel: pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 Feb 05 12:46:15 volumio-main kernel: pci 0000:00:00.0: PME# supported from D0 D3hot Feb 05 12:46:15 volumio-main kernel: PCI: bus0: Fast back to back transfers disabled Feb 05 12:46:15 volumio-main kernel: pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 Feb 05 12:46:15 volumio-main kernel: pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] Feb 05 12:46:15 volumio-main kernel: pci 0000:01:00.0: PME# supported from D0 D3hot Feb 05 12:46:15 volumio-main kernel: PCI: bus1: Fast back to back transfers disabled Feb 05 12:46:15 volumio-main kernel: pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] Feb 05 12:46:15 volumio-main kernel: pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] Feb 05 12:46:15 volumio-main kernel: pci 0000:00:00.0: PCI bridge to [bus 01] Feb 05 12:46:15 volumio-main kernel: pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] Feb 05 12:46:15 volumio-main kernel: pcieport 0000:00:00.0: enabling device (0140 -> 0142) Feb 05 12:46:15 volumio-main kernel: pcieport 0000:00:00.0: PME: Signaling with IRQ 62 Feb 05 12:46:15 volumio-main kernel: bcm2708_fb soc:fb: FB found 1 display(s) Feb 05 12:46:15 volumio-main kernel: Console: switching to colour frame buffer device 100x30 Feb 05 12:46:15 volumio-main kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 800x480 Feb 05 12:46:15 volumio-main kernel: iproc-rng200 fe104000.rng: hwrng registered Feb 05 12:46:15 volumio-main kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Feb 05 12:46:15 volumio-main kernel: gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000 Feb 05 12:46:15 volumio-main kernel: brd: module loaded Feb 05 12:46:15 volumio-main kernel: loop: module loaded Feb 05 12:46:15 volumio-main kernel: Loading iSCSI transport class v2.0-870. Feb 05 12:46:15 volumio-main kernel: libphy: Fixed MDIO Bus: probed Feb 05 12:46:15 volumio-main kernel: bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 Feb 05 12:46:15 volumio-main kernel: libphy: bcmgenet MII bus: probed Feb 05 12:46:15 volumio-main kernel: unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus Feb 05 12:46:15 volumio-main kernel: usbcore: registered new interface driver r8152 Feb 05 12:46:15 volumio-main kernel: usbcore: registered new interface driver lan78xx Feb 05 12:46:15 volumio-main kernel: usbcore: registered new interface driver smsc95xx Feb 05 12:46:15 volumio-main kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Feb 05 12:46:15 volumio-main kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 Feb 05 12:46:15 volumio-main kernel: xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00000e0000000890 Feb 05 12:46:15 volumio-main kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 Feb 05 12:46:15 volumio-main kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 05 12:46:15 volumio-main kernel: usb usb1: Product: xHCI Host Controller Feb 05 12:46:15 volumio-main kernel: usb usb1: Manufacturer: Linux 5.10.90-v7l+ xhci-hcd Feb 05 12:46:15 volumio-main kernel: usb usb1: SerialNumber: 0000:01:00.0 Feb 05 12:46:15 volumio-main kernel: hub 1-0:1.0: USB hub found Feb 05 12:46:15 volumio-main kernel: hub 1-0:1.0: 1 port detected Feb 05 12:46:15 volumio-main kernel: xhci_hcd 0000:01:00.0: xHCI Host Controller Feb 05 12:46:15 volumio-main kernel: xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 Feb 05 12:46:15 volumio-main kernel: xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed Feb 05 12:46:15 volumio-main kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 Feb 05 12:46:15 volumio-main kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 05 12:46:15 volumio-main kernel: usb usb2: Product: xHCI Host Controller Feb 05 12:46:15 volumio-main kernel: usb usb2: Manufacturer: Linux 5.10.90-v7l+ xhci-hcd Feb 05 12:46:15 volumio-main kernel: usb usb2: SerialNumber: 0000:01:00.0 Feb 05 12:46:15 volumio-main kernel: hub 2-0:1.0: USB hub found Feb 05 12:46:15 volumio-main kernel: hub 2-0:1.0: 4 ports detected Feb 05 12:46:15 volumio-main kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 05 12:46:15 volumio-main kernel: dwc_otg: FIQ enabled Feb 05 12:46:15 volumio-main kernel: dwc_otg: NAK holdoff enabled Feb 05 12:46:15 volumio-main kernel: dwc_otg: FIQ split-transaction FSM enabled Feb 05 12:46:15 volumio-main kernel: Module dwc_common_port init Feb 05 12:46:15 volumio-main kernel: usbcore: registered new interface driver uas Feb 05 12:46:15 volumio-main kernel: usbcore: registered new interface driver usb-storage Feb 05 12:46:15 volumio-main kernel: mousedev: PS/2 mouse device common for all mice Feb 05 12:46:15 volumio-main kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Feb 05 12:46:15 volumio-main kernel: sdhci: Secure Digital Host Controller Interface driver Feb 05 12:46:15 volumio-main kernel: sdhci: Copyright(c) Pierre Ossman Feb 05 12:46:15 volumio-main kernel: mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe Feb 05 12:46:15 volumio-main kernel: sdhci-pltfm: SDHCI platform and OF driver helper Feb 05 12:46:15 volumio-main kernel: ledtrig-cpu: registered to indicate activity on CPUs Feb 05 12:46:15 volumio-main kernel: hid: raw HID events driver (C) Jiri Kosina Feb 05 12:46:15 volumio-main kernel: usbcore: registered new interface driver usbhid Feb 05 12:46:15 volumio-main kernel: usbhid: USB HID core driver Feb 05 12:46:15 volumio-main kernel: Initializing XFRM netlink socket Feb 05 12:46:15 volumio-main kernel: NET: Registered protocol family 17 Feb 05 12:46:15 volumio-main kernel: Key type dns_resolver registered Feb 05 12:46:15 volumio-main kernel: Registering SWP/SWPB emulation handler Feb 05 12:46:15 volumio-main kernel: registered taskstats version 1 Feb 05 12:46:15 volumio-main kernel: Loading compiled-in X.509 certificates Feb 05 12:46:15 volumio-main kernel: Key type ._fscrypt registered Feb 05 12:46:15 volumio-main kernel: Key type .fscrypt registered Feb 05 12:46:15 volumio-main kernel: Key type fscrypt-provisioning registered Feb 05 12:46:15 volumio-main kernel: uart-pl011 fe201000.serial: there is not valid maps for state default Feb 05 12:46:15 volumio-main kernel: uart-pl011 fe201000.serial: cts_event_workaround enabled Feb 05 12:46:15 volumio-main kernel: fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 36, base_baud = 0) is a PL011 rev2 Feb 05 12:46:15 volumio-main kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Feb 05 12:46:15 volumio-main kernel: mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 Feb 05 12:46:15 volumio-main kernel: mmc-bcm2835 fe300000.mmcnr: DMA channel allocated Feb 05 12:46:15 volumio-main kernel: of_cfs_init Feb 05 12:46:15 volumio-main kernel: of_cfs_init: OK Feb 05 12:46:15 volumio-main kernel: mmc1: queuing unknown CIS tuple 0x80 (2 bytes) Feb 05 12:46:15 volumio-main kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Feb 05 12:46:15 volumio-main kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Feb 05 12:46:15 volumio-main kernel: mmc1: queuing unknown CIS tuple 0x80 (7 bytes) Feb 05 12:46:15 volumio-main kernel: mmc1: queuing unknown CIS tuple 0x80 (3 bytes) Feb 05 12:46:15 volumio-main kernel: mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA Feb 05 12:46:15 volumio-main kernel: Freeing unused kernel memory: 2048K Feb 05 12:46:15 volumio-main kernel: Run /init as init process Feb 05 12:46:15 volumio-main kernel: with arguments: Feb 05 12:46:15 volumio-main kernel: /init Feb 05 12:46:15 volumio-main kernel: splash Feb 05 12:46:15 volumio-main kernel: with environment: Feb 05 12:46:15 volumio-main kernel: HOME=/ Feb 05 12:46:15 volumio-main kernel: TERM=linux Feb 05 12:46:15 volumio-main kernel: imgpart=/dev/mmcblk0p2 Feb 05 12:46:15 volumio-main kernel: imgfile=/volumio_current.sqsh Feb 05 12:46:15 volumio-main kernel: bootdelay=5 Feb 05 12:46:15 volumio-main kernel: mmc1: new high speed SDIO card at address 0001 Feb 05 12:46:15 volumio-main kernel: mmc0: new ultra high speed DDR50 SDXC card at address aaaa Feb 05 12:46:15 volumio-main kernel: mmcblk0: mmc0:aaaa SN64G 59.5 GiB Feb 05 12:46:15 volumio-main kernel: mmcblk0: p1 p2 p3 Feb 05 12:46:15 volumio-main kernel: usb 1-1: new high-speed USB device number 2 using xhci_hcd Feb 05 12:46:15 volumio-main initramfs: Booting Volumio for BCM2711 Feb 05 12:46:15 volumio-main initramfs: This script mounts rootfs RO with an overlay RW layer. Feb 05 12:46:15 volumio-main kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 05 12:46:15 volumio-main initramfs: IMGPART=/dev/mmcblk0p2 Feb 05 12:46:15 volumio-main initramfs: IMGFILE=/volumio_current.sqsh Feb 05 12:46:15 volumio-main initramfs: Boot delay (except first time) will be 5 seconds Feb 05 12:46:15 volumio-main initramfs: /dev/mmcblk0p2: Feb 05 12:46:15 volumio-main kernel: EXT4-fs (mmcblk0p2): recovery complete Feb 05 12:46:15 volumio-main kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Opts: (null) Feb 05 12:46:15 volumio-main initramfs: Doing a 5 second delay here to give kernel load a headstart Feb 05 12:46:15 volumio-main kernel: usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21 Feb 05 12:46:15 volumio-main kernel: usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 Feb 05 12:46:15 volumio-main kernel: usb 1-1: Product: USB2.0 Hub Feb 05 12:46:15 volumio-main kernel: hub 1-1:1.0: USB hub found Feb 05 12:46:15 volumio-main kernel: hub 1-1:1.0: 4 ports detected Feb 05 12:46:15 volumio-main initramfs: Checking for USB updates Feb 05 12:46:15 volumio-main initramfs: No USB device detected (when incorrect, try adding 'bootdelay=5' to your boot cmdline) Feb 05 12:46:15 volumio-main kernel: EXT4-fs (mmcblk0p3): recovery complete Feb 05 12:46:15 volumio-main kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Opts: (null) Feb 05 12:46:15 volumio-main initramfs: With Option: Feb 05 12:46:15 volumio-main initramfs: VOLUMIO_VERSION="3.198" Feb 05 12:46:15 volumio-main initramfs: Finish initramfs, continue booting Volumio Feb 05 12:46:15 volumio-main systemd[1]: System time before build time, advancing clock. Feb 05 12:46:15 volumio-main kernel: NET: Registered protocol family 10 Feb 05 12:46:15 volumio-main kernel: Segment Routing with IPv6 Feb 05 12:46:15 volumio-main 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) Feb 05 12:46:15 volumio-main systemd[1]: Detected architecture arm. Feb 05 12:46:15 volumio-main systemd[1]: Set hostname to . Feb 05 12:46:15 volumio-main 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. Feb 05 12:46:15 volumio-main kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 05 12:46:15 volumio-main kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 05 12:46:15 volumio-main kernel: random: systemd: uninitialized urandom read (16 bytes read) Feb 05 12:46:15 volumio-main systemd[1]: Listening on initctl Compatibility Named Pipe. Feb 05 12:46:15 volumio-main systemd[1]: Created slice system-systemd\x2dfsck.slice. Feb 05 12:46:15 volumio-main systemd[1]: Started Forward Password Requests to Wall Directory Watch. Feb 05 12:46:15 volumio-main systemd[1]: Created slice User and Session Slice. Feb 05 12:46:15 volumio-main systemd[1]: Reached target Slices. Feb 05 12:46:15 volumio-main systemd[1]: Reached target System Time Synchronized. Feb 05 12:46:15 volumio-main kernel: i2c /dev entries driver Feb 05 12:46:15 volumio-main systemd-journald[201]: Journal started Feb 05 12:46:15 volumio-main systemd-journald[201]: Runtime journal (/run/log/journal/f67eb8adba804d11b617d1be61ee763c) is 3.7M, max 30.0M, 26.2M free. Feb 05 12:46:15 volumio-main systemd-modules-load[188]: Inserted module 'i2c_dev' Feb 05 12:46:15 volumio-main fake-hwclock[190]: Sat Feb 5 19:46:15 UTC 2022 Feb 05 12:46:15 volumio-main systemd[1]: Started Create System Users. Feb 05 12:46:15 volumio-main systemd[1]: Starting Create Static Device Nodes in /dev... Feb 05 12:46:15 volumio-main systemd[1]: Started Create Static Device Nodes in /dev. Feb 05 12:46:15 volumio-main systemd[1]: Starting udev Kernel Device Manager... Feb 05 12:46:15 volumio-main systemd[1]: Reached target Local File Systems (Pre). Feb 05 12:46:15 volumio-main systemd[1]: Mounting /var/spool/cups... Feb 05 12:46:15 volumio-main systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Feb 05 12:46:15 volumio-main systemd[1]: Mounting /tmp... Feb 05 12:46:15 volumio-main systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Feb 05 12:46:15 volumio-main systemd[1]: Mounting /var/log... Feb 05 12:46:15 volumio-main systemd[1]: Mounted /var/spool/cups. Feb 05 12:46:15 volumio-main systemd[1]: Mounted /tmp. Feb 05 12:46:15 volumio-main systemd[1]: Mounting /var/spool/cups/tmp... Feb 05 12:46:15 volumio-main systemd-udevd[217]: Network interface NamePolicy= disabled on kernel command line, ignoring. Feb 05 12:46:15 volumio-main systemd[1]: Started udev Coldplug all Devices. Feb 05 12:46:15 volumio-main systemd[1]: Mounted /var/log. Feb 05 12:46:15 volumio-main systemd[1]: Mounted /var/spool/cups/tmp. Feb 05 12:46:15 volumio-main systemd[1]: Starting Flush Journal to Persistent Storage... Feb 05 12:46:15 volumio-main systemd[1]: Starting Helper to synchronize boot up for ifupdown... Feb 05 12:46:15 volumio-main systemd-journald[201]: Runtime journal (/run/log/journal/f67eb8adba804d11b617d1be61ee763c) is 3.7M, max 30.0M, 26.2M free. Feb 05 12:46:15 volumio-main systemd[1]: Started Flush Journal to Persistent Storage. Feb 05 12:46:15 volumio-main systemd[1]: Started udev Kernel Device Manager. Feb 05 12:46:15 volumio-main systemd[1]: Starting Show Plymouth Boot Screen... Feb 05 12:46:15 volumio-main systemd[1]: Received SIGRTMIN+20 from PID 244 (plymouthd). Feb 05 12:46:15 volumio-main systemd[1]: Started Show Plymouth Boot Screen. Feb 05 12:46:15 volumio-main systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Feb 05 12:46:15 volumio-main systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 05 12:46:15 volumio-main systemd[1]: Reached target Local Encrypted Volumes. Feb 05 12:46:15 volumio-main systemd[1]: Reached target Paths. Feb 05 12:46:15 volumio-main systemd-udevd[234]: Using default interface naming scheme 'v240'. Feb 05 12:46:15 volumio-main kernel: rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000 Feb 05 12:46:15 volumio-main kernel: rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000 Feb 05 12:46:15 volumio-main kernel: rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000 Feb 05 12:46:15 volumio-main kernel: rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000 Feb 05 12:46:15 volumio-main systemd-udevd[234]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 05 12:46:15 volumio-main kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Feb 05 12:46:15 volumio-main kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Feb 05 12:46:15 volumio-main kernel: [vc_sm_connected_init]: start Feb 05 12:46:15 volumio-main kernel: [vc_sm_connected_init]: installed successfully Feb 05 12:46:15 volumio-main kernel: mc: Linux media interface: v0.10 Feb 05 12:46:16 volumio-main kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Feb 05 12:46:16 volumio-main kernel: bcm2835_audio bcm2835_audio: card created with 8 channels Feb 05 12:46:16 volumio-main kernel: input: raspberrypi-ts as /devices/platform/soc/soc:firmware/soc:firmware:touchscreen/input/input0 Feb 05 12:46:16 volumio-main systemd-udevd[230]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 05 12:46:16 volumio-main kernel: videodev: Linux video capture interface: v2.00 Feb 05 12:46:16 volumio-main kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Feb 05 12:46:16 volumio-main kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Feb 05 12:46:16 volumio-main kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Feb 05 12:46:16 volumio-main kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Feb 05 12:46:16 volumio-main kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Feb 05 12:46:16 volumio-main kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Feb 05 12:46:16 volumio-main kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Feb 05 12:46:16 volumio-main kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Feb 05 12:46:16 volumio-main kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Feb 05 12:46:16 volumio-main kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Feb 05 12:46:16 volumio-main kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Feb 05 12:46:16 volumio-main kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Feb 05 12:46:16 volumio-main kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Feb 05 12:46:16 volumio-main kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Feb 05 12:46:17 volumio-main kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Feb 05 12:46:17 volumio-main kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Feb 05 12:46:17 volumio-main systemd-udevd[239]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 05 12:46:17 volumio-main kernel: brcmfmac: F1 signature read @0x18000000=0x15264345 Feb 05 12:46:17 volumio-main kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Feb 05 12:46:17 volumio-main kernel: usbcore: registered new interface driver brcmfmac Feb 05 12:46:17 volumio-main systemd-udevd[238]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Feb 05 12:46:18 volumio-main kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Feb 05 12:46:18 volumio-main kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 Feb 05 12:46:18 volumio-main 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 Feb 05 12:46:18 volumio-main systemd-udevd[229]: Using default interface naming scheme 'v240'. Feb 05 12:46:18 volumio-main systemd-udevd[229]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Feb 05 12:46:18 volumio-main systemd[1]: Found device /dev/mmcblk0p1. Feb 05 12:46:18 volumio-main systemd[1]: Condition check resulted in /sys/subsystem/net/devices/eth0 being skipped. Feb 05 12:46:18 volumio-main systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Feb 05 12:46:18 volumio-main systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Feb 05 12:46:18 volumio-main systemd[1]: Condition check resulted in FUSE Control File System being skipped. Feb 05 12:46:18 volumio-main systemd[1]: Condition check resulted in Huge Pages File System being skipped. Feb 05 12:46:18 volumio-main systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Feb 05 12:46:18 volumio-main systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Feb 05 12:46:18 volumio-main systemd[1]: Created slice system-systemd\x2dbacklight.slice. Feb 05 12:46:18 volumio-main systemd[1]: Starting Load/Save Screen Backlight Brightness of backlight:rpi_backlight... Feb 05 12:46:18 volumio-main systemd[1]: Starting File System Check on /dev/mmcblk0p1... Feb 05 12:46:18 volumio-main systemd[1]: Starting Load/Save RF Kill Switch Status... Feb 05 12:46:19 volumio-main systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Feb 05 12:46:19 volumio-main systemd[1]: Started Load/Save RF Kill Switch Status. Feb 05 12:46:19 volumio-main systemd[1]: Started Helper to synchronize boot up for ifupdown. Feb 05 12:46:19 volumio-main systemd[1]: Started Load/Save Screen Backlight Brightness of backlight:rpi_backlight. Feb 05 12:46:19 volumio-main systemd[1]: Started File System Check Daemon to report status. Feb 05 12:46:19 volumio-main systemd-fsck[371]: fsck.fat 4.1 (2017-01-24) Feb 05 12:46:19 volumio-main systemd-fsck[371]: /dev/mmcblk0p1: 296 files, 27483/46774 clusters Feb 05 12:46:19 volumio-main systemd[1]: Started File System Check on /dev/mmcblk0p1. Feb 05 12:46:19 volumio-main systemd[1]: Mounting /boot... Feb 05 12:46:19 volumio-main systemd[1]: Mounted /boot. Feb 05 12:46:19 volumio-main systemd[1]: Reached target Local File Systems. Feb 05 12:46:19 volumio-main systemd[1]: Started ifup for wlan0. Feb 05 12:46:19 volumio-main systemd[1]: Started ifup for eth0. Feb 05 12:46:19 volumio-main systemd[1]: Starting Create Volatile Files and Directories... Feb 05 12:46:19 volumio-main systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Feb 05 12:46:19 volumio-main systemd[1]: Starting Raise network interfaces... Feb 05 12:46:19 volumio-main systemd[1]: Starting Preprocess NFS configuration... Feb 05 12:46:19 volumio-main systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Feb 05 12:46:19 volumio-main systemd[1]: plymouth-read-write.service: Succeeded. Feb 05 12:46:19 volumio-main systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Feb 05 12:46:19 volumio-main systemd[1]: Received SIGRTMIN+20 from PID 244 (plymouthd). Feb 05 12:46:19 volumio-main systemd[1]: nfs-config.service: Succeeded. Feb 05 12:46:19 volumio-main systemd[1]: Started Preprocess NFS configuration. Feb 05 12:46:19 volumio-main systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Feb 05 12:46:19 volumio-main systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Feb 05 12:46:19 volumio-main systemd[1]: Reached target NFS client services. Feb 05 12:46:19 volumio-main systemd[1]: Started Create Volatile Files and Directories. Feb 05 12:46:19 volumio-main systemd[1]: Starting RPC bind portmap service... Feb 05 12:46:19 volumio-main systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Feb 05 12:46:19 volumio-main systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Feb 05 12:46:19 volumio-main systemd[1]: Starting Update UTMP about System Boot/Shutdown... Feb 05 12:46:19 volumio-main systemd[1]: Started Update UTMP about System Boot/Shutdown. Feb 05 12:46:19 volumio-main kernel: 8021q: 802.1Q VLAN Support v1.8 Feb 05 12:46:19 volumio-main systemd[1]: Reached target System Initialization. Feb 05 12:46:19 volumio-main systemd[1]: Listening on triggerhappy.socket. Feb 05 12:46:19 volumio-main systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Feb 05 12:46:19 volumio-main systemd[1]: Listening on D-Bus System Message Bus Socket. Feb 05 12:46:19 volumio-main systemd[1]: Listening on mpd.socket. Feb 05 12:46:19 volumio-main systemd[1]: Reached target Sockets. Feb 05 12:46:19 volumio-main systemd[1]: Started Daily man-db regeneration. Feb 05 12:46:19 volumio-main systemd[1]: Started Daily Cleanup of Temporary Directories. Feb 05 12:46:19 volumio-main systemd[1]: Reached target Basic System. Feb 05 12:46:19 volumio-main systemd[1]: Starting triggerhappy global hotkey daemon... Feb 05 12:46:19 volumio-main systemd[1]: Started volumio-remote-updater.service. Feb 05 12:46:19 volumio-main systemd[1]: Starting Configure Bluetooth Modems connected by UART... Feb 05 12:46:19 volumio-main systemd[1]: Started Volumio Iptables Module. Feb 05 12:46:19 volumio-main thd[450]: Unable to parse trigger line: Feb 05 12:46:19 volumio-main thd[450]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Feb 05 12:46:19 volumio-main thd[450]: Unable to parse trigger line: Feb 05 12:46:19 volumio-main thd[450]: Found socket passed from systemd Feb 05 12:46:19 volumio-main kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Feb 05 12:46:19 volumio-main systemd[1]: Starting Login Service... Feb 05 12:46:19 volumio-main systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Feb 05 12:46:19 volumio-main systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Feb 05 12:46:19 volumio-main systemd[1]: Started D-Bus System Message Bus. Feb 05 12:46:19 volumio-main systemd[1]: Starting WPA supplicant... Feb 05 12:46:19 volumio-main systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Feb 05 12:46:19 volumio-main systemd[1]: Starting dhcpcd on all interfaces... Feb 05 12:46:19 volumio-main systemd[1]: Started Daily apt download activities. Feb 05 12:46:19 volumio-main systemd[1]: Started Daily apt upgrade and clean activities. Feb 05 12:46:19 volumio-main systemd[1]: Reached target Timers. Feb 05 12:46:19 volumio-main systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Feb 05 12:46:19 volumio-main systemd[1]: Started Manage Sound Card State (restore and store). Feb 05 12:46:19 volumio-main systemd[1]: Starting Save/Restore Sound Card State... Feb 05 12:46:19 volumio-main alsactl[474]: alsactl 1.1.8 daemon started Feb 05 12:46:19 volumio-main systemd[1]: Started UPnP Renderer front-end to MPD. Feb 05 12:46:20 volumio-main dhcpcd[471]: Not running dhcpcd because /etc/network/interfaces Feb 05 12:46:20 volumio-main dhcpcd[471]: defines some interfaces that will use a Feb 05 12:46:20 volumio-main dhcpcd[471]: DHCP client or static address Feb 05 12:46:20 volumio-main systemd[1]: Starting Wireless Services... Feb 05 12:46:20 volumio-main systemd[1]: Started triggerhappy global hotkey daemon. Feb 05 12:46:20 volumio-main systemd[1]: Started RPC bind portmap service. Feb 05 12:46:20 volumio-main systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Feb 05 12:46:20 volumio-main systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Feb 05 12:46:20 volumio-main systemd[1]: Failed to start dhcpcd on all interfaces. Feb 05 12:46:20 volumio-main kernel: uart-pl011 fe201000.serial: no DMA platform data Feb 05 12:46:20 volumio-main systemd[1]: Started Save/Restore Sound Card State. Feb 05 12:46:20 volumio-main ifup[387]: ifup: waiting for lock on /run/network/ifstate.wlan0 Feb 05 12:46:20 volumio-main avahi-daemon[461]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Feb 05 12:46:20 volumio-main avahi-daemon[461]: Successfully dropped root privileges. Feb 05 12:46:20 volumio-main systemd-logind[455]: New seat seat0. Feb 05 12:46:20 volumio-main avahi-daemon[461]: avahi-daemon 0.7 starting up. Feb 05 12:46:20 volumio-main systemd[1]: Reached target Sound Card. Feb 05 12:46:20 volumio-main systemd[1]: Reached target RPC Port Mapper. Feb 05 12:46:20 volumio-main systemd[1]: Reached target Remote File Systems (Pre). Feb 05 12:46:20 volumio-main systemd[1]: Reached target Remote File Systems. Feb 05 12:46:20 volumio-main sh[378]: eth0: waiting for carrier Feb 05 12:46:20 volumio-main dhcpcd[425]: eth0: waiting for carrier Feb 05 12:46:20 volumio-main systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Feb 05 12:46:20 volumio-main kernel: bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) Feb 05 12:46:20 volumio-main kernel: bcmgenet fd580000.ethernet eth0: Link is Down Feb 05 12:46:20 volumio-main sh[377]: wlan0=wlan0 Feb 05 12:46:20 volumio-main systemd[1]: Started Login Service. Feb 05 12:46:20 volumio-main avahi-daemon[461]: Successfully called chroot(). Feb 05 12:46:20 volumio-main avahi-daemon[461]: Successfully dropped remaining capabilities. Feb 05 12:46:20 volumio-main avahi-daemon[461]: Loading service file /services/volumio.service. Feb 05 12:46:20 volumio-main systemd[1]: Started Avahi mDNS/DNS-SD Stack. Feb 05 12:46:20 volumio-main avahi-daemon[461]: Network interface enumeration completed. Feb 05 12:46:20 volumio-main avahi-daemon[461]: Server startup complete. Host name is volumio-main.local. Local service cookie is 1437564122. Feb 05 12:46:20 volumio-main avahi-daemon[461]: Service "Volumio-Main" (/services/volumio.service) successfully established. Feb 05 12:46:20 volumio-main systemd[1]: Started Raise network interfaces. Feb 05 12:46:21 volumio-main loadcpufreq[512]: Loading cpufreq kernel modules...done (none). Feb 05 12:46:21 volumio-main systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Feb 05 12:46:21 volumio-main systemd[1]: Starting LSB: set CPUFreq kernel parameters... Feb 05 12:46:21 volumio-main cpufrequtils[570]: CPUFreq Utilities: Setting performance CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Feb 05 12:46:21 volumio-main systemd[1]: Started LSB: set CPUFreq kernel parameters. Feb 05 12:46:21 volumio-main systemd[1]: Started WPA supplicant. Feb 05 12:46:21 volumio-main systemd[1]: Reached target Network. Feb 05 12:46:21 volumio-main systemd[1]: Reached target Network is Online. Feb 05 12:46:21 volumio-main systemd[1]: Starting LSB: Brings up/down network automatically... Feb 05 12:46:21 volumio-main wpa_supplicant[470]: Successfully initialized wpa_supplicant Feb 05 12:46:21 volumio-main systemd[1]: Starting OpenBSD Secure Shell server... Feb 05 12:46:21 volumio-main systemd[1]: Starting Network Time Service... Feb 05 12:46:21 volumio-main systemd[1]: Starting Samba NMB Daemon... Feb 05 12:46:21 volumio-main systemd[1]: Starting Music Player Daemon... Feb 05 12:46:21 volumio-main systemd[1]: Starting /etc/rc.local Compatibility... Feb 05 12:46:21 volumio-main systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Feb 05 12:46:21 volumio-main systemd[1]: Starting Permit User Sessions... Feb 05 12:46:21 volumio-main systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 05 12:46:21 volumio-main systemd[1]: iptables.service: Succeeded. Feb 05 12:46:21 volumio-main systemd[1]: Started /etc/rc.local Compatibility. Feb 05 12:46:21 volumio-main systemd[1]: Started Permit User Sessions. Feb 05 12:46:21 volumio-main systemd[1]: Starting Hold until boot process finishes up... Feb 05 12:46:21 volumio-main systemd[1]: Starting Terminate Plymouth Boot Screen... Feb 05 12:46:21 volumio-main systemd[1]: Received SIGRTMIN+21 from PID 244 (plymouthd). Feb 05 12:46:21 volumio-main ifplugd(eth0)[618]: ifplugd 0.28 initializing. Feb 05 12:46:21 volumio-main ifplugd(eth0)[618]: Using interface eth0/E4:5F:01:5D:2B:B5 with driver (version: 5.10.90-v7l+) Feb 05 12:46:21 volumio-main ifplugd(eth0)[618]: Using detection mode: SIOCETHTOOL Feb 05 12:46:21 volumio-main ifplugd(eth0)[618]: Initialization complete, link beat not detected. Feb 05 12:46:21 volumio-main systemd[1]: Received SIGRTMIN+21 from PID 244 (plymouthd). Feb 05 12:46:21 volumio-main systemd[1]: plymouth-quit-wait.service: Succeeded. Feb 05 12:46:21 volumio-main systemd[1]: Started Hold until boot process finishes up. Feb 05 12:46:21 volumio-main systemd[1]: plymouth-start.service: Succeeded. Feb 05 12:46:21 volumio-main systemd[1]: plymouth-quit.service: Succeeded. Feb 05 12:46:21 volumio-main systemd[1]: Started Terminate Plymouth Boot Screen. Feb 05 12:46:21 volumio-main systemd[1]: Started Getty on tty1. Feb 05 12:46:21 volumio-main systemd[1]: Reached target Login Prompts. Feb 05 12:46:21 volumio-main ntpd[593]: ntpd 4.2.8p12@1.3728-o (1): Starting Feb 05 12:46:21 volumio-main ntpd[593]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Feb 05 12:46:21 volumio-main systemd[1]: Started Network Time Service. Feb 05 12:46:21 volumio-main ifplugd[589]: Network Interface Plugging Daemon...start eth0...done. Feb 05 12:46:21 volumio-main systemd[1]: Started LSB: Brings up/down network automatically. Feb 05 12:46:21 volumio-main ntpd[622]: proto: precision = 0.796 usec (-20) Feb 05 12:46:22 volumio-main ntpd[622]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Feb 05 12:46:22 volumio-main ntpd[622]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-06-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Feb 05 12:46:22 volumio-main ntpd[622]: Listen and drop on 0 v6wildcard [::]:123 Feb 05 12:46:22 volumio-main ntpd[622]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Feb 05 12:46:22 volumio-main ntpd[622]: Listen normally on 2 lo 127.0.0.1:123 Feb 05 12:46:22 volumio-main ntpd[622]: Listening on routing socket on fd #19 for interface updates Feb 05 12:46:22 volumio-main ntpd[622]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 05 12:46:22 volumio-main ntpd[622]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Feb 05 12:46:22 volumio-main haveged[430]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Feb 05 12:46:22 volumio-main haveged[430]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Feb 05 12:46:22 volumio-main haveged[430]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00219 Feb 05 12:46:22 volumio-main haveged[430]: haveged: fills: 0, generated: 0 Feb 05 12:46:22 volumio-main kernel: random: crng init done Feb 05 12:46:22 volumio-main kernel: random: 7 urandom warning(s) missed due to ratelimiting Feb 05 12:46:22 volumio-main volumio-remote-updater[451]: Error: No active session Feb 05 12:46:22 volumio-main volumio-remote-updater[451]: [2022-02-05 12:46:22] [info] asio async_connect error: system:111 (Connection refused) Feb 05 12:46:22 volumio-main volumio-remote-updater[451]: [2022-02-05 12:46:22] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 05 12:46:22 volumio-main volumio-remote-updater[451]: [2022-02-05 12:46:22] [error] handle_connect error: Underlying Transport Error Feb 05 12:46:22 volumio-main volumio[478]: Could not open config: /tmp/upmpdcli.conf Feb 05 12:46:22 volumio-main systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Feb 05 12:46:22 volumio-main systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Feb 05 12:46:22 volumio-main sshd[628]: Server listening on 0.0.0.0 port 22. Feb 05 12:46:22 volumio-main sshd[628]: Server listening on :: port 22. Feb 05 12:46:22 volumio-main systemd[1]: Started OpenBSD Secure Shell server. Feb 05 12:46:22 volumio-main ntpd[622]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 05 12:46:23 volumio-main nmbd[605]: [2022/02/05 12:46:23.368057, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Feb 05 12:46:23 volumio-main nmbd[605]: started asyncdns process 634 Feb 05 12:46:23 volumio-main nmbd[605]: [2022/02/05 12:46:23.369916, 0] ../lib/util/become_daemon.c:149(daemon_status) Feb 05 12:46:23 volumio-main nmbd[605]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Feb 05 12:46:23 volumio-main nmbd[605]: [2022/02/05 12:46:23.370040, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Feb 05 12:46:23 volumio-main nmbd[605]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Feb 05 12:46:23 volumio-main ntpd[622]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 05 12:46:24 volumio-main systemd[1]: systemd-rfkill.service: Succeeded. Feb 05 12:46:24 volumio-main wireless.js[493]: WIRELESS DAEMON: start Feb 05 12:46:24 volumio-main wireless.js[493]: WIRELESS: Loaded configuration Feb 05 12:46:24 volumio-main wireless.js[493]: Cleaning previous... Feb 05 12:46:24 volumio-main sudo[639]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 05 12:46:24 volumio-main sudo[639]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:24 volumio-main sudo[639]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:24 volumio-main sudo[641]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 05 12:46:24 volumio-main sudo[641]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:24 volumio-main kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Feb 05 12:46:24 volumio-main mpd[621]: Feb 05 12:46 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 05 12:46:24 volumio-main mpd[621]: Feb 05 12:46 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 05 12:46:24 volumio-main mpd[621]: Feb 05 12:46 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 05 12:46:24 volumio-main sh[378]: eth0: carrier acquired Feb 05 12:46:24 volumio-main dhcpcd[425]: eth0: carrier acquired Feb 05 12:46:24 volumio-main sudo[641]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:24 volumio-main systemd[1]: Started Music Player Daemon. Feb 05 12:46:24 volumio-main sh[378]: DUID 00:01:00:01:29:81:34:6a:e4:5f:01:5d:2b:b5 Feb 05 12:46:24 volumio-main sh[378]: eth0: IAID 01:5d:2b:b5 Feb 05 12:46:24 volumio-main sh[378]: eth0: adding address fe80::e65f:1ff:fe5d:2bb5 Feb 05 12:46:24 volumio-main sh[378]: ipv6_addaddr1: Permission denied Feb 05 12:46:24 volumio-main dhcpcd[425]: DUID 00:01:00:01:29:81:34:6a:e4:5f:01:5d:2b:b5 Feb 05 12:46:24 volumio-main dhcpcd[425]: eth0: IAID 01:5d:2b:b5 Feb 05 12:46:24 volumio-main dhcpcd[425]: eth0: adding address fe80::e65f:1ff:fe5d:2bb5 Feb 05 12:46:24 volumio-main dhcpcd[425]: ipv6_addaddr1: Permission denied Feb 05 12:46:24 volumio-main sh[378]: eth0: soliciting an IPv6 router Feb 05 12:46:24 volumio-main dhcpcd[425]: eth0: soliciting an IPv6 router Feb 05 12:46:24 volumio-main wireless.js[493]: Stopped aP Feb 05 12:46:24 volumio-main wireless.js[493]: Wireless Networking DISABLED, not starting wireless flow Feb 05 12:46:24 volumio-main systemd[1]: wireless.service: Succeeded. Feb 05 12:46:24 volumio-main systemd[1]: Started Wireless Services. Feb 05 12:46:24 volumio-main systemd[1]: Started Volumio Backend Module. Feb 05 12:46:24 volumio-main systemd[1]: Started Volumio Streaming Daemon. Feb 05 12:46:24 volumio-main ifplugd(eth0)[618]: Link beat detected. Feb 05 12:46:24 volumio-main ntpd[622]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 05 12:46:25 volumio-main volumio-streaming-daemon[652]: ############################ Feb 05 12:46:25 volumio-main volumio-streaming-daemon[652]: # Volumio Streaming Daemon # Feb 05 12:46:25 volumio-main volumio-streaming-daemon[652]: # Running on port 7777 # Feb 05 12:46:25 volumio-main volumio-streaming-daemon[652]: ############################ Feb 05 12:46:25 volumio-main volumio-streaming-daemon[652]: Environment: production Feb 05 12:46:25 volumio-main ifplugd(eth0)[618]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Feb 05 12:46:25 volumio-main ifplugd(eth0)[618]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Feb 05 12:46:25 volumio-main sh[378]: eth0: soliciting a DHCP lease Feb 05 12:46:25 volumio-main dhcpcd[425]: eth0: soliciting a DHCP lease Feb 05 12:46:25 volumio-main sh[378]: eth0: offered 10.0.2.51 from 10.0.2.1 Feb 05 12:46:25 volumio-main dhcpcd[425]: eth0: offered 10.0.2.51 from 10.0.2.1 Feb 05 12:46:25 volumio-main sh[378]: eth0: probing address 10.0.2.51/24 Feb 05 12:46:25 volumio-main dhcpcd[425]: eth0: probing address 10.0.2.51/24 Feb 05 12:46:25 volumio-main ntpd[622]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 05 12:46:26 volumio-main volumio[651]: info: ------------------------------------------- Feb 05 12:46:26 volumio-main volumio[651]: info: ----- Volumio3 ---- Feb 05 12:46:26 volumio-main volumio[651]: info: ------------------------------------------- Feb 05 12:46:26 volumio-main volumio[651]: info: ----- System startup ---- Feb 05 12:46:26 volumio-main volumio[651]: info: ------------------------------------------- Feb 05 12:46:27 volumio-main kernel: Bluetooth: Core ver 2.22 Feb 05 12:46:27 volumio-main kernel: NET: Registered protocol family 31 Feb 05 12:46:27 volumio-main kernel: Bluetooth: HCI device and connection manager initialized Feb 05 12:46:27 volumio-main kernel: Bluetooth: HCI socket layer initialized Feb 05 12:46:27 volumio-main kernel: Bluetooth: L2CAP socket layer initialized Feb 05 12:46:27 volumio-main kernel: Bluetooth: SCO socket layer initialized Feb 05 12:46:27 volumio-main btuart[452]: bcm43xx_init Feb 05 12:46:27 volumio-main btuart[452]: Flash firmware /lib/firmware/brcm/BCM4345C0.hcd Feb 05 12:46:27 volumio-main btuart[452]: Set Controller UART speed to 3000000 bit/s Feb 05 12:46:27 volumio-main btuart[452]: Device setup complete Feb 05 12:46:27 volumio-main kernel: Bluetooth: HCI UART driver ver 2.3 Feb 05 12:46:27 volumio-main kernel: Bluetooth: HCI UART protocol H4 registered Feb 05 12:46:27 volumio-main kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Feb 05 12:46:27 volumio-main kernel: Bluetooth: HCI UART protocol Broadcom registered Feb 05 12:46:27 volumio-main systemd[1]: Starting Load/Save RF Kill Switch Status... Feb 05 12:46:27 volumio-main systemd[1]: Started Configure Bluetooth Modems connected by UART. Feb 05 12:46:27 volumio-main systemd[1]: Started Load/Save RF Kill Switch Status. Feb 05 12:46:27 volumio-main systemd[1]: Created slice system-bthelper.slice. Feb 05 12:46:27 volumio-main systemd[1]: Starting Raspberry Pi bluetooth helper... Feb 05 12:46:27 volumio-main bthelper[687]: Raspberry Pi BDADDR already set Feb 05 12:46:27 volumio-main systemd[1]: Started Raspberry Pi bluetooth helper. Feb 05 12:46:27 volumio-main systemd[1]: Starting Bluetooth service... Feb 05 12:46:27 volumio-main volumio[651]: info: MYVOLUMIO Environment detected Feb 05 12:46:27 volumio-main bluetoothd[698]: Bluetooth daemon 5.50 Feb 05 12:46:27 volumio-main bluetoothd[698]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Feb 05 12:46:27 volumio-main systemd[1]: Started Bluetooth service. Feb 05 12:46:27 volumio-main systemd[1]: Reached target Bluetooth. Feb 05 12:46:27 volumio-main bluetoothd[698]: Starting SDP server Feb 05 12:46:27 volumio-main bluetoothd[698]: Excluding (cli) sap Feb 05 12:46:27 volumio-main volumio-remote-updater[451]: [2022-02-05 12:46:27] [connect] Successful connection Feb 05 12:46:27 volumio-main bluetoothd[698]: Bluetooth management interface 1.18 initialized Feb 05 12:46:27 volumio-main dbus-daemon[464]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=698 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Feb 05 12:46:27 volumio-main kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Feb 05 12:46:27 volumio-main kernel: Bluetooth: BNEP filters: protocol multicast Feb 05 12:46:27 volumio-main kernel: Bluetooth: BNEP socket layer initialized Feb 05 12:46:27 volumio-main systemd[1]: Starting Hostname Service... Feb 05 12:46:27 volumio-main volumio[651]: info: Plugin folders cleanup Feb 05 12:46:27 volumio-main volumio[651]: info: Scanning into folder /volumio/app/plugins/ Feb 05 12:46:27 volumio-main volumio[651]: info: Scanning category audio_interface Feb 05 12:46:27 volumio-main volumio[651]: info: Scanning category miscellanea Feb 05 12:46:27 volumio-main volumio[651]: info: Scanning category music_service Feb 05 12:46:27 volumio-main volumio[651]: info: Scanning category plugins.json Feb 05 12:46:27 volumio-main volumio[651]: info: Scanning category system_controller Feb 05 12:46:27 volumio-main volumio[651]: info: Scanning category user_interface Feb 05 12:46:27 volumio-main volumio[651]: info: Scanning into folder /data/plugins/ Feb 05 12:46:27 volumio-main volumio[651]: info: Scanning category audio_interface Feb 05 12:46:27 volumio-main volumio[651]: info: Scanning category music_service Feb 05 12:46:27 volumio-main volumio[651]: info: Scanning category system_controller Feb 05 12:46:27 volumio-main volumio[651]: info: Scanning category user_interface Feb 05 12:46:27 volumio-main volumio[651]: info: Plugin folders cleanup completed Feb 05 12:46:27 volumio-main volumio[651]: info: ------------------------------------------- Feb 05 12:46:27 volumio-main volumio[651]: info: ----- Core plugins startup ---- Feb 05 12:46:27 volumio-main volumio[651]: info: ------------------------------------------- Feb 05 12:46:27 volumio-main volumio[651]: info: Loading plugins from folder /volumio/app/plugins/ Feb 05 12:46:27 volumio-main volumio[651]: info: Adding plugin upnp to MyMusic Plugins Feb 05 12:46:27 volumio-main volumio[651]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 05 12:46:27 volumio-main volumio[651]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 05 12:46:27 volumio-main volumio[651]: info: Loading plugins from folder /data/plugins/ Feb 05 12:46:27 volumio-main volumio[651]: info: Loading plugin "system"... Feb 05 12:46:27 volumio-main bluetoothd[698]: Failed to set privacy: Rejected (0x0b) Feb 05 12:46:27 volumio-main volumio[651]: info: Loading plugin "appearance"... Feb 05 12:46:27 volumio-main dbus-daemon[464]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 05 12:46:27 volumio-main systemd[1]: Started Hostname Service. Feb 05 12:46:28 volumio-main volumio[651]: info: Loading plugin "network"... Feb 05 12:46:28 volumio-main volumio[651]: info: Refreshing Cached IP Addresses Feb 05 12:46:28 volumio-main sudo[703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 05 12:46:28 volumio-main volumio[651]: info: Loading plugin "services"... Feb 05 12:46:28 volumio-main sudo[703]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:28 volumio-main volumio[651]: info: Loading plugin "alsa_controller"... Feb 05 12:46:28 volumio-main sudo[703]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:28 volumio-main sudo[705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 05 12:46:28 volumio-main sudo[705]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:28 volumio-main sudo[705]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:28 volumio-main volumio[651]: info: Loading plugin "wizard"... Feb 05 12:46:28 volumio-main volumio[651]: info: Loading plugin "volumio_command_line_client"... Feb 05 12:46:28 volumio-main volumio[651]: info: Loading plugin "upnp"... Feb 05 12:46:28 volumio-main volumio[651]: info: [1644090388632] Starting Upmpd Daemon Feb 05 12:46:28 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 05 12:46:28 volumio-main volumio[651]: info: Loading plugin "my_music"... Feb 05 12:46:28 volumio-main volumio[651]: info: Loading plugin "mpd"... Feb 05 12:46:28 volumio-main volumio[651]: info: Creating MPD Configuration file Feb 05 12:46:28 volumio-main volumio[651]: info: Loading plugin "upnp_browser"... Feb 05 12:46:28 volumio-main sudo[711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 05 12:46:28 volumio-main sudo[711]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:28 volumio-main sudo[711]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:28 volumio-main sudo[713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 05 12:46:28 volumio-main sudo[713]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:28 volumio-main systemd[1]: Stopping Music Player Daemon... Feb 05 12:46:29 volumio-main systemd[1]: mpd.service: Succeeded. Feb 05 12:46:29 volumio-main systemd[1]: Stopped Music Player Daemon. Feb 05 12:46:29 volumio-main systemd[1]: Starting Music Player Daemon... Feb 05 12:46:29 volumio-main volumio[651]: info: Loading plugin "networkfs"... Feb 05 12:46:29 volumio-main sudo[721]: 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 //10.0.2.15/Music/FLAC /mnt/NAS/FLAC Feb 05 12:46:29 volumio-main sudo[721]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:29 volumio-main sudo[723]: 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 //10.0.2.15/Music/MP3 /mnt/NAS/MP3 Feb 05 12:46:29 volumio-main sudo[723]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:29 volumio-main sudo[727]: 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 //10.0.2.15/Music/Various /mnt/NAS/Various Feb 05 12:46:29 volumio-main sudo[727]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:29 volumio-main sudo[734]: 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 //10.0.2.15/Music/Classical /mnt/NAS/Classical Feb 05 12:46:29 volumio-main sudo[734]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:29 volumio-main volumio[651]: info: Starting Udev Watcher for removable devices Feb 05 12:46:29 volumio-main kernel: FS-Cache: Netfs 'cifs' registered for caching Feb 05 12:46:29 volumio-main sudo[723]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:29 volumio-main kernel: Key type cifs.spnego registered Feb 05 12:46:29 volumio-main kernel: Key type cifs.idmap registered Feb 05 12:46:29 volumio-main kernel: CIFS: Attempting to mount //10.0.2.15/Music/MP3 Feb 05 12:46:29 volumio-main 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. Feb 05 12:46:29 volumio-main kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 05 12:46:29 volumio-main kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Feb 05 12:46:29 volumio-main kernel: CIFS: Attempting to mount //10.0.2.15/Music/FLAC Feb 05 12:46:29 volumio-main kernel: CIFS: Attempting to mount //10.0.2.15/Music/Various Feb 05 12:46:29 volumio-main kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 05 12:46:29 volumio-main kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 05 12:46:29 volumio-main kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Feb 05 12:46:29 volumio-main sudo[721]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:29 volumio-main sudo[727]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:29 volumio-main sudo[740]: 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 //10.0.2.15/Music/Society of Sound /mnt/NAS/Society_of_Sound Feb 05 12:46:29 volumio-main sudo[740]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:29 volumio-main sudo[745]: 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 //10.0.2.15/Music/Techno /mnt/NAS/Techno Feb 05 12:46:29 volumio-main kernel: CIFS: Attempting to mount //10.0.2.15/Music/Classical Feb 05 12:46:29 volumio-main kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 05 12:46:29 volumio-main kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Feb 05 12:46:29 volumio-main sudo[745]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:29 volumio-main sudo[734]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:29 volumio-main sudo[740]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:29 volumio-main sudo[745]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:29 volumio-main kernel: CIFS: Attempting to mount //10.0.2.15/Music/Society of Sound Feb 05 12:46:29 volumio-main kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 05 12:46:29 volumio-main kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Feb 05 12:46:29 volumio-main kernel: CIFS: Attempting to mount //10.0.2.15/Music/Techno Feb 05 12:46:29 volumio-main kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 05 12:46:29 volumio-main kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Feb 05 12:46:29 volumio-main mpd[718]: Feb 05 12:46 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 05 12:46:30 volumio-main mpd[718]: Feb 05 12:46 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 05 12:46:30 volumio-main mpd[718]: Feb 05 12:46 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 05 12:46:30 volumio-main systemd[1]: Started Music Player Daemon. Feb 05 12:46:30 volumio-main sudo[713]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:30 volumio-main volumio[651]: info: Ignoring mount for partition: boot Feb 05 12:46:30 volumio-main volumio[651]: info: Ignoring mount for partition: volumio Feb 05 12:46:30 volumio-main volumio[651]: info: Ignoring mount for partition: volumio_data Feb 05 12:46:30 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 05 12:46:30 volumio-main volumio[651]: info: Loading plugin "alarm-clock"... Feb 05 12:46:30 volumio-main volumio[651]: info: Loading plugin "airplay_emulation"... Feb 05 12:46:30 volumio-main volumio[651]: info: Starting Shairport Sync Feb 05 12:46:30 volumio-main volumio[651]: info: Loading plugin "last_100"... Feb 05 12:46:30 volumio-main volumio[651]: info: Loading plugin "webradio"... Feb 05 12:46:30 volumio-main volumio[651]: info: Loading plugin "i2s_dacs"... Feb 05 12:46:30 volumio-main volumio[651]: info: Loading plugin "volumiodiscovery"... Feb 05 12:46:30 volumio-main volumio[651]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 05 12:46:30 volumio-main volumio[651]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 05 12:46:30 volumio-main node[651]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 05 12:46:30 volumio-main volumio[651]: *** WARNING *** For more information see Feb 05 12:46:30 volumio-main volumio[651]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 05 12:46:30 volumio-main volumio[651]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 05 12:46:30 volumio-main volumio[651]: *** WARNING *** For more information see Feb 05 12:46:30 volumio-main node[651]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 05 12:46:30 volumio-main node[651]: *** WARNING *** For more information see Feb 05 12:46:30 volumio-main node[651]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 05 12:46:30 volumio-main node[651]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 05 12:46:30 volumio-main node[651]: *** WARNING *** For more information see Feb 05 12:46:30 volumio-main volumio[651]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 05 12:46:30 volumio-main volumio[651]: info: Discovery: Started advertising with name: Volumio-Main Feb 05 12:46:30 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 05 12:46:30 volumio-main volumio[651]: info: Loading plugin "gpio-buttons"... Feb 05 12:46:30 volumio-main sh[378]: eth0: leased 10.0.2.51 for 86400 seconds Feb 05 12:46:30 volumio-main sh[378]: eth0: adding route to 10.0.2.0/24 Feb 05 12:46:30 volumio-main sh[378]: eth0: adding default route via 10.0.2.1 Feb 05 12:46:30 volumio-main dhcpcd[425]: eth0: leased 10.0.2.51 for 86400 seconds Feb 05 12:46:30 volumio-main dhcpcd[425]: eth0: adding route to 10.0.2.0/24 Feb 05 12:46:30 volumio-main dhcpcd[425]: eth0: adding default route via 10.0.2.1 Feb 05 12:46:30 volumio-main avahi-daemon[461]: Joining mDNS multicast group on interface eth0.IPv4 with address 10.0.2.51. Feb 05 12:46:30 volumio-main avahi-daemon[461]: New relevant interface eth0.IPv4 for mDNS. Feb 05 12:46:30 volumio-main avahi-daemon[461]: Registering new address record for 10.0.2.51 on eth0.IPv4. Feb 05 12:46:30 volumio-main sh[378]: forked to background, child pid 788 Feb 05 12:46:30 volumio-main dhcpcd[425]: forked to background, child pid 788 Feb 05 12:46:30 volumio-main volumio[651]: info: GPIO-Buttons initialized Feb 05 12:46:30 volumio-main volumio[651]: info: Loading plugin "now_playing"... Feb 05 12:46:31 volumio-main ifplugd(eth0)[618]: client: ifup: interface eth0 already configured Feb 05 12:46:31 volumio-main sh[378]: eth0=eth0 Feb 05 12:46:31 volumio-main ifplugd(eth0)[618]: Program executed successfully. Feb 05 12:46:31 volumio-main volumio[651]: info: Loading plugin "outputs"... Feb 05 12:46:31 volumio-main volumio[651]: info: Loading plugin "albumart"... Feb 05 12:46:31 volumio-main volumio[651]: info: Plugin example_plugin is not enabled Feb 05 12:46:31 volumio-main volumio[651]: info: Loading plugin "inputs"... Feb 05 12:46:31 volumio-main volumio[651]: info: Loading plugin "updater_comm"... Feb 05 12:46:31 volumio-main volumio[651]: info: Plugin mpdemulation is not enabled Feb 05 12:46:31 volumio-main volumio[651]: info: Loading plugin "rest_api"... Feb 05 12:46:31 volumio-main volumio[651]: info: Loading plugin "websocket"... Feb 05 12:46:31 volumio-main volumio[651]: info: Plugin fusiondsp is not enabled Feb 05 12:46:31 volumio-main volumio[651]: info: Loading plugin "volspotconnect2"... Feb 05 12:46:31 volumio-main volumio[651]: Forking 3 albumart workers Feb 05 12:46:31 volumio-main ntpd[622]: Listen normally on 3 eth0 10.0.2.51:123 Feb 05 12:46:31 volumio-main ntpd[622]: new interface(s) found: waking up resolver Feb 05 12:46:32 volumio-main systemd[1]: systemd-rfkill.service: Succeeded. Feb 05 12:46:32 volumio-main volumio[651]: Starting albumart workers Feb 05 12:46:32 volumio-main volumio[651]: Starting albumart workers Feb 05 12:46:32 volumio-main volumio[651]: Starting albumart workers Feb 05 12:46:32 volumio-main bthelper[687]: Changing power off succeeded Feb 05 12:46:32 volumio-main volumio[651]: info: Loading plugin "touch_display"... Feb 05 12:46:32 volumio-main bthelper[687]: [CHG] Controller E4:5F:01:5D:2B:B9 Class: 0x0000041c Feb 05 12:46:32 volumio-main bthelper[687]: Changing power on succeeded Feb 05 12:46:32 volumio-main volumio[651]: info: Applying required configuration parameters for plugin touch_display Feb 05 12:46:32 volumio-main volumio[651]: info: Loading i18n strings for locale en Feb 05 12:46:32 volumio-main volumio[651]: Updating browse sources language Feb 05 12:46:32 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::initPlayerControls Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: Express server listening on port 3000 Feb 05 12:46:33 volumio-main volumio[651]: [Metrics] WebUI: 7s 5.73ms Feb 05 12:46:33 volumio-main volumio[651]: info: CoreStateMachine::resetVolumioState Feb 05 12:46:33 volumio-main volumio[651]: info: CoreStateMachine::getcurrentVolume Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::volumioRetrievevolume Feb 05 12:46:33 volumio-main volumio[651]: info: Cannot mount NAS Techno at system boot, trial number 1 ,retrying in 5 seconds Feb 05 12:46:33 volumio-main volumio[651]: info: Cannot mount NAS Society_of_Sound at system boot, trial number 1 ,retrying in 5 seconds Feb 05 12:46:33 volumio-main volumio[651]: info: Cannot mount NAS Classical at system boot, trial number 1 ,retrying in 5 seconds Feb 05 12:46:33 volumio-main volumio[651]: info: Cannot mount NAS Various at system boot, trial number 1 ,retrying in 5 seconds Feb 05 12:46:33 volumio-main volumio[651]: info: Cannot mount NAS MP3 at system boot, trial number 1 ,retrying in 5 seconds Feb 05 12:46:33 volumio-main volumio[651]: info: Cannot mount NAS FLAC at system boot, trial number 1 ,retrying in 5 seconds Feb 05 12:46:33 volumio-main volumio[651]: info: MPD Permissions set Feb 05 12:46:33 volumio-main volumio[651]: info: CoreStateMachine::pushState Feb 05 12:46:33 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::volumioPushState Feb 05 12:46:33 volumio-main volumio[651]: info: CoreStateMachine::updateTrackBlock Feb 05 12:46:33 volumio-main volumio[651]: info: CorePlayQueue::getTrackBlock Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::volumioRetrievevolume Feb 05 12:46:33 volumio-main volumio[651]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 Feb 05 12:46:33 volumio-main volumio[651]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Feb 05 12:46:33 volumio-main volumio[651]: info: MPD running with PID718 Feb 05 12:46:33 volumio-main volumio[651]: ,establishing connection Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: Reloading queue from file Feb 05 12:46:33 volumio-main volumio[651]: error: updateQueue error: null Feb 05 12:46:33 volumio-main volumio[651]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Feb 05 12:46:33 volumio-main nmbd[605]: [2022/02/05 12:46:33.376773, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 05 12:46:33 volumio-main systemd[1]: Started Samba NMB Daemon. Feb 05 12:46:33 volumio-main nmbd[605]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 05 12:46:33 volumio-main systemd[1]: Starting Samba Winbind Daemon... Feb 05 12:46:33 volumio-main volumio[651]: info: CoreStateMachine::pushState Feb 05 12:46:33 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::volumioPushState Feb 05 12:46:33 volumio-main volumio[651]: info: CoreStateMachine::setRepeat null single undefined Feb 05 12:46:33 volumio-main volumio[651]: info: CoreStateMachine::pushState Feb 05 12:46:33 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::volumioPushState Feb 05 12:46:33 volumio-main volumio[651]: info: CoreStateMachine::setRandom null Feb 05 12:46:33 volumio-main volumio[651]: info: CoreStateMachine::pushState Feb 05 12:46:33 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::volumioPushState Feb 05 12:46:33 volumio-main volumio[651]: info: Setting Device type: Raspberry PI Feb 05 12:46:33 volumio-main volumio[651]: info: Completed loading Core Plugins Feb 05 12:46:33 volumio-main volumio[651]: info: Preparing to generate the ALSA configuration file Feb 05 12:46:33 volumio-main volumio[651]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 05 12:46:33 volumio-main volumio[651]: info: Reading ALSA contributions from plugins. Feb 05 12:46:33 volumio-main volumio[651]: info: Discovery: adding c425a47d-2c78-4310-b049-a87f1cd945f0 Feb 05 12:46:33 volumio-main volumio[651]: info: mDNS: Found device Volumio-Main Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 12:46:33 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 12:46:33 volumio-main winbindd[897]: [2022/02/05 12:46:33.606234, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 05 12:46:33 volumio-main winbindd[897]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 05 12:46:33 volumio-main volumio[651]: error: updateQueue error: null Feb 05 12:46:33 volumio-main winbindd[897]: [2022/02/05 12:46:33.632736, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 05 12:46:33 volumio-main systemd[1]: Started Samba Winbind Daemon. Feb 05 12:46:33 volumio-main winbindd[897]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 05 12:46:33 volumio-main systemd[1]: Starting Samba SMB Daemon... Feb 05 12:46:33 volumio-main volumio[651]: info: Asound.conf file unchanged, so no further update is needed Feb 05 12:46:33 volumio-main volumio[651]: info: Output device has changed, restarting MPD Feb 05 12:46:33 volumio-main sudo[905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 05 12:46:33 volumio-main sudo[905]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:33 volumio-main volumio[651]: info: Output device has changed, restarting Shairport Sync Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:46:33 volumio-main sudo[905]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:33 volumio-main sudo[908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 05 12:46:33 volumio-main sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:33 volumio-main systemd[1]: Stopping Music Player Daemon... Feb 05 12:46:33 volumio-main volumio[651]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 05 12:46:33 volumio-main volumio[651]: info: ___________ START PLUGINS ___________ Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 05 12:46:33 volumio-main systemd[1]: mpd.service: Succeeded. Feb 05 12:46:33 volumio-main systemd[1]: Stopped Music Player Daemon. Feb 05 12:46:33 volumio-main systemd[1]: Starting Music Player Daemon... Feb 05 12:46:33 volumio-main volumio[651]: info: [1644090393854] CoreMusicLibrary::Adding element Media Servers Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 05 12:46:33 volumio-main volumio[651]: Cannot find translation for sourceMedia Servers Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:46:33 volumio-main volumio[651]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 05 12:46:33 volumio-main volumio[651]: info: [1644090393965] CoreMusicLibrary::Adding element Last_100 Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 05 12:46:33 volumio-main volumio[651]: Cannot find translation for sourceMedia Servers Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 05 12:46:33 volumio-main volumio[651]: info: [1644090393968] CoreMusicLibrary::Adding element Webradio Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 05 12:46:33 volumio-main volumio[651]: Cannot find translation for sourceMedia Servers Feb 05 12:46:33 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 05 12:46:33 volumio-main volumio[651]: info: GPIO-Buttons: Reading config and creating triggers... Feb 05 12:46:33 volumio-main volumio[651]: info: GPIO-Buttons: playPause on pin 5 Feb 05 12:46:33 volumio-main volumio[651]: info: GPIO-Buttons: volumeUp on pin 2 Feb 05 12:46:34 volumio-main volumio[651]: info: GPIO-Buttons: volumeDown on pin 4 Feb 05 12:46:34 volumio-main volumio[651]: info: GPIO-Buttons: previous on pin 8 Feb 05 12:46:34 volumio-main volumio[651]: info: GPIO-Buttons: next on pin 7 Feb 05 12:46:34 volumio-main volumio[651]: info: GPIO-Buttons: shutdown on pin 3 Feb 05 12:46:34 volumio-main volumio[651]: [SpotifyConnect] Creating VLS config file Feb 05 12:46:34 volumio-main smbd[903]: [2022/02/05 12:46:34.082693, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 05 12:46:34 volumio-main systemd[1]: Started Samba SMB Daemon. Feb 05 12:46:34 volumio-main smbd[903]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:34 volumio-main volumio[651]: [SpotifyConnect] Starting metadata listener Feb 05 12:46:34 volumio-main volumio[651]: info: Loading i18n strings for locale en Feb 05 12:46:34 volumio-main sudo[966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Feb 05 12:46:34 volumio-main sudo[966]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:34 volumio-main volumio[651]: info: Volumio Calling Home Feb 05 12:46:34 volumio-main systemd[1]: Started Volspotconnect2 Daemon. Feb 05 12:46:34 volumio-main sudo[966]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:34 volumio-main sudo[969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 05 12:46:34 volumio-main sudo[969]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:34 volumio-main systemd[1]: Reloading. Feb 05 12:46:34 volumio-main volumio[971]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 Feb 05 12:46:34 volumio-main volumio[971]: Reading Config from "volspotify.toml" Feb 05 12:46:34 volumio-main volumio[971]: [Vollibrespot] : Using Alsa backend with device: volumio Feb 05 12:46:34 volumio-main volumio[651]: info: GPIO-Buttons started Feb 05 12:46:34 volumio-main volumio[651]: info: [now-playing-app] App is listening on port 4004. Feb 05 12:46:34 volumio-main volumio[971]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Feb 05 12:46:34 volumio-main volumio[651]: [SpotifyConnect] Vollibrespot Daemon service started! Feb 05 12:46:34 volumio-main volumio[651]: [Metrics] SpotifyConnect: 0s 392.62ms Feb 05 12:46:34 volumio-main volumio[651]: info: MPD Permissions set Feb 05 12:46:34 volumio-main 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. Feb 05 12:46:34 volumio-main volumio[651]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:46:34 volumio-main volumio[651]: info: Starting Shairport Sync Feb 05 12:46:34 volumio-main volumio[651]: info: Starting Shairport Sync Feb 05 12:46:34 volumio-main sudo[994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 05 12:46:34 volumio-main sudo[994]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:34 volumio-main volumio[651]: info: Starting Shairport Sync Feb 05 12:46:34 volumio-main systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Feb 05 12:46:34 volumio-main sudo[998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 05 12:46:34 volumio-main sudo[999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 05 12:46:34 volumio-main sudo[998]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:34 volumio-main mpd[925]: Feb 05 12:46 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 05 12:46:34 volumio-main sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:34 volumio-main volumio[651]: info: Volumio called home Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 12:46:34 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 12:46:34 volumio-main sudo[969]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:34 volumio-main volumio[651]: info: touch_display: systemctl daemon-reload succeeded. Feb 05 12:46:34 volumio-main systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 05 12:46:34 volumio-main systemd[1]: shairport-sync.service: Succeeded. Feb 05 12:46:34 volumio-main systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 05 12:46:34 volumio-main sudo[1003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 05 12:46:34 volumio-main sudo[1003]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:34 volumio-main sudo[998]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:34 volumio-main systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 05 12:46:34 volumio-main sudo[999]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:34 volumio-main mpd[925]: Feb 05 12:46 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Feb 05 12:46:34 volumio-main mpd[925]: Feb 05 12:46 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Feb 05 12:46:34 volumio-main sudo[994]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:34 volumio-main volumio[651]: info: Shairport-Sync Started Feb 05 12:46:34 volumio-main systemd[1]: Started Music Player Daemon. Feb 05 12:46:34 volumio-main volumio[651]: Error adding Membership: Error: addMembership EINVAL Feb 05 12:46:34 volumio-main sudo[908]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:34 volumio-main volumio[651]: info: Shairport-Sync Started Feb 05 12:46:34 volumio-main volumio[651]: info: Shairport-Sync Started Feb 05 12:46:34 volumio-main systemd[1]: Started Volumio Kiosk. Feb 05 12:46:34 volumio-main systemd[1]: Reached target Multi-User System. Feb 05 12:46:34 volumio-main systemd[1]: Reached target Graphical Interface. Feb 05 12:46:34 volumio-main volumio[651]: error: updateQueue error: null Feb 05 12:46:34 volumio-main systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 05 12:46:34 volumio-main sudo[1003]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:34 volumio-main volumio[651]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 05 12:46:34 volumio-main volumio[651]: info: touch_display: Volumio Kiosk started Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 12:46:34 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 12:46:34 volumio-main systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 05 12:46:34 volumio-main systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 05 12:46:34 volumio-main systemd[1]: Startup finished in 9.494s (kernel) + 21.569s (userspace) = 31.063s. Feb 05 12:46:34 volumio-main volumio[651]: info: Completed starting Core Plugins Feb 05 12:46:34 volumio-main volumio[651]: info: ------------------------------------------- Feb 05 12:46:34 volumio-main volumio[651]: info: ----- MyVolumio plugins startup ---- Feb 05 12:46:34 volumio-main volumio[651]: info: ------------------------------------------- Feb 05 12:46:34 volumio-main volumio[651]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 05 12:46:34 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 12:46:34 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 12:46:34 volumio-main volumio[651]: info: touch_display: Raspberry Pi Foundation touch screen detected. Feb 05 12:46:34 volumio-main volumio[651]: info: touch_display: Backlight module of a Raspberry Pi Foundation touch screen detected. Feb 05 12:46:34 volumio-main volumio[651]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X Feb 05 12:46:35 volumio-main sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/devices/platform/rpi_backlight/backlight/rpi_backlight/brightness Feb 05 12:46:35 volumio-main sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:35 volumio-main sudo[1040]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:35 volumio-main volumio[651]: info: touch_display: File permissions for backlight brightness control set. Feb 05 12:46:35 volumio-main sudo[1032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Option "TransformationMatrix"/d /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 05 12:46:35 volumio-main sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:35 volumio-main sudo[1032]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:35 volumio-main volumio[651]: info: touch_display: Touchscreen transformation matrix removed. Feb 05 12:46:35 volumio-main startx[1008]: X.Org X Server 1.20.4 Feb 05 12:46:35 volumio-main startx[1008]: X Protocol Version 11, Revision 0 Feb 05 12:46:35 volumio-main startx[1008]: Build Operating System: Linux 5.4.0-72-generic armv8l Raspbian Feb 05 12:46:35 volumio-main startx[1008]: Current Operating System: Linux volumio-main 5.10.90-v7l+ #1512 SMP Thu Jan 13 16:33:39 GMT 2022 armv7l Feb 05 12:46:35 volumio-main startx[1008]: 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=E4:5F:01:5D:2B:B5 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh rootwait bootdelay=5 elevator=noop logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 loglevel=0 Feb 05 12:46:35 volumio-main startx[1008]: Build Date: 04 January 2022 10:05:34AM Feb 05 12:46:35 volumio-main startx[1008]: xorg-server 2:1.20.4-1+rpt4+deb10u4 (https://www.debian.org/support) Feb 05 12:46:35 volumio-main startx[1008]: Current version of pixman: 0.36.0 Feb 05 12:46:35 volumio-main startx[1008]: Before reporting problems, check http://wiki.x.org Feb 05 12:46:35 volumio-main startx[1008]: to make sure that you have the latest version. Feb 05 12:46:35 volumio-main startx[1008]: Markers: (--) probed, (**) from config file, (==) default setting, Feb 05 12:46:35 volumio-main startx[1008]: (++) from command line, (!!) notice, (II) informational, Feb 05 12:46:35 volumio-main startx[1008]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Feb 05 12:46:35 volumio-main startx[1008]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Feb 5 12:46:35 2022 Feb 05 12:46:35 volumio-main volumio[651]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 05 12:46:35 volumio-main startx[1008]: (==) Using config directory: "/etc/X11/xorg.conf.d" Feb 05 12:46:35 volumio-main startx[1008]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Feb 05 12:46:35 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 12:46:35 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 12:46:35 volumio-main volumio[651]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 05 12:46:36 volumio-main volumio[651]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 05 12:46:36 volumio-main kernel: cam-dummy-reg: disabling Feb 05 12:46:36 volumio-main kernel: cam1-reg: disabling Feb 05 12:46:38 volumio-main sudo[1129]: 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 //10.0.2.15/Music/Techno /mnt/NAS/Techno Feb 05 12:46:38 volumio-main sudo[1129]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:38 volumio-main kernel: CIFS: Attempting to mount //10.0.2.15/Music/Techno Feb 05 12:46:38 volumio-main sudo[1132]: 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 //10.0.2.15/Music/Society of Sound /mnt/NAS/Society_of_Sound Feb 05 12:46:38 volumio-main sudo[1132]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:38 volumio-main kernel: CIFS: Attempting to mount //10.0.2.15/Music/Society of Sound Feb 05 12:46:38 volumio-main sudo[1140]: 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 //10.0.2.15/Music/Classical /mnt/NAS/Classical Feb 05 12:46:38 volumio-main sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:38 volumio-main sudo[1149]: 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 //10.0.2.15/Music/Various /mnt/NAS/Various Feb 05 12:46:38 volumio-main sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:38 volumio-main kernel: CIFS: Attempting to mount //10.0.2.15/Music/Classical Feb 05 12:46:38 volumio-main kernel: CIFS: Attempting to mount //10.0.2.15/Music/Various Feb 05 12:46:38 volumio-main sudo[1159]: 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 //10.0.2.15/Music/MP3 /mnt/NAS/MP3 Feb 05 12:46:38 volumio-main sudo[1159]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:38 volumio-main sudo[1167]: 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 //10.0.2.15/Music/FLAC /mnt/NAS/FLAC Feb 05 12:46:38 volumio-main kernel: CIFS: Attempting to mount //10.0.2.15/Music/MP3 Feb 05 12:46:38 volumio-main sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:38 volumio-main kernel: CIFS: Attempting to mount //10.0.2.15/Music/FLAC Feb 05 12:46:38 volumio-main sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 05 12:46:38 volumio-main sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:38 volumio-main sudo[1176]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:38 volumio-main kernel: FS-Cache: Duplicate cookie detected Feb 05 12:46:38 volumio-main kernel: FS-Cache: O-cookie c=0d390003 [p=bed87de9 fl=222 nc=0 na=1] Feb 05 12:46:38 volumio-main kernel: FS-Cache: O-cookie d=bd950baf n=fad1c015 Feb 05 12:46:38 volumio-main kernel: FS-Cache: O-key=[5] '4d75736963' Feb 05 12:46:38 volumio-main kernel: FS-Cache: N-cookie c=b2ae163b [p=bed87de9 fl=2 nc=0 na=1] Feb 05 12:46:38 volumio-main kernel: FS-Cache: N-cookie d=bd950baf n=2fca9b34 Feb 05 12:46:38 volumio-main kernel: FS-Cache: N-key=[5] '4d75736963' Feb 05 12:46:38 volumio-main sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 05 12:46:38 volumio-main sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:38 volumio-main kernel: FS-Cache: Duplicate cookie detected Feb 05 12:46:38 volumio-main kernel: FS-Cache: O-cookie c=0d390003 [p=bed87de9 fl=222 nc=0 na=1] Feb 05 12:46:38 volumio-main kernel: FS-Cache: O-cookie d=bd950baf n=fad1c015 Feb 05 12:46:38 volumio-main kernel: FS-Cache: O-key=[5] '4d75736963' Feb 05 12:46:38 volumio-main kernel: FS-Cache: N-cookie c=d92332ab [p=bed87de9 fl=2 nc=0 na=1] Feb 05 12:46:38 volumio-main kernel: FS-Cache: N-cookie d=bd950baf n=ba58640b Feb 05 12:46:38 volumio-main kernel: FS-Cache: N-key=[5] '4d75736963' Feb 05 12:46:38 volumio-main sudo[1179]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:38 volumio-main kernel: FS-Cache: Duplicate cookie detected Feb 05 12:46:38 volumio-main kernel: FS-Cache: O-cookie c=0d390003 [p=bed87de9 fl=222 nc=0 na=1] Feb 05 12:46:38 volumio-main kernel: FS-Cache: O-cookie d=bd950baf n=fad1c015 Feb 05 12:46:38 volumio-main kernel: FS-Cache: O-key=[5] '4d75736963' Feb 05 12:46:38 volumio-main kernel: FS-Cache: N-cookie c=d92332ab [p=bed87de9 fl=2 nc=0 na=1] Feb 05 12:46:38 volumio-main kernel: FS-Cache: N-cookie d=bd950baf n=ffca1f2a Feb 05 12:46:38 volumio-main kernel: FS-Cache: N-key=[5] '4d75736963' Feb 05 12:46:38 volumio-main sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 05 12:46:38 volumio-main sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:38 volumio-main kernel: FS-Cache: Duplicate cookie detected Feb 05 12:46:38 volumio-main kernel: FS-Cache: O-cookie c=0d390003 [p=bed87de9 fl=222 nc=0 na=1] Feb 05 12:46:38 volumio-main kernel: FS-Cache: O-cookie d=bd950baf n=fad1c015 Feb 05 12:46:38 volumio-main kernel: FS-Cache: O-key=[5] '4d75736963' Feb 05 12:46:38 volumio-main kernel: FS-Cache: N-cookie c=d92332ab [p=bed87de9 fl=2 nc=0 na=1] Feb 05 12:46:38 volumio-main kernel: FS-Cache: N-cookie d=bd950baf n=8a418174 Feb 05 12:46:38 volumio-main kernel: FS-Cache: N-key=[5] '4d75736963' Feb 05 12:46:38 volumio-main kernel: FS-Cache: Duplicate cookie detected Feb 05 12:46:38 volumio-main kernel: FS-Cache: O-cookie c=0d390003 [p=bed87de9 fl=222 nc=0 na=1] Feb 05 12:46:38 volumio-main kernel: FS-Cache: O-cookie d=bd950baf n=fad1c015 Feb 05 12:46:38 volumio-main kernel: FS-Cache: O-key=[5] '4d75736963' Feb 05 12:46:38 volumio-main kernel: FS-Cache: N-cookie c=d92332ab [p=bed87de9 fl=2 nc=0 na=1] Feb 05 12:46:38 volumio-main kernel: FS-Cache: N-cookie d=bd950baf n=34f5fd1c Feb 05 12:46:38 volumio-main kernel: FS-Cache: N-key=[5] '4d75736963' Feb 05 12:46:38 volumio-main systemd[1]: Started UPnP Renderer front-end to MPD. Feb 05 12:46:38 volumio-main sudo[1149]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:38 volumio-main sudo[1159]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:38 volumio-main sudo[1167]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:38 volumio-main sudo[1140]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:38 volumio-main sudo[1129]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:38 volumio-main sudo[1132]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:38 volumio-main sudo[1182]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:38 volumio-main volumio[651]: info: Upmpdcli Daemon Started Feb 05 12:46:38 volumio-main volumio[1188]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 05 12:46:39 volumio-main volumio-remote-updater[451]: [2022-02-05 12:46:39] [connect] Successful connection Feb 05 12:46:39 volumio-main volumio-remote-updater[451]: [2022-02-05 12:46:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1644090399 101 Feb 05 12:46:39 volumio-main volumio[651]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 4 Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 05 12:46:43 volumio-main volumio[651]: info: Adding plugin bluetooth to MyMusic Plugins Feb 05 12:46:43 volumio-main volumio[651]: info: Adding plugin multiroom to MyMusic Plugins Feb 05 12:46:43 volumio-main volumio[651]: info: Adding plugin cd_controller to MyMusic Plugins Feb 05 12:46:43 volumio-main volumio[651]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 05 12:46:43 volumio-main volumio[651]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 05 12:46:43 volumio-main volumio[651]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 05 12:46:43 volumio-main volumio[1188]: ....................................................++++ Feb 05 12:46:44 volumio-main volumio[651]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 05 12:46:44 volumio-main volumio[651]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 05 12:46:44 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:46:44 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:46:44 volumio-main volumio[651]: info: Starting MyVolumio Remote Streaming Endpoints Feb 05 12:46:44 volumio-main volumio[651]: info: MyVolumio login type: Token Feb 05 12:46:44 volumio-main volumio[651]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 05 12:46:44 volumio-main volumio[651]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 05 12:46:44 volumio-main volumio[651]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 05 12:46:44 volumio-main volumio[651]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 05 12:46:44 volumio-main volumio[651]: info: Streaming services startup Feb 05 12:46:44 volumio-main volumio[651]: info: Starting Streaming Daemon Feb 05 12:46:44 volumio-main sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 05 12:46:44 volumio-main sudo[1325]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:44 volumio-main systemd[1]: Stopping Volumio Streaming Daemon... Feb 05 12:46:44 volumio-main systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Feb 05 12:46:44 volumio-main systemd[1]: volumio-streaming-daemon.service: Succeeded. Feb 05 12:46:44 volumio-main systemd[1]: Stopped Volumio Streaming Daemon. Feb 05 12:46:44 volumio-main systemd[1]: Started Volumio Streaming Daemon. Feb 05 12:46:44 volumio-main sudo[1325]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:44 volumio-main volumio-streaming-daemon[1331]: ############################ Feb 05 12:46:44 volumio-main volumio-streaming-daemon[1331]: # Volumio Streaming Daemon # Feb 05 12:46:44 volumio-main volumio-streaming-daemon[1331]: # Running on port 7777 # Feb 05 12:46:44 volumio-main volumio-streaming-daemon[1331]: ############################ Feb 05 12:46:44 volumio-main volumio-streaming-daemon[1331]: Environment: production Feb 05 12:46:44 volumio-main volumio[651]: error: [now-playing-app] App error: Feb 05 12:46:44 volumio-main volumio[651]: verbose: New Socket.io Connection to localhost: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: 5 Feb 05 12:46:44 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 12:46:44 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 12:46:44 volumio-main volumio[651]: error: MyVolumio Custom Token format not valid, refreshing it Feb 05 12:46:45 volumio-main volumio[651]: info: MyVolumio login type: Token Feb 05 12:46:45 volumio-main volumio[651]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 05 12:46:45 volumio-main volumio[651]: info: MYVOLUMIO: Adding device Feb 05 12:46:45 volumio-main volumio[651]: info: MYVOLUMIO: Evaluating Server Feb 05 12:46:45 volumio-main volumio[651]: info: MyVolumio status changed Feb 05 12:46:45 volumio-main volumio[651]: info: Streaming services startup Feb 05 12:46:45 volumio-main volumio[651]: info: Re-Starting Streaming Daemon Feb 05 12:46:45 volumio-main volumio[651]: info: Removing browser output: myVolumio user plan is not superstar Feb 05 12:46:45 volumio-main volumio[651]: info: Removing audio output: Feb 05 12:46:45 volumio-main volumio[651]: info: Stoppping Tunnel 1 Feb 05 12:46:46 volumio-main volumio-streaming-daemon[1331]: Restarting daemon Feb 05 12:46:46 volumio-main volumio-streaming-daemon[1331]: Environment: production Feb 05 12:46:46 volumio-main sudo[1473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 05 12:46:46 volumio-main sudo[1473]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 12:46:46 volumio-main sudo[1473]: pam_unix(sudo:session): session closed for user root Feb 05 12:46:46 volumio-main volumio[651]: info: Remote SSH Stopped Feb 05 12:46:46 volumio-main volumio[651]: info: Setting Geolocation for MyVolumio to us2 Feb 05 12:46:46 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:46:46 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:46:46 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:46:46 volumio-main volumio-streaming-daemon[1331]: Environment: production Feb 05 12:46:46 volumio-main volumio[651]: info: Fetching Streaming Services browse cache Feb 05 12:46:47 volumio-main volumio-streaming-daemon[1331]: Environment: production Feb 05 12:46:47 volumio-main volumio[651]: info: Fetching Streaming Services browse cache Feb 05 12:46:47 volumio-main volumio[651]: info: Updating MyVolumio device info Feb 05 12:46:47 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:46:47 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:46:47 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:46:49 volumio-main systemd[1]: systemd-fsckd.service: Succeeded. Feb 05 12:46:52 volumio-main volumio[1188]: .............................................................................................++++ Feb 05 12:46:52 volumio-main volumio[1188]: e is 65537 (0x010001) Feb 05 12:46:52 volumio-main volumio[1188]: writing RSA key Feb 05 12:46:57 volumio-main systemd[1]: systemd-hostnamed.service: Succeeded. Feb 05 12:47:04 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 05 12:47:04 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:47:04 volumio-main volumio[651]: error: MyVolumio Plugin failed to authenticate in a timely fashion Feb 05 12:47:04 volumio-main volumio[651]: info: Completed starting MyVolumio Plugin Feb 05 12:47:04 volumio-main volumio[651]: info: BOOT COMPLETED Feb 05 12:47:04 volumio-main volumio[651]: [Metrics] CommandRouter: 37s 899.25ms Feb 05 12:47:04 volumio-main volumio[651]: info: CoreCommandRouter::volumiosetStartupVolume Feb 05 12:47:04 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 05 12:47:04 volumio-main volumio[651]: info: VolumeController:: Setting startup Volume 25 Feb 05 12:47:04 volumio-main volumio[651]: info: VolumeController::SetAlsaVolume25 Feb 05 12:47:04 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 05 12:47:04 volumio-main volumio[651]: info: CoreCommandRouter::Close All Modals sent Feb 05 12:47:04 volumio-main volumio[651]: info: CoreCommandRouter::Close All Modals sent Feb 05 12:47:04 volumio-main volumio[651]: info: CoreStateMachine::pushState Feb 05 12:47:04 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 12:47:04 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 05 12:47:04 volumio-main volumio[651]: info: CoreCommandRouter::volumioPushState Feb 05 12:47:05 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 05 12:47:05 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 05 12:47:05 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 05 12:47:28 volumio-main ntpd[622]: Soliciting pool server 44.190.6.254 Feb 05 12:47:28 volumio-main ntpd[622]: Soliciting pool server 38.229.58.9 Feb 05 12:47:29 volumio-main ntpd[622]: Soliciting pool server 74.6.168.73 Feb 05 12:47:29 volumio-main ntpd[622]: Soliciting pool server 45.15.168.96 Feb 05 12:47:29 volumio-main ntpd[622]: Soliciting pool server 24.229.44.105 Feb 05 12:47:30 volumio-main ntpd[622]: Soliciting pool server 38.229.59.9 Feb 05 12:47:30 volumio-main ntpd[622]: Soliciting pool server 216.218.254.202 Feb 05 12:47:30 volumio-main ntpd[622]: Soliciting pool server 216.229.4.66 Feb 05 12:47:30 volumio-main ntpd[622]: Soliciting pool server 23.92.64.226 Feb 05 12:47:31 volumio-main ntpd[622]: Soliciting pool server 38.229.62.9 Feb 05 12:47:31 volumio-main ntpd[622]: Soliciting pool server 159.89.86.140 Feb 05 12:47:31 volumio-main ntpd[622]: Soliciting pool server 107.172.201.10 Feb 05 12:47:31 volumio-main ntpd[622]: Soliciting pool server 138.68.201.49 Feb 05 12:47:32 volumio-main ntpd[622]: Soliciting pool server 148.100.77.81 Feb 05 12:47:32 volumio-main ntpd[622]: Soliciting pool server 2604:6600:0:42::cafe:beef Feb 05 12:47:32 volumio-main ntpd[622]: Soliciting pool server 216.240.36.24 Feb 05 12:47:34 volumio-main ntpd[622]: Soliciting pool server 162.159.200.1 Feb 05 18:54:04 volumio-main ntpd[622]: receive: Unexpected origin timestamp 0xe5a952d7.f101c9c8 does not match aorg 0000000000.00000000 from server@45.15.168.96 xmt 0xe5a9a8bc.929140e1 Feb 05 18:54:04 volumio-main ntpd[622]: receive: Unexpected origin timestamp 0xe5a952d7.f0fc1721 does not match aorg 0000000000.00000000 from server@148.100.77.81 xmt 0xe5a9a8bc.962045ac Feb 05 18:54:04 volumio-main ntpd[622]: receive: Unexpected origin timestamp 0xe5a952d7.f1032fd6 does not match aorg 0000000000.00000000 from server@38.229.58.9 xmt 0xe5a9a8bc.9881f8d6 Feb 05 18:54:40 volumio-main volumio[651]: info: mDNS: A device disapperared from network Feb 05 18:54:40 volumio-main volumio[651]: info: mDNS: Device volumio-main disapperared from network Feb 05 18:54:40 volumio-main volumio[651]: info: Discovery: adding c425a47d-2c78-4310-b049-a87f1cd945f0 Feb 05 18:54:40 volumio-main volumio[651]: info: mDNS: Found device Volumio-Main Feb 05 18:54:40 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 18:54:40 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 18:55:17 volumio-main sudo[1588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 05 18:55:17 volumio-main sudo[1588]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 18:55:17 volumio-main sudo[1588]: pam_unix(sudo:session): session closed for user root Feb 05 18:55:17 volumio-main sudo[1591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 05 18:55:17 volumio-main sudo[1591]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 18:55:17 volumio-main sudo[1591]: pam_unix(sudo:session): session closed for user root Feb 05 18:55:17 volumio-main volumio[651]: verbose: New Socket.io Connection to 10.0.2.51 from 10.0.2.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:97.0) Gecko/20100101 Firefox/97.0 Total Clients: 6 Feb 05 18:55:17 volumio-main sudo[1594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 05 18:55:17 volumio-main sudo[1594]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 18:55:17 volumio-main sudo[1594]: pam_unix(sudo:session): session closed for user root Feb 05 18:55:17 volumio-main sudo[1596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 05 18:55:17 volumio-main sudo[1596]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 18:55:17 volumio-main sudo[1596]: pam_unix(sudo:session): session closed for user root Feb 05 18:55:17 volumio-main volumio[651]: verbose: New Socket.io Connection to 10.0.2.51 from 10.0.2.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:97.0) Gecko/20100101 Firefox/97.0 Total Clients: 7 Feb 05 18:55:17 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 18:55:17 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 18:55:17 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 05 18:55:17 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetVisibleSources Feb 05 18:55:17 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 05 18:55:17 volumio-main volumio[651]: info: Listing playlists Feb 05 18:55:17 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 05 18:55:17 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 05 18:55:17 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 05 18:55:17 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 05 18:55:19 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 05 18:55:19 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 05 18:55:22 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 05 18:55:26 volumio-main volumio[651]: verbose: New Socket.io Connection to 10.0.2.51 from 10.0.2.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:97.0) Gecko/20100101 Firefox/97.0 Total Clients: 8 Feb 05 18:55:26 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 18:55:26 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 18:55:26 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetQueue Feb 05 18:55:26 volumio-main volumio[651]: info: CoreStateMachine::getQueue Feb 05 18:55:26 volumio-main volumio[651]: info: CorePlayQueue::getQueue Feb 05 18:55:26 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Feb 05 18:55:30 volumio-main volumio[651]: info: CALLMETHOD: system_controller system enableLiveLog true Feb 05 18:55:30 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Feb 05 18:55:30 volumio-main volumio[651]: info: Launching a new LiveLog session Feb 05 18:55:52 volumio-main volumio[971]: [Vollibrespot] : Connecting to AP "ap-guc3.spotify.com:443" Feb 05 18:55:52 volumio-main volumio[971]: [Vollibrespot] : Authenticated as "sakete00" ! Feb 05 18:55:52 volumio-main volumio[971]: [Vollibrespot] : Setting up new mixer: card:hw:1 mixer:SoftMaster index:0 Feb 05 18:55:52 volumio-main volumio[971]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Feb 05 18:55:52 volumio-main volumio[971]: [Vollibrespot] : Alsa Mixer info min: 0 (MilliBel(0)[dB]) -- max: 99 (MilliBel(0)[dB]) HW: false Feb 05 18:55:52 volumio-main volumio[971]: [Vollibrespot] : Using Alsa linear volume Feb 05 18:55:52 volumio-main volumio[971]: [Vollibrespot] : Alsa min-db is not SND_CTL_TLV_DB_GAIN_MUTE!! Feb 05 18:55:52 volumio-main volumio[971]: [Vollibrespot] : Using alsa sink Feb 05 18:55:52 volumio-main volumio[971]: [Vollibrespot] : Metadata pipe established Feb 05 18:55:52 volumio-main volumio[971]: [Vollibrespot] : Country: "US" Feb 05 18:55:52 volumio-main volumio[971]: [Vollibrespot] : Event: Volume { volume_to_mixer: 16383 } Feb 05 18:55:52 volumio-main volumio[651]: [SpotifyConnect] 24.998855573357748 Feb 05 18:55:52 volumio-main volumio[651]: [SpotifyConnect] Volume: Spotify:24.998855573357748 Volumio: 25 Feb 05 18:55:52 volumio-main volumio[651]: info: CoreStateMachine::pushState Feb 05 18:55:52 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 18:55:52 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 05 18:55:52 volumio-main volumio[651]: info: CoreCommandRouter::volumioPushState Feb 05 18:55:53 volumio-main volumio[971]: [Vollibrespot] : Event: SessionActive { became_active_at: 1644112553179 } Feb 05 18:55:53 volumio-main volumio[971]: [Vollibrespot] : SessionActive! Feb 05 18:55:53 volumio-main volumio[971]: [Vollibrespot] : Fetching autoplay context uri Feb 05 18:55:53 volumio-main volumio[971]: [Vollibrespot] : Event: PlaybackStarted { track_id: SpotifyId { id: 154206726208332202903071627080011995233, audio_type: Track } } Feb 05 18:55:53 volumio-main volumio[971]: [Vollibrespot] : Event: PlaybackLoading { track_id: SpotifyId { id: 154206726208332202903071627080011995233, audio_type: Track } } Feb 05 18:55:53 volumio-main volumio[651]: [SpotifyConnect] A connect session has begun Feb 05 18:55:53 volumio-main volumio[651]: [SpotifyConnect] Device palyback is active! Feb 05 18:55:53 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 18:55:53 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 18:55:53 volumio-main volumio[651]: [SpotifyConnect] Currently active: mpd Feb 05 18:55:53 volumio-main volumio[651]: [SpotifyConnect] Stopping currently active service Feb 05 18:55:53 volumio-main volumio[651]: info: CoreCommandRouter::volumioStop Feb 05 18:55:53 volumio-main volumio[651]: info: CoreStateMachine::stop Feb 05 18:55:53 volumio-main volumio[651]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 05 18:55:53 volumio-main volumio[651]: verbose: UNSET VOLATILE Feb 05 18:55:53 volumio-main volumio[651]: [SpotifyConnect] Vollibrespot Active Feb 05 18:55:53 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 18:55:53 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 18:55:53 volumio-main volumio[651]: [SpotifyConnect] Currently active: mpd Feb 05 18:55:53 volumio-main volumio[651]: [SpotifyConnect] Setting Volatile state to Volspotconnect2 Feb 05 18:55:53 volumio-main volumio[651]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 05 18:55:53 volumio-main volumio[651]: info: CoreCommandRouter::servicePushState Feb 05 18:55:53 volumio-main volumio[651]: info: CoreStateMachine::pushState Feb 05 18:55:53 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 05 18:55:53 volumio-main volumio[651]: info: CoreCommandRouter::volumioPushState Feb 05 18:55:53 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 18:55:53 volumio-main volumio[651]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received volspotconnect2 Feb 05 18:55:53 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 18:55:53 volumio-main volumio[651]: [SpotifyConnect] Currently active: undefined Feb 05 18:55:53 volumio-main volumio[651]: [SpotifyConnect] Pushing new state :: true Feb 05 18:55:53 volumio-main volumio[651]: info: CoreCommandRouter::servicePushState Feb 05 18:55:53 volumio-main volumio[651]: info: CoreStateMachine::pushState Feb 05 18:55:53 volumio-main volumio[651]: info: CoreCommandRouter::volumioPushState Feb 05 18:55:53 volumio-main volumio[971]: [Vollibrespot] : No autoplay_uri found Feb 05 18:55:53 volumio-main volumio[971]: [Vollibrespot] : AutoplayError: MercuryError Feb 05 18:55:53 volumio-main volumio[651]: info: CorePlayQueue::getTrack 0 Feb 05 18:55:53 volumio-main volumio[651]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received volspotconnect2 Feb 05 18:55:53 volumio-main volumio[971]: [Vollibrespot] : Event: GotToken { token: Token { access_token: "BQB_RtNoKLy71yuKPzQE9LoDBfI__cLRgKcGg2WgpSa61EHTqzRMavFT_qAy9nu4ZD6kQ0Fy1W2kQNKrZIy2czSwlckZuC0FfE8FOimv0qxbv9UvjX1hiaVQFjRaTc2o0f5Tz-npE3_PLIdsZK4Ef8axC2nQhsKiOeY", expires_in: 3600, token_type: "Bearer", scope: ["streaming", "user-read-playback-state", "user-modify-playback-state", "user-read-currently-playing", "user-read-private"] } } Feb 05 18:55:53 volumio-main volumio[971]: [Vollibrespot] : Loading with Spotify URI Feb 05 18:55:53 volumio-main volumio[651]: [SpotifyConnect] BQB_RtNoKLy71yuKPzQE9LoDBfI__cLRgKcGg2WgpSa61EHTqzRMavFT_qAy9nu4ZD6kQ0Fy1W2kQNKrZIy2czSwlckZuC0FfE8FOimv0qxbv9UvjX1hiaVQFjRaTc2o0f5Tz-npE3_PLIdsZK4Ef8axC2nQhsKiOeY Feb 05 18:55:53 volumio-main volumio[971]: [Vollibrespot] : Reducing normalisation factor to prevent clipping. Please add negative pregain to avoid. Feb 05 18:55:53 volumio-main volumio[651]: [SpotifyConnect] Setting VLS device_id: 083f0b0b5145216a533cfe423c02ff0f5b3986c7 Feb 05 18:55:53 volumio-main volumio[971]: [Vollibrespot] : (577500 ms) loaded Feb 05 18:55:53 volumio-main volumio[971]: [Vollibrespot] : Event: SinkActive Feb 05 18:55:53 volumio-main volumio[651]: [SpotifyConnect] Sink acquired Feb 05 18:55:53 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 18:55:53 volumio-main volumio[651]: [SpotifyConnect] Currently active: undefined Feb 05 18:55:53 volumio-main volumio[651]: [SpotifyConnect] Pushing new state :: true Feb 05 18:55:53 volumio-main volumio[651]: info: CoreCommandRouter::servicePushState Feb 05 18:55:53 volumio-main volumio[651]: info: CoreStateMachine::pushState Feb 05 18:55:53 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 05 18:55:53 volumio-main volumio[651]: info: CoreCommandRouter::volumioPushState Feb 05 18:55:53 volumio-main volumio[651]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 05 18:55:53 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 18:55:53 volumio-main volumio[651]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 05 18:55:53 volumio-main volumio[651]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 05 18:56:05 volumio-main volumio[971]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 154206726208332202903071627080011995233, audio_type: Track }, position_ms: 16761 } Feb 05 18:56:05 volumio-main volumio[651]: [SpotifyConnect] pause Feb 05 18:56:05 volumio-main volumio[651]: [SpotifyConnect] Roses Are Blue Feb 05 18:56:05 volumio-main volumio[651]: [SpotifyConnect] Pushing metadata Feb 05 18:56:05 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 18:56:05 volumio-main volumio[651]: [SpotifyConnect] Currently active: volspotconnect2 Feb 05 18:56:05 volumio-main volumio[651]: [SpotifyConnect] Pushing new state :: true Feb 05 18:56:05 volumio-main volumio[651]: info: CoreCommandRouter::servicePushState Feb 05 18:56:05 volumio-main volumio[651]: info: CoreStateMachine::pushState Feb 05 18:56:05 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 05 18:56:05 volumio-main volumio[651]: info: CoreCommandRouter::volumioPushState Feb 05 18:56:06 volumio-main volumio[971]: [Vollibrespot] : Event: SinkInactive Feb 05 18:56:06 volumio-main volumio[651]: [SpotifyConnect] Sink released Feb 05 18:56:06 volumio-main volumio[651]: info: CoreCommandRouter::servicePushState Feb 05 18:56:06 volumio-main volumio[971]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 154206726208332202903071627080011995233, audio_type: Track } } Feb 05 18:56:06 volumio-main volumio[651]: info: CoreStateMachine::pushState Feb 05 18:56:06 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 05 18:56:06 volumio-main volumio[651]: info: CoreCommandRouter::volumioPushState Feb 05 18:56:06 volumio-main volumio[651]: [SpotifyConnect] Device palyback is inactive Feb 05 18:56:06 volumio-main volumio[651]: [SpotifyConnect] Device is paused Feb 05 18:56:06 volumio-main volumio[651]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 05 18:56:06 volumio-main volumio[971]: [Vollibrespot] : Event: Play { track_id: SpotifyId { id: 154206726208332202903071627080011995233, audio_type: Track }, position_ms: 17154 } Feb 05 18:56:06 volumio-main volumio[651]: [SpotifyConnect] play Feb 05 18:56:06 volumio-main volumio[971]: [Vollibrespot] : Event: SinkActive Feb 05 18:56:06 volumio-main volumio[651]: [SpotifyConnect] Roses Are Blue Feb 05 18:56:06 volumio-main volumio[651]: [SpotifyConnect] Pushing metadata Feb 05 18:56:06 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 18:56:06 volumio-main volumio[651]: [SpotifyConnect] Currently active: volspotconnect2 Feb 05 18:56:06 volumio-main volumio[651]: [SpotifyConnect] Pushing new state :: true Feb 05 18:56:06 volumio-main volumio[651]: info: CoreCommandRouter::servicePushState Feb 05 18:56:06 volumio-main volumio[651]: info: CoreStateMachine::pushState Feb 05 18:56:06 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 05 18:56:06 volumio-main volumio[651]: info: CoreCommandRouter::volumioPushState Feb 05 18:56:06 volumio-main volumio[651]: [SpotifyConnect] Sink acquired Feb 05 18:56:06 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 18:56:06 volumio-main volumio[651]: [SpotifyConnect] Currently active: volspotconnect2 Feb 05 18:56:06 volumio-main volumio[651]: [SpotifyConnect] Pushing new state :: true Feb 05 18:56:06 volumio-main volumio[651]: info: CoreCommandRouter::servicePushState Feb 05 18:56:06 volumio-main volumio[651]: info: CoreStateMachine::pushState Feb 05 18:56:06 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 05 18:56:06 volumio-main volumio[651]: info: CoreCommandRouter::volumioPushState Feb 05 18:56:07 volumio-main volumio[651]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 05 18:56:35 volumio-main volumio[971]: ALSA lib pcm.c:8424:(snd_pcm_recover) underrun occurred Feb 05 18:56:42 volumio-main volumio[971]: ALSA lib pcm.c:8424:(snd_pcm_recover) underrun occurred Feb 05 18:57:35 volumio-main volumio[971]: ALSA lib pcm.c:8424:(snd_pcm_recover) underrun occurred Feb 05 18:59:11 volumio-main volumio[651]: info: CoreCommandRouter::volumioPause Feb 05 18:59:11 volumio-main volumio[651]: info: CoreStateMachine::pause Feb 05 18:59:11 volumio-main volumio[651]: info: CoreStateMachine::stPlaybackTimer Feb 05 18:59:11 volumio-main volumio[651]: info: CoreStateMachine::servicePause Feb 05 18:59:11 volumio-main volumio[651]: info: CoreCommandRouter::servicePause Feb 05 18:59:11 volumio-main volumio[651]: [SpotifyConnect] Received pause Feb 05 18:59:12 volumio-main volumio[971]: [Vollibrespot] : Event: Pause { track_id: SpotifyId { id: 154206726208332202903071627080011995233, audio_type: Track }, position_ms: 202453 } Feb 05 18:59:12 volumio-main volumio[651]: [SpotifyConnect] pause Feb 05 18:59:12 volumio-main volumio[651]: [SpotifyConnect] Roses Are Blue Feb 05 18:59:12 volumio-main volumio[651]: [SpotifyConnect] Pushing metadata Feb 05 18:59:12 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 18:59:12 volumio-main volumio[651]: [SpotifyConnect] Currently active: volspotconnect2 Feb 05 18:59:12 volumio-main volumio[651]: [SpotifyConnect] Pushing new state :: true Feb 05 18:59:12 volumio-main volumio[651]: info: CoreCommandRouter::servicePushState Feb 05 18:59:12 volumio-main volumio[651]: info: CoreStateMachine::pushState Feb 05 18:59:12 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 05 18:59:12 volumio-main volumio[651]: info: CoreCommandRouter::volumioPushState Feb 05 18:59:12 volumio-main volumio[971]: [Vollibrespot] : Event: SinkInactive Feb 05 18:59:12 volumio-main volumio[971]: [Vollibrespot] : Event: PlaybackStopped { track_id: SpotifyId { id: 154206726208332202903071627080011995233, audio_type: Track } } Feb 05 18:59:12 volumio-main volumio[651]: [SpotifyConnect] Sink released Feb 05 18:59:12 volumio-main volumio[651]: info: CoreCommandRouter::servicePushState Feb 05 18:59:12 volumio-main volumio[651]: info: CoreStateMachine::pushState Feb 05 18:59:12 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 05 18:59:12 volumio-main volumio[651]: info: CoreCommandRouter::volumioPushState Feb 05 18:59:12 volumio-main volumio[651]: [SpotifyConnect] Device palyback is inactive Feb 05 18:59:12 volumio-main volumio[651]: [SpotifyConnect] Device is paused Feb 05 18:59:12 volumio-main volumio[651]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 05 18:59:12 volumio-main volumio[651]: info: CoreCommandRouter::volumioGetState Feb 05 18:59:12 volumio-main volumio[651]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 05 18:59:12 volumio-main volumio[651]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 05 18:59:15 volumio-main volumio[651]: info: CALLMETHOD: system_controller system enableLiveLog false Feb 05 18:59:15 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Feb 05 18:59:15 volumio-main volumio[651]: info: Launching a new LiveLog session Feb 05 18:59:15 volumio-main volumio[651]: info: Live Log process terminated: null Feb 05 18:59:24 volumio-main volumio[651]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Feb 05 18:59:24 volumio-main volumio[651]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Feb 05 18:59:25 volumio-main sudo[1673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Feb 05 18:59:25 volumio-main sudo[1673]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 05 18:59:25 volumio-main sudo[1673]: pam_unix(sudo:session): session closed for user root Feb 05 18:59:31 volumio-main sudo[1716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Feb 05 18:59:31 volumio-main sudo[1716]: pam_unix(sudo:session): session opened for user root by (uid=0)