Tue Jun 4 19:18:17 CEST 2024 Description="'Hello Everyone ! I'\''ve an RPi Zero 2W + Pirate Audio: Line-out. Volumio version : 3.695. Version of the plugin : 0.1.1. This setup worked perfectely few weeks but one day, the screen seems to be sticked on "please wait" and flirckling... web UI in working perfectly and sound in going out as expected... What can i do ? Thanks for help !'" # cat /proc/version --------------- Linux version 6.1.77-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1730 SMP Thu Feb 8 15:17:51 GMT 2024 # 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="ef589a0572b08c3d1e9a984ec6a655ef3698e415" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="abe72e92bc7de90ab6193cbc17e21ad2750980a2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 28 May 2024 12:17:12 PM CEST" VOLUMIO_VERSION="3.695" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8f37ff40efaaf3752fac55d6a95d61cb" # ifconfig --------------- lo: flags=73 mtu 65536 inet 127.0.0.1 netmask 255.0.0.0 loop txqueuelen 1000 (Local Loopback) RX packets 5901 bytes 1556241 (1.4 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 5901 bytes 1556241 (1.4 MiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 wlan0: flags=4163 mtu 1500 inet 192.168.1.34 netmask 255.255.255.0 broadcast 192.168.1.255 ether d8:3a:dd:8f:b1:d9 txqueuelen 1000 (Ethernet) RX packets 11349 bytes 12546414 (11.9 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 7044 bytes 955001 (932.6 KiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- lo no wireless extensions. wlan0 IEEE 802.11 ESSID:"Livebox-ARAKELOV" Mode:Managed Frequency:2.462 GHz Access Point: 9C:9D:7E:95:80:6D Bit Rate=43.3 Mb/s Tx-Power=31 dBm Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off Link Quality=45/70 Signal level=-65 dBm Rx invalid nwid:0 Rx invalid crypt:0 Rx invalid frag:0 Tx excessive retries:36 Invalid misc:0 Missed beacon:0 # aplay -l --------------- **** List of PLAYBACK Hardware Devices **** card 0: b1 [bcm2835 HDMI 1], device 0: bcm2835 HDMI 1 [bcm2835 HDMI 1] Subdevices: 4/4 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 card 0: b1 [bcm2835 HDMI 1], device 1: bcm2835 HDMI 1 [bcm2835 HDMI 1] Subdevices: 1/1 Subdevice #0: subdevice #0 card 1: Headphones [bcm2835 Headphones], device 0: bcm2835 Headphones [bcm2835 Headphones] Subdevices: 4/4 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 card 2: sndrpihifiberry [snd_rpi_hifiberry_dac], device 0: HifiBerry DAC HiFi pcm5102a-hifi-0 [HifiBerry DAC HiFi pcm5102a-hifi-0] Subdevices: 0/1 Subdevice #0: subdevice #0 # lsusb --------------- Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub # lsusb -t --------------- /: Bus 01.Port 1: Dev 1, Class=root_hub, Driver=dwc_otg/1p, 480M # lsusb -v --------------- Couldn't open device, some information will be missing Bus 001 Device 001: ID 1d6b:0002 Linux Foundation 2.0 root hub Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 2.00 bDeviceClass 9 Hub bDeviceSubClass 0 bDeviceProtocol 1 Single TT bMaxPacketSize0 64 idVendor 0x1d6b Linux Foundation idProduct 0x0002 2.0 root hub bcdDevice 6.01 iManufacturer 3 iProduct 2 iSerial 1 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x0019 bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0xe0 Self Powered Remote Wakeup MaxPower 0mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 1 bInterfaceClass 9 Hub bInterfaceSubClass 0 bInterfaceProtocol 0 Full speed (or root) hub iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0004 1x 4 bytes bInterval 12 # ps -ef --------------- UID PID PPID C STIME TTY TIME CMD root 1 0 1 19:11 ? 00:00:05 /sbin/init splash nodebug root 2 0 0 19:11 ? 00:00:00 [kthreadd] root 3 2 0 19:11 ? 00:00:00 [rcu_gp] root 4 2 0 19:11 ? 00:00:00 [rcu_par_gp] root 5 2 0 19:11 ? 00:00:00 [slub_flushwq] root 6 2 0 19:11 ? 00:00:00 [netns] root 7 2 0 19:11 ? 00:00:00 [kworker/0:0-events] root 10 2 0 19:11 ? 00:00:00 [mm_percpu_wq] root 11 2 0 19:11 ? 00:00:00 [rcu_tasks_rude_kthread] root 12 2 0 19:11 ? 00:00:00 [rcu_tasks_trace_kthread] root 13 2 0 19:11 ? 00:00:00 [ksoftirqd/0] root 14 2 0 19:11 ? 00:00:00 [rcu_sched] root 15 2 0 19:11 ? 00:00:00 [migration/0] root 16 2 0 19:11 ? 00:00:00 [cpuhp/0] root 17 2 0 19:11 ? 00:00:00 [cpuhp/1] root 18 2 0 19:11 ? 00:00:00 [migration/1] root 19 2 0 19:11 ? 00:00:00 [ksoftirqd/1] root 22 2 0 19:11 ? 00:00:00 [cpuhp/2] root 23 2 0 19:11 ? 00:00:00 [migration/2] root 24 2 0 19:11 ? 00:00:00 [ksoftirqd/2] root 27 2 0 19:11 ? 00:00:00 [cpuhp/3] root 28 2 0 19:11 ? 00:00:00 [migration/3] root 29 2 0 19:11 ? 00:00:00 [ksoftirqd/3] root 30 2 0 19:11 ? 00:00:00 [kworker/3:0-ipv6_addrconf] root 32 2 0 19:11 ? 00:00:00 [kdevtmpfs] root 33 2 0 19:11 ? 00:00:00 [inet_frag_wq] root 35 2 0 19:11 ? 00:00:00 [kauditd] root 36 2 0 19:11 ? 00:00:00 [khungtaskd] root 38 2 0 19:11 ? 00:00:00 [oom_reaper] root 39 2 0 19:11 ? 00:00:00 [writeback] root 40 2 0 19:11 ? 00:00:00 [kcompactd0] root 41 2 0 19:11 ? 00:00:00 [kintegrityd] root 42 2 0 19:11 ? 00:00:00 [kblockd] root 43 2 0 19:11 ? 00:00:00 [blkcg_punt_bio] root 44 2 0 19:11 ? 00:00:00 [watchdogd] root 45 2 0 19:11 ? 00:00:01 [kworker/u8:2-events_unbound] root 48 2 0 19:11 ? 00:00:00 [kworker/2:1H-kblockd] root 49 2 0 19:11 ? 00:00:00 [rpciod] root 50 2 0 19:11 ? 00:00:00 [xprtiod] root 51 2 0 19:11 ? 00:00:00 [kworker/u8:3-loop0] root 53 2 0 19:11 ? 00:00:00 [kswapd0] root 54 2 0 19:11 ? 00:00:00 [nfsiod] root 60 2 0 19:11 ? 00:00:00 [hwrng] root 61 2 0 19:11 ? 00:00:00 [iscsi_conn_clea] root 62 2 0 19:11 ? 00:00:00 [dwc_otg] root 63 2 0 19:11 ? 00:00:00 [DWC Notificatio] root 65 2 0 19:11 ? 00:00:00 [vchiq-slot/0] root 66 2 0 19:11 ? 00:00:00 [vchiq-recy/0] root 67 2 0 19:11 ? 00:00:00 [vchiq-sync/0] root 68 2 0 19:11 ? 00:00:00 [zswap-shrink] root 69 2 0 19:11 ? 00:00:01 [kworker/u9:0-brcmf_wq/mmc1:0001:1] root 89 2 0 19:11 ? 00:00:00 [mmc_complete] root 90 2 0 19:11 ? 00:00:00 [kworker/0:1H-mmc_complete] root 116 2 0 19:11 ? 00:00:00 [kworker/3:1H-kblockd] root 117 2 0 19:11 ? 00:00:00 [kworker/1:1H-kblockd] root 134 2 0 19:11 ? 00:00:00 [kworker/0:2H-kblockd] root 143 2 0 19:11 ? 00:00:00 [kworker/3:2H-kblockd] root 144 2 0 19:11 ? 00:00:00 [kworker/1:2H-kblockd] root 170 2 0 19:11 ? 00:00:00 [uas] root 191 2 0 19:11 ? 00:00:00 [jbd2/mmcblk0p2-8] root 192 2 0 19:11 ? 00:00:00 [ext4-rsv-conver] root 227 2 0 19:11 ? 00:00:00 [jbd2/mmcblk0p3-8] root 228 2 0 19:11 ? 00:00:00 [ext4-rsv-conver] root 262 2 0 19:11 ? 00:00:00 [mld] root 263 2 0 19:11 ? 00:00:00 [ipv6_addrconf] root 295 1 0 19:11 ? 00:00:01 /lib/systemd/systemd-journald root 308 2 0 19:11 ? 00:00:00 [kworker/2:2H-kblockd] root 311 2 0 19:11 ? 00:00:00 [kworker/0:3-events] root 324 1 0 19:11 ? 00:00:00 /lib/systemd/systemd-udevd root 345 2 0 19:11 ? 00:00:00 [vchiq-keep/0] root 347 2 0 19:11 ? 00:00:00 [SMIO] root 380 2 0 19:11 ? 00:00:00 [mmal-vchiq] root 381 2 0 19:11 ? 00:00:00 [mmal-vchiq] root 383 2 0 19:11 ? 00:00:00 [mmal-vchiq] root 385 2 0 19:11 ? 00:00:00 [mmal-vchiq] root 387 2 0 19:11 ? 00:00:00 [mmal-vchiq] root 388 2 0 19:11 ? 00:00:00 [mmal-vchiq] root 389 2 0 19:11 ? 00:00:00 [mmal-vchiq] root 420 2 0 19:11 ? 00:00:00 [cfg80211] root 439 2 0 19:12 ? 00:00:00 [brcmf_wq/mmc1:0] root 440 2 0 19:12 ? 00:00:00 [brcmf_wdog/mmc1:0001:1] root 452 2 0 19:12 ? 00:00:00 [kworker/u9:3-brcmf_wq/mmc1:0001:1] _rpc 475 1 0 19:12 ? 00:00:00 /sbin/rpcbind -f -w root 480 2 0 19:12 ? 00:00:00 [kworker/2:8-events_power_efficient] root 481 2 0 19:12 ? 00:00:00 [kworker/2:9-mm_percpu_wq] root 486 1 0 19:12 ? 00:00:01 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 root 508 1 0 19:12 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon root 509 2 0 19:12 ? 00:00:00 [kworker/3:3-mm_percpu_wq] root 514 1 0 19:12 ? 00:00:00 /lib/systemd/systemd-logind nobody 519 1 0 19:12 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* volumio 521 1 0 19:12 ? 00:00:00 /usr/local/bin/volumio-remote-updater root 524 1 0 19:12 ? 00:00:00 /bin/bash /bin/volumiologrotate avahi 529 1 0 19:12 ? 00:00:00 avahi-daemon: running [volumio.local] message+ 533 1 0 19:12 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 541 1 0 19:12 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant avahi 602 529 0 19:12 ? 00:00:00 avahi-daemon: chroot helper root 648 1 0 19:12 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap root 653 1 0 19:12 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 684 1 0 19:12 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux ntp 697 1 0 19:12 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 711 2 0 19:12 ? 00:00:00 [kworker/1:4-events] root 712 2 0 19:12 ? 00:00:00 [kworker/1:5-cgroup_destroy] root 714 653 0 19:12 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 778 1 0 19:12 ? 00:00:00 wpa_supplicant -s -B -Dnl80211,wext -c/etc/wpa_supplicant/wpa_supplicant.conf -iwlan0 root 794 1 0 19:12 ? 00:00:00 /sbin/dhcpcd volumio 854 1 8 19:12 ? 00:00:32 /usr/bin/node /volumio/index.js root 857 1 0 19:12 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 874 857 0 19:12 ? 00:00:00 winbindd: domain child [VOLUMIO] root 876 1 0 19:12 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 878 876 0 19:12 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 879 876 0 19:12 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 880 857 0 19:12 ? 00:00:00 winbindd: idmap child root 881 857 0 19:12 ? 00:00:00 winbindd: domain child [BUILTIN] root 883 876 0 19:12 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group volumio 913 854 0 19:12 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 920 913 0 19:12 ? 00:00:02 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 921 913 0 19:12 ? 00:00:02 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 922 913 0 19:12 ? 00:00:02 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart mpd 1003 1 5 19:12 ? 00:00:20 /usr/bin/mpd --systemd shairpo+ 1042 1 0 19:12 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf volumio 1056 1 1 19:12 ? 00:00:03 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf volumio 1079 854 0 19:12 ? 00:00:01 /usr/bin/node /myvolumio/plugins/music_service/streaming_services/streamingProxy.js root 1877 2 0 19:17 ? 00:00:00 [kworker/2:0-events] root 1981 2 0 19:18 ? 00:00:00 [kworker/0:1-events] root 2027 524 0 19:18 ? 00:00:00 sleep 60 root 2032 2 0 19:18 ? 00:00:00 [kworker/3:1-mm_percpu_wq] root 2045 2 0 19:18 ? 00:00:00 [kworker/1:0H] volumio 2057 854 0 19:18 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js 'Hello Everyone ! I'\''ve an RPi Zero 2W + Pirate Audio: Line-out. Volumio version : 3.695. Version of the plugin : 0.1.1. This setup worked perfectely few weeks but one day, the screen seems to be sticked on "please wait" and flirckling... web UI in working perfectly and sound in going out as expected... What can i do ? Thanks for help !' volumio 2058 2057 0 19:18 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js Hello Everyone ! I've an RPi Zero 2W + Pirate Audio: Line-out. Volumio version : 3.695. Version of the plugin : 0.1.1. This setup worked perfectely few weeks but one day, the screen seems to be sticked on "please wait" and flirckling... web UI in working perfectly and sound in going out as expected... What can i do ? Thanks for help ! volumio 2086 2058 0 19:18 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 2087 2086 0 19:18 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on udev 190M 0 190M 0% /dev tmpfs 46M 5.2M 41M 12% /run /dev/mmcblk0p2 2.5G 1.1G 1.3G 47% /imgpart /dev/loop0 478M 478M 0 100% /static overlay 26G 1.2G 24G 5% / tmpfs 230M 0 230M 0% /dev/shm tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 230M 0 230M 0% /sys/fs/cgroup /dev/mmcblk0p3 26G 1.2G 24G 5% /swap tmpfs 20M 28K 20M 1% /var/log tmpfs 230M 0 230M 0% /var/spool/cups tmpfs 230M 60K 230M 1% /tmp tmpfs 230M 0 230M 0% /var/spool/cups/tmp /dev/mmcblk0p1 92M 66M 26M 72% /boot # mount --------------- sysfs on /sys type sysfs (rw,nosuid,nodev,noexec,relatime) proc on /proc type proc (rw,relatime) udev on /dev type devtmpfs (rw,nosuid,relatime,size=194484k,nr_inodes=48621,mode=755) devpts on /dev/pts type devpts (rw,nosuid,noexec,relatime,gid=5,mode=620,ptmxmode=000) tmpfs on /run type tmpfs (rw,nosuid,nodev,noexec,relatime,size=46988k,mode=755) /dev/mmcblk0p2 on /imgpart type ext4 (rw,relatime) /dev/loop0 on /static type squashfs (ro,relatime,errors=continue) overlay on / type overlay (rw,relatime,lowerdir=/mnt/static,upperdir=/mnt/ext/dyn,workdir=/mnt/ext/work) securityfs on /sys/kernel/security type securityfs (rw,nosuid,nodev,noexec,relatime) tmpfs on /dev/shm type tmpfs (rw,nosuid,nodev,noexec) 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) pstore on /sys/fs/pstore type pstore (rw,nosuid,nodev,noexec,relatime) bpf on /sys/fs/bpf type bpf (rw,nosuid,nodev,noexec,relatime,mode=700) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) 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/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) cgroup on /sys/fs/cgroup/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=34,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) fusectl on /sys/fs/fuse/connections type fusectl (rw,relatime) configfs on /sys/kernel/config type configfs (rw,relatime) /dev/mmcblk0p3 on /swap type ext4 (rw,relatime) tmpfs on /var/log type tmpfs (rw,nodev,relatime,size=20480k,mode=777,uid=1000,gid=4) tmpfs on /var/spool/cups type tmpfs (rw,noatime,mode=755) tmpfs on /tmp type tmpfs (rw,noatime,mode=755) 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) # 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:111 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49152 0.0.0.0:* LISTEN 1056/upmpdcli tcp 0 0 0.0.0.0:139 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49149 0.0.0.0:* LISTEN 1056/upmpdcli tcp 0 0 0.0.0.0:5000 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:445 0.0.0.0:* LISTEN - tcp 0 0 127.0.0.1:58914 127.0.0.1:3000 ESTABLISHED 854/node tcp 0 0 192.168.1.34:49152 192.168.1.1:55494 FIN_WAIT2 - tcp 0 0 127.0.0.1:57594 127.0.0.1:6599 ESTABLISHED 1056/upmpdcli tcp 0 0 127.0.0.1:58898 127.0.0.1:3000 ESTABLISHED 521/volumio-remote- tcp 0 0 127.0.0.1:57598 127.0.0.1:6599 ESTABLISHED 1056/upmpdcli tcp 1 0 192.168.1.34:38266 173.194.10.169:443 CLOSE_WAIT - tcp 0 0 127.0.0.1:43952 127.0.0.1:6600 ESTABLISHED 854/node tcp 0 0 192.168.1.34:34220 34.120.206.254:443 ESTABLISHED 854/node tcp 0 0 127.0.0.1:48882 127.0.0.1:3000 ESTABLISHED 854/node tcp 0 0 127.0.0.1:43944 127.0.0.1:6600 ESTABLISHED 854/node tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::3245 :::* LISTEN 1079/node tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::6599 :::* LISTEN 854/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::3000 :::* LISTEN 854/node tcp6 0 0 :::3001 :::* LISTEN 913/node tcp6 0 0 :::3005 :::* LISTEN 854/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 192.168.1.34:3000 192.168.1.30:44102 ESTABLISHED 854/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:48882 ESTABLISHED 854/node tcp6 0 0 192.168.1.34:3000 192.168.1.65:34926 ESTABLISHED 854/node tcp6 0 0 192.168.1.34:3000 192.168.1.65:34924 TIME_WAIT - tcp6 0 0 192.168.1.34:3000 192.168.1.65:34919 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:58914 ESTABLISHED 854/node tcp6 0 0 192.168.1.34:3000 192.168.1.30:44148 ESTABLISHED 854/node tcp6 0 0 127.0.0.1:6600 127.0.0.1:43944 ESTABLISHED - tcp6 0 0 192.168.1.34:3000 192.168.1.65:34923 TIME_WAIT - tcp6 0 0 127.0.0.1:3000 127.0.0.1:58898 ESTABLISHED 854/node tcp6 0 0 127.0.0.1:6600 127.0.0.1:43952 ESTABLISHED - tcp6 0 0 127.0.0.1:6599 127.0.0.1:57598 ESTABLISHED 854/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:57594 ESTABLISHED 854/node tcp6 0 0 192.168.1.34:3000 192.168.1.65:34536 TIME_WAIT - # cat /boot/config.txt --------------- ### DO NOT EDIT THIS FILE ### ### APPLY CUSTOM PARAMETERS TO userconfig.txt ### initramfs volumio.initrd gpu_mem=128 gpu_mem_256=32 gpu_mem_512=32 gpu_mem_1024=128 max_usb_current=1 [pi5] usb_max_current_enable=1 [all] include volumioconfig.txt include userconfig.txt #### Volumio i2s setting below: do not alter #### dtoverlay=hifiberry-dac # cat /boot/userconfig.txt --------------- # Add your custom config.txt options to this file, which will be preserved during updates # 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 2 device 0 } max_dB 0.0 min_dB -50.0 resolution 100 } pcm.postVolume { type empty slave.pcm "volumioOutput" } # There is always a plug before the hardware to be safe pcm.volumioOutput { type plug slave.pcm "volumioHw" } pcm.volumioHw { type hw card "sndrpihifiberry" } # cat /var/log/mpd.log --------------- Jun 04 19:12 : zeroconf: No global port, disabling zeroconf Jun 04 19:12 : exception: No such directory Jun 04 19:12 : ffmpeg/matroska,webm: Read error Jun 04 19:12 : player: played "https://rr2---sn-2gb7snez.googlevideo.com/videoplayback?expire=1717542775&ei=F0tfZvC8F7aEp-oP7Yn2gAw&ip=82.65.93.89&id=o-AJayXpT637OdClmUSPX9VlUJFpvirwbQSxIacUUsLPsl&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=vc&mm=31%2C29&mn=sn-2gb7snez%2Csn-25glenld&ms=au%2Crdu&mv=m&mvi=2&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2295000&hightc=yes&siu=1&bui=AbKP-1NdaD4YWevJsKAvHaMNs5It_SYcDvxgVOxD8yR6BP53MIU-lEJTWcrgsgU4DKBiWfgiqg&spc=UWF9f5IXR00Q4uotzUIovknOY7rR_cpUhEAPxZxapLLlKUYuMvsSolUxu7Vf&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yI_XUFi2H9ORQhHO_ajb8CcQ&rqh=1&gir=yes&clen=17743595&dur=536.301&lmt=1714707010369793&mt=1717520697&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=HafEX-EnbaA4yg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIhAIT8J5uby82QyZg9-TRlXrPDZsZ655m4a8UU_wLz8gxyAiBsez6zQMWbgYCsUHVXpsH6OeEDim6RTUlsACpEKKS01A%3D%3D&sig=AJfQdSswRAIgLQdebbf6CUbrnU57mwTVgCdsWF4dznoWQwAhEyFYBpECIE21zxkxmJdJOE4WRule-OUZA6xrvAZ_wFZpx2Cd8NlB&cver=1.20211213.00.00&t.YouTube" Jun 04 19:12 : alsa_output: Decoder is too slow; playing silence to avoid xrun Jun 04 19:13 : alsa_output: Decoder is too slow; playing silence to avoid xrun # cat /tmp/camilladsp.log --------------- cat: /tmp/camilladsp.log: No such file or directory # cat /tmp/cec-client.log --------------- cat: /tmp/cec-client.log: No such file or directory # volumio plugin list --------------- This command will list installed plugins on your device ----------------------- Installed Plugins: [ { prettyName: 'YouTube Music', name: 'ytmusic', category: 'music_service', version: '1.0.2', icon: 'fa-youtube-play', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'AutoStart', name: 'autostart', category: 'system_controller', version: '3.0.4', icon: 'fa-lightbulb-o', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'Backup & Restore Data', name: 'backup_restore', category: 'system_controller', version: '0.7.4', icon: 'fa-floppy-o', isManuallyInstalled: false, enabled: true, active: true }, { prettyName: 'Pirate Audio', name: 'pirateaudio', category: 'system_hardware', version: '0.1.1', icon: 'fa-television', 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": "STARTED" } }, "ytmusic": { "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" } } }, "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" } } }, "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" } }, "autostart": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "backup_restore": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "system_hardware": { "pirateaudio": { "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" } audio_output { type "fifo" enabled "no" name "multiroom" path "/tmp/snapfifo" format "44100:16:2" } #replaygain "album" #replaygain_preamp "0" volume_normalization "no" ############################################################################### # MPD Internal Buffering ###################################################### audio_buffer_size "2048" ############################################################################### # Resource Limitations ######################################################## #connection_timeout "60" max_connections "20" max_playlist_length "81920" max_command_list_size "81920" max_output_buffer_size "81920" ############################################################################### # Character Encoding ########################################################## filesystem_charset "UTF-8" ############################################################################### # volumio endpointstest --------------- TESTING REMOTE ENDPOINTS https://google.com, 606 ms: OK https://www.googleapis.com, 506 ms: OK https://securetoken.googleapis.com, 482 ms: OK https://myvolumio.firebaseio.com, 550 ms: OK https://functions.volumio.cloud, 505 ms: OK https://oauth-performer.dfs.volumio.org, 432 ms: OK https://browsing-performer.dfs.volumio.org, 559 ms: OK http://cddb.volumio.org, 767 ms: OK https://functions.volumio.cloud, 579 ms: OK http://pushupdates.volumio.org, 416 ms: OK http://plugins.volumio.org, 896 ms: OK https://database.volumio.cloud, 757 ms: OK https://radio-directory.firebaseapp.com, 545 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # cat /data/updatesHistoryFile --------------- --- Tue Feb 13 2024 17:34:53 GMT+0000 (Coordinated Universal Time) {"systemversion":"3.616","builddate":"Tue 13 Feb 2024 06:28:23 PM CET","variant":"volumio","hardware":"pi"} ------ Mon Feb 26 2024 16:56:29 GMT+0100 (Central European Standard Time) {"systemversion":"3.631","builddate":"Wed 21 Feb 2024 09:37:22 PM CET","variant":"volumio","hardware":"pi"} ------ Tue May 14 2024 15:01:55 GMT+0200 (Central European Summer Time) {"systemversion":"3.661","builddate":"Wed 24 Apr 2024 02:10:14 PM CEST","variant":"volumio","hardware":"pi"} ------ Tue Jun 04 2024 03:30:12 GMT+0200 (Central European Summer Time) {"systemversion":"3.695","builddate":"Tue 28 May 2024 12:17:12 PM CEST","variant":"volumio","hardware":"pi"} --- # sudo journalctl -p 7 --------------- -- Logs begin at Tue 2024-06-04 19:11:33 CEST, end at Tue 2024-06-04 19:18:24 CEST. -- Jun 04 19:11:33 volumio kernel: Booting Linux on physical CPU 0x0 Jun 04 19:11:33 volumio kernel: Linux version 6.1.77-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc-8 (Ubuntu/Linaro 8.4.0-3ubuntu1) 8.4.0, GNU ld (GNU Binutils for Ubuntu) 2.34) #1730 SMP Thu Feb 8 15:17:51 GMT 2024 Jun 04 19:11:33 volumio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Jun 04 19:11:33 volumio kernel: CPU: div instructions available: patching division code Jun 04 19:11:33 volumio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Jun 04 19:11:33 volumio kernel: OF: fdt: Machine model: Raspberry Pi Zero 2 W Rev 1.0 Jun 04 19:11:33 volumio kernel: random: crng init done Jun 04 19:11:33 volumio kernel: Memory policy: Data cache writealloc Jun 04 19:11:33 volumio kernel: Reserved memory: created CMA memory pool at 0x19000000, size 64 MiB Jun 04 19:11:33 volumio kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Jun 04 19:11:33 volumio kernel: Zone ranges: Jun 04 19:11:33 volumio kernel: DMA [mem 0x0000000000000000-0x000000001dffffff] Jun 04 19:11:33 volumio kernel: Normal empty Jun 04 19:11:33 volumio kernel: Movable zone start for each node Jun 04 19:11:33 volumio kernel: Early memory node ranges Jun 04 19:11:33 volumio kernel: node 0: [mem 0x0000000000000000-0x000000001dffffff] Jun 04 19:11:33 volumio kernel: Initmem setup node 0 [mem 0x0000000000000000-0x000000001dffffff] Jun 04 19:11:33 volumio kernel: percpu: Embedded 17 pages/cpu s37012 r8192 d24428 u69632 Jun 04 19:11:33 volumio kernel: pcpu-alloc: s37012 r8192 d24428 u69632 alloc=17*4096 Jun 04 19:11:33 volumio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jun 04 19:11:33 volumio kernel: Built 1 zonelists, mobility grouping on. Total pages: 121800 Jun 04 19:11:33 volumio kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=B8:27:EB:96:EC:BF vc_mem.mem_base=0x1ec00000 vc_mem.mem_size=0x20000000 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=UUID=ae35765e-0158-4d8d-a475-17b52add8039 imgfile=/volumio_current.sqsh bootpart=UUID=6C60-DAB2 datapart=UUID=8648801e-3ad8-424d-949f-c52da8279dd7 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jun 04 19:11:33 volumio kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=ae35765e-0158-4d8d-a475-17b52add8039 imgfile=/volumio_current.sqsh bootpart=UUID=6C60-DAB2 datapart=UUID=8648801e-3ad8-424d-949f-c52da8279dd7 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe bootdelay=7 use_kmsg=no", will be passed to user space. Jun 04 19:11:33 volumio kernel: Dentry cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Jun 04 19:11:33 volumio kernel: Inode-cache hash table entries: 32768 (order: 5, 131072 bytes, linear) Jun 04 19:11:33 volumio kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jun 04 19:11:33 volumio kernel: Memory: 388968K/491520K available (10240K kernel code, 1454K rwdata, 3144K rodata, 1024K init, 612K bss, 37016K reserved, 65536K cma-reserved) Jun 04 19:11:33 volumio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jun 04 19:11:33 volumio kernel: ftrace: allocating 34953 entries in 103 pages Jun 04 19:11:33 volumio kernel: ftrace: allocated 103 pages with 5 groups Jun 04 19:11:33 volumio kernel: trace event string verifier disabled Jun 04 19:11:33 volumio kernel: rcu: Hierarchical RCU implementation. Jun 04 19:11:33 volumio kernel: Rude variant of Tasks RCU enabled. Jun 04 19:11:33 volumio kernel: Tracing variant of Tasks RCU enabled. Jun 04 19:11:33 volumio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Jun 04 19:11:33 volumio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Jun 04 19:11:33 volumio kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jun 04 19:11:33 volumio kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Jun 04 19:11:33 volumio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Jun 04 19:11:33 volumio kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Jun 04 19:11:33 volumio kernel: Switching to timer-based delay loop, resolution 52ns Jun 04 19:11:33 volumio kernel: Console: colour dummy device 80x30 Jun 04 19:11:33 volumio kernel: printk: console [tty1] enabled Jun 04 19:11:33 volumio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Jun 04 19:11:33 volumio kernel: CPU: Testing write buffer coherency: ok Jun 04 19:11:33 volumio kernel: pid_max: default: 32768 minimum: 301 Jun 04 19:11:33 volumio kernel: LSM: Security Framework initializing Jun 04 19:11:33 volumio kernel: Mount-cache hash table entries: 1024 (order: 0, 4096 bytes, linear) Jun 04 19:11:33 volumio kernel: Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes, linear) Jun 04 19:11:33 volumio kernel: cgroup: Disabling memory control group subsystem Jun 04 19:11:33 volumio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Jun 04 19:11:33 volumio kernel: cblist_init_generic: Setting adjustable number of callback queues. Jun 04 19:11:33 volumio kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Jun 04 19:11:33 volumio kernel: cblist_init_generic: Setting adjustable number of callback queues. Jun 04 19:11:33 volumio kernel: cblist_init_generic: Setting shift to 2 and lim to 1. Jun 04 19:11:33 volumio kernel: Setting up static identity map for 0x100000 - 0x10003c Jun 04 19:11:33 volumio kernel: rcu: Hierarchical SRCU implementation. Jun 04 19:11:33 volumio kernel: rcu: Max phase no-delay instances is 1000. Jun 04 19:11:33 volumio kernel: smp: Bringing up secondary CPUs ... Jun 04 19:11:33 volumio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Jun 04 19:11:33 volumio kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Jun 04 19:11:33 volumio kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Jun 04 19:11:33 volumio kernel: smp: Brought up 1 node, 4 CPUs Jun 04 19:11:33 volumio kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Jun 04 19:11:33 volumio kernel: CPU: All CPU(s) started in HYP mode. Jun 04 19:11:33 volumio kernel: CPU: Virtualization extensions available. Jun 04 19:11:33 volumio kernel: devtmpfs: initialized Jun 04 19:11:33 volumio kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Jun 04 19:11:33 volumio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Jun 04 19:11:33 volumio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jun 04 19:11:33 volumio kernel: pinctrl core: initialized pinctrl subsystem Jun 04 19:11:33 volumio kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jun 04 19:11:33 volumio kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Jun 04 19:11:33 volumio kernel: audit: initializing netlink subsys (disabled) Jun 04 19:11:33 volumio kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Jun 04 19:11:33 volumio kernel: thermal_sys: Registered thermal governor 'step_wise' Jun 04 19:11:33 volumio kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Jun 04 19:11:33 volumio kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Jun 04 19:11:33 volumio kernel: Serial: AMBA PL011 UART driver Jun 04 19:11:33 volumio kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Jun 04 19:11:33 volumio kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2023-10-17T15:42:39, variant start Jun 04 19:11:33 volumio kernel: raspberrypi-firmware soc:firmware: Firmware hash is 30f0c5e4d076da3ab4f341d88e7d505760b93ad7 Jun 04 19:11:33 volumio kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jun 04 19:11:33 volumio kernel: bcm2835-dma 3f007000.dma: DMA legacy API manager, dmachans=0x1 Jun 04 19:11:33 volumio kernel: SCSI subsystem initialized Jun 04 19:11:33 volumio kernel: usbcore: registered new interface driver usbfs Jun 04 19:11:33 volumio kernel: usbcore: registered new interface driver hub Jun 04 19:11:33 volumio kernel: usbcore: registered new device driver usb Jun 04 19:11:33 volumio kernel: usb_phy_generic phy: supply vcc not found, using dummy regulator Jun 04 19:11:33 volumio kernel: pps_core: LinuxPPS API ver. 1 registered Jun 04 19:11:33 volumio kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jun 04 19:11:33 volumio kernel: PTP clock support registered Jun 04 19:11:33 volumio kernel: clocksource: Switched to clocksource arch_sys_counter Jun 04 19:11:33 volumio kernel: VFS: Disk quotas dquot_6.6.0 Jun 04 19:11:33 volumio kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Jun 04 19:11:33 volumio kernel: FS-Cache: Loaded Jun 04 19:11:33 volumio kernel: CacheFiles: Loaded Jun 04 19:11:33 volumio kernel: NET: Registered PF_INET protocol family Jun 04 19:11:33 volumio kernel: IP idents hash table entries: 8192 (order: 4, 65536 bytes, linear) Jun 04 19:11:33 volumio kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Jun 04 19:11:33 volumio kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jun 04 19:11:33 volumio kernel: TCP established hash table entries: 4096 (order: 2, 16384 bytes, linear) Jun 04 19:11:33 volumio kernel: TCP bind hash table entries: 4096 (order: 4, 65536 bytes, linear) Jun 04 19:11:33 volumio kernel: TCP: Hash tables configured (established 4096 bind 4096) Jun 04 19:11:33 volumio kernel: UDP hash table entries: 256 (order: 1, 8192 bytes, linear) Jun 04 19:11:33 volumio kernel: UDP-Lite hash table entries: 256 (order: 1, 8192 bytes, linear) Jun 04 19:11:33 volumio kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jun 04 19:11:33 volumio kernel: RPC: Registered named UNIX socket transport module. Jun 04 19:11:33 volumio kernel: RPC: Registered udp transport module. Jun 04 19:11:33 volumio kernel: RPC: Registered tcp transport module. Jun 04 19:11:33 volumio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Jun 04 19:11:33 volumio kernel: Trying to unpack rootfs image as initramfs... Jun 04 19:11:33 volumio kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Jun 04 19:11:33 volumio kernel: Freeing initrd memory: 14316K Jun 04 19:11:33 volumio kernel: Initialise system trusted keyrings Jun 04 19:11:33 volumio kernel: workingset: timestamp_bits=14 max_order=17 bucket_order=3 Jun 04 19:11:33 volumio kernel: zbud: loaded Jun 04 19:11:33 volumio kernel: NFS: Registering the id_resolver key type Jun 04 19:11:33 volumio kernel: Key type id_resolver registered Jun 04 19:11:33 volumio kernel: Key type id_legacy registered Jun 04 19:11:33 volumio kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Jun 04 19:11:33 volumio kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Jun 04 19:11:33 volumio kernel: Key type asymmetric registered Jun 04 19:11:33 volumio kernel: Asymmetric key parser 'x509' registered Jun 04 19:11:33 volumio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Jun 04 19:11:33 volumio kernel: io scheduler mq-deadline registered Jun 04 19:11:33 volumio kernel: io scheduler kyber registered Jun 04 19:11:33 volumio kernel: bcm2708_fb soc:fb: FB found 1 display(s) Jun 04 19:11:33 volumio kernel: Console: switching to colour frame buffer device 80x30 Jun 04 19:11:33 volumio kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Jun 04 19:11:33 volumio kernel: bcm2835-rng 3f104000.rng: hwrng registered Jun 04 19:11:33 volumio kernel: vc-mem: phys_addr:0x00000000 mem_base=0x1ec00000 mem_size:0x20000000(512 MiB) Jun 04 19:11:33 volumio kernel: brd: module loaded Jun 04 19:11:33 volumio kernel: loop: module loaded Jun 04 19:11:33 volumio kernel: Loading iSCSI transport class v2.0-870. Jun 04 19:11:33 volumio kernel: usbcore: registered new interface driver lan78xx Jun 04 19:11:33 volumio kernel: usbcore: registered new interface driver smsc95xx Jun 04 19:11:33 volumio kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Jun 04 19:11:33 volumio kernel: Core Release: 2.80a Jun 04 19:11:33 volumio kernel: Setting default values for core params Jun 04 19:11:33 volumio kernel: Finished setting default values for core params Jun 04 19:11:33 volumio kernel: Using Buffer DMA mode Jun 04 19:11:33 volumio kernel: Periodic Transfer Interrupt Enhancement - disabled Jun 04 19:11:33 volumio kernel: Multiprocessor Interrupt Enhancement - disabled Jun 04 19:11:33 volumio kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Jun 04 19:11:33 volumio kernel: Dedicated Tx FIFOs mode Jun 04 19:11:33 volumio kernel: Jun 04 19:11:33 volumio kernel: WARN::dwc_otg_hcd_init:1074: FIQ DMA bounce buffers: virt = 99114000 dma = 0xd9114000 len=9024 Jun 04 19:11:33 volumio kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Jun 04 19:11:33 volumio kernel: dwc_otg: Microframe scheduler enabled Jun 04 19:11:33 volumio kernel: Jun 04 19:11:33 volumio kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Jun 04 19:11:33 volumio kernel: Jun 04 19:11:33 volumio kernel: WARN::hcd_init_fiq:458: FIQ ASM at 807c7754 length 36 Jun 04 19:11:33 volumio kernel: Jun 04 19:11:33 volumio kernel: WARN::hcd_init_fiq:497: MPHI regs_base at 9e810000 Jun 04 19:11:33 volumio kernel: dwc_otg 3f980000.usb: DWC OTG Controller Jun 04 19:11:33 volumio kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Jun 04 19:11:33 volumio kernel: dwc_otg 3f980000.usb: irq 89, io mem 0x00000000 Jun 04 19:11:33 volumio kernel: Init: Port Power? op_state=1 Jun 04 19:11:33 volumio kernel: Init: Power Port (0) Jun 04 19:11:33 volumio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.01 Jun 04 19:11:33 volumio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Jun 04 19:11:33 volumio kernel: usb usb1: Product: DWC OTG Controller Jun 04 19:11:33 volumio kernel: usb usb1: Manufacturer: Linux 6.1.77-v7+ dwc_otg_hcd Jun 04 19:11:33 volumio kernel: usb usb1: SerialNumber: 3f980000.usb Jun 04 19:11:33 volumio kernel: hub 1-0:1.0: USB hub found Jun 04 19:11:33 volumio kernel: hub 1-0:1.0: 1 port detected Jun 04 19:11:33 volumio kernel: dwc_otg: FIQ enabled Jun 04 19:11:33 volumio kernel: dwc_otg: NAK holdoff enabled Jun 04 19:11:33 volumio kernel: dwc_otg: FIQ split-transaction FSM enabled Jun 04 19:11:33 volumio kernel: Module dwc_common_port init Jun 04 19:11:33 volumio kernel: usbcore: registered new interface driver usb-storage Jun 04 19:11:33 volumio kernel: mousedev: PS/2 mouse device common for all mice Jun 04 19:11:33 volumio kernel: sdhci: Secure Digital Host Controller Interface driver Jun 04 19:11:33 volumio kernel: sdhci: Copyright(c) Pierre Ossman Jun 04 19:11:33 volumio kernel: sdhci-pltfm: SDHCI platform and OF driver helper Jun 04 19:11:33 volumio kernel: ledtrig-cpu: registered to indicate activity on CPUs Jun 04 19:11:33 volumio kernel: hid: raw HID events driver (C) Jiri Kosina Jun 04 19:11:33 volumio kernel: usbcore: registered new interface driver usbhid Jun 04 19:11:33 volumio kernel: usbhid: USB HID core driver Jun 04 19:11:33 volumio kernel: bcm2835_vchiq 3f00b840.mailbox: there is not valid maps for state default Jun 04 19:11:33 volumio kernel: Initializing XFRM netlink socket Jun 04 19:11:33 volumio kernel: NET: Registered PF_PACKET protocol family Jun 04 19:11:33 volumio kernel: Key type dns_resolver registered Jun 04 19:11:33 volumio kernel: Registering SWP/SWPB emulation handler Jun 04 19:11:33 volumio kernel: registered taskstats version 1 Jun 04 19:11:33 volumio kernel: Loading compiled-in X.509 certificates Jun 04 19:11:33 volumio kernel: Key type .fscrypt registered Jun 04 19:11:33 volumio kernel: Key type fscrypt-provisioning registered Jun 04 19:11:33 volumio kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Jun 04 19:11:33 volumio kernel: 3f201000.serial: ttyAMA1 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2 Jun 04 19:11:33 volumio kernel: serial serial0: tty port ttyAMA1 registered Jun 04 19:11:33 volumio kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Jun 04 19:11:33 volumio kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Jun 04 19:11:33 volumio kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Jun 04 19:11:33 volumio kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Jun 04 19:11:33 volumio kernel: sdhost: log_buf @ 30fe3a3c (d9113000) Jun 04 19:11:33 volumio kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Jun 04 19:11:33 volumio kernel: of_cfs_init Jun 04 19:11:33 volumio kernel: of_cfs_init: OK Jun 04 19:11:33 volumio kernel: Freeing unused kernel image (initmem) memory: 1024K Jun 04 19:11:33 volumio kernel: mmc0: host does not support reading read-only switch, assuming write-enable Jun 04 19:11:33 volumio kernel: Run /init as init process Jun 04 19:11:33 volumio kernel: with arguments: Jun 04 19:11:33 volumio kernel: /init Jun 04 19:11:33 volumio kernel: splash Jun 04 19:11:33 volumio kernel: nodebug Jun 04 19:11:33 volumio kernel: with environment: Jun 04 19:11:33 volumio kernel: HOME=/ Jun 04 19:11:33 volumio kernel: TERM=linux Jun 04 19:11:33 volumio kernel: imgpart=UUID=ae35765e-0158-4d8d-a475-17b52add8039 Jun 04 19:11:33 volumio kernel: imgfile=/volumio_current.sqsh Jun 04 19:11:33 volumio kernel: bootpart=UUID=6C60-DAB2 Jun 04 19:11:33 volumio kernel: datapart=UUID=8648801e-3ad8-424d-949f-c52da8279dd7 Jun 04 19:11:33 volumio kernel: uuidconfig=cmdline.txt Jun 04 19:11:33 volumio kernel: pcie_aspm=off Jun 04 19:11:33 volumio kernel: pci=pcie_bus_safe Jun 04 19:11:33 volumio kernel: bootdelay=7 Jun 04 19:11:33 volumio kernel: use_kmsg=no Jun 04 19:11:33 volumio kernel: mmc0: new high speed SDHC card at address 59b4 Jun 04 19:11:33 volumio kernel: mmcblk0: mmc0:59b4 29.0 GiB Jun 04 19:11:33 volumio kernel: mmcblk0: p1 p2 p3 Jun 04 19:11:33 volumio kernel: mmcblk0: mmc0:59b4 29.0 GiB Jun 04 19:11:33 volumio kernel: mmc1: new high speed SDIO card at address 0001 Jun 04 19:11:33 volumio kernel: fuse: init (API version 7.37) Jun 04 19:11:33 volumio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jun 04 19:11:33 volumio kernel: usbcore: registered new interface driver uas Jun 04 19:11:33 volumio kernel: EXT4-fs (mmcblk0p2): recovery complete Jun 04 19:11:33 volumio kernel: EXT4-fs (mmcblk0p2): mounted filesystem with ordered data mode. Quota mode: none. Jun 04 19:11:33 volumio kernel: loop0: detected capacity change from 0 to 977896 Jun 04 19:11:33 volumio kernel: EXT4-fs (mmcblk0p3): recovery complete Jun 04 19:11:33 volumio kernel: EXT4-fs (mmcblk0p3): mounted filesystem with ordered data mode. Quota mode: none. Jun 04 19:11:33 volumio systemd[1]: System time before build time, advancing clock. Jun 04 19:11:33 volumio kernel: NET: Registered PF_INET6 protocol family Jun 04 19:11:33 volumio kernel: Segment Routing with IPv6 Jun 04 19:11:33 volumio kernel: In-situ OAM (IOAM) with IPv6 Jun 04 19:11:33 volumio 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) Jun 04 19:11:33 volumio systemd[1]: Detected architecture arm. Jun 04 19:11:33 volumio systemd[1]: Set hostname to . Jun 04 19:11:33 volumio 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. Jun 04 19:11:33 volumio systemd[1]: Listening on initctl Compatibility Named Pipe. Jun 04 19:11:33 volumio systemd[1]: Listening on fsck to fsckd communication Socket. Jun 04 19:11:33 volumio systemd[1]: Listening on udev Kernel Socket. Jun 04 19:11:33 volumio systemd[1]: Listening on udev Control Socket. Jun 04 19:11:33 volumio systemd[1]: Created slice system-getty.slice. Jun 04 19:11:33 volumio systemd[1]: Started Forward Password Requests to Wall Directory Watch. Jun 04 19:11:33 volumio kernel: i2c_dev: i2c /dev entries driver Jun 04 19:11:33 volumio systemd-journald[295]: Journal started Jun 04 19:11:33 volumio systemd-journald[295]: Runtime journal (/run/log/journal/ba41094955353179f46b1ad365cba63d) is 3.7M, max 30.0M, 26.2M free. Jun 04 19:11:33 volumio systemd-modules-load[278]: Inserted module 'i2c_dev' Jun 04 19:11:33 volumio fake-hwclock[286]: Tue Jun 4 17:11:33 UTC 2024 Jun 04 19:11:33 volumio systemd[1]: Started Create System Users. Jun 04 19:11:33 volumio volumio[297]: 512 MB or less RAM Detected, need to enable swap Jun 04 19:11:33 volumio systemd[1]: Starting Create Static Device Nodes in /dev... Jun 04 19:11:33 volumio systemd[1]: Started udev Coldplug all Devices. Jun 04 19:11:33 volumio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Jun 04 19:11:33 volumio systemd[1]: Started Helper to synchronize boot up for ifupdown. Jun 04 19:11:33 volumio kernel: ext4 filesystem being mounted at /swap supports timestamps until 2038 (0x7fffffff) Jun 04 19:11:33 volumio volumio[297]: Enabling Swap Jun 04 19:11:33 volumio systemd[1]: Started Create Static Device Nodes in /dev. Jun 04 19:11:33 volumio systemd[1]: Reached target Local File Systems (Pre). Jun 04 19:11:33 volumio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Jun 04 19:11:33 volumio kernel: Adding 524228k swap on /swap/swapfile. Priority:-2 extents:14 across:15892476k SSFS Jun 04 19:11:33 volumio volumio[297]: Setting swappiness to 40 Jun 04 19:11:33 volumio systemd[1]: Mounting /var/log... Jun 04 19:11:33 volumio systemd[1]: Mounting /var/spool/cups... Jun 04 19:11:33 volumio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Jun 04 19:11:33 volumio systemd[1]: Mounting /tmp... Jun 04 19:11:33 volumio systemd[1]: Starting udev Kernel Device Manager... Jun 04 19:11:33 volumio volumio[297]: vm.swappiness = 40 Jun 04 19:11:33 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 04 19:11:33 volumio systemd[1]: Mounted /var/log. Jun 04 19:11:33 volumio systemd[1]: Mounted /var/spool/cups. Jun 04 19:11:33 volumio systemd[1]: Mounted /tmp. Jun 04 19:11:33 volumio systemd[1]: Mounting /var/spool/cups/tmp... Jun 04 19:11:33 volumio systemd[1]: Starting Flush Journal to Persistent Storage... Jun 04 19:11:33 volumio systemd[1]: Mounted /var/spool/cups/tmp. Jun 04 19:11:33 volumio systemd-udevd[324]: Network interface NamePolicy= disabled on kernel command line, ignoring. Jun 04 19:11:33 volumio systemd-journald[295]: Runtime journal (/run/log/journal/ba41094955353179f46b1ad365cba63d) is 3.7M, max 30.0M, 26.2M free. Jun 04 19:11:33 volumio systemd[1]: Started udev Kernel Device Manager. Jun 04 19:11:33 volumio systemd[1]: Started Flush Journal to Persistent Storage. Jun 04 19:11:33 volumio systemd[1]: Starting Show Plymouth Boot Screen... Jun 04 19:11:33 volumio systemd[1]: plymouth-start.service: Succeeded. Jun 04 19:11:33 volumio systemd[1]: Started Show Plymouth Boot Screen. Jun 04 19:11:33 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jun 04 19:11:33 volumio systemd[1]: Reached target Local Encrypted Volumes. Jun 04 19:11:33 volumio systemd[1]: Reached target Paths. Jun 04 19:11:33 volumio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Jun 04 19:11:34 volumio kernel: mc: Linux media interface: v0.10 Jun 04 19:11:34 volumio kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Jun 04 19:11:34 volumio kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Jun 04 19:11:34 volumio kernel: [vc_sm_connected_init]: start Jun 04 19:11:34 volumio kernel: [vc_sm_connected_init]: installed successfully Jun 04 19:11:34 volumio kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Jun 04 19:11:34 volumio kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Jun 04 19:11:34 volumio kernel: videodev: Linux video capture interface: v2.00 Jun 04 19:11:34 volumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jun 04 19:11:34 volumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jun 04 19:11:34 volumio kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Jun 04 19:11:34 volumio kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Jun 04 19:11:34 volumio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Jun 04 19:11:34 volumio kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Jun 04 19:11:34 volumio systemd[1]: Found device /dev/disk/by-uuid/6C60-DAB2. Jun 04 19:11:34 volumio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Jun 04 19:11:34 volumio kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Jun 04 19:11:34 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Jun 04 19:11:34 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Jun 04 19:11:34 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Jun 04 19:11:34 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Jun 04 19:11:34 volumio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jun 04 19:11:34 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Jun 04 19:11:34 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Jun 04 19:11:34 volumio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Jun 04 19:11:34 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Jun 04 19:11:34 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Jun 04 19:11:34 volumio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Jun 04 19:11:34 volumio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Jun 04 19:11:34 volumio kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Jun 04 19:11:35 volumio systemd[1]: Starting File System Check on /dev/disk/by-uuid/6C60-DAB2... Jun 04 19:11:35 volumio kernel: Bluetooth: Core ver 2.22 Jun 04 19:11:35 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Jun 04 19:11:35 volumio kernel: Bluetooth: HCI device and connection manager initialized Jun 04 19:11:35 volumio kernel: Bluetooth: HCI socket layer initialized Jun 04 19:11:35 volumio kernel: Bluetooth: L2CAP socket layer initialized Jun 04 19:11:35 volumio kernel: Bluetooth: SCO socket layer initialized Jun 04 19:11:35 volumio systemd-udevd[335]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 04 19:11:35 volumio systemd-fsck[401]: fsck.fat 4.1 (2017-01-24) Jun 04 19:11:35 volumio systemd-fsck[401]: /dev/mmcblk0p1: 373 files, 33667/46774 clusters Jun 04 19:11:35 volumio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Jun 04 19:11:35 volumio systemd[1]: Started File System Check on /dev/disk/by-uuid/6C60-DAB2. Jun 04 19:11:35 volumio kernel: Bluetooth: HCI UART driver ver 2.3 Jun 04 19:11:35 volumio kernel: Bluetooth: HCI UART protocol H4 registered Jun 04 19:11:35 volumio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Jun 04 19:11:35 volumio kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Jun 04 19:11:35 volumio kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Jun 04 19:11:35 volumio kernel: Bluetooth: HCI UART protocol Broadcom registered Jun 04 19:11:35 volumio kernel: cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Jun 04 19:11:35 volumio kernel: cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Jun 04 19:11:35 volumio kernel: cfg80211: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Jun 04 19:11:35 volumio kernel: cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Jun 04 19:11:35 volumio kernel: uart-pl011 3f201000.serial: no DMA platform data Jun 04 19:11:35 volumio kernel: Bluetooth: hci0: BCM: chip id 94 Jun 04 19:11:35 volumio kernel: Bluetooth: hci0: BCM: features 0x2e Jun 04 19:11:35 volumio kernel: Bluetooth: hci0: BCM43430A1 Jun 04 19:11:35 volumio kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000 Jun 04 19:11:35 volumio kernel: Bluetooth: hci0: BCM43430A1 'brcm/BCM43430A1.hcd' Patch Jun 04 19:11:36 volumio kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Jun 04 19:11:36 volumio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Jun 04 19:11:36 volumio kernel: brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43430-sdio.raspberrypi,model-zero-2-w.bin failed with error -2 Jun 04 19:11:36 volumio kernel: usbcore: registered new interface driver brcmfmac Jun 04 19:11:36 volumio kernel: brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM43430/1 wl0: Jul 19 2021 03:24:18 version 7.45.98 (TOB) (56df937 CY) FWID 01-8e14b897 Jun 04 19:11:36 volumio systemd-udevd[337]: Using default interface naming scheme 'v240'. Jun 04 19:11:36 volumio systemd-udevd[337]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Jun 04 19:11:36 volumio systemd[1]: Created slice system-bthelper.slice. Jun 04 19:11:36 volumio kernel: Bluetooth: hci0: BCM: features 0x2e Jun 04 19:11:36 volumio kernel: Bluetooth: hci0: BCM43438A1 37.4MHz Raspberry Pi 3-0141 Jun 04 19:11:36 volumio kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0508 Jun 04 19:11:36 volumio systemd[1]: Starting Show Plymouth Boot Screen... Jun 04 19:11:36 volumio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Jun 04 19:11:36 volumio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Jun 04 19:11:36 volumio systemd[1]: Condition check resulted in Huge Pages File System being skipped. Jun 04 19:11:36 volumio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Jun 04 19:11:36 volumio systemd[1]: Mounting /boot... Jun 04 19:11:36 volumio systemd[1]: Started File System Check Daemon to report status. Jun 04 19:11:36 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Jun 04 19:11:36 volumio systemd[1]: Mounted /boot. Jun 04 19:11:36 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Jun 04 19:11:36 volumio systemd[1]: Reached target Local File Systems. Jun 04 19:11:36 volumio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Jun 04 19:11:36 volumio systemd[1]: Starting Preprocess NFS configuration... Jun 04 19:11:36 volumio systemd[1]: Starting Raise network interfaces... Jun 04 19:11:36 volumio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Jun 04 19:11:36 volumio systemd[1]: Starting Create Volatile Files and Directories... Jun 04 19:11:36 volumio systemd[1]: plymouth-start.service: Succeeded. Jun 04 19:11:36 volumio systemd[1]: Started Show Plymouth Boot Screen. Jun 04 19:11:36 volumio systemd[1]: nfs-config.service: Succeeded. Jun 04 19:11:36 volumio systemd[1]: Started Preprocess NFS configuration. Jun 04 19:11:36 volumio systemd[1]: plymouth-read-write.service: Succeeded. Jun 04 19:11:36 volumio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Jun 04 19:11:36 volumio systemd[1]: Received SIGRTMIN+20 from PID 177 (plymouthd). Jun 04 19:11:36 volumio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Jun 04 19:11:36 volumio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Jun 04 19:11:36 volumio systemd[1]: Reached target NFS client services. Jun 04 19:11:36 volumio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Jun 04 19:11:36 volumio systemd[1]: Started Create Volatile Files and Directories. Jun 04 19:11:36 volumio systemd[1]: Starting RPC bind portmap service... Jun 04 19:11:36 volumio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Jun 04 19:11:37 volumio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Jun 04 19:11:37 volumio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Jun 04 19:11:37 volumio systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Jun 04 19:11:37 volumio systemd[1]: Started RPC bind portmap service. Jun 04 19:11:37 volumio systemd[1]: Started Update UTMP about System Boot/Shutdown. Jun 04 19:11:37 volumio systemd[1]: Reached target System Initialization. Jun 04 19:11:37 volumio systemd[1]: Started Daily man-db regeneration. Jun 04 19:11:37 volumio systemd[1]: Started Daily Cleanup of Temporary Directories. Jun 04 19:11:37 volumio systemd[1]: Started Daily apt download activities. Jun 04 19:11:37 volumio systemd[1]: Listening on D-Bus System Message Bus Socket. Jun 04 19:11:37 volumio systemd[1]: Listening on triggerhappy.socket. Jun 04 19:11:37 volumio systemd[1]: Started Daily apt upgrade and clean activities. Jun 04 19:11:37 volumio systemd[1]: Reached target Timers. Jun 04 19:11:37 volumio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Jun 04 19:11:37 volumio systemd[1]: Reached target Sockets. Jun 04 19:11:37 volumio systemd[1]: Reached target Basic System. Jun 04 19:11:37 volumio systemd[1]: Started Manage Sound Card State (restore and store). Jun 04 19:11:37 volumio systemd[1]: Starting Wireless Services... Jun 04 19:11:37 volumio alsactl[508]: alsactl 1.1.8 daemon started Jun 04 19:11:37 volumio systemd[1]: Starting Configure Bluetooth Modems connected by UART... Jun 04 19:11:37 volumio systemd[1]: Starting Login Service... Jun 04 19:11:37 volumio systemd[1]: Starting Save/Restore Sound Card State... Jun 04 19:11:37 volumio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Jun 04 19:11:37 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 04 19:11:37 volumio systemd[1]: Starting triggerhappy global hotkey daemon... Jun 04 19:11:37 volumio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Jun 04 19:11:37 volumio systemd[1]: Started volumio-remote-updater.service. Jun 04 19:11:37 volumio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Jun 04 19:11:37 volumio systemd[1]: Starting dhcpcd on all interfaces... Jun 04 19:11:37 volumio systemd[1]: Started Volumio Log Rotation Service. Jun 04 19:11:37 volumio thd[519]: Unable to parse trigger line: Jun 04 19:11:37 volumio thd[519]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Jun 04 19:11:37 volumio thd[519]: Unable to parse trigger line: Jun 04 19:11:37 volumio thd[519]: Found socket passed from systemd Jun 04 19:11:37 volumio systemd[1]: Started Volumio Iptables Module. Jun 04 19:11:37 volumio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Jun 04 19:11:37 volumio systemd[1]: Started D-Bus System Message Bus. Jun 04 19:11:37 volumio dhcpcd[522]: Not running dhcpcd because /etc/network/interfaces Jun 04 19:11:37 volumio dhcpcd[522]: defines some interfaces that will use a Jun 04 19:11:37 volumio dhcpcd[522]: DHCP client or static address Jun 04 19:11:37 volumio systemd[1]: Starting WPA supplicant... Jun 04 19:11:37 volumio systemd[1]: Reached target RPC Port Mapper. Jun 04 19:11:37 volumio systemd[1]: Reached target Remote File Systems (Pre). Jun 04 19:11:37 volumio systemd[1]: Reached target Remote File Systems. Jun 04 19:11:37 volumio systemd-logind[514]: New seat seat0. Jun 04 19:11:37 volumio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Jun 04 19:11:37 volumio systemd[1]: Started ifup for wlan0. Jun 04 19:11:37 volumio systemd[1]: Started triggerhappy global hotkey daemon. Jun 04 19:11:37 volumio systemd[1]: Started Save/Restore Sound Card State. Jun 04 19:11:37 volumio systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Jun 04 19:11:37 volumio systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Jun 04 19:11:37 volumio systemd[1]: Failed to start dhcpcd on all interfaces. Jun 04 19:11:37 volumio systemd[1]: hciuart.service: Succeeded. Jun 04 19:11:37 volumio systemd[1]: Started Configure Bluetooth Modems connected by UART. Jun 04 19:11:37 volumio systemd[1]: Starting Raspberry Pi bluetooth helper... Jun 04 19:11:37 volumio systemd[1]: Reached target Sound Card. Jun 04 19:11:38 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 04 19:11:38 volumio bthelper[549]: Raspberry Pi BDADDR already set Jun 04 19:11:38 volumio avahi-daemon[529]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Jun 04 19:11:38 volumio avahi-daemon[529]: Successfully dropped root privileges. Jun 04 19:11:38 volumio avahi-daemon[529]: avahi-daemon 0.7 starting up. Jun 04 19:11:38 volumio systemd[1]: Started Raspberry Pi bluetooth helper. Jun 04 19:11:38 volumio avahi-daemon[529]: Successfully called chroot(). Jun 04 19:11:38 volumio avahi-daemon[529]: Successfully dropped remaining capabilities. Jun 04 19:11:38 volumio wpa_supplicant[541]: Successfully initialized wpa_supplicant Jun 04 19:11:38 volumio avahi-daemon[529]: No service file found in /etc/avahi/services. Jun 04 19:11:38 volumio avahi-daemon[529]: Network interface enumeration completed. Jun 04 19:11:38 volumio avahi-daemon[529]: Server startup complete. Host name is volumio.local. Local service cookie is 1790286896. Jun 04 19:11:39 volumio sh[545]: wlan0=wlan0 Jun 04 19:11:39 volumio systemd[1]: Started Raise network interfaces. Jun 04 19:11:39 volumio systemd[1]: Started Login Service. Jun 04 19:11:39 volumio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Jun 04 19:11:39 volumio systemd[1]: Started WPA supplicant. Jun 04 19:11:39 volumio systemd[1]: Reached target Network. Jun 04 19:11:39 volumio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Jun 04 19:11:39 volumio volumio-remote-updater[521]: Error: No active session Jun 04 19:11:39 volumio volumio-remote-updater[521]: [2024-06-04 19:11:39] [info] asio async_connect error: system:111 (Connection refused) Jun 04 19:11:39 volumio volumio-remote-updater[521]: [2024-06-04 19:11:39] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 04 19:11:39 volumio volumio-remote-updater[521]: [2024-06-04 19:11:39] [error] handle_connect error: Underlying Transport Error Jun 04 19:11:39 volumio systemd[1]: Starting Permit User Sessions... Jun 04 19:11:39 volumio systemd[1]: Starting Network Time Service... Jun 04 19:11:39 volumio systemd[1]: Reached target Network is Online. Jun 04 19:11:39 volumio volumio[518]: Could not open config: /tmp/upmpdcli.conf Jun 04 19:11:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 04 19:11:39 volumio systemd[1]: Starting Samba NMB Daemon... Jun 04 19:11:39 volumio systemd[1]: Starting LSB: Brings up/down network automatically... Jun 04 19:11:39 volumio systemd[1]: Starting /etc/rc.local Compatibility... Jun 04 19:11:39 volumio systemd[1]: Starting Bluetooth service... Jun 04 19:11:39 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jun 04 19:11:39 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jun 04 19:11:39 volumio systemd[1]: Started Permit User Sessions. Jun 04 19:11:39 volumio systemd[1]: Started /etc/rc.local Compatibility. Jun 04 19:11:39 volumio systemd[1]: Starting Terminate Plymouth Boot Screen... Jun 04 19:11:39 volumio systemd[1]: Starting Hold until boot process finishes up... Jun 04 19:11:39 volumio systemd[1]: plymouth-quit.service: Succeeded. Jun 04 19:11:39 volumio systemd[1]: Started Terminate Plymouth Boot Screen. Jun 04 19:11:39 volumio systemd[1]: plymouth-quit-wait.service: Succeeded. Jun 04 19:11:39 volumio systemd[1]: Started Hold until boot process finishes up. Jun 04 19:11:39 volumio systemd[1]: Received SIGRTMIN+21 from PID 177 (n/a). Jun 04 19:11:39 volumio systemd[1]: Received SIGRTMIN+21 from PID 177 (n/a). Jun 04 19:11:39 volumio ifplugd[645]: Network Interface Plugging Daemon...skip eth0...done. Jun 04 19:11:39 volumio systemd[1]: Started LSB: Brings up/down network automatically. Jun 04 19:11:39 volumio systemd[1]: Started Getty on tty1. Jun 04 19:11:39 volumio systemd[1]: Reached target Login Prompts. Jun 04 19:11:39 volumio systemd[1]: iptables.service: Succeeded. Jun 04 19:11:39 volumio loadcpufreq[544]: Loading cpufreq kernel modules...done (none). Jun 04 19:11:39 volumio bluetoothd[648]: Bluetooth daemon 5.50 Jun 04 19:11:40 volumio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Jun 04 19:11:40 volumio bluetoothd[648]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jun 04 19:11:40 volumio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Jun 04 19:11:40 volumio systemd[1]: Started Bluetooth service. Jun 04 19:11:40 volumio systemd[1]: Reached target Bluetooth. Jun 04 19:11:40 volumio bluetoothd[648]: Starting SDP server Jun 04 19:11:40 volumio bluetoothd[648]: Excluding (cli) sap Jun 04 19:11:40 volumio ntpd[642]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 04 19:11:40 volumio ntpd[642]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Jun 04 19:11:40 volumio systemd[1]: Started Network Time Service. Jun 04 19:11:40 volumio ntpd[697]: proto: precision = 1.458 usec (-19) Jun 04 19:11:40 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Jun 04 19:11:40 volumio kernel: Bluetooth: BNEP filters: protocol multicast Jun 04 19:11:40 volumio kernel: Bluetooth: BNEP socket layer initialized Jun 04 19:11:40 volumio bluetoothd[648]: Bluetooth management interface 1.22 initialized Jun 04 19:11:40 volumio dbus-daemon[533]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.5' (uid=0 pid=648 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Jun 04 19:11:40 volumio kernel: Bluetooth: MGMT ver 1.22 Jun 04 19:11:40 volumio ntpd[697]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 04 19:11:40 volumio ntpd[697]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 04 19:11:40 volumio ntpd[697]: Listen and drop on 0 v6wildcard [::]:123 Jun 04 19:11:40 volumio ntpd[697]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 04 19:11:40 volumio ntpd[697]: Listen normally on 2 lo 127.0.0.1:123 Jun 04 19:11:40 volumio ntpd[697]: Listening on routing socket on fd #19 for interface updates Jun 04 19:11:40 volumio ntpd[697]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 04 19:11:40 volumio ntpd[697]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jun 04 19:11:40 volumio bluetoothd[648]: Failed to set privacy: Rejected (0x0b) Jun 04 19:11:40 volumio cpufrequtils[689]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Jun 04 19:11:40 volumio haveged[486]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Jun 04 19:11:40 volumio haveged[486]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Jun 04 19:11:40 volumio haveged[486]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00077 Jun 04 19:11:40 volumio haveged[486]: haveged: fills: 0, generated: 0 Jun 04 19:11:40 volumio systemd[1]: Started LSB: set CPUFreq kernel parameters. Jun 04 19:11:40 volumio systemd[1]: Starting Hostname Service... Jun 04 19:11:40 volumio dbus-daemon[533]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 04 19:11:40 volumio systemd[1]: Started Hostname Service. Jun 04 19:11:41 volumio nmbd[653]: [2024/06/04 19:11:41.074074, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Jun 04 19:11:41 volumio nmbd[653]: started asyncdns process 714 Jun 04 19:11:41 volumio nmbd[653]: [2024/06/04 19:11:41.076656, 0] ../lib/util/become_daemon.c:149(daemon_status) Jun 04 19:11:41 volumio nmbd[653]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Jun 04 19:11:41 volumio nmbd[653]: [2024/06/04 19:11:41.076845, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Jun 04 19:11:41 volumio nmbd[653]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Jun 04 19:11:41 volumio ntpd[697]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Jun 04 19:11:41 volumio wireless.js[512]: Cleaning previous... Jun 04 19:11:41 volumio systemd[1]: systemd-rfkill.service: Succeeded. Jun 04 19:11:41 volumio sudo[722]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 04 19:11:41 volumio sudo[722]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:41 volumio sudo[722]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:41 volumio sudo[724]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 04 19:11:41 volumio sudo[724]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:42 volumio ntpd[697]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Jun 04 19:11:42 volumio sudo[724]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:42 volumio wireless.js[512]: Stopped aP Jun 04 19:11:42 volumio sudo[731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 04 19:11:42 volumio sudo[731]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:42 volumio sudo[731]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:42 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 04 19:11:42 volumio sudo[733]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 04 19:11:42 volumio sudo[733]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:42 volumio sudo[733]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:42 volumio sudo[740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 04 19:11:42 volumio sudo[740]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:42 volumio sudo[740]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:42 volumio sudo[742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 04 19:11:42 volumio sudo[742]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:43 volumio ntpd[697]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Jun 04 19:11:43 volumio sudo[742]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:43 volumio wireless.js[512]: SETTING APPROPRIATE REG DOMAIN: FR Jun 04 19:11:43 volumio sudo[747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 04 19:11:43 volumio sudo[747]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:43 volumio sudo[747]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:43 volumio sudo[749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set FR Jun 04 19:11:43 volumio sudo[749]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:43 volumio sudo[749]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:43 volumio wireless.js[512]: SUCCESSFULLY SET NEW REGDOMAIN: FR Jun 04 19:11:43 volumio wireless.js[512]: Start wireless flow Jun 04 19:11:43 volumio wireless.js[512]: Stopped hotspot (if there).. Jun 04 19:11:43 volumio sudo[754]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 04 19:11:43 volumio sudo[754]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:43 volumio sudo[754]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:43 volumio sudo[756]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 04 19:11:43 volumio sudo[756]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:43 volumio bthelper[549]: Changing power off succeeded Jun 04 19:11:43 volumio bthelper[549]: [CHG] Controller D8:3A:DD:8F:B1:DA Class: 0x0000041c Jun 04 19:11:43 volumio bthelper[549]: Changing power on succeeded Jun 04 19:11:43 volumio sudo[756]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:43 volumio wireless.js[512]: DHCP IP Jun 04 19:11:43 volumio wireless.js[512]: Start ap Jun 04 19:11:43 volumio wpa_supplicant[764]: Successfully initialized wpa_supplicant Jun 04 19:11:43 volumio sudo[765]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 04 19:11:43 volumio sudo[765]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:43 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 04 19:11:43 volumio dhcpcd[766]: dev: loaded udev Jun 04 19:11:44 volumio kernel: 8021q: 802.1Q VLAN Support v1.8 Jun 04 19:11:44 volumio dhcpcd-run-hooks[774]: wlan0: starting wpa_supplicant Jun 04 19:11:44 volumio ntpd[697]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Jun 04 19:11:44 volumio volumio-remote-updater[521]: [2024-06-04 19:11:44] [info] asio async_connect error: system:111 (Connection refused) Jun 04 19:11:44 volumio volumio-remote-updater[521]: [2024-06-04 19:11:44] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 04 19:11:44 volumio volumio-remote-updater[521]: [2024-06-04 19:11:44] [error] handle_connect error: Underlying Transport Error Jun 04 19:11:44 volumio dhcpcd-run-hooks[779]: wlan0: failed to start wpa_supplicant Jun 04 19:11:44 volumio dhcpcd-run-hooks[780]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma Jun 04 19:11:44 volumio dhcpcd[766]: wlan0: connected to Access Point `' Jun 04 19:11:44 volumio dhcpcd[766]: no interfaces have a carrier Jun 04 19:11:44 volumio dhcpcd[766]: forked to background, child pid 794 Jun 04 19:11:44 volumio dhcpcd[794]: wlan0: waiting for carrier Jun 04 19:11:44 volumio sudo[765]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:44 volumio wpa_supplicant[778]: wlan0: Trying to associate with SSID 'Livebox-ARAKELOV' Jun 04 19:11:44 volumio wireless.js[512]: trying... Jun 04 19:11:44 volumio sudo[796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:11:44 volumio sudo[796]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:44 volumio sudo[796]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:45 volumio dhcpcd[794]: wlan0: carrier acquired Jun 04 19:11:45 volumio dhcpcd[794]: wlan0: connected to Access Point `Livebox-ARAKELOV' Jun 04 19:11:45 volumio wpa_supplicant[778]: wlan0: Associated with 9c:9d:7e:95:80:6d Jun 04 19:11:45 volumio wpa_supplicant[778]: wlan0: CTRL-EVENT-CONNECTED - Connection to 9c:9d:7e:95:80:6d completed [id=0 id_str=] Jun 04 19:11:45 volumio wpa_supplicant[778]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 04 19:11:45 volumio dhcpcd[794]: DUID 00:01:00:01:2d:5e:66:e6:d8:3a:dd:8f:b1:d9 Jun 04 19:11:45 volumio dhcpcd[794]: wlan0: IAID dd:8f:b1:d9 Jun 04 19:11:45 volumio dhcpcd[794]: wlan0: adding address fe80::e7e1:d10c:189e:79ab Jun 04 19:11:45 volumio dhcpcd[794]: ipv6_addaddr1: Permission denied Jun 04 19:11:45 volumio dhcpcd[794]: wlan0: carrier lost Jun 04 19:11:45 volumio dhcpcd[794]: wlan0: carrier acquired Jun 04 19:11:45 volumio dhcpcd[794]: wlan0: IAID dd:8f:b1:d9 Jun 04 19:11:45 volumio dhcpcd[794]: wlan0: soliciting an IPv6 router Jun 04 19:11:45 volumio dhcpcd[794]: wlan0: rebinding lease of 192.168.1.34 Jun 04 19:11:45 volumio wireless.js[512]: trying... Jun 04 19:11:45 volumio sudo[811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:11:45 volumio sudo[811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:45 volumio sudo[811]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:45 volumio dhcpcd[794]: wlan0: probing address 192.168.1.34/24 Jun 04 19:11:46 volumio wireless.js[512]: trying... Jun 04 19:11:46 volumio sudo[814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:11:46 volumio sudo[814]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:46 volumio sudo[814]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:47 volumio wireless.js[512]: trying... Jun 04 19:11:47 volumio sudo[817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:11:47 volumio sudo[817]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:47 volumio sudo[817]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:48 volumio wireless.js[512]: trying... Jun 04 19:11:48 volumio sudo[820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:11:48 volumio sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:48 volumio sudo[820]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:49 volumio wireless.js[512]: trying... Jun 04 19:11:49 volumio sudo[823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:11:49 volumio sudo[823]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:49 volumio sudo[823]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:50 volumio dhcpcd[794]: wlan0: leased 192.168.1.34 for 43200 seconds Jun 04 19:11:50 volumio avahi-daemon[529]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.34. Jun 04 19:11:50 volumio avahi-daemon[529]: New relevant interface wlan0.IPv4 for mDNS. Jun 04 19:11:50 volumio dhcpcd[794]: wlan0: adding route to 192.168.1.0/24 Jun 04 19:11:50 volumio avahi-daemon[529]: Registering new address record for 192.168.1.34 on wlan0.IPv4. Jun 04 19:11:50 volumio dhcpcd[794]: wlan0: adding default route via 192.168.1.254 Jun 04 19:11:50 volumio wireless.js[512]: trying... Jun 04 19:11:50 volumio sudo[846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 04 19:11:50 volumio sudo[846]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:50 volumio sudo[846]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:50 volumio wireless.js[512]: Connected to: ----Livebox-ARAKELOV Jun 04 19:11:50 volumio wireless.js[512]: ---- Jun 04 19:11:50 volumio sudo[849]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 19:11:50 volumio sudo[849]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:50 volumio sudo[849]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:50 volumio wireless.js[512]: ... joined AP, wlan0 IPv4 is 192.168.1.34, ipV6 is undefined Jun 04 19:11:50 volumio wireless.js[512]: It's done! AP Jun 04 19:11:51 volumio nmbd[653]: [2024/06/04 19:11:51.083112, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 04 19:11:51 volumio systemd[1]: Started Samba NMB Daemon. Jun 04 19:11:51 volumio nmbd[653]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jun 04 19:11:51 volumio systemd[1]: Started Wireless Services. Jun 04 19:11:51 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Jun 04 19:11:51 volumio systemd[1]: Started Volumio Backend Module. Jun 04 19:11:51 volumio systemd[1]: Started Volumio Cpu Tweaker. Jun 04 19:11:51 volumio systemd[1]: Starting Samba Winbind Daemon... Jun 04 19:11:51 volumio volumio-cpu-tweak[856]: Setting RT Priority for mpd Jun 04 19:11:51 volumio volumio-cpu-tweak[856]: pid 35's current scheduling policy: SCHED_OTHER Jun 04 19:11:51 volumio volumio-cpu-tweak[856]: pid 35's current scheduling priority: 0 Jun 04 19:11:51 volumio volumio-cpu-tweak[856]: Setting MPD Affinity Jun 04 19:11:51 volumio volumio-cpu-tweak[856]: pid 3's current affinity mask: f Jun 04 19:11:51 volumio volumio-cpu-tweak[856]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jun 04 19:11:51 volumio volumio-cpu-tweak[856]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jun 04 19:11:51 volumio systemd[1]: volumio_cpu_tweak.service: Succeeded. Jun 04 19:11:51 volumio winbindd[857]: [2024/06/04 19:11:51.550817, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jun 04 19:11:51 volumio winbindd[857]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jun 04 19:11:51 volumio winbindd[857]: [2024/06/04 19:11:51.575134, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 04 19:11:51 volumio winbindd[857]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jun 04 19:11:51 volumio systemd[1]: Started Samba Winbind Daemon. Jun 04 19:11:51 volumio systemd[1]: Starting Samba SMB Daemon... Jun 04 19:11:51 volumio volumio-remote-updater[521]: [2024-06-04 19:11:51] [info] asio async_connect error: system:111 (Connection refused) Jun 04 19:11:51 volumio volumio-remote-updater[521]: [2024-06-04 19:11:51] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 04 19:11:51 volumio volumio-remote-updater[521]: [2024-06-04 19:11:51] [error] handle_connect error: Underlying Transport Error Jun 04 19:11:52 volumio ntpd[697]: Listen normally on 3 wlan0 192.168.1.34:123 Jun 04 19:11:52 volumio ntpd[697]: new interface(s) found: waking up resolver Jun 04 19:11:52 volumio systemd[1]: Started Samba SMB Daemon. Jun 04 19:11:52 volumio systemd[1]: Reached target Multi-User System. Jun 04 19:11:52 volumio systemd[1]: Reached target Graphical Interface. Jun 04 19:11:52 volumio smbd[876]: [2024/06/04 19:11:52.263913, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jun 04 19:11:52 volumio smbd[876]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jun 04 19:11:52 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 04 19:11:52 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 04 19:11:52 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 04 19:11:52 volumio systemd[1]: Startup finished in 16.967s (kernel) + 21.467s (userspace) = 38.435s. Jun 04 19:11:54 volumio volumio[854]: info: ------------------------------------------- Jun 04 19:11:54 volumio volumio[854]: info: ----- Volumio3 ---- Jun 04 19:11:54 volumio volumio[854]: info: ------------------------------------------- Jun 04 19:11:54 volumio volumio[854]: info: ----- System startup ---- Jun 04 19:11:54 volumio volumio[854]: info: ------------------------------------------- Jun 04 19:11:55 volumio volumio[854]: info: MYVOLUMIO Environment detected Jun 04 19:11:56 volumio volumio[854]: info: Plugin folders cleanup Jun 04 19:11:56 volumio volumio[854]: info: Scanning into folder /volumio/app/plugins/ Jun 04 19:11:56 volumio volumio[854]: info: Scanning category audio_interface Jun 04 19:11:56 volumio volumio[854]: info: Scanning category miscellanea Jun 04 19:11:56 volumio volumio[854]: info: Scanning category music_service Jun 04 19:11:56 volumio volumio[854]: info: Scanning category plugins.json Jun 04 19:11:56 volumio volumio[854]: info: Scanning category system_controller Jun 04 19:11:56 volumio volumio[854]: info: Scanning category user_interface Jun 04 19:11:56 volumio volumio[854]: info: Scanning into folder /data/plugins/ Jun 04 19:11:56 volumio volumio[854]: info: Scanning category music_service Jun 04 19:11:56 volumio volumio[854]: info: Scanning category system_controller Jun 04 19:11:56 volumio volumio[854]: info: Scanning category system_hardware Jun 04 19:11:56 volumio volumio[854]: info: Scanning category user_interface Jun 04 19:11:56 volumio volumio[854]: info: Plugin folders cleanup completed Jun 04 19:11:56 volumio volumio[854]: info: ------------------------------------------- Jun 04 19:11:56 volumio volumio[854]: info: ----- Core plugins startup ---- Jun 04 19:11:56 volumio volumio[854]: info: ------------------------------------------- Jun 04 19:11:56 volumio volumio[854]: info: Loading plugins from folder /volumio/app/plugins/ Jun 04 19:11:56 volumio volumio[854]: info: Adding plugin upnp to MyMusic Plugins Jun 04 19:11:56 volumio volumio[854]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 04 19:11:56 volumio volumio[854]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 04 19:11:56 volumio volumio[854]: info: Loading plugins from folder /data/plugins/ Jun 04 19:11:56 volumio volumio[854]: info: Loading plugin "system"... Jun 04 19:11:56 volumio volumio[854]: info: Loading plugin "appearance"... Jun 04 19:11:58 volumio volumio[854]: info: Loading plugin "network"... Jun 04 19:11:58 volumio volumio[854]: info: Refreshing Cached IP Addresses Jun 04 19:11:58 volumio sudo[899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 19:11:58 volumio sudo[899]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:58 volumio volumio[854]: info: Loading plugin "services"... Jun 04 19:11:58 volumio sudo[901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 19:11:58 volumio sudo[901]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:58 volumio volumio[854]: info: Loading plugin "alsa_controller"... Jun 04 19:11:58 volumio sudo[901]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:58 volumio sudo[904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 04 19:11:58 volumio sudo[904]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:11:58 volumio sudo[899]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:58 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 19:11:58 volumio volumio[854]: info: Loading plugin "wizard"... Jun 04 19:11:58 volumio volumio[854]: info: Loading plugin "networkfs"... Jun 04 19:11:58 volumio volumio[854]: info: Starting Udev Watcher for removable devices Jun 04 19:11:58 volumio volumio[854]: info: Ignoring mount for partition: boot Jun 04 19:11:58 volumio volumio[854]: info: Ignoring mount for partition: volumio Jun 04 19:11:58 volumio volumio[854]: info: Ignoring mount for partition: volumio_data Jun 04 19:11:58 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 04 19:11:58 volumio volumio[854]: info: Loading plugin "volumio_command_line_client"... Jun 04 19:11:58 volumio volumio[854]: info: Loading plugin "upnp"... Jun 04 19:11:58 volumio volumio[854]: info: [1717521118305] Starting Upmpd Daemon Jun 04 19:11:58 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 04 19:11:58 volumio volumio[854]: info: Loading plugin "my_music"... Jun 04 19:11:58 volumio volumio[854]: info: Loading plugin "mpd"... Jun 04 19:11:58 volumio volumio[854]: info: Loading plugin "upnp_browser"... Jun 04 19:11:58 volumio sudo[904]: pam_unix(sudo:session): session closed for user root Jun 04 19:11:59 volumio volumio[854]: info: Loading plugin "alarm-clock"... Jun 04 19:11:59 volumio volumio[854]: info: Loading plugin "airplay_emulation"... Jun 04 19:11:59 volumio volumio[854]: info: Starting Shairport Sync Jun 04 19:11:59 volumio volumio[854]: info: Loading plugin "last_100"... Jun 04 19:11:59 volumio volumio[854]: info: Loading plugin "webradio"... Jun 04 19:11:59 volumio volumio[854]: info: Loading plugin "i2s_dacs"... Jun 04 19:11:59 volumio volumio[854]: info: Loading plugin "volumiodiscovery"... Jun 04 19:12:00 volumio volumio[854]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 04 19:12:00 volumio volumio[854]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 19:12:00 volumio volumio[854]: *** WARNING *** For more information see Jun 04 19:12:00 volumio volumio[854]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 04 19:12:00 volumio node[854]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 04 19:12:00 volumio volumio[854]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 19:12:00 volumio volumio[854]: *** WARNING *** For more information see Jun 04 19:12:00 volumio node[854]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 19:12:00 volumio node[854]: *** WARNING *** For more information see Jun 04 19:12:00 volumio node[854]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 04 19:12:00 volumio node[854]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 04 19:12:00 volumio node[854]: *** WARNING *** For more information see Jun 04 19:12:00 volumio volumio[854]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 04 19:12:00 volumio volumio[854]: info: Discovery: Started advertising with name: Volumio Jun 04 19:12:00 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 04 19:12:00 volumio volumio[854]: info: Loading plugin "ytmusic"... Jun 04 19:12:01 volumio volumio-remote-updater[521]: [2024-06-04 19:12:01] [connect] Successful connection Jun 04 19:12:02 volumio volumio[854]: info: Loading plugin "autostart"... Jun 04 19:12:02 volumio volumio[854]: info: Applying required configuration parameters for plugin autostart Jun 04 19:12:02 volumio volumio[854]: info: AutoStart - onVolumioStart - read config.json Jun 04 19:12:02 volumio volumio[854]: info: Loading plugin "outputs"... Jun 04 19:12:02 volumio volumio[854]: info: Loading plugin "albumart"... Jun 04 19:12:02 volumio volumio[854]: info: Plugin example_plugin is not enabled Jun 04 19:12:02 volumio volumio[854]: info: Loading plugin "inputs"... Jun 04 19:12:02 volumio volumio[854]: info: Loading plugin "updater_comm"... Jun 04 19:12:02 volumio volumio[854]: info: Plugin mpdemulation is not enabled Jun 04 19:12:02 volumio volumio[854]: info: Loading plugin "rest_api"... Jun 04 19:12:02 volumio volumio[854]: info: Loading plugin "websocket"... Jun 04 19:12:02 volumio volumio[854]: info: Starting Socket.io Server version 2.3.0 Jun 04 19:12:02 volumio volumio[854]: info: Loading plugin "backup_restore"... Jun 04 19:12:03 volumio volumio[854]: Forking 3 albumart workers Jun 04 19:12:03 volumio volumio[854]: info: Applying required configuration parameters for plugin backup_restore Jun 04 19:12:03 volumio volumio[854]: info: Loading plugin "pirateaudio"... Jun 04 19:12:03 volumio volumio[854]: info: Applying required configuration parameters for plugin pirateaudio Jun 04 19:12:03 volumio volumio[854]: info: Loading i18n strings for locale fr Jun 04 19:12:03 volumio volumio[854]: Updating browse sources language Jun 04 19:12:03 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:12:03 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 19:12:03 volumio volumio[854]: info: CoreCommandRouter::initPlayerControls Jun 04 19:12:03 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:03 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:03 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:03 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:03 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:03 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:03 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:03 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:04 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 19:12:04 volumio volumio[854]: Express server listening on port 3000 Jun 04 19:12:04 volumio volumio[854]: [Metrics] WebUI: 10s 565.78ms Jun 04 19:12:04 volumio volumio[854]: info: CoreStateMachine::resetVolumioState Jun 04 19:12:04 volumio volumio[854]: info: CoreStateMachine::getcurrentVolume Jun 04 19:12:04 volumio volumio[854]: info: CoreCommandRouter::volumioRetrievevolume Jun 04 19:12:04 volumio volumio[854]: Starting albumart workers Jun 04 19:12:04 volumio volumio[854]: Starting albumart workers Jun 04 19:12:04 volumio volumio[854]: Starting albumart workers Jun 04 19:12:04 volumio volumio[854]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.65 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Engine version: 3 Transport: polling Total Clients: 1 Jun 04 19:12:04 volumio volumio[854]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.65 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Engine version: 3 Transport: polling Total Clients: 2 Jun 04 19:12:04 volumio volumio[854]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.65 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Engine version: 3 Transport: polling Total Clients: 3 Jun 04 19:12:04 volumio volumio[854]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.30 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 4 Jun 04 19:12:04 volumio volumio[854]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.65 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Engine version: 3 Transport: polling Total Clients: 5 Jun 04 19:12:04 volumio volumio[854]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.30 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Jun 04 19:12:04 volumio volumio[854]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.65 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Engine version: 3 Transport: polling Total Clients: 7 Jun 04 19:12:04 volumio volumio-remote-updater[521]: [2024-06-04 19:12:04] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1717521121 101 Jun 04 19:12:04 volumio volumio[854]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 8 Jun 04 19:12:04 volumio volumio[854]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.30 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 9 Jun 04 19:12:04 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:04 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:04 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:12:04 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:04 volumio volumio[854]: info: CoreStateMachine::updateTrackBlock Jun 04 19:12:04 volumio volumio[854]: info: CorePlayQueue::getTrackBlock Jun 04 19:12:04 volumio volumio[854]: info: CoreCommandRouter::volumioRetrievevolume Jun 04 19:12:04 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:04 volumio volumio[854]: info: Reloading queue from file Jun 04 19:12:04 volumio volumio[854]: info: CoreStateMachine::setRepeat false single undefined Jun 04 19:12:04 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:04 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:04 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:12:04 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:04 volumio volumio[854]: info: CoreStateMachine::setRandom null Jun 04 19:12:04 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:04 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:04 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:04 volumio volumio[854]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 04 19:12:04 volumio volumio[854]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jun 04 19:12:04 volumio volumio[854]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 04 19:12:04 volumio volumio[854]: info: Setting Device type: Raspberry PI Jun 04 19:12:04 volumio volumio[854]: info: Completed loading Core Plugins Jun 04 19:12:04 volumio volumio[854]: info: Preparing to generate the ALSA configuration file Jun 04 19:12:04 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:04 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:04 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:04 volumio volumio[854]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 04 19:12:04 volumio volumio[854]: info: Reading ALSA contributions from plugins. Jun 04 19:12:04 volumio volumio[854]: info: Asound.conf file unchanged, so no further update is needed Jun 04 19:12:04 volumio volumio[854]: info: Output device has changed, restarting MPD Jun 04 19:12:04 volumio volumio[854]: info: Output device has changed, restarting Shairport Sync Jun 04 19:12:04 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:04 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:04 volumio sudo[962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 04 19:12:04 volumio sudo[965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 04 19:12:04 volumio sudo[962]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:12:04 volumio sudo[965]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:12:05 volumio sudo[962]: pam_unix(sudo:session): session closed for user root Jun 04 19:12:05 volumio volumio[854]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 19:12:05 volumio volumio[854]: info: ___________ START PLUGINS ___________ Jun 04 19:12:05 volumio volumio[854]: info: ControllerMpd::onStart: Initializing MPD Jun 04 19:12:05 volumio volumio[854]: info: Creating MPD Configuration file Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 19:12:05 volumio volumio[854]: info: [1717521125084] CoreMusicLibrary::Adding element Serveurs Média Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:12:05 volumio systemd[1]: Listening on mpd.socket. Jun 04 19:12:05 volumio sudo[987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 04 19:12:05 volumio systemd[1]: Starting Music Player Daemon... Jun 04 19:12:05 volumio sudo[983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 04 19:12:05 volumio sudo[987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:12:05 volumio sudo[983]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:12:05 volumio sudo[983]: pam_unix(sudo:session): session closed for user root Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:05 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jun 04 19:12:05 volumio systemd[1]: mpd.service: Succeeded. Jun 04 19:12:05 volumio systemd[1]: Stopped Music Player Daemon. Jun 04 19:12:05 volumio systemd[1]: Starting Music Player Daemon... Jun 04 19:12:05 volumio volumio[854]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 19:12:05 volumio volumio[854]: info: [1717521125298] CoreMusicLibrary::Adding element Last_100 Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 19:12:05 volumio volumio[854]: info: [1717521125302] CoreMusicLibrary::Adding element Webradio Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 19:12:05 volumio volumio[854]: info: Initializing BBC Radios Jun 04 19:12:05 volumio volumio[854]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 04 19:12:05 volumio volumio[854]: info: [1717521125399] CoreMusicLibrary::Adding element YouTube Music Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:12:05 volumio volumio[854]: Cannot find translation for source YouTube Music Jun 04 19:12:05 volumio volumio[854]: info: AutoStart - onStart Jun 04 19:12:05 volumio volumio[854]: info: Volumio Calling Home Jun 04 19:12:05 volumio sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start pirateaudio.service Jun 04 19:12:05 volumio sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:12:05 volumio sudo[1020]: pam_unix(sudo:session): session closed for user root Jun 04 19:12:05 volumio volumio[854]: info: Pirate audio service failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start pirateaudio.service Jun 04 19:12:05 volumio volumio[854]: Failed to start pirateaudio.service: Unit pirateaudio.service not found. Jun 04 19:12:05 volumio volumio[854]: info: MPD Permissions set Jun 04 19:12:05 volumio volumio[854]: info: MPD Permissions set Jun 04 19:12:05 volumio volumio[854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:05 volumio volumio[854]: info: Starting Shairport Sync Jun 04 19:12:05 volumio volumio[854]: info: Starting Shairport Sync Jun 04 19:12:05 volumio volumio[854]: info: Starting Shairport Sync Jun 04 19:12:05 volumio sudo[1034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 19:12:05 volumio sudo[1034]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:12:05 volumio sudo[1036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 19:12:05 volumio sudo[1036]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:12:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 04 19:12:05 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 04 19:12:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 04 19:12:05 volumio sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 04 19:12:05 volumio sudo[1039]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:12:06 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Jun 04 19:12:06 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 04 19:12:06 volumio sudo[1034]: pam_unix(sudo:session): session closed for user root Jun 04 19:12:06 volumio volumio[854]: info: Shairport-Sync Started Jun 04 19:12:06 volumio sudo[1036]: pam_unix(sudo:session): session closed for user root Jun 04 19:12:06 volumio sudo[1039]: pam_unix(sudo:session): session closed for user root Jun 04 19:12:06 volumio volumio[854]: Error adding Membership: Error: addMembership EINVAL Jun 04 19:12:06 volumio volumio[854]: info: Shairport-Sync Started Jun 04 19:12:06 volumio volumio[854]: info: Shairport-Sync Started Jun 04 19:12:06 volumio volumio[854]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.65 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Engine version: 3 Transport: polling Total Clients: 11 Jun 04 19:12:06 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 04 19:12:06 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 04 19:12:06 volumio volumio[854]: info: Discovery: adding 1774440e-520c-4934-84e0-c3a412917b88 Jun 04 19:12:06 volumio volumio[854]: info: Discovery: Found device Volumio Jun 04 19:12:06 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Jun 04 19:12:06 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:06 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Jun 04 19:12:06 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:06 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jun 04 19:12:08 volumio sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 04 19:12:08 volumio sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 04 19:12:08 volumio sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:12:08 volumio sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:12:08 volumio sudo[1048]: pam_unix(sudo:session): session closed for user root Jun 04 19:12:08 volumio sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 04 19:12:08 volumio sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:12:08 volumio sudo[1046]: pam_unix(sudo:session): session closed for user root Jun 04 19:12:08 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jun 04 19:12:08 volumio sudo[1050]: pam_unix(sudo:session): session closed for user root Jun 04 19:12:08 volumio volumio[854]: info: Upmpdcli Daemon Started Jun 04 19:12:09 volumio mpd[1003]: Jun 04 19:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 04 19:12:09 volumio systemd[1]: Started Music Player Daemon. Jun 04 19:12:09 volumio sudo[987]: pam_unix(sudo:session): session closed for user root Jun 04 19:12:09 volumio sudo[965]: pam_unix(sudo:session): session closed for user root Jun 04 19:12:09 volumio volumio[854]: info: Completed starting Core Plugins Jun 04 19:12:09 volumio volumio[854]: info: ------------------------------------------- Jun 04 19:12:09 volumio volumio[854]: info: ----- MyVolumio plugins startup ---- Jun 04 19:12:09 volumio volumio[854]: info: ------------------------------------------- Jun 04 19:12:09 volumio volumio[854]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 04 19:12:09 volumio volumio[854]: error: MPD error: The expression evaluated to a falsy value: Jun 04 19:12:09 volumio volumio[854]: assert.ok(self.idling) Jun 04 19:12:09 volumio volumio[854]: error: The expression evaluated to a falsy value: Jun 04 19:12:09 volumio volumio[854]: assert.ok(self.idling) Jun 04 19:12:09 volumio volumio[854]: error: updateQueue error: null Jun 04 19:12:09 volumio volumio[854]: info: MPD running with PID1003 Jun 04 19:12:09 volumio volumio[854]: ,establishing connection Jun 04 19:12:09 volumio volumio[854]: error: updateQueue error: null Jun 04 19:12:09 volumio volumio[1056]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 04 19:12:09 volumio volumio[854]: error: error Jun 04 19:12:09 volumio volumio[854]: info: Volumio called home Jun 04 19:12:10 volumio volumio[854]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.30 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 12 Jun 04 19:12:10 volumio volumio[854]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.65 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Engine version: 3 Transport: polling Total Clients: 13 Jun 04 19:12:10 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 04 19:12:10 volumio volumio[854]: info: CoreCommandRouter::volumioGetVisibleSources Jun 04 19:12:10 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:12:10 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Jun 04 19:12:10 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:10 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Jun 04 19:12:10 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:10 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 04 19:12:10 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 04 19:12:10 volumio volumio[854]: info: Received Get System Info Jun 04 19:12:10 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 19:12:10 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 19:12:10 volumio volumio[854]: info: Discovery: Getting this device information Jun 04 19:12:10 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Jun 04 19:12:10 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:10 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 19:12:10 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Jun 04 19:12:10 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:10 volumio volumio[854]: info: Listing playlists Jun 04 19:12:10 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 04 19:12:10 volumio volumio[1056]: ......++++ Jun 04 19:12:13 volumio volumio[854]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.30 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 14 Jun 04 19:12:14 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 04 19:12:14 volumio volumio[854]: info: CoreCommandRouter::volumioGetVisibleSources Jun 04 19:12:14 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:12:14 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Jun 04 19:12:14 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:14 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 04 19:12:14 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 04 19:12:14 volumio volumio[854]: info: Received Get System Info Jun 04 19:12:14 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 19:12:14 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 19:12:14 volumio volumio[854]: info: Discovery: Getting this device information Jun 04 19:12:14 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Jun 04 19:12:14 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:14 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 19:12:14 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Jun 04 19:12:14 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:14 volumio volumio[854]: info: Listing playlists Jun 04 19:12:14 volumio volumio[854]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 04 19:12:17 volumio volumio[854]: info: Adding plugin bluetooth to MyMusic Plugins Jun 04 19:12:17 volumio volumio[854]: info: Adding plugin multiroom to MyMusic Plugins Jun 04 19:12:17 volumio volumio[854]: info: Adding plugin metavolumio to MyMusic Plugins Jun 04 19:12:17 volumio volumio[854]: info: Adding plugin cd_controller to MyMusic Plugins Jun 04 19:12:17 volumio volumio[854]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 04 19:12:17 volumio volumio[854]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 04 19:12:17 volumio volumio[854]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 04 19:12:19 volumio volumio[854]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 04 19:12:19 volumio volumio[854]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 04 19:12:19 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:19 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:19 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:19 volumio volumio[854]: info: Starting MyVolumio Remote Streaming Endpoints Jun 04 19:12:19 volumio volumio[854]: info: MyVolumio login type: Token Jun 04 19:12:19 volumio volumio[854]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 04 19:12:19 volumio volumio[854]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 04 19:12:21 volumio volumio[854]: info: Starting Streaming Service Transparent Proxy Jun 04 19:12:21 volumio volumio[854]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 04 19:12:21 volumio volumio[854]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 04 19:12:21 volumio volumio[854]: info: Streaming services startup Jun 04 19:12:21 volumio volumio[854]: info: Starting Streaming Daemon Jun 04 19:12:21 volumio sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 04 19:12:21 volumio sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:12:21 volumio volumio[854]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 04 19:12:21 volumio sudo[1086]: pam_unix(sudo:session): session closed for user root Jun 04 19:12:21 volumio volumio[854]: error: Cannot start Volumio Streaming Daemon Jun 04 19:12:21 volumio volumio[854]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 04 19:12:21 volumio volumio[854]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 04 19:12:21 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 04 19:12:23 volumio volumio[854]: STREAMING PROXY: Starting server on port 3245 Jun 04 19:12:23 volumio volumio[854]: Node JS runtime: 14 Jun 04 19:12:23 volumio volumio[1056]: ...............................................................................++++ Jun 04 19:12:23 volumio volumio[1056]: e is 65537 (0x010001) Jun 04 19:12:23 volumio volumio[1056]: writing RSA key Jun 04 19:12:24 volumio volumio[854]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 04 19:12:25 volumio volumio[854]: info: AutoStart - Plugin is starting Jun 04 19:12:25 volumio volumio[854]: info: CoreCommandRouter::volumioGetQueue Jun 04 19:12:25 volumio volumio[854]: info: CoreStateMachine::getQueue Jun 04 19:12:25 volumio volumio[854]: info: CorePlayQueue::getQueue Jun 04 19:12:25 volumio volumio[854]: info: AutoStart - start playing Jun 04 19:12:25 volumio volumio[854]: info: AutoStart - start playing with no specific position Jun 04 19:12:25 volumio volumio[854]: info: CoreCommandRouter::volumioPlay Jun 04 19:12:25 volumio volumio[854]: info: CoreStateMachine::play index 0 Jun 04 19:12:25 volumio volumio[854]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 19:12:25 volumio volumio[854]: info: CoreStateMachine::stop Jun 04 19:12:25 volumio volumio[854]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 19:12:25 volumio volumio[854]: info: CoreStateMachine::play index undefined Jun 04 19:12:25 volumio volumio[854]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 19:12:25 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:25 volumio volumio[854]: info: CoreStateMachine::startPlaybackTimer Jun 04 19:12:25 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:25 volumio volumio[854]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Beginnings%20Are%20Such%20Delicate%20Times%22%2C%22artist%22%3A%22Hans%20Zimmer%22%2C%22album%22%3A%22Dune%3A%20Part%20Two%20(Original%20Motion%20Picture%20Soundtrack)%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FQ0BZW6aOZbumBmATyOLG6oNBz_zD9cZsme512atw25D5P2brotYkYQAs9u5MxQvYhiwAOZfv5ToPczo%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22acSkvBrfluM%22%2C%22playlistId%22%3A%22OLAK5uy_mBSlrOS3YnDt2YZ7CFnuarC6sLF0i6h0I%22%2C%22params%22%3A%22OAHyAQIIAQ%253D%253D%22%2C%22index%22%3A0%2C%22playlistSetVideoId%22%3A%2236AC7241CA33AA2E%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_mBSlrOS3YnDt2YZ7CFnuarC6sLF0i6h0I%22%2C%22params%22%3A%22OAHyAQIIAQ%253D%253D%22%2C%22videoId%22%3A%22HnES5cg-5Rc%22%7D%7D%7D%7D Jun 04 19:12:25 volumio volumio[854]: info: [ytmusic] InnertubeLoader: creating Innertube instance... Jun 04 19:12:25 volumio volumio[854]: info: MyVolumio token set successfully Jun 04 19:12:25 volumio volumio[854]: info: MYVOLUMIO: Adding device Jun 04 19:12:25 volumio volumio[854]: info: MYVOLUMIO: Evaluating Server Jun 04 19:12:26 volumio volumio[854]: info: MyVolumio status changed Jun 04 19:12:26 volumio volumio[854]: info: Streaming services startup Jun 04 19:12:26 volumio volumio[854]: info: Starting Streaming Daemon Jun 04 19:12:26 volumio volumio[854]: info: Removing browser output: myVolumio user plan is not superstar Jun 04 19:12:26 volumio volumio[854]: info: Removing audio output: Jun 04 19:12:26 volumio volumio[854]: info: Stoppping Tunnel 1 Jun 04 19:12:26 volumio sudo[1133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 04 19:12:26 volumio sudo[1133]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:12:26 volumio sudo[1133]: pam_unix(sudo:session): session closed for user root Jun 04 19:12:26 volumio sudo[1136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 04 19:12:26 volumio volumio[854]: error: Cannot start Volumio Streaming Daemon Jun 04 19:12:26 volumio volumio[854]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 04 19:12:26 volumio volumio[854]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 04 19:12:26 volumio sudo[1136]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:12:26 volumio sudo[1136]: pam_unix(sudo:session): session closed for user root Jun 04 19:12:26 volumio volumio[854]: info: Remote SSH Stopped Jun 04 19:12:28 volumio volumio[854]: info: Setting Geolocation for MyVolumio to as1 Jun 04 19:12:28 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:28 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:28 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:28 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:29 volumio volumio[854]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 04 19:12:29 volumio volumio[854]: info: Updating MyVolumio device info Jun 04 19:12:29 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:29 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:30 volumio volumio[854]: info: [ytmusic] InnertubeLoader: creating Auth instance... Jun 04 19:12:30 volumio volumio[854]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 19:12:30 volumio volumio[854]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 19:12:30 volumio volumio[854]: info: [ytmusic] InnertubeLoader: Auth instance created (status: signed in) Jun 04 19:12:30 volumio volumio[854]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 04 19:12:31 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:31 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 19:12:31 volumio volumio[854]: info: sendMpdCommand stop took 10 milliseconds Jun 04 19:12:31 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand clear Jun 04 19:12:31 volumio volumio[854]: info: sendMpdCommand clear took 1 milliseconds Jun 04 19:12:31 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand addid "https://rr2---sn-2gb7snez.googlevideo.com/videoplayback?expire=1717542775&ei=F0tfZvC8F7aEp-oP7Yn2gAw&ip=82.65.93.89&id=o-AJayXpT637OdClmUSPX9VlUJFpvirwbQSxIacUUsLPsl&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=vc&mm=31%2C29&mn=sn-2gb7snez%2Csn-25glenld&ms=au%2Crdu&mv=m&mvi=2&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2295000&hightc=yes&siu=1&bui=AbKP-1NdaD4YWevJsKAvHaMNs5It_SYcDvxgVOxD8yR6BP53MIU-lEJTWcrgsgU4DKBiWfgiqg&spc=UWF9f5IXR00Q4uotzUIovknOY7rR_cpUhEAPxZxapLLlKUYuMvsSolUxu7Vf&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yI_XUFi2H9ORQhHO_ajb8CcQ&rqh=1&gir=yes&clen=17743595&dur=536.301&lmt=1714707010369793&mt=1717520697&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=HafEX-EnbaA4yg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIhAIT8J5uby82QyZg9-TRlXrPDZsZ655m4a8UU_wLz8gxyAiBsez6zQMWbgYCsUHVXpsH6OeEDim6RTUlsACpEKKS01A%3D%3D&sig=AJfQdSswRAIgLQdebbf6CUbrnU57mwTVgCdsWF4dznoWQwAhEyFYBpECIE21zxkxmJdJOE4WRule-OUZA6xrvAZ_wFZpx2Cd8NlB&cver=1.20211213.00.00&t.YouTube" Jun 04 19:12:31 volumio volumio[854]: info: Jun 04 19:12:31 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:31 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:31 volumio volumio[854]: info: Jun 04 19:12:31 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:31 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:31 volumio volumio[854]: info: sendMpdCommand addid "https://rr2---sn-2gb7snez.googlevideo.com/videoplayback?expire=1717542775&ei=F0tfZvC8F7aEp-oP7Yn2gAw&ip=82.65.93.89&id=o-AJayXpT637OdClmUSPX9VlUJFpvirwbQSxIacUUsLPsl&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=vc&mm=31%2C29&mn=sn-2gb7snez%2Csn-25glenld&ms=au%2Crdu&mv=m&mvi=2&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2295000&hightc=yes&siu=1&bui=AbKP-1NdaD4YWevJsKAvHaMNs5It_SYcDvxgVOxD8yR6BP53MIU-lEJTWcrgsgU4DKBiWfgiqg&spc=UWF9f5IXR00Q4uotzUIovknOY7rR_cpUhEAPxZxapLLlKUYuMvsSolUxu7Vf&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yI_XUFi2H9ORQhHO_ajb8CcQ&rqh=1&gir=yes&clen=17743595&dur=536.301&lmt=1714707010369793&mt=1717520697&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=HafEX-EnbaA4yg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIhAIT8J5uby82QyZg9-TRlXrPDZsZ655m4a8UU_wLz8gxyAiBsez6zQMWbgYCsUHVXpsH6OeEDim6RTUlsACpEKKS01A%3D%3D&sig=AJfQdSswRAIgLQdebbf6CUbrnU57mwTVgCdsWF4dznoWQwAhEyFYBpECIE21zxkxmJdJOE4WRule-OUZA6xrvAZ_wFZpx2Cd8NlB&cver=1.20211213.00.00&t.YouTube" took 5 milliseconds Jun 04 19:12:31 volumio volumio[854]: verbose: MPD COMMAND [object Object] Jun 04 19:12:31 volumio volumio[854]: verbose: MPD COMMAND [object Object] Jun 04 19:12:31 volumio volumio[854]: verbose: MPD COMMAND [object Object] Jun 04 19:12:31 volumio volumio[854]: info: Jun 04 19:12:31 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:31 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:31 volumio volumio[854]: info: Jun 04 19:12:31 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:31 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:31 volumio volumio[854]: info: Jun 04 19:12:31 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:31 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:31 volumio volumio[854]: info: ------------------------------ 16ms Jun 04 19:12:31 volumio volumio[854]: info: ------------------------------ 14ms Jun 04 19:12:31 volumio volumio[854]: info: ------------------------------ 9ms Jun 04 19:12:31 volumio volumio[854]: info: ------------------------------ 8ms Jun 04 19:12:31 volumio volumio[854]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 04 19:12:31 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand play Jun 04 19:12:31 volumio volumio[854]: info: Jun 04 19:12:31 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:31 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:31 volumio volumio[854]: info: Jun 04 19:12:31 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:31 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:31 volumio volumio[854]: info: ------------------------------ 11ms Jun 04 19:12:31 volumio volumio[854]: info: sendMpdCommand play took 8 milliseconds Jun 04 19:12:31 volumio volumio[854]: info: ------------------------------ 7ms Jun 04 19:12:31 volumio volumio[854]: info: ------------------------------ 6ms Jun 04 19:12:33 volumio volumio[854]: info: MYVOLUMIO: Adding device Jun 04 19:12:33 volumio volumio[854]: info: MYVOLUMIO: Evaluating Server Jun 04 19:12:33 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:33 volumio volumio[854]: info: Jun 04 19:12:33 volumio volumio[854]: ---------------------------- MPD announces state update: player Jun 04 19:12:33 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:33 volumio volumio[854]: info: Jun 04 19:12:33 volumio volumio[854]: ---------------------------- MPD announces state update: player Jun 04 19:12:33 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:33 volumio volumio[854]: info: Jun 04 19:12:33 volumio volumio[854]: ---------------------------- MPD announces state update: player Jun 04 19:12:33 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand status took 63 milliseconds Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:33 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:33 volumio volumio[854]: info: Jun 04 19:12:33 volumio volumio[854]: ---------------------------- MPD announces state update: player Jun 04 19:12:33 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand status took 70 milliseconds Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand status took 69 milliseconds Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand status took 67 milliseconds Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:12:33 volumio volumio[854]: info: Jun 04 19:12:33 volumio volumio[854]: ---------------------------- MPD announces state update: player Jun 04 19:12:33 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:33 volumio volumio[854]: info: Jun 04 19:12:33 volumio volumio[854]: ---------------------------- MPD announces state update: player Jun 04 19:12:33 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand status took 18 milliseconds Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand status took 17 milliseconds Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand status took 7 milliseconds Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand status took 5 milliseconds Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:33 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:12:33 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:33 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":221,"duration":536,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beginnings Are Such Delicate Times","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr2---sn-2gb7snez.googlevideo.com/videoplayback?expire=1717542775&ei=F0tfZvC8F7aEp-oP7Yn2gAw&ip=82.65.93.89&id=o-AJayXpT637OdClmUSPX9VlUJFpvirwbQSxIacUUsLPsl&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=vc&mm=31%2C29&mn=sn-2gb7snez%2Csn-25glenld&ms=au%2Crdu&mv=m&mvi=2&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2295000&hightc=yes&siu=1&bui=AbKP-1NdaD4YWevJsKAvHaMNs5It_SYcDvxgVOxD8yR6BP53MIU-lEJTWcrgsgU4DKBiWfgiqg&spc=UWF9f5IXR00Q4uotzUIovknOY7rR_cpUhEAPxZxapLLlKUYuMvsSolUxu7Vf&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yI_XUFi2H9ORQhHO_ajb8CcQ&rqh=1&gir=yes&clen=17743595&dur=536.301&lmt=1714707010369793&mt=1717520697&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=HafEX-EnbaA4yg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIhAIT8J5uby82QyZg9-TRlXrPDZsZ655m4a8UU_wLz8gxyAiBsez6zQMWbgYCsUHVXpsH6OeEDim6RTUlsACpEKKS01A%3D%3D&sig=AJfQdSswRAIgLQdebbf6CUbrnU57mwTVgCdsWF4dznoWQwAhEyFYBpECIE21zxkxmJdJOE4WRule-OUZA6xrvAZ_wFZpx2Cd8NlB&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:12:33 volumio volumio[854]: verbose: CURRENT POSITION 0 Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus stop Jun 04 19:12:33 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:12:33 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:33 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":315,"duration":536,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beginnings Are Such Delicate Times","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr2---sn-2gb7snez.googlevideo.com/videoplayback?expire=1717542775&ei=F0tfZvC8F7aEp-oP7Yn2gAw&ip=82.65.93.89&id=o-AJayXpT637OdClmUSPX9VlUJFpvirwbQSxIacUUsLPsl&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=vc&mm=31%2C29&mn=sn-2gb7snez%2Csn-25glenld&ms=au%2Crdu&mv=m&mvi=2&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2295000&hightc=yes&siu=1&bui=AbKP-1NdaD4YWevJsKAvHaMNs5It_SYcDvxgVOxD8yR6BP53MIU-lEJTWcrgsgU4DKBiWfgiqg&spc=UWF9f5IXR00Q4uotzUIovknOY7rR_cpUhEAPxZxapLLlKUYuMvsSolUxu7Vf&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yI_XUFi2H9ORQhHO_ajb8CcQ&rqh=1&gir=yes&clen=17743595&dur=536.301&lmt=1714707010369793&mt=1717520697&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=HafEX-EnbaA4yg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIhAIT8J5uby82QyZg9-TRlXrPDZsZ655m4a8UU_wLz8gxyAiBsez6zQMWbgYCsUHVXpsH6OeEDim6RTUlsACpEKKS01A%3D%3D&sig=AJfQdSswRAIgLQdebbf6CUbrnU57mwTVgCdsWF4dznoWQwAhEyFYBpECIE21zxkxmJdJOE4WRule-OUZA6xrvAZ_wFZpx2Cd8NlB&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:12:33 volumio volumio[854]: verbose: CURRENT POSITION 0 Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus play Jun 04 19:12:33 volumio volumio[854]: info: Received an update from plugin. extracting info from payload Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:33 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:12:33 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:33 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":379,"duration":536,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beginnings Are Such Delicate Times","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr2---sn-2gb7snez.googlevideo.com/videoplayback?expire=1717542775&ei=F0tfZvC8F7aEp-oP7Yn2gAw&ip=82.65.93.89&id=o-AJayXpT637OdClmUSPX9VlUJFpvirwbQSxIacUUsLPsl&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=vc&mm=31%2C29&mn=sn-2gb7snez%2Csn-25glenld&ms=au%2Crdu&mv=m&mvi=2&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2295000&hightc=yes&siu=1&bui=AbKP-1NdaD4YWevJsKAvHaMNs5It_SYcDvxgVOxD8yR6BP53MIU-lEJTWcrgsgU4DKBiWfgiqg&spc=UWF9f5IXR00Q4uotzUIovknOY7rR_cpUhEAPxZxapLLlKUYuMvsSolUxu7Vf&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yI_XUFi2H9ORQhHO_ajb8CcQ&rqh=1&gir=yes&clen=17743595&dur=536.301&lmt=1714707010369793&mt=1717520697&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=HafEX-EnbaA4yg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIhAIT8J5uby82QyZg9-TRlXrPDZsZ655m4a8UU_wLz8gxyAiBsez6zQMWbgYCsUHVXpsH6OeEDim6RTUlsACpEKKS01A%3D%3D&sig=AJfQdSswRAIgLQdebbf6CUbrnU57mwTVgCdsWF4dznoWQwAhEyFYBpECIE21zxkxmJdJOE4WRule-OUZA6xrvAZ_wFZpx2Cd8NlB&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:12:33 volumio volumio[854]: verbose: CURRENT POSITION 0 Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus play Jun 04 19:12:33 volumio volumio[854]: info: Received an update from plugin. extracting info from payload Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:33 volumio volumio[854]: info: ------------------------------ 140ms Jun 04 19:12:33 volumio volumio[854]: info: ------------------------------ 153ms Jun 04 19:12:33 volumio volumio[854]: info: ------------------------------ 152ms Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand playlistinfo took 73 milliseconds Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand playlistinfo took 74 milliseconds Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand playlistinfo took 72 milliseconds Jun 04 19:12:33 volumio volumio[854]: info: sendMpdCommand playlistinfo took 71 milliseconds Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:12:33 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:12:33 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:12:33 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:33 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":536,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beginnings Are Such Delicate Times","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr2---sn-2gb7snez.googlevideo.com/videoplayback?expire=1717542775&ei=F0tfZvC8F7aEp-oP7Yn2gAw&ip=82.65.93.89&id=o-AJayXpT637OdClmUSPX9VlUJFpvirwbQSxIacUUsLPsl&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=vc&mm=31%2C29&mn=sn-2gb7snez%2Csn-25glenld&ms=au%2Crdu&mv=m&mvi=2&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2295000&hightc=yes&siu=1&bui=AbKP-1NdaD4YWevJsKAvHaMNs5It_SYcDvxgVOxD8yR6BP53MIU-lEJTWcrgsgU4DKBiWfgiqg&spc=UWF9f5IXR00Q4uotzUIovknOY7rR_cpUhEAPxZxapLLlKUYuMvsSolUxu7Vf&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yI_XUFi2H9ORQhHO_ajb8CcQ&rqh=1&gir=yes&clen=17743595&dur=536.301&lmt=1714707010369793&mt=1717520697&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=HafEX-EnbaA4yg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIhAIT8J5uby82QyZg9-TRlXrPDZsZ655m4a8UU_wLz8gxyAiBsez6zQMWbgYCsUHVXpsH6OeEDim6RTUlsACpEKKS01A%3D%3D&sig=AJfQdSswRAIgLQdebbf6CUbrnU57mwTVgCdsWF4dznoWQwAhEyFYBpECIE21zxkxmJdJOE4WRule-OUZA6xrvAZ_wFZpx2Cd8NlB&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:12:33 volumio volumio[854]: verbose: CURRENT POSITION 0 Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus play Jun 04 19:12:33 volumio volumio[854]: info: Received an update from plugin. extracting info from payload Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:33 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:12:33 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:33 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":536,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beginnings Are Such Delicate Times","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr2---sn-2gb7snez.googlevideo.com/videoplayback?expire=1717542775&ei=F0tfZvC8F7aEp-oP7Yn2gAw&ip=82.65.93.89&id=o-AJayXpT637OdClmUSPX9VlUJFpvirwbQSxIacUUsLPsl&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=vc&mm=31%2C29&mn=sn-2gb7snez%2Csn-25glenld&ms=au%2Crdu&mv=m&mvi=2&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2295000&hightc=yes&siu=1&bui=AbKP-1NdaD4YWevJsKAvHaMNs5It_SYcDvxgVOxD8yR6BP53MIU-lEJTWcrgsgU4DKBiWfgiqg&spc=UWF9f5IXR00Q4uotzUIovknOY7rR_cpUhEAPxZxapLLlKUYuMvsSolUxu7Vf&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yI_XUFi2H9ORQhHO_ajb8CcQ&rqh=1&gir=yes&clen=17743595&dur=536.301&lmt=1714707010369793&mt=1717520697&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=HafEX-EnbaA4yg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIhAIT8J5uby82QyZg9-TRlXrPDZsZ655m4a8UU_wLz8gxyAiBsez6zQMWbgYCsUHVXpsH6OeEDim6RTUlsACpEKKS01A%3D%3D&sig=AJfQdSswRAIgLQdebbf6CUbrnU57mwTVgCdsWF4dznoWQwAhEyFYBpECIE21zxkxmJdJOE4WRule-OUZA6xrvAZ_wFZpx2Cd8NlB&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:12:33 volumio volumio[854]: verbose: CURRENT POSITION 0 Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus play Jun 04 19:12:33 volumio volumio[854]: info: Received an update from plugin. extracting info from payload Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:33 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:12:33 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:33 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":536,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Beginnings Are Such Delicate Times","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr2---sn-2gb7snez.googlevideo.com/videoplayback?expire=1717542775&ei=F0tfZvC8F7aEp-oP7Yn2gAw&ip=82.65.93.89&id=o-AJayXpT637OdClmUSPX9VlUJFpvirwbQSxIacUUsLPsl&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=vc&mm=31%2C29&mn=sn-2gb7snez%2Csn-25glenld&ms=au%2Crdu&mv=m&mvi=2&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2295000&hightc=yes&siu=1&bui=AbKP-1NdaD4YWevJsKAvHaMNs5It_SYcDvxgVOxD8yR6BP53MIU-lEJTWcrgsgU4DKBiWfgiqg&spc=UWF9f5IXR00Q4uotzUIovknOY7rR_cpUhEAPxZxapLLlKUYuMvsSolUxu7Vf&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=yI_XUFi2H9ORQhHO_ajb8CcQ&rqh=1&gir=yes&clen=17743595&dur=536.301&lmt=1714707010369793&mt=1717520697&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=HafEX-EnbaA4yg&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRQIhAIT8J5uby82QyZg9-TRlXrPDZsZ655m4a8UU_wLz8gxyAiBsez6zQMWbgYCsUHVXpsH6OeEDim6RTUlsACpEKKS01A%3D%3D&sig=AJfQdSswRAIgLQdebbf6CUbrnU57mwTVgCdsWF4dznoWQwAhEyFYBpECIE21zxkxmJdJOE4WRule-OUZA6xrvAZ_wFZpx2Cd8NlB&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:12:33 volumio volumio[854]: verbose: CURRENT POSITION 0 Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus play Jun 04 19:12:33 volumio volumio[854]: info: Received an update from plugin. extracting info from payload Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:33 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:33 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:33 volumio volumio[854]: info: ------------------------------ 150ms Jun 04 19:12:33 volumio volumio[854]: info: ------------------------------ 139ms Jun 04 19:12:33 volumio volumio[854]: info: ------------------------------ 138ms Jun 04 19:12:34 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 04 19:12:34 volumio volumio[854]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 04 19:12:34 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Jun 04 19:12:35 volumio volumio[854]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jun 04 19:12:35 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:35 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 19:12:35 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:35 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 04 19:12:39 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:39 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:39 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 04 19:12:39 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 04 19:12:39 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 04 19:12:39 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 04 19:12:39 volumio volumio[854]: info: CoreCommandRouter::volumioGetBrowseSources Jun 04 19:12:39 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 04 19:12:41 volumio volumio[854]: info: Setting Geolocation for MyVolumio to eu2 Jun 04 19:12:41 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:41 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:41 volumio volumio[854]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 04 19:12:41 volumio volumio[854]: info: Completed starting MyVolumio Plugin Jun 04 19:12:41 volumio volumio[854]: info: BOOT COMPLETED Jun 04 19:12:41 volumio volumio[854]: [Metrics] CommandRouter: 46s 973.48ms Jun 04 19:12:41 volumio volumio[854]: info: CoreCommandRouter::volumiosetStartupVolume Jun 04 19:12:41 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 04 19:12:41 volumio volumio[854]: info: VolumeController:: Setting startup Volume 10 Jun 04 19:12:41 volumio volumio[854]: info: VolumeController::SetAlsaVolume10 Jun 04 19:12:41 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:41 volumio volumio[854]: info: CoreCommandRouter::Close All Modals sent Jun 04 19:12:41 volumio volumio[854]: info: CoreCommandRouter::Close All Modals sent Jun 04 19:12:41 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:41 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:12:41 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:41 volumio volumio[854]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 04 19:12:41 volumio volumio[854]: aplay: main:828: audio open error: Device or resource busy Jun 04 19:12:42 volumio volumio[854]: info: Updating MyVolumio device info Jun 04 19:12:42 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:42 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 04 19:12:42 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 04 19:12:42 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 04 19:12:42 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 04 19:12:44 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 04 19:12:44 volumio volumio-remote-updater[521]: No test mode Jun 04 19:12:44 volumio volumio-remote-updater[521]: No alpha test mode Jun 04 19:12:46 volumio ntpd[697]: Soliciting pool server 188.165.49.6 Jun 04 19:12:46 volumio volumio[854]: info: CoreCommandRouter::volumioNext Jun 04 19:12:46 volumio volumio[854]: info: CoreStateMachine::next Jun 04 19:12:46 volumio volumio[854]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 04 19:12:46 volumio volumio[854]: info: ControllerMpd::next Jun 04 19:12:46 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand next Jun 04 19:12:46 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:46 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:46 volumio volumio[854]: info: Jun 04 19:12:46 volumio volumio[854]: ---------------------------- MPD announces state update: player Jun 04 19:12:46 volumio volumio[854]: info: sendMpdCommand next took 18 milliseconds Jun 04 19:12:46 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:46 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:46 volumio volumio[854]: info: Jun 04 19:12:46 volumio volumio[854]: ---------------------------- MPD announces state update: player Jun 04 19:12:46 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:46 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:46 volumio volumio[854]: info: Jun 04 19:12:46 volumio volumio[854]: ---------------------------- MPD announces state update: player Jun 04 19:12:46 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:46 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:46 volumio volumio[854]: info: sendMpdCommand status took 12 milliseconds Jun 04 19:12:46 volumio volumio[854]: info: sendMpdCommand status took 10 milliseconds Jun 04 19:12:46 volumio volumio[854]: info: sendMpdCommand status took 6 milliseconds Jun 04 19:12:46 volumio volumio[854]: info: sendMpdCommand status took 4 milliseconds Jun 04 19:12:46 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:46 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:46 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:46 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:46 volumio volumio[854]: info: CoreStateMachine::getQueue Jun 04 19:12:46 volumio volumio[854]: info: CorePlayQueue::getQueue Jun 04 19:12:46 volumio volumio[854]: info: CoreStateMachine::getQueue Jun 04 19:12:46 volumio volumio[854]: info: CorePlayQueue::getQueue Jun 04 19:12:46 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:12:46 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:12:46 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:46 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:12:46 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:46 volumio volumio[854]: info: CorePlayQueue::getTrack 0 Jun 04 19:12:46 volumio volumio[854]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 04 19:12:46 volumio volumio[854]: verbose: CURRENT POSITION 0 Jun 04 19:12:46 volumio volumio[854]: info: CoreStateMachine::syncState stateService stop Jun 04 19:12:46 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus play Jun 04 19:12:46 volumio volumio[854]: info: CoreStateMachine::play index undefined Jun 04 19:12:46 volumio volumio[854]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 04 19:12:46 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:46 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:46 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:12:46 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:46 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:12:46 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:12:46 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:46 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:46 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:46 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:46 volumio volumio[854]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Jun 04 19:12:46 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:12:46 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:12:46 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:46 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:46 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:46 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:46 volumio volumio[854]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd Jun 04 19:12:46 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:46 volumio volumio[854]: info: CoreStateMachine::startPlaybackTimer Jun 04 19:12:46 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:46 volumio volumio[854]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22Eclipse%22%2C%22artist%22%3A%22Hans%20Zimmer%22%2C%22album%22%3A%22Dune%3A%20Part%20Two%20(Original%20Motion%20Picture%20Soundtrack)%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FQ0BZW6aOZbumBmATyOLG6oNBz_zD9cZsme512atw25D5P2brotYkYQAs9u5MxQvYhiwAOZfv5ToPczo%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22bagHvmvrxG0%22%2C%22playlistId%22%3A%22OLAK5uy_mBSlrOS3YnDt2YZ7CFnuarC6sLF0i6h0I%22%2C%22params%22%3A%22OAHyAQIIAQ%253D%253D%22%2C%22index%22%3A1%2C%22playlistSetVideoId%22%3A%22094B38EAE3176E80%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_mBSlrOS3YnDt2YZ7CFnuarC6sLF0i6h0I%22%2C%22params%22%3A%22OAHyAQIIAQ%253D%253D%22%2C%22videoId%22%3A%22HnES5cg-5Rc%22%7D%7D%7D%7D Jun 04 19:12:46 volumio volumio[854]: info: ------------------------------ 59ms Jun 04 19:12:46 volumio volumio[854]: info: ------------------------------ 56ms Jun 04 19:12:46 volumio volumio[854]: info: ------------------------------ 54ms Jun 04 19:12:46 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:46 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:46 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:12:46 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:46 volumio volumio[854]: info: VolumeController::SetAlsaVolume100 Jun 04 19:12:46 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:46 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:46 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:12:46 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:47 volumio ntpd[697]: Soliciting pool server 82.64.81.218 Jun 04 19:12:48 volumio ntpd[697]: Soliciting pool server 82.65.248.56 Jun 04 19:12:48 volumio ntpd[697]: Soliciting pool server 178.33.41.123 Jun 04 19:12:49 volumio ntpd[697]: Soliciting pool server 178.32.23.17 Jun 04 19:12:49 volumio ntpd[697]: Soliciting pool server 5.39.80.51 Jun 04 19:12:49 volumio ntpd[697]: Soliciting pool server 217.182.137.208 Jun 04 19:12:50 volumio ntpd[697]: Soliciting pool server 51.15.191.239 Jun 04 19:12:50 volumio ntpd[697]: Soliciting pool server 51.195.104.188 Jun 04 19:12:50 volumio ntpd[697]: Soliciting pool server 162.159.200.123 Jun 04 19:12:50 volumio volumio[854]: error: Failed to add MyVolumio device: undefined Jun 04 19:12:50 volumio volumio[854]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 04 19:12:50 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 04 19:12:50 volumio volumio[854]: info: VolumeController::SetAlsaVolume100 Jun 04 19:12:50 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:50 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:50 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:12:50 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:51 volumio ntpd[697]: Soliciting pool server 54.38.114.34 Jun 04 19:12:51 volumio ntpd[697]: Soliciting pool server 79.143.250.33 Jun 04 19:12:51 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:51 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand stop Jun 04 19:12:51 volumio volumio[854]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 04 19:12:51 volumio volumio[854]: info: sendMpdCommand stop took 15 milliseconds Jun 04 19:12:51 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand clear Jun 04 19:12:51 volumio volumio[854]: info: Jun 04 19:12:51 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:51 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:51 volumio volumio[854]: info: sendMpdCommand clear took 10 milliseconds Jun 04 19:12:51 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand addid "https://rr4---sn-2gb7sne6.googlevideo.com/videoplayback?expire=1717542795&ei=K0tfZt39HPuTxN8P3_uN8Ak&ip=82.65.93.89&id=o-ANJ7yeNNZfeNX_bIIdaxRXro1BorEOPQFLEgrSYFPEoW&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kz&mm=31%2C29&mn=sn-2gb7sne6%2Csn-25glenlz&ms=au%2Crdu&mv=m&mvi=4&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2132500&hightc=yes&siu=1&bui=AbKP-1PRm3p6t6RqK2tQyLdVN1VDbDn-47fcKg9ZWuV-xQ5YRfPGcFm7ZEAam22tFthkir4TDg&spc=UWF9fxlpr2cS2yJt7MHFZWqyBE40jMLJFSBeIauCj8cEX9W1T7ggzZk5WWTa&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=PKEEmMBgp4gfr-jhFZSUFFQQ&rqh=1&gir=yes&clen=9718727&dur=313.961&lmt=1714724834100663&mt=1717520938&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=ABxjZ1spu6JIlA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgCknpnmdg0OAf0VPfCU7vEhk-O7HzwI3bSGFv3FLk0hMCIAYR7TlPkq2bVx_PTqzG1LWFuXfl5TDFAgPqSUgUuOqU&sig=AJfQdSswRAIgPitj6fr52YT5hutLTRYabO1gl0ROFBzokpz2Q1ZAK4ECIBi7fZqNgBIlH_8NG_ZWg0WVqV8P_NYqmNU3sLoNkbx8&cver=1.20211213.00.00&t.YouTube" Jun 04 19:12:51 volumio volumio[854]: info: Jun 04 19:12:51 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:51 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:51 volumio volumio[854]: info: Jun 04 19:12:51 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:51 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:51 volumio volumio[854]: error: updateQueue error: null Jun 04 19:12:51 volumio volumio[854]: info: Jun 04 19:12:51 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:51 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:51 volumio volumio[854]: info: ------------------------------ 19ms Jun 04 19:12:51 volumio volumio[854]: info: sendMpdCommand addid "https://rr4---sn-2gb7sne6.googlevideo.com/videoplayback?expire=1717542795&ei=K0tfZt39HPuTxN8P3_uN8Ak&ip=82.65.93.89&id=o-ANJ7yeNNZfeNX_bIIdaxRXro1BorEOPQFLEgrSYFPEoW&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kz&mm=31%2C29&mn=sn-2gb7sne6%2Csn-25glenlz&ms=au%2Crdu&mv=m&mvi=4&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2132500&hightc=yes&siu=1&bui=AbKP-1PRm3p6t6RqK2tQyLdVN1VDbDn-47fcKg9ZWuV-xQ5YRfPGcFm7ZEAam22tFthkir4TDg&spc=UWF9fxlpr2cS2yJt7MHFZWqyBE40jMLJFSBeIauCj8cEX9W1T7ggzZk5WWTa&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=PKEEmMBgp4gfr-jhFZSUFFQQ&rqh=1&gir=yes&clen=9718727&dur=313.961&lmt=1714724834100663&mt=1717520938&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=ABxjZ1spu6JIlA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgCknpnmdg0OAf0VPfCU7vEhk-O7HzwI3bSGFv3FLk0hMCIAYR7TlPkq2bVx_PTqzG1LWFuXfl5TDFAgPqSUgUuOqU&sig=AJfQdSswRAIgPitj6fr52YT5hutLTRYabO1gl0ROFBzokpz2Q1ZAK4ECIBi7fZqNgBIlH_8NG_ZWg0WVqV8P_NYqmNU3sLoNkbx8&cver=1.20211213.00.00&t.YouTube" took 15 milliseconds Jun 04 19:12:51 volumio volumio[854]: info: ------------------------------ 15ms Jun 04 19:12:51 volumio volumio[854]: info: ------------------------------ 12ms Jun 04 19:12:51 volumio volumio[854]: verbose: MPD COMMAND [object Object] Jun 04 19:12:51 volumio volumio[854]: verbose: MPD COMMAND [object Object] Jun 04 19:12:51 volumio volumio[854]: verbose: MPD COMMAND [object Object] Jun 04 19:12:51 volumio volumio[854]: info: Jun 04 19:12:51 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:51 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:51 volumio volumio[854]: info: Jun 04 19:12:51 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:51 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:51 volumio volumio[854]: info: Jun 04 19:12:51 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:51 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:51 volumio volumio[854]: info: ------------------------------ 23ms Jun 04 19:12:51 volumio volumio[854]: info: ------------------------------ 13ms Jun 04 19:12:51 volumio volumio[854]: info: ------------------------------ 9ms Jun 04 19:12:51 volumio volumio[854]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 04 19:12:51 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand play Jun 04 19:12:51 volumio volumio[854]: info: Jun 04 19:12:51 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:51 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:51 volumio volumio[854]: info: Jun 04 19:12:51 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:12:51 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:12:51 volumio volumio[854]: info: ------------------------------ 24ms Jun 04 19:12:51 volumio volumio[854]: info: sendMpdCommand play took 20 milliseconds Jun 04 19:12:51 volumio volumio[854]: info: ------------------------------ 19ms Jun 04 19:12:51 volumio volumio[854]: info: ------------------------------ 17ms Jun 04 19:12:52 volumio ntpd[697]: Soliciting pool server 82.64.45.50 Jun 04 19:12:52 volumio ntpd[697]: Soliciting pool server 2001:861:4080:81b1::3 Jun 04 19:12:52 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:52 volumio volumio[854]: info: Jun 04 19:12:52 volumio volumio[854]: ---------------------------- MPD announces state update: player Jun 04 19:12:52 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:52 volumio volumio[854]: info: Jun 04 19:12:52 volumio volumio[854]: ---------------------------- MPD announces state update: player Jun 04 19:12:52 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:52 volumio volumio[854]: info: Jun 04 19:12:52 volumio volumio[854]: ---------------------------- MPD announces state update: player Jun 04 19:12:52 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:52 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:52 volumio volumio[854]: info: Jun 04 19:12:52 volumio volumio[854]: ---------------------------- MPD announces state update: player Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand status took 28 milliseconds Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand status took 27 milliseconds Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand status took 26 milliseconds Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand status took 24 milliseconds Jun 04 19:12:52 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand status took 13 milliseconds Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand status took 8 milliseconds Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:12:52 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:12:52 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:52 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Eclipse","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr4---sn-2gb7sne6.googlevideo.com/videoplayback?expire=1717542795&ei=K0tfZt39HPuTxN8P3_uN8Ak&ip=82.65.93.89&id=o-ANJ7yeNNZfeNX_bIIdaxRXro1BorEOPQFLEgrSYFPEoW&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kz&mm=31%2C29&mn=sn-2gb7sne6%2Csn-25glenlz&ms=au%2Crdu&mv=m&mvi=4&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2132500&hightc=yes&siu=1&bui=AbKP-1PRm3p6t6RqK2tQyLdVN1VDbDn-47fcKg9ZWuV-xQ5YRfPGcFm7ZEAam22tFthkir4TDg&spc=UWF9fxlpr2cS2yJt7MHFZWqyBE40jMLJFSBeIauCj8cEX9W1T7ggzZk5WWTa&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=PKEEmMBgp4gfr-jhFZSUFFQQ&rqh=1&gir=yes&clen=9718727&dur=313.961&lmt=1714724834100663&mt=1717520938&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=ABxjZ1spu6JIlA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgCknpnmdg0OAf0VPfCU7vEhk-O7HzwI3bSGFv3FLk0hMCIAYR7TlPkq2bVx_PTqzG1LWFuXfl5TDFAgPqSUgUuOqU&sig=AJfQdSswRAIgPitj6fr52YT5hutLTRYabO1gl0ROFBzokpz2Q1ZAK4ECIBi7fZqNgBIlH_8NG_ZWg0WVqV8P_NYqmNU3sLoNkbx8&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:12:52 volumio volumio[854]: verbose: CURRENT POSITION 1 Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus stop Jun 04 19:12:52 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:12:52 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:52 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Eclipse","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr4---sn-2gb7sne6.googlevideo.com/videoplayback?expire=1717542795&ei=K0tfZt39HPuTxN8P3_uN8Ak&ip=82.65.93.89&id=o-ANJ7yeNNZfeNX_bIIdaxRXro1BorEOPQFLEgrSYFPEoW&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kz&mm=31%2C29&mn=sn-2gb7sne6%2Csn-25glenlz&ms=au%2Crdu&mv=m&mvi=4&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2132500&hightc=yes&siu=1&bui=AbKP-1PRm3p6t6RqK2tQyLdVN1VDbDn-47fcKg9ZWuV-xQ5YRfPGcFm7ZEAam22tFthkir4TDg&spc=UWF9fxlpr2cS2yJt7MHFZWqyBE40jMLJFSBeIauCj8cEX9W1T7ggzZk5WWTa&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=PKEEmMBgp4gfr-jhFZSUFFQQ&rqh=1&gir=yes&clen=9718727&dur=313.961&lmt=1714724834100663&mt=1717520938&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=ABxjZ1spu6JIlA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgCknpnmdg0OAf0VPfCU7vEhk-O7HzwI3bSGFv3FLk0hMCIAYR7TlPkq2bVx_PTqzG1LWFuXfl5TDFAgPqSUgUuOqU&sig=AJfQdSswRAIgPitj6fr52YT5hutLTRYabO1gl0ROFBzokpz2Q1ZAK4ECIBi7fZqNgBIlH_8NG_ZWg0WVqV8P_NYqmNU3sLoNkbx8&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:12:52 volumio volumio[854]: verbose: CURRENT POSITION 1 Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus play Jun 04 19:12:52 volumio volumio[854]: info: Received an update from plugin. extracting info from payload Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:52 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:12:52 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:52 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Eclipse","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr4---sn-2gb7sne6.googlevideo.com/videoplayback?expire=1717542795&ei=K0tfZt39HPuTxN8P3_uN8Ak&ip=82.65.93.89&id=o-ANJ7yeNNZfeNX_bIIdaxRXro1BorEOPQFLEgrSYFPEoW&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kz&mm=31%2C29&mn=sn-2gb7sne6%2Csn-25glenlz&ms=au%2Crdu&mv=m&mvi=4&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2132500&hightc=yes&siu=1&bui=AbKP-1PRm3p6t6RqK2tQyLdVN1VDbDn-47fcKg9ZWuV-xQ5YRfPGcFm7ZEAam22tFthkir4TDg&spc=UWF9fxlpr2cS2yJt7MHFZWqyBE40jMLJFSBeIauCj8cEX9W1T7ggzZk5WWTa&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=PKEEmMBgp4gfr-jhFZSUFFQQ&rqh=1&gir=yes&clen=9718727&dur=313.961&lmt=1714724834100663&mt=1717520938&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=ABxjZ1spu6JIlA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgCknpnmdg0OAf0VPfCU7vEhk-O7HzwI3bSGFv3FLk0hMCIAYR7TlPkq2bVx_PTqzG1LWFuXfl5TDFAgPqSUgUuOqU&sig=AJfQdSswRAIgPitj6fr52YT5hutLTRYabO1gl0ROFBzokpz2Q1ZAK4ECIBi7fZqNgBIlH_8NG_ZWg0WVqV8P_NYqmNU3sLoNkbx8&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:12:52 volumio volumio[854]: verbose: CURRENT POSITION 1 Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus play Jun 04 19:12:52 volumio volumio[854]: info: Received an update from plugin. extracting info from payload Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:52 volumio volumio[854]: info: ------------------------------ 80ms Jun 04 19:12:52 volumio volumio[854]: info: ------------------------------ 88ms Jun 04 19:12:52 volumio volumio[854]: info: ------------------------------ 88ms Jun 04 19:12:52 volumio volumio[854]: info: Jun 04 19:12:52 volumio volumio[854]: ---------------------------- MPD announces state update: player Jun 04 19:12:52 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:52 volumio volumio[854]: info: Jun 04 19:12:52 volumio volumio[854]: ---------------------------- MPD announces state update: player Jun 04 19:12:52 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand playlistinfo took 63 milliseconds Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand playlistinfo took 62 milliseconds Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand status took 13 milliseconds Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand status took 5 milliseconds Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:12:52 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:12:52 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:52 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Eclipse","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr4---sn-2gb7sne6.googlevideo.com/videoplayback?expire=1717542795&ei=K0tfZt39HPuTxN8P3_uN8Ak&ip=82.65.93.89&id=o-ANJ7yeNNZfeNX_bIIdaxRXro1BorEOPQFLEgrSYFPEoW&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kz&mm=31%2C29&mn=sn-2gb7sne6%2Csn-25glenlz&ms=au%2Crdu&mv=m&mvi=4&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2132500&hightc=yes&siu=1&bui=AbKP-1PRm3p6t6RqK2tQyLdVN1VDbDn-47fcKg9ZWuV-xQ5YRfPGcFm7ZEAam22tFthkir4TDg&spc=UWF9fxlpr2cS2yJt7MHFZWqyBE40jMLJFSBeIauCj8cEX9W1T7ggzZk5WWTa&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=PKEEmMBgp4gfr-jhFZSUFFQQ&rqh=1&gir=yes&clen=9718727&dur=313.961&lmt=1714724834100663&mt=1717520938&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=ABxjZ1spu6JIlA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgCknpnmdg0OAf0VPfCU7vEhk-O7HzwI3bSGFv3FLk0hMCIAYR7TlPkq2bVx_PTqzG1LWFuXfl5TDFAgPqSUgUuOqU&sig=AJfQdSswRAIgPitj6fr52YT5hutLTRYabO1gl0ROFBzokpz2Q1ZAK4ECIBi7fZqNgBIlH_8NG_ZWg0WVqV8P_NYqmNU3sLoNkbx8&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:12:52 volumio volumio[854]: verbose: CURRENT POSITION 1 Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus play Jun 04 19:12:52 volumio volumio[854]: info: Received an update from plugin. extracting info from payload Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:52 volumio volumio[854]: info: ------------------------------ 105ms Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand playlistinfo took 26 milliseconds Jun 04 19:12:52 volumio volumio[854]: info: sendMpdCommand playlistinfo took 24 milliseconds Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:12:52 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:12:52 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:12:52 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:52 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":357,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Eclipse","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr4---sn-2gb7sne6.googlevideo.com/videoplayback?expire=1717542795&ei=K0tfZt39HPuTxN8P3_uN8Ak&ip=82.65.93.89&id=o-ANJ7yeNNZfeNX_bIIdaxRXro1BorEOPQFLEgrSYFPEoW&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kz&mm=31%2C29&mn=sn-2gb7sne6%2Csn-25glenlz&ms=au%2Crdu&mv=m&mvi=4&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2132500&hightc=yes&siu=1&bui=AbKP-1PRm3p6t6RqK2tQyLdVN1VDbDn-47fcKg9ZWuV-xQ5YRfPGcFm7ZEAam22tFthkir4TDg&spc=UWF9fxlpr2cS2yJt7MHFZWqyBE40jMLJFSBeIauCj8cEX9W1T7ggzZk5WWTa&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=PKEEmMBgp4gfr-jhFZSUFFQQ&rqh=1&gir=yes&clen=9718727&dur=313.961&lmt=1714724834100663&mt=1717520938&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=ABxjZ1spu6JIlA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgCknpnmdg0OAf0VPfCU7vEhk-O7HzwI3bSGFv3FLk0hMCIAYR7TlPkq2bVx_PTqzG1LWFuXfl5TDFAgPqSUgUuOqU&sig=AJfQdSswRAIgPitj6fr52YT5hutLTRYabO1gl0ROFBzokpz2Q1ZAK4ECIBi7fZqNgBIlH_8NG_ZWg0WVqV8P_NYqmNU3sLoNkbx8&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:12:52 volumio volumio[854]: verbose: CURRENT POSITION 1 Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus play Jun 04 19:12:52 volumio volumio[854]: info: Received an update from plugin. extracting info from payload Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:52 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:12:52 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:12:52 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":988,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Eclipse","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr4---sn-2gb7sne6.googlevideo.com/videoplayback?expire=1717542795&ei=K0tfZt39HPuTxN8P3_uN8Ak&ip=82.65.93.89&id=o-ANJ7yeNNZfeNX_bIIdaxRXro1BorEOPQFLEgrSYFPEoW&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kz&mm=31%2C29&mn=sn-2gb7sne6%2Csn-25glenlz&ms=au%2Crdu&mv=m&mvi=4&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2132500&hightc=yes&siu=1&bui=AbKP-1PRm3p6t6RqK2tQyLdVN1VDbDn-47fcKg9ZWuV-xQ5YRfPGcFm7ZEAam22tFthkir4TDg&spc=UWF9fxlpr2cS2yJt7MHFZWqyBE40jMLJFSBeIauCj8cEX9W1T7ggzZk5WWTa&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=PKEEmMBgp4gfr-jhFZSUFFQQ&rqh=1&gir=yes&clen=9718727&dur=313.961&lmt=1714724834100663&mt=1717520938&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=ABxjZ1spu6JIlA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgCknpnmdg0OAf0VPfCU7vEhk-O7HzwI3bSGFv3FLk0hMCIAYR7TlPkq2bVx_PTqzG1LWFuXfl5TDFAgPqSUgUuOqU&sig=AJfQdSswRAIgPitj6fr52YT5hutLTRYabO1gl0ROFBzokpz2Q1ZAK4ECIBi7fZqNgBIlH_8NG_ZWg0WVqV8P_NYqmNU3sLoNkbx8&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:12:52 volumio volumio[854]: verbose: CURRENT POSITION 1 Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus play Jun 04 19:12:52 volumio volumio[854]: info: Received an update from plugin. extracting info from payload Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:52 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:12:52 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:12:52 volumio volumio[854]: info: ------------------------------ 72ms Jun 04 19:12:52 volumio volumio[854]: info: ------------------------------ 68ms Jun 04 19:12:53 volumio ntpd[697]: Soliciting pool server 82.64.42.185 Jun 04 19:13:20 volumio ntpd[697]: receive: Unexpected origin timestamp 0xea09c995.1b5f5750 does not match aorg 0000000000.00000000 from server@51.15.191.239 xmt 0xea09c9ae.1085e217 Jun 04 19:13:20 volumio ntpd[697]: receive: Unexpected origin timestamp 0xea09c995.1b5cb260 does not match aorg 0000000000.00000000 from server@51.195.104.188 xmt 0xea09c9ae.1185f870 Jun 04 19:13:20 volumio ntpd[697]: receive: Unexpected origin timestamp 0xea09c995.1b526b3d does not match aorg 0000000000.00000000 from server@82.64.45.50 xmt 0xea09c9ae.117ec7fd Jun 04 19:13:20 volumio ntpd[697]: receive: Unexpected origin timestamp 0xea09c995.1b614190 does not match aorg 0000000000.00000000 from server@217.182.137.208 xmt 0xea09c9ae.14844d36 Jun 04 19:13:20 volumio ntpd[697]: receive: Unexpected origin timestamp 0xea09c995.1b62ca52 does not match aorg 0000000000.00000000 from server@5.39.80.51 xmt 0xea09c9ae.153c3b8b Jun 04 19:13:20 volumio ntpd[697]: receive: Unexpected origin timestamp 0xea09c995.1b649f80 does not match aorg 0000000000.00000000 from server@82.65.248.56 xmt 0xea09c9ae.16c7bd06 Jun 04 19:13:20 volumio ntpd[697]: receive: Unexpected origin timestamp 0xea09c995.1b660860 does not match aorg 0xea09c9b0.01020f49 from server@188.165.49.6 xmt 0xea09c9ae.17fa9f8a Jun 04 19:13:20 volumio ntpd[697]: receive: Unexpected origin timestamp 0xea09c996.1b5bce12 does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xea09c9af.10e26b33 Jun 04 19:13:20 volumio ntpd[697]: receive: Unexpected origin timestamp 0xea09c996.1b59eb6c does not match aorg 0xea09c9b0.00f178a0 from server@54.38.114.34 xmt 0xea09c9af.11978634 Jun 04 19:13:20 volumio ntpd[697]: receive: Unexpected origin timestamp 0xea09c996.1b5d41d2 does not match aorg 0000000000.00000000 from server@178.32.23.17 xmt 0xea09c9af.118898b2 Jun 04 19:13:20 volumio ntpd[697]: receive: Unexpected origin timestamp 0xea09c996.1b5ea937 does not match aorg 0xea09c9b0.00f71a05 from server@178.33.41.123 xmt 0xea09c9af.11d5e620 Jun 04 19:13:21 volumio ntpd[697]: Soliciting pool server 51.38.113.118 Jun 04 19:13:22 volumio ntpd[697]: Soliciting pool server 54.36.61.42 Jun 04 19:13:23 volumio ntpd[697]: Soliciting pool server 91.134.138.86 Jun 04 19:13:23 volumio volumio[854]: error: error Jun 04 19:13:25 volumio volumio[854]: error: error Jun 04 19:13:26 volumio volumio[854]: error: error Jun 04 19:13:34 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 04 19:13:34 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 04 19:13:37 volumio volumio[854]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 19:13:39 volumio volumio[854]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.30 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Jun 04 19:13:39 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 04 19:13:39 volumio volumio[854]: info: CoreCommandRouter::volumioGetVisibleSources Jun 04 19:13:39 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 04 19:13:39 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Jun 04 19:13:39 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 04 19:13:39 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 04 19:13:39 volumio volumio[854]: info: Received Get System Info Jun 04 19:13:39 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 04 19:13:39 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 04 19:13:39 volumio volumio[854]: info: Discovery: Getting this device information Jun 04 19:13:39 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Jun 04 19:13:39 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 04 19:13:39 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Jun 04 19:13:39 volumio volumio[854]: info: Listing playlists Jun 04 19:13:39 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 04 19:13:40 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 04 19:13:40 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 04 19:14:32 volumio volumio[854]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 04 19:14:34 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 04 19:14:34 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 04 19:16:40 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 04 19:17:27 volumio volumio[854]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.65 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:124.0) Gecko/20100101 Firefox/124.0 Engine version: 3 Transport: polling Total Clients: 6 Jun 04 19:17:27 volumio volumio[854]: info: CoreCommandRouter::volumioGetState Jun 04 19:17:27 volumio volumio[854]: info: CoreCommandRouter::volumioGetQueue Jun 04 19:17:27 volumio volumio[854]: info: CoreStateMachine::getQueue Jun 04 19:17:27 volumio volumio[854]: info: CorePlayQueue::getQueue Jun 04 19:17:27 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jun 04 19:17:27 volumio volumio[854]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Jun 04 19:17:27 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Jun 04 19:17:27 volumio volumio[854]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Jun 04 19:17:27 volumio volumio[854]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Jun 04 19:17:27 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Jun 04 19:18:01 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:18:01 volumio volumio[854]: info: CorePlayQueue::getTrack 2 Jun 04 19:18:01 volumio volumio[854]: info: Prefetching next song Jun 04 19:18:02 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand addid "https://rr4---sn-2gb7snez.googlevideo.com/videoplayback?expire=1717543081&ei=SUxfZo-XMqyQxN8Pm9ivuA8&ip=82.65.93.89&id=o-AEPQfzlfoMWkZY5VwPSaAgf_3uLCu3bCSjNpTYT5HlZH&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=zV&mm=31%2C29&mn=sn-2gb7snez%2Csn-25ge7nzz&ms=au%2Crdu&mv=m&mvi=4&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=1923750&hightc=yes&siu=1&bui=AbKP-1M6xndY2Mzo9ysKGH0MaGlmpuSxJQcyq6P19psYc5SLwd03hfRGaE-OBxaenOkk3h7PZQ&spc=UWF9f2CMV8csQ34ALfI3uLE2fzC8NtvA7vRRCblkFLFxPAeuPNW3i5EfUYQL&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=ltyLQ2sbt3s-1ZNroiXvwBkQ&rqh=1&gir=yes&clen=4930298&dur=154.341&lmt=1714153237911511&mt=1717521170&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=y4IXV4JKA1bJHQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgYYv0Dyorn8AkXrGkiq4Q5O2pdht-nl9nhe3BTmg8RRoCICreNP8Qqo2qEq9NCcbhphZxk1wSMuiGZTBpjTGFkJ0F&sig=AJfQdSswRgIhALyZaCnAvXSLoJPY_-JN9OBuc3JMTfQI_kGXu6bK_Cd0AiEAsJR5eIvCBb1NsTQ6BYuRC6Ek1kbwAHStLOiXmwjVoSQ%3D&cver=1.20211213.00.00&t.YouTube" Jun 04 19:18:02 volumio volumio[854]: info: Jun 04 19:18:02 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:18:02 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:18:02 volumio volumio[854]: info: sendMpdCommand addid "https://rr4---sn-2gb7snez.googlevideo.com/videoplayback?expire=1717543081&ei=SUxfZo-XMqyQxN8Pm9ivuA8&ip=82.65.93.89&id=o-AEPQfzlfoMWkZY5VwPSaAgf_3uLCu3bCSjNpTYT5HlZH&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=zV&mm=31%2C29&mn=sn-2gb7snez%2Csn-25ge7nzz&ms=au%2Crdu&mv=m&mvi=4&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=1923750&hightc=yes&siu=1&bui=AbKP-1M6xndY2Mzo9ysKGH0MaGlmpuSxJQcyq6P19psYc5SLwd03hfRGaE-OBxaenOkk3h7PZQ&spc=UWF9f2CMV8csQ34ALfI3uLE2fzC8NtvA7vRRCblkFLFxPAeuPNW3i5EfUYQL&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=ltyLQ2sbt3s-1ZNroiXvwBkQ&rqh=1&gir=yes&clen=4930298&dur=154.341&lmt=1714153237911511&mt=1717521170&fvip=2&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=y4IXV4JKA1bJHQ&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgYYv0Dyorn8AkXrGkiq4Q5O2pdht-nl9nhe3BTmg8RRoCICreNP8Qqo2qEq9NCcbhphZxk1wSMuiGZTBpjTGFkJ0F&sig=AJfQdSswRgIhALyZaCnAvXSLoJPY_-JN9OBuc3JMTfQI_kGXu6bK_Cd0AiEAsJR5eIvCBb1NsTQ6BYuRC6Ek1kbwAHStLOiXmwjVoSQ%3D&cver=1.20211213.00.00&t.YouTube" took 6 milliseconds Jun 04 19:18:02 volumio volumio[854]: verbose: MPD COMMAND [object Object] Jun 04 19:18:02 volumio volumio[854]: verbose: MPD COMMAND [object Object] Jun 04 19:18:02 volumio volumio[854]: verbose: MPD COMMAND [object Object] Jun 04 19:18:02 volumio volumio[854]: info: Jun 04 19:18:02 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:18:02 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:18:02 volumio volumio[854]: info: Jun 04 19:18:02 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:18:02 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:18:02 volumio volumio[854]: info: Jun 04 19:18:02 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:18:02 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:18:02 volumio volumio[854]: info: ------------------------------ 10ms Jun 04 19:18:02 volumio volumio[854]: info: ------------------------------ 6ms Jun 04 19:18:02 volumio volumio[854]: info: ------------------------------ 5ms Jun 04 19:18:02 volumio volumio[854]: info: [ytmusic-play] Prefetched and added track to MPD queue: The Sietch Jun 04 19:18:02 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand consume 1 Jun 04 19:18:02 volumio volumio[854]: info: Jun 04 19:18:02 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:18:02 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:18:02 volumio volumio[854]: info: Jun 04 19:18:02 volumio volumio[854]: ---------------------------- MPD announces system playlist update Jun 04 19:18:02 volumio volumio[854]: info: Ignoring MPD Status Update Jun 04 19:18:02 volumio volumio[854]: info: Jun 04 19:18:02 volumio volumio[854]: ---------------------------- MPD announces state update: options Jun 04 19:18:02 volumio volumio[854]: info: ------------------------------ 10ms Jun 04 19:18:02 volumio volumio[854]: info: sendMpdCommand consume 1 took 7 milliseconds Jun 04 19:18:02 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:18:02 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:18:02 volumio volumio[854]: info: ------------------------------ 7ms Jun 04 19:18:02 volumio volumio[854]: info: ------------------------------ 6ms Jun 04 19:18:02 volumio volumio[854]: info: Jun 04 19:18:02 volumio volumio[854]: ---------------------------- MPD announces state update: options Jun 04 19:18:02 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:18:02 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:18:02 volumio volumio[854]: info: Jun 04 19:18:02 volumio volumio[854]: ---------------------------- MPD announces state update: options Jun 04 19:18:02 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:18:02 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:18:02 volumio volumio[854]: info: sendMpdCommand status took 7 milliseconds Jun 04 19:18:02 volumio volumio[854]: info: sendMpdCommand status took 5 milliseconds Jun 04 19:18:02 volumio volumio[854]: info: sendMpdCommand status took 4 milliseconds Jun 04 19:18:02 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:18:02 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:18:02 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:18:02 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:18:02 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:18:02 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:18:02 volumio volumio[854]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 04 19:18:02 volumio volumio[854]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 04 19:18:02 volumio volumio[854]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 04 19:18:02 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:18:02 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:18:02 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:18:02 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:18:02 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:18:02 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:18:02 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":278429,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Eclipse","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr4---sn-2gb7sne6.googlevideo.com/videoplayback?expire=1717542795&ei=K0tfZt39HPuTxN8P3_uN8Ak&ip=82.65.93.89&id=o-ANJ7yeNNZfeNX_bIIdaxRXro1BorEOPQFLEgrSYFPEoW&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kz&mm=31%2C29&mn=sn-2gb7sne6%2Csn-25glenlz&ms=au%2Crdu&mv=m&mvi=4&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2132500&hightc=yes&siu=1&bui=AbKP-1PRm3p6t6RqK2tQyLdVN1VDbDn-47fcKg9ZWuV-xQ5YRfPGcFm7ZEAam22tFthkir4TDg&spc=UWF9fxlpr2cS2yJt7MHFZWqyBE40jMLJFSBeIauCj8cEX9W1T7ggzZk5WWTa&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=PKEEmMBgp4gfr-jhFZSUFFQQ&rqh=1&gir=yes&clen=9718727&dur=313.961&lmt=1714724834100663&mt=1717520938&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=ABxjZ1spu6JIlA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgCknpnmdg0OAf0VPfCU7vEhk-O7HzwI3bSGFv3FLk0hMCIAYR7TlPkq2bVx_PTqzG1LWFuXfl5TDFAgPqSUgUuOqU&sig=AJfQdSswRAIgPitj6fr52YT5hutLTRYabO1gl0ROFBzokpz2Q1ZAK4ECIBi7fZqNgBIlH_8NG_ZWg0WVqV8P_NYqmNU3sLoNkbx8&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:18:02 volumio volumio[854]: verbose: CURRENT POSITION 1 Jun 04 19:18:02 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:18:02 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus play Jun 04 19:18:02 volumio volumio[854]: info: Received an update from plugin. extracting info from payload Jun 04 19:18:02 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:18:02 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:18:02 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:18:02 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:18:02 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:18:02 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:18:02 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:18:02 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:18:02 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":278429,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Eclipse","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr4---sn-2gb7sne6.googlevideo.com/videoplayback?expire=1717542795&ei=K0tfZt39HPuTxN8P3_uN8Ak&ip=82.65.93.89&id=o-ANJ7yeNNZfeNX_bIIdaxRXro1BorEOPQFLEgrSYFPEoW&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kz&mm=31%2C29&mn=sn-2gb7sne6%2Csn-25glenlz&ms=au%2Crdu&mv=m&mvi=4&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2132500&hightc=yes&siu=1&bui=AbKP-1PRm3p6t6RqK2tQyLdVN1VDbDn-47fcKg9ZWuV-xQ5YRfPGcFm7ZEAam22tFthkir4TDg&spc=UWF9fxlpr2cS2yJt7MHFZWqyBE40jMLJFSBeIauCj8cEX9W1T7ggzZk5WWTa&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=PKEEmMBgp4gfr-jhFZSUFFQQ&rqh=1&gir=yes&clen=9718727&dur=313.961&lmt=1714724834100663&mt=1717520938&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=ABxjZ1spu6JIlA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgCknpnmdg0OAf0VPfCU7vEhk-O7HzwI3bSGFv3FLk0hMCIAYR7TlPkq2bVx_PTqzG1LWFuXfl5TDFAgPqSUgUuOqU&sig=AJfQdSswRAIgPitj6fr52YT5hutLTRYabO1gl0ROFBzokpz2Q1ZAK4ECIBi7fZqNgBIlH_8NG_ZWg0WVqV8P_NYqmNU3sLoNkbx8&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:18:02 volumio volumio[854]: verbose: CURRENT POSITION 1 Jun 04 19:18:02 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:18:02 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus play Jun 04 19:18:02 volumio volumio[854]: info: Received an update from plugin. extracting info from payload Jun 04 19:18:02 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:18:02 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:18:02 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:18:02 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:18:02 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:18:02 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:18:02 volumio volumio[854]: info: CorePlayQueue::getTrack 1 Jun 04 19:18:02 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":278429,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Eclipse","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr4---sn-2gb7sne6.googlevideo.com/videoplayback?expire=1717542795&ei=K0tfZt39HPuTxN8P3_uN8Ak&ip=82.65.93.89&id=o-ANJ7yeNNZfeNX_bIIdaxRXro1BorEOPQFLEgrSYFPEoW&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kz&mm=31%2C29&mn=sn-2gb7sne6%2Csn-25glenlz&ms=au%2Crdu&mv=m&mvi=4&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2132500&hightc=yes&siu=1&bui=AbKP-1PRm3p6t6RqK2tQyLdVN1VDbDn-47fcKg9ZWuV-xQ5YRfPGcFm7ZEAam22tFthkir4TDg&spc=UWF9fxlpr2cS2yJt7MHFZWqyBE40jMLJFSBeIauCj8cEX9W1T7ggzZk5WWTa&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=PKEEmMBgp4gfr-jhFZSUFFQQ&rqh=1&gir=yes&clen=9718727&dur=313.961&lmt=1714724834100663&mt=1717520938&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=ABxjZ1spu6JIlA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgCknpnmdg0OAf0VPfCU7vEhk-O7HzwI3bSGFv3FLk0hMCIAYR7TlPkq2bVx_PTqzG1LWFuXfl5TDFAgPqSUgUuOqU&sig=AJfQdSswRAIgPitj6fr52YT5hutLTRYabO1gl0ROFBzokpz2Q1ZAK4ECIBi7fZqNgBIlH_8NG_ZWg0WVqV8P_NYqmNU3sLoNkbx8&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:18:02 volumio volumio[854]: verbose: CURRENT POSITION 1 Jun 04 19:18:02 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:18:02 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus play Jun 04 19:18:02 volumio volumio[854]: info: Received an update from plugin. extracting info from payload Jun 04 19:18:02 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:18:02 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:18:02 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:18:02 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:18:02 volumio volumio[854]: info: ------------------------------ 79ms Jun 04 19:18:02 volumio volumio[854]: info: ------------------------------ 75ms Jun 04 19:18:02 volumio volumio[854]: info: ------------------------------ 74ms Jun 04 19:18:06 volumio volumio[854]: info: CoreStateMachine::startPlaybackTimer Jun 04 19:18:06 volumio volumio[854]: info: CorePlayQueue::getTrack 2 Jun 04 19:18:06 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:18:06 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:18:06 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:18:06 volumio volumio[854]: info: CorePlayQueue::getTrack 2 Jun 04 19:18:06 volumio volumio[854]: info: ControllerMpd::getState Jun 04 19:18:06 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand status Jun 04 19:18:06 volumio volumio[854]: info: sendMpdCommand status took 7 milliseconds Jun 04 19:18:06 volumio volumio[854]: verbose: ControllerMpd::parseState Jun 04 19:18:06 volumio volumio[854]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 04 19:18:06 volumio volumio[854]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 04 19:18:06 volumio volumio[854]: verbose: ControllerMpd::parseTrackInfo Jun 04 19:18:06 volumio volumio[854]: info: ControllerMpd::pushState Jun 04 19:18:06 volumio volumio[854]: info: CoreCommandRouter::servicePushState Jun 04 19:18:06 volumio volumio[854]: info: CorePlayQueue::getTrack 2 Jun 04 19:18:06 volumio volumio[854]: verbose: STATE SERVICE {"status":"play","position":0,"seek":282556,"duration":314,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Eclipse","artist":"Hans Zimmer","album":"Dune: Part Two (Original Motion Picture Soundtrack)","uri":"https://rr4---sn-2gb7sne6.googlevideo.com/videoplayback?expire=1717542795&ei=K0tfZt39HPuTxN8P3_uN8Ak&ip=82.65.93.89&id=o-ANJ7yeNNZfeNX_bIIdaxRXro1BorEOPQFLEgrSYFPEoW&itag=774&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=kz&mm=31%2C29&mn=sn-2gb7sne6%2Csn-25glenlz&ms=au%2Crdu&mv=m&mvi=4&pl=25&ctier=A&pfa=5&gcr=fr&initcwndbps=2132500&hightc=yes&siu=1&bui=AbKP-1PRm3p6t6RqK2tQyLdVN1VDbDn-47fcKg9ZWuV-xQ5YRfPGcFm7ZEAam22tFthkir4TDg&spc=UWF9fxlpr2cS2yJt7MHFZWqyBE40jMLJFSBeIauCj8cEX9W1T7ggzZk5WWTa&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=PKEEmMBgp4gfr-jhFZSUFFQQ&rqh=1&gir=yes&clen=9718727&dur=313.961&lmt=1714724834100663&mt=1717520938&fvip=1&keepalive=yes&c=WEB_REMIX&sefc=1&txp=2318224&n=ABxjZ1spu6JIlA&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cctier%2Cpfa%2Cgcr%2Chightc%2Csiu%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHlkHjAwRAIgCknpnmdg0OAf0VPfCU7vEhk-O7HzwI3bSGFv3FLk0hMCIAYR7TlPkq2bVx_PTqzG1LWFuXfl5TDFAgPqSUgUuOqU&sig=AJfQdSswRAIgPitj6fr52YT5hutLTRYabO1gl0ROFBzokpz2Q1ZAK4ECIBi7fZqNgBIlH_8NG_ZWg0WVqV8P_NYqmNU3sLoNkbx8&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} Jun 04 19:18:06 volumio volumio[854]: verbose: CURRENT POSITION 2 Jun 04 19:18:06 volumio volumio[854]: info: CoreStateMachine::syncState stateService play Jun 04 19:18:06 volumio volumio[854]: info: CoreStateMachine::syncState currentStatus play Jun 04 19:18:06 volumio volumio[854]: info: Received an update from plugin. extracting info from payload Jun 04 19:18:06 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:18:06 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 04 19:18:06 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:18:06 volumio volumio[854]: info: CoreStateMachine::pushState Jun 04 19:18:06 volumio volumio[854]: info: CoreCommandRouter::volumioPushState Jun 04 19:18:16 volumio volumio[854]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Jun 04 19:18:16 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Jun 04 19:18:17 volumio sudo[2074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Jun 04 19:18:17 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 04 19:18:17 volumio sudo[2074]: pam_unix(sudo:session): session closed for user root Jun 04 19:18:24 volumio sudo[2152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Jun 04 19:18:24 volumio sudo[2152]: pam_unix(sudo:session): session opened for user root by (uid=0)