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)