Mon Apr 7 08:28:26 BST 2025 Description="'2nd send of log with hang on rapid skip of tracks (tidal connect) after update to 3.799. This is sent with volumio in its hung state. Hang occurs both from ipad pro and iPhone running vumio app. System is rpi 3b with allo digione signature. '" # cat /proc/version --------------- Linux version 6.6.62-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #1816 SMP Mon Nov 18 15:30:49 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8" # ifconfig --------------- eth0: flags=4163 mtu 1500 inet 192.168.1.213 netmask 255.255.255.0 broadcast 192.168.1.255 ether b8:27:eb:e2:7b:c9 txqueuelen 1000 (Ethernet) RX packets 38863 bytes 50899961 (48.5 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 23827 bytes 2521394 (2.4 MiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 lo: flags=73 mtu 65536 inet 127.0.0.1 netmask 255.0.0.0 loop txqueuelen 1000 (Local Loopback) RX packets 8309 bytes 48501707 (46.2 MiB) RX errors 0 dropped 0 overruns 0 frame 0 TX packets 8309 bytes 48501707 (46.2 MiB) TX errors 0 dropped 0 overruns 0 carrier 0 collisions 0 # sudo iwconfig --------------- lo no wireless extensions. eth0 no wireless extensions. wlan0 IEEE 802.11 ESSID:off/any Mode:Managed Access Point: Not-Associated Retry short limit:7 RTS thr:off Fragment thr:off Encryption key:off Power Management:off # aplay -l --------------- xcb_connection_has_error() returned true **** List of PLAYBACK Hardware Devices **** card 0: b1 [bcm2835 HDMI 1], device 0: bcm2835 HDMI 1 [bcm2835 HDMI 1] Subdevices: 4/4 Subdevice #0: subdevice #0 Subdevice #1: subdevice #1 Subdevice #2: subdevice #2 Subdevice #3: subdevice #3 card 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: sndallodigione [snd_allo_digione], device 0: Allo DigiOne HiFi wm8804-spdif-0 [Allo DigiOne HiFi wm8804-spdif-0] Subdevices: 1/1 Subdevice #0: subdevice #0 # lsusb --------------- Bus 001 Device 003: ID 0424:ec00 Standard Microsystems Corp. SMSC9512/9514 Fast Ethernet Adapter Bus 001 Device 002: ID 0424:9514 Standard Microsystems Corp. SMC9514 Hub 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 |__ Port 1: Dev 2, If 0, Class=Hub, Driver=hub/5p, 480M |__ Port 1: Dev 3, If 0, Class=Vendor Specific Class, Driver=smsc95xx, 480M # lsusb -v --------------- Couldn't open device, some information will be missing Couldn't open device, some information will be missing Bus 001 Device 003: ID 0424:ec00 Standard Microsystems Corp. SMSC9512/9514 Fast Ethernet Adapter Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 2.00 bDeviceClass 255 Vendor Specific Class bDeviceSubClass 0 bDeviceProtocol 1 bMaxPacketSize0 64 idVendor 0x0424 Standard Microsystems Corp. idProduct 0xec00 SMSC9512/9514 Fast Ethernet Adapter bcdDevice 2.00 iManufacturer 0 iProduct 0 iSerial 0 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x0027 bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0xe0 Self Powered Remote Wakeup MaxPower 2mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 3 bInterfaceClass 255 Vendor Specific Class bInterfaceSubClass 0 bInterfaceProtocol 255 iInterface 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x81 EP 1 IN bmAttributes 2 Transfer Type Bulk Synch Type None Usage Type Data wMaxPacketSize 0x0200 1x 512 bytes bInterval 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x02 EP 2 OUT bmAttributes 2 Transfer Type Bulk Synch Type None Usage Type Data wMaxPacketSize 0x0200 1x 512 bytes bInterval 0 Endpoint Descriptor: bLength 7 bDescriptorType 5 bEndpointAddress 0x83 EP 3 IN bmAttributes 3 Transfer Type Interrupt Synch Type None Usage Type Data wMaxPacketSize 0x0010 1x 16 bytes bInterval 4 Bus 001 Device 002: ID 0424:9514 Standard Microsystems Corp. SMC9514 Hub Device Descriptor: bLength 18 bDescriptorType 1 bcdUSB 2.00 bDeviceClass 9 Hub bDeviceSubClass 0 bDeviceProtocol 2 TT per port bMaxPacketSize0 64 idVendor 0x0424 Standard Microsystems Corp. idProduct 0x9514 SMC9514 Hub bcdDevice 2.00 iManufacturer 0 iProduct 0 iSerial 0 bNumConfigurations 1 Configuration Descriptor: bLength 9 bDescriptorType 2 wTotalLength 0x0029 bNumInterfaces 1 bConfigurationValue 1 iConfiguration 0 bmAttributes 0xe0 Self Powered Remote Wakeup MaxPower 2mA Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 0 bNumEndpoints 1 bInterfaceClass 9 Hub bInterfaceSubClass 0 bInterfaceProtocol 1 Single TT 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 0x0001 1x 1 bytes bInterval 12 Interface Descriptor: bLength 9 bDescriptorType 4 bInterfaceNumber 0 bAlternateSetting 1 Couldn't open device, some information will be missing bNumEndpoints 1 bInterfaceClass 9 Hub bInterfaceSubClass 0 bInterfaceProtocol 2 TT per port 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 0x0001 1x 1 bytes bInterval 12 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.06 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 0 07:59 ? 00:00:04 /sbin/init splash nodebug root 2 0 0 07:59 ? 00:00:00 [kthreadd] root 3 2 0 07:59 ? 00:00:00 [pool_workqueue_release] root 4 2 0 07:59 ? 00:00:00 [kworker/R-rcu_g] root 5 2 0 07:59 ? 00:00:00 [kworker/R-rcu_p] root 6 2 0 07:59 ? 00:00:00 [kworker/R-slub_] root 7 2 0 07:59 ? 00:00:00 [kworker/R-netns] root 9 2 0 07:59 ? 00:00:00 [kworker/0:0H-events_highpri] root 10 2 0 07:59 ? 00:00:00 [kworker/0:1-events] root 11 2 0 07:59 ? 00:00:00 [kworker/u8:0-netns] root 12 2 0 07:59 ? 00:00:00 [kworker/R-mm_pe] root 13 2 0 07:59 ? 00:00:00 [rcu_tasks_rude_kthread] root 14 2 0 07:59 ? 00:00:00 [rcu_tasks_trace_kthread] root 15 2 0 07:59 ? 00:00:00 [ksoftirqd/0] root 16 2 0 07:59 ? 00:00:00 [rcu_sched] root 17 2 0 07:59 ? 00:00:00 [migration/0] root 18 2 0 07:59 ? 00:00:00 [cpuhp/0] root 19 2 0 07:59 ? 00:00:00 [cpuhp/1] root 20 2 0 07:59 ? 00:00:00 [migration/1] root 21 2 0 07:59 ? 00:00:00 [ksoftirqd/1] root 23 2 0 07:59 ? 00:00:00 [kworker/1:0H-events_highpri] root 24 2 0 07:59 ? 00:00:00 [cpuhp/2] root 25 2 0 07:59 ? 00:00:00 [migration/2] root 26 2 0 07:59 ? 00:00:00 [ksoftirqd/2] root 28 2 0 07:59 ? 00:00:00 [kworker/2:0H-kblockd] root 29 2 0 07:59 ? 00:00:00 [cpuhp/3] root 30 2 0 07:59 ? 00:00:00 [migration/3] root 31 2 0 07:59 ? 00:00:00 [ksoftirqd/3] root 33 2 0 07:59 ? 00:00:00 [kworker/3:0H-events_highpri] root 35 2 0 07:59 ? 00:00:00 [kworker/u10:0-events_unbound] root 38 2 0 07:59 ? 00:00:00 [kdevtmpfs] root 39 2 0 07:59 ? 00:00:00 [kworker/R-inet_] root 40 2 0 07:59 ? 00:00:01 [kworker/u9:1-writeback] root 41 2 0 07:59 ? 00:00:00 [kauditd] root 42 2 0 07:59 ? 00:00:00 [khungtaskd] root 43 2 0 07:59 ? 00:00:00 [oom_reaper] root 44 2 0 07:59 ? 00:00:00 [kworker/R-write] root 45 2 0 07:59 ? 00:00:00 [kcompactd0] root 46 2 0 07:59 ? 00:00:00 [kworker/R-kinte] root 47 2 0 07:59 ? 00:00:00 [kworker/R-kbloc] root 48 2 0 07:59 ? 00:00:00 [kworker/R-blkcg] root 49 2 0 07:59 ? 00:00:00 [watchdogd] root 50 2 0 07:59 ? 00:00:00 [kworker/u10:1-events_unbound] root 51 2 0 07:59 ? 00:00:00 [kworker/1:1-events] root 52 2 0 07:59 ? 00:00:00 [kworker/1:1H-kblockd] root 53 2 0 07:59 ? 00:00:00 [kworker/R-rpcio] root 54 2 0 07:59 ? 00:00:00 [kworker/R-xprti] root 55 2 0 07:59 ? 00:00:00 [kworker/u10:2] root 57 2 0 07:59 ? 00:00:00 [kswapd0] root 58 2 0 07:59 ? 00:00:00 [kworker/R-nfsio] root 60 2 0 07:59 ? 00:00:00 [hwrng] root 61 2 0 07:59 ? 00:00:00 [kworker/R-iscsi] root 62 2 0 07:59 ? 00:00:00 [kworker/R-dwc_o] root 63 2 0 07:59 ? 00:00:00 [kworker/R-DWC N] root 65 2 0 07:59 ? 00:00:00 [vchiq-slot/0] root 66 2 0 07:59 ? 00:00:00 [vchiq-recy/0] root 67 2 0 07:59 ? 00:00:00 [vchiq-sync/0] root 68 2 0 07:59 ? 00:00:00 [kworker/u13:0-hci0] root 69 2 0 07:59 ? 00:00:00 [kworker/u14:0] root 70 2 0 07:59 ? 00:00:00 [kworker/u15:0] root 71 2 0 07:59 ? 00:00:00 [kworker/u16:0] root 72 2 0 07:59 ? 00:00:00 [kworker/u17:0] root 73 2 0 07:59 ? 00:00:00 [kworker/u11:1-loop0] root 74 2 0 07:59 ? 00:00:00 [kworker/u11:2-events_unbound] root 99 2 0 07:59 ? 00:00:00 [kworker/0:1H-kblockd] root 101 2 0 07:59 ? 00:00:00 [kworker/R-mmc_c] root 102 2 0 07:59 ? 00:00:00 [kworker/2:1H-kblockd] root 105 2 0 07:59 ? 00:00:00 [kworker/3:1H-kblockd] root 116 2 0 07:59 ? 00:00:00 [kworker/u12:1-events_unbound] root 118 2 0 07:59 ? 00:00:00 [kworker/u12:2-loop0] root 125 2 0 07:59 ? 00:00:00 [kworker/3:2-mm_percpu_wq] root 172 2 0 07:59 ? 00:00:00 [kworker/0:2-mm_percpu_wq] root 174 2 0 07:59 ? 00:00:00 [irq/199-usb-001:003:01] root 179 2 0 07:59 ? 00:00:00 [kworker/1:2-events] root 190 2 0 07:59 ? 00:00:00 [kworker/R-uas] root 210 2 0 07:59 ? 00:00:00 [jbd2/mmcblk0p2-8] root 211 2 0 07:59 ? 00:00:00 [kworker/R-ext4-] root 244 2 0 08:00 ? 00:00:00 [kworker/u11:3-events_unbound] root 245 2 0 08:00 ? 00:00:00 [kworker/u9:2-events_unbound] root 248 2 0 08:00 ? 00:00:00 [jbd2/mmcblk0p3-8] root 249 2 0 08:00 ? 00:00:00 [kworker/R-ext4-] root 282 2 0 08:00 ? 00:00:00 [kworker/R-mld] root 283 2 0 08:00 ? 00:00:00 [kworker/R-ipv6_] root 306 1 0 08:00 ? 00:00:01 /lib/systemd/systemd-journald root 337 1 0 08:00 ? 00:00:00 /lib/systemd/systemd-udevd root 357 2 0 08:00 ? 00:00:00 [vchiq-keep/0] root 358 2 0 08:00 ? 00:00:00 [SMIO] root 379 2 0 08:00 ? 00:00:00 [kworker/R-mmal-] root 388 2 0 08:00 ? 00:00:00 [kworker/R-mmal-] root 390 2 0 08:00 ? 00:00:00 [kworker/R-mmal-] root 391 2 0 08:00 ? 00:00:00 [kworker/R-mmal-] root 392 2 0 08:00 ? 00:00:00 [kworker/u12:3-events_unbound] root 394 2 0 08:00 ? 00:00:00 [kworker/R-mmal-] root 395 2 0 08:00 ? 00:00:00 [kworker/R-mmal-] root 396 2 0 08:00 ? 00:00:00 [kworker/R-mmal-] root 405 2 0 08:00 ? 00:00:00 [kworker/R-cfg80] root 416 2 0 08:00 ? 00:00:00 [kworker/u13:2-hci0] root 442 2 0 08:00 ? 00:00:00 [kworker/R-brcmf] root 444 2 0 08:00 ? 00:00:00 [brcmf_wdog/mmc1:0001:1] root 510 2 0 08:00 ? 00:00:00 [kworker/u9:3-events_unbound] root 541 1 0 08:00 ? 00:00:01 /usr/sbin/haveged --Foreground --verbose=1 -w 1024 _rpc 546 1 0 08:00 ? 00:00:00 /sbin/rpcbind -f -w root 561 1 0 08:00 ? 00:00:00 /bin/bash /bin/mpd_monitor.sh root 571 1 0 08:00 ? 00:00:00 /lib/systemd/systemd-logind root 579 1 0 08:00 ? 00:00:00 /usr/sbin/alsactl -E HOME=/run/alsa -s -n 19 -c rdaemon message+ 581 1 0 08:00 ? 00:00:00 /usr/bin/dbus-daemon --system --address=systemd: --nofork --nopidfile --systemd-activation --syslog-only root 582 1 0 08:00 ? 00:00:00 /sbin/wpa_supplicant -u -s -O /run/wpa_supplicant volumio 583 1 0 08:00 ? 00:00:00 /usr/local/bin/volumio-remote-updater avahi 584 1 0 08:00 ? 00:00:00 avahi-daemon: running [dynaudio.local] nobody 586 1 0 08:00 ? 00:00:00 /usr/sbin/thd --triggers /etc/triggerhappy/triggers.d/ --socket /run/thd.socket --user nobody --deviceglob /dev/input/event* root 590 1 0 08:00 ? 00:00:00 /bin/bash /bin/volumiologrotate avahi 649 584 0 08:00 ? 00:00:00 avahi-daemon: chroot helper root 721 1 0 08:00 ? 00:00:00 /usr/lib/bluetooth/bluetoothd --noplugin=sap root 789 1 0 08:00 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group root 805 1 0 08:00 ? 00:00:00 /usr/sbin/ifplugd -i eth0 -q -f -u0 -d10 -w -I ntp 808 1 0 08:00 ? 00:00:00 /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 root 817 1 0 08:00 tty1 00:00:00 /sbin/agetty -o -p -- \u --noclear tty1 linux root 821 789 0 08:00 ? 00:00:00 /usr/sbin/nmbd --foreground --no-process-group volumio 839 1 1 08:00 ? 00:00:27 /usr/bin/node /volumio/index.js root 900 1 0 08:00 ? 00:00:00 /sbin/dhcpcd eth0 volumio 933 839 0 08:00 ? 00:00:00 /bin/bash /volumio/app/plugins/system_controller/network/network_monitor.sh volumio 962 839 0 08:00 ? 00:00:00 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 969 962 0 08:00 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 970 962 0 08:00 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart volumio 971 962 0 08:00 ? 00:00:01 /usr/bin/node /volumio/app/plugins/miscellanea/albumart/serverStartup.js 3001 /data/albumart root 990 1 0 08:00 ? 00:00:00 /usr/sbin/winbindd --foreground --no-process-group root 993 990 0 08:00 ? 00:00:00 winbindd: domain child [DYNAUDIO] root 995 1 0 08:00 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1000 995 0 08:00 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1001 995 0 08:00 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group root 1002 990 0 08:00 ? 00:00:00 winbindd: idmap child root 1003 990 0 08:00 ? 00:00:00 winbindd: domain child [BUILTIN] root 1005 995 0 08:00 ? 00:00:00 /usr/sbin/smbd --foreground --no-process-group volumio 1127 1 0 08:00 ? 00:00:00 /usr/bin/upmpdcli -c /tmp/upmpdcli.conf volumio 1146 839 0 08:00 ? 00:00:07 /usr/bin/node /myvolumio/plugins/music_service/streaming_services/streamingProxy.js root 1211 2 0 08:00 ? 00:00:00 [kworker/u8:1] mpd 1267 1 0 08:00 ? 00:00:03 /usr/bin/mpd --no-daemon shairpo+ 1279 1 0 08:00 ? 00:00:00 /usr/bin/shairport-sync --configfile=/tmp/shairport-sync.conf volumio 1289 1 0 08:00 ? 00:00:00 /bin/sh /tmp/sshtunnel.sh volumio 1290 1289 0 08:00 ? 00:00:00 /usr/lib/autossh/autossh -M 0 -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R 7MrPjXrYvKRRncUdHGLTqmphqso2-b5b3b8bac075da67729d128c164a6b58.eu3.myvolumio.org:8091:localhost:3000 eu3.myvolumio.org volumio 1293 1290 0 08:00 ? 00:00:00 /usr/bin/ssh -o ServerAliveInterval 30 -o ServerAliveCountMax 3 -o StrictHostKeyChecking=no -o UserKnownHostsFile=/dev/null -N -p 2222 -R 7MrPjXrYvKRRncUdHGLTqmphqso2-b5b3b8bac075da67729d128c164a6b58.eu3.myvolumio.org:8091:localhost:3000 eu3.myvolumio.org volumio 1299 1 0 08:00 ? 00:00:00 /bin/sh /bin/bt/btstart.sh start volumio 1314 1 0 08:00 ? 00:00:00 /bin/sh -c /usr/bin/vtcs volumio 1315 1314 0 08:00 ? 00:00:00 /usr/bin/vtcs volumio 1337 1 0 08:00 ? 00:00:00 /usr/bin/pulseaudio --high-priority=true rtkit 1338 1 0 08:00 ? 00:00:00 /usr/lib/rtkit/rtkit-daemon root 1342 1 0 08:00 ? 00:00:00 /usr/lib/policykit-1/polkitd --no-debug root 1350 2 0 08:00 ? 00:00:00 [krfcommd] volumio 1351 1299 0 08:00 ? 00:00:00 /usr/bin/python /bin/bt/simple-agent -c NoInputNoOutput root 2924 2 0 08:12 ? 00:00:00 [kworker/2:0-events] root 3698 2 0 08:17 ? 00:00:00 [kworker/3:0-mm_percpu_wq] root 4441 2 0 08:22 ? 00:00:00 [kworker/2:2-events] root 4583 2 0 08:23 ? 00:00:00 [kworker/3:1-mm_percpu_wq] root 5168 2 0 08:27 ? 00:00:00 [kworker/2:1] root 5239 590 0 08:28 ? 00:00:00 sleep 60 root 5256 561 0 08:28 ? 00:00:00 sleep 20 volumio 5270 933 0 08:28 ? 00:00:00 sleep 10 volumio 5271 839 1 08:28 ? 00:00:00 /bin/sh -c /usr/bin/node /volumio/logsubmit.js '2nd send of log with hang on rapid skip of tracks (tidal connect) after update to 3.799. This is sent with volumio in its hung state. Hang occurs both from ipad pro and iPhone running vumio app. System is rpi 3b with allo digione signature. ' volumio 5272 5271 39 08:28 ? 00:00:00 /usr/bin/node /volumio/logsubmit.js 2nd send of log with hang on rapid skip of tracks (tidal connect) after update to 3.799. This is sent with volumio in its hung state. Hang occurs both from ipad pro and iPhone running vumio app. System is rpi 3b with allo digione signature. volumio 5300 5272 0 08:28 ? 00:00:00 /bin/sh -c ps -ef >>/tmp/logondemand 2>&1 volumio 5301 5300 0 08:28 ? 00:00:00 ps -ef # df -h --------------- Filesystem Size Used Avail Use% Mounted on udev 389M 0 389M 0% /dev tmpfs 87M 9.0M 79M 11% /run /dev/mmcblk0p2 2.5G 1.2G 1.2G 49% /imgpart /dev/loop0 487M 487M 0 100% /static overlay 12G 18M 12G 1% / tmpfs 435M 0 435M 0% /dev/shm tmpfs 5.0M 4.0K 5.0M 1% /run/lock tmpfs 435M 0 435M 0% /sys/fs/cgroup tmpfs 20M 28K 20M 1% /var/log tmpfs 435M 0 435M 0% /var/spool/cups tmpfs 435M 76K 435M 1% /tmp tmpfs 435M 0 435M 0% /var/spool/cups/tmp /dev/mmcblk0p1 91M 83M 8.2M 92% /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=397992k,nr_inodes=99498,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=89064k,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,uuid=null) 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/cpuset type cgroup (rw,nosuid,nodev,noexec,relatime,cpuset) cgroup on /sys/fs/cgroup/blkio type cgroup (rw,nosuid,nodev,noexec,relatime,blkio) cgroup on /sys/fs/cgroup/freezer type cgroup (rw,nosuid,nodev,noexec,relatime,freezer) cgroup on /sys/fs/cgroup/cpu,cpuacct type cgroup (rw,nosuid,nodev,noexec,relatime,cpu,cpuacct) cgroup on /sys/fs/cgroup/pids type cgroup (rw,nosuid,nodev,noexec,relatime,pids) cgroup on /sys/fs/cgroup/devices type cgroup (rw,nosuid,nodev,noexec,relatime,devices) cgroup on /sys/fs/cgroup/net_cls,net_prio type cgroup (rw,nosuid,nodev,noexec,relatime,net_cls,net_prio) cgroup on /sys/fs/cgroup/perf_event type cgroup (rw,nosuid,nodev,noexec,relatime,perf_event) systemd-1 on /proc/sys/fs/binfmt_misc type autofs (rw,relatime,fd=32,pgrp=1,timeout=0,minproto=5,maxproto=5,direct) debugfs on /sys/kernel/debug type debugfs (rw,relatime) sunrpc on /run/rpc_pipefs type rpc_pipefs (rw,relatime) mqueue on /dev/mqueue type mqueue (rw,relatime) fusectl on /sys/fs/fuse/connections type fusectl (rw,relatime) configfs on /sys/kernel/config type configfs (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:49152 0.0.0.0:* LISTEN 1127/upmpdcli tcp 0 0 0.0.0.0:111 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:4713 0.0.0.0:* LISTEN 1337/pulseaudio tcp 0 0 0.0.0.0:139 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:445 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:5000 0.0.0.0:* LISTEN - tcp 0 0 0.0.0.0:49149 0.0.0.0:* LISTEN 1127/upmpdcli tcp 0 0 127.0.0.1:48212 127.0.0.1:3000 ESTABLISHED 583/volumio-remote- tcp 0 0 127.0.0.1:33926 127.0.0.1:6599 ESTABLISHED 1127/upmpdcli tcp 0 0 127.0.0.1:48228 127.0.0.1:3000 ESTABLISHED 839/node tcp 0 0 127.0.0.1:33896 127.0.0.1:3000 ESTABLISHED 839/node tcp 0 0 127.0.0.1:33928 127.0.0.1:6599 ESTABLISHED 1127/upmpdcli tcp 0 0 127.0.0.1:39358 127.0.0.1:3000 TIME_WAIT - tcp 0 0 192.168.1.213:45222 167.71.136.233:2222 ESTABLISHED 1293/ssh tcp 68444 0 192.168.1.213:39856 143.204.180.248:80 ESTABLISHED 1146/node tcp 69780 0 192.168.1.213:49908 143.204.180.248:80 ESTABLISHED 1146/node tcp 0 0 192.168.1.213:40438 84.247.14.230:80 ESTABLISHED 839/node tcp 0 0 127.0.0.1:45174 127.0.0.1:3000 ESTABLISHED 839/node tcp 0 0 192.168.1.213:53278 35.201.97.85:443 ESTABLISHED 839/node tcp6 0 0 :::111 :::* LISTEN - tcp6 0 0 :::4713 :::* LISTEN 1337/pulseaudio tcp6 0 0 :::3245 :::* LISTEN 1146/node tcp6 0 0 :::139 :::* LISTEN - tcp6 0 0 :::3005 :::* LISTEN 839/node tcp6 0 0 :::445 :::* LISTEN - tcp6 0 0 :::3001 :::* LISTEN 962/node tcp6 0 0 :::3000 :::* LISTEN 839/node tcp6 0 0 :::5000 :::* LISTEN - tcp6 0 0 :::2019 :::* LISTEN 1315/vtcs tcp6 0 0 :::6600 :::* LISTEN - tcp6 0 0 :::6599 :::* LISTEN 839/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:45174 ESTABLISHED 839/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:33926 ESTABLISHED 839/node tcp6 0 0 127.0.0.1:6599 127.0.0.1:33928 ESTABLISHED 839/node tcp6 0 0 192.168.1.213:3000 192.168.1.50:62353 ESTABLISHED 839/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:48228 ESTABLISHED 839/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:48212 ESTABLISHED 839/node tcp6 0 0 127.0.0.1:3000 127.0.0.1:33896 ESTABLISHED 839/node # 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=allo-digione # 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 "volumioMultiRoomServer" } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/server/switch.target pcm.volumioMultiRoomServer { type volumioswitch target_file "/tmp/multiroom/server/switch.target" control_fifo "/tmp/multiroom/server/switch.fifo" rate_min 44100 rate_max 192000 } # This PCM switches output to the ALSA target named by the file /tmp/multiroom/client/switch.target pcm.volumioMultiRoomClient { type volumioswitch target_file "/tmp/multiroom/client/switch.target" control_fifo "/tmp/multiroom/client/switch.fifo" } # This PCM performs any necessary rate/format/channel conversion before sending on to the Server FIFO pcm.volumioMultiRoom { type plug slave { pcm "volumioMultiRoomFIFO" rate 48000 format "S16_LE" channels 2 } rate_converter "speexrate_medium" } # Write to the FIFO watched by the Server pcm.volumioMultiRoomFIFO { type volumiofifo fifo "/tmp/multiroom/server/fifo" clear_on_drop false lead_in_frames 16384 } # This pcm is used by pcm.volumioMultiRoomServer and pcm.volumioMultiRoomClient to play audio on the local output pcm.volumioLocalPlayback { type empty slave.pcm "postMultiRoom" } # This pcm is used by pcm.volumioMultiRoomClient to discard audio and allow for faster switching pcm.volumioDiscard { type null } pcm.postMultiRoom { type empty slave.pcm "volumioOutput" } # There is always a plug before the hardware to be safe pcm.volumioOutput { type plug slave.pcm "volumioHw" } pcm.volumioHw { type hw card "sndallodigione" } # cat /var/log/mpd.log --------------- Apr 07 07:59 : zeroconf: No global port, disabling zeroconf Apr 07 08:00 : zeroconf: No global port, disabling zeroconf Apr 07 08:00 : exception: No such directory Apr 07 08:00 : zeroconf: No global port, disabling zeroconf ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Apr 07 08:00 : exception: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": Device or resource busy Apr 07 08:00 : exception: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": Device or resource busy Apr 07 08:00 : player: problems opening audio device while playing "http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES" Apr 07 08:00 : player: played "http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES" ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:143:(_snd_pcm_volumioswitch_open_target_pcm) PCM Volumio ALSA Switch Plugin failed to open the switcher target pcm volumioLocalPlayback Apr 07 08:00 : exception: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": Device or resource busy Apr 07 08:00 : exception: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": Device or resource busy Apr 07 08:00 : player: problems opening audio device while playing "http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES" Apr 07 08:00 : player: played "http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES" Apr 07 08:24 : player: played "http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES" Apr 07 08:24 : player: played "http://127.0.0.1:3245?data=tidal://song/88632421&soundQuality=HI_RES" Apr 07 08:24 : player: played "http://127.0.0.1:3245?data=tidal://song/88632421&soundQuality=HI_RES" Apr 07 08:24 : player: played "http://127.0.0.1:3245?data=tidal://song/98089668&soundQuality=HI_RES" Apr 07 08:24 : exception: Not playing # 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: 'Radio Paradise', name: 'radio_paradise', category: 'music_service', version: '1.0.12', icon: 'fa-headphones', 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" } }, "cd_controller": { "status": { "type": "string", "value": "STARTED" } }, "smart_inputs": { "status": { "type": "string", "value": "STARTED" } }, "hi_res_audio": { "status": { "type": "string", "value": "STARTED" } }, "tidal": { "status": { "type": "string", "value": "STARTED" } }, "qobuz": { "status": { "type": "string", "value": "STARTED" } }, "tidalconnect": { "status": { "type": "string", "value": "STARTED" } }, "radio_paradise": { "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" } }, "bluetooth": { "status": { "type": "string", "value": "STARTED" } }, "multiroom": { "status": { "type": "string", "value": "STARTED" } } }, "miscellanea": { "alarm-clock": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "albumart": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "appearance": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "wizard": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_music": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "metavolumio": { "status": { "type": "string", "value": "STARTED" } }, "manifestui": { "status": { "type": "string", "value": "STARTED" } } }, "user_interface": { "websocket": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "mpdemulation": { "enabled": { "type": "boolean", "value": false }, "status": { "type": "string", "value": "STARTED" } }, "rest_api": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } } }, "system_controller": { "updater_comm": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "network": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "networkfs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "services": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "system": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "i2s_dacs": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumiodiscovery": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "volumio_command_line_client": { "enabled": { "type": "boolean", "value": true }, "status": { "type": "string", "value": "STARTED" } }, "my_volumio": { "status": { "type": "string", "value": "STARTED" } } } } # cat /etc/mpd.conf --------------- # Volumio MPD Configuration File # Files and directories ####################################################### music_directory "/var/lib/mpd/music" playlist_directory "/var/lib/mpd/playlists" db_file "/var/lib/mpd/tag_cache" log_file "/var/log/mpd.log" #pid_file "/var/run/mpd/pid" #state_file "/var/lib/mpd/state" #sticker_file "/var/lib/mpd/sticker.sql" ############################################################################### # General music daemon options ################################################ user "mpd" group "audio" bind_to_address "any" #port "6600" log_level "default" #save_absolute_paths_in_playlists "no" #metadata_to_use "artist,album,title,track,name,genre,date,composer,performer,disc" auto_update "no" #auto_update_depth "3" ############################################################################### # Symbolic link behavior ###################################################### follow_outside_symlinks "yes" follow_inside_symlinks "yes" ############################################################################### # Input ####################################################################### # input { plugin "curl" # proxy "proxy.isp.com:8080" # proxy_user "user" # proxy_password "password" } ############################################################################### # Decoder ################################################################ decoder { plugin "faad" enabled "no" } ############################################################################### # Audio Output ################################################################ resampler { plugin "soxr" quality "high" threads "1" } audio_output { type "alsa" name "alsa" device "volumio" dop "no" } audio_output { type "fifo" enabled "no" name "multiroom" path "/tmp/snapfifo" format "44100:16:2" } #replaygain "album" #replaygain_preamp "0" volume_normalization "no" ############################################################################### # MPD Internal Buffering ###################################################### audio_buffer_size "2048" ############################################################################### # Resource Limitations ######################################################## #connection_timeout "60" max_connections "20" max_playlist_length "81920" max_command_list_size "81920" max_output_buffer_size "81920" ############################################################################### # Character Encoding ########################################################## filesystem_charset "UTF-8" ############################################################################### # volumio endpointstest --------------- TESTING REMOTE ENDPOINTS https://google.com, 535 ms: OK https://www.googleapis.com, 369 ms: OK https://securetoken.googleapis.com, 419 ms: OK https://myvolumio.firebaseio.com, 461 ms: OK https://functions.volumio.cloud, 428 ms: OK https://oauth-performer.dfs.volumio.org, 505 ms: OK https://browsing-performer.dfs.volumio.org, 375 ms: OK http://cddb.volumio.org, 2517 ms: OK https://functions.volumio.cloud, 498 ms: OK http://pushupdates.volumio.org, 354 ms: OK http://plugins.volumio.org, 2648 ms: OK https://database.volumio.cloud, 733 ms: OK https://radio-directory.firebaseapp.com, 415 ms: OK ---------- REMOTE ENDPOINTS TEST OK, all Endpoints are reachable ---------- # cat /data/updatesHistoryFile --------------- --- Tue Sep 17 2024 16:07:47 GMT+0100 (British Summer Time) {"systemversion":"3.757","builddate":"Tue 17 Sep 2024 05:01:09 PM CEST","variant":"volumio","hardware":"pi"} ------ Mon Mar 31 2025 19:28:15 GMT+0100 (British Summer Time) {"systemversion":"3.795","builddate":"Thu 27 Feb 2025 11:42:54 AM CET","variant":"volumio","hardware":"pi"} ------ Sun Apr 06 2025 22:10:48 GMT+0100 (British Summer Time) {"systemversion":"3.799","builddate":"Mon 31 Mar 2025 06:54:47 PM CEST","variant":"volumio","hardware":"pi"} --- # sudo journalctl -p 7 --------------- -- Logs begin at Thu 2019-02-14 10:11:59 GMT, end at Mon 2025-04-07 08:28:33 BST. -- Feb 14 10:11:59 dynaudio kernel: Booting Linux on physical CPU 0x0 Feb 14 10:11:59 dynaudio kernel: Linux version 6.6.62-v7+ (dom@buildbot) (arm-linux-gnueabihf-gcc (Ubuntu 11.4.0-1ubuntu1~22.04) 11.4.0, GNU ld (GNU Binutils for Ubuntu) 2.38) #1816 SMP Mon Nov 18 15:30:49 GMT 2024 Feb 14 10:11:59 dynaudio kernel: CPU: ARMv7 Processor [410fd034] revision 4 (ARMv7), cr=10c5383d Feb 14 10:11:59 dynaudio kernel: CPU: div instructions available: patching division code Feb 14 10:11:59 dynaudio kernel: CPU: PIPT / VIPT nonaliasing data cache, VIPT aliasing instruction cache Feb 14 10:11:59 dynaudio kernel: OF: fdt: Machine model: Raspberry Pi 3 Model B Rev 1.2 Feb 14 10:11:59 dynaudio kernel: random: crng init done Feb 14 10:11:59 dynaudio kernel: Memory policy: Data cache writealloc Feb 14 10:11:59 dynaudio kernel: Reserved memory: created CMA memory pool at 0x34000000, size 64 MiB Feb 14 10:11:59 dynaudio kernel: OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool Feb 14 10:11:59 dynaudio kernel: OF: reserved mem: 0x34000000..0x37ffffff (65536 KiB) map reusable linux,cma Feb 14 10:11:59 dynaudio kernel: Zone ranges: Feb 14 10:11:59 dynaudio kernel: DMA [mem 0x0000000000000000-0x0000000037ffffff] Feb 14 10:11:59 dynaudio kernel: Normal empty Feb 14 10:11:59 dynaudio kernel: Movable zone start for each node Feb 14 10:11:59 dynaudio kernel: Early memory node ranges Feb 14 10:11:59 dynaudio kernel: node 0: [mem 0x0000000000000000-0x0000000037ffffff] Feb 14 10:11:59 dynaudio kernel: Initmem setup node 0 [mem 0x0000000000000000-0x0000000037ffffff] Feb 14 10:11:59 dynaudio kernel: percpu: Embedded 18 pages/cpu s41812 r8192 d23724 u73728 Feb 14 10:11:59 dynaudio kernel: pcpu-alloc: s41812 r8192 d23724 u73728 alloc=18*4096 Feb 14 10:11:59 dynaudio kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Feb 14 10:11:59 dynaudio kernel: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=8543fa12-4125-4451-a534-cec5480e0606 imgfile=/volumio_current.sqsh bootpart=UUID=9C48-E5A3 datapart=UUID=f7cca1c6-391b-47a8-9c19-3b1948539def 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 Feb 14 10:11:59 dynaudio kernel: cgroup: Disabling memory control group subsystem Feb 14 10:11:59 dynaudio kernel: Unknown kernel command line parameters "splash nodebug imgpart=UUID=8543fa12-4125-4451-a534-cec5480e0606 imgfile=/volumio_current.sqsh bootpart=UUID=9C48-E5A3 datapart=UUID=f7cca1c6-391b-47a8-9c19-3b1948539def uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe bootdelay=7 use_kmsg=no", will be passed to user space. Feb 14 10:11:59 dynaudio kernel: Dentry cache hash table entries: 131072 (order: 7, 524288 bytes, linear) Feb 14 10:11:59 dynaudio kernel: Inode-cache hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 10:11:59 dynaudio kernel: Built 1 zonelists, mobility grouping on. Total pages: 227360 Feb 14 10:11:59 dynaudio kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 14 10:11:59 dynaudio kernel: Memory: 795988K/917504K available (11264K kernel code, 1476K rwdata, 3260K rodata, 1024K init, 606K bss, 55980K reserved, 65536K cma-reserved) Feb 14 10:11:59 dynaudio kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Feb 14 10:11:59 dynaudio kernel: ftrace: allocating 36457 entries in 107 pages Feb 14 10:11:59 dynaudio kernel: ftrace: allocated 107 pages with 5 groups Feb 14 10:11:59 dynaudio kernel: trace event string verifier disabled Feb 14 10:11:59 dynaudio kernel: rcu: Hierarchical RCU implementation. Feb 14 10:11:59 dynaudio kernel: Rude variant of Tasks RCU enabled. Feb 14 10:11:59 dynaudio kernel: Tracing variant of Tasks RCU enabled. Feb 14 10:11:59 dynaudio kernel: rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. Feb 14 10:11:59 dynaudio kernel: NR_IRQS: 16, nr_irqs: 16, preallocated irqs: 16 Feb 14 10:11:59 dynaudio kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Feb 14 10:11:59 dynaudio kernel: arch_timer: cp15 timer(s) running at 19.20MHz (phys). Feb 14 10:11:59 dynaudio kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x46d987e47, max_idle_ns: 440795202767 ns Feb 14 10:11:59 dynaudio kernel: sched_clock: 56 bits at 19MHz, resolution 52ns, wraps every 4398046511078ns Feb 14 10:11:59 dynaudio kernel: Switching to timer-based delay loop, resolution 52ns Feb 14 10:11:59 dynaudio kernel: Console: colour dummy device 80x30 Feb 14 10:11:59 dynaudio kernel: printk: console [tty1] enabled Feb 14 10:11:59 dynaudio kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 38.40 BogoMIPS (lpj=192000) Feb 14 10:11:59 dynaudio kernel: CPU: Testing write buffer coherency: ok Feb 14 10:11:59 dynaudio kernel: pid_max: default: 32768 minimum: 301 Feb 14 10:11:59 dynaudio kernel: LSM: initializing lsm=capability,integrity Feb 14 10:11:59 dynaudio kernel: Mount-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 dynaudio kernel: Mountpoint-cache hash table entries: 2048 (order: 1, 8192 bytes, linear) Feb 14 10:11:59 dynaudio kernel: CPU0: thread -1, cpu 0, socket 0, mpidr 80000000 Feb 14 10:11:59 dynaudio kernel: RCU Tasks Rude: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 14 10:11:59 dynaudio kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=4. Feb 14 10:11:59 dynaudio kernel: Setting up static identity map for 0x100000 - 0x10003c Feb 14 10:11:59 dynaudio kernel: rcu: Hierarchical SRCU implementation. Feb 14 10:11:59 dynaudio kernel: rcu: Max phase no-delay instances is 1000. Feb 14 10:11:59 dynaudio kernel: smp: Bringing up secondary CPUs ... Feb 14 10:11:59 dynaudio kernel: CPU1: thread -1, cpu 1, socket 0, mpidr 80000001 Feb 14 10:11:59 dynaudio kernel: CPU2: thread -1, cpu 2, socket 0, mpidr 80000002 Feb 14 10:11:59 dynaudio kernel: CPU3: thread -1, cpu 3, socket 0, mpidr 80000003 Feb 14 10:11:59 dynaudio kernel: smp: Brought up 1 node, 4 CPUs Feb 14 10:11:59 dynaudio kernel: SMP: Total of 4 processors activated (153.60 BogoMIPS). Feb 14 10:11:59 dynaudio kernel: CPU: All CPU(s) started in HYP mode. Feb 14 10:11:59 dynaudio kernel: CPU: Virtualization extensions available. Feb 14 10:11:59 dynaudio kernel: devtmpfs: initialized Feb 14 10:11:59 dynaudio kernel: VFP support v0.3: implementor 41 architecture 3 part 40 variant 3 rev 4 Feb 14 10:11:59 dynaudio kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns Feb 14 10:11:59 dynaudio kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Feb 14 10:11:59 dynaudio kernel: pinctrl core: initialized pinctrl subsystem Feb 14 10:11:59 dynaudio kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Feb 14 10:11:59 dynaudio kernel: DMA: preallocated 1024 KiB pool for atomic coherent allocations Feb 14 10:11:59 dynaudio kernel: audit: initializing netlink subsys (disabled) Feb 14 10:11:59 dynaudio kernel: audit: type=2000 audit(0.010:1): state=initialized audit_enabled=0 res=1 Feb 14 10:11:59 dynaudio kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 14 10:11:59 dynaudio kernel: hw-breakpoint: found 5 (+1 reserved) breakpoint and 4 watchpoint registers. Feb 14 10:11:59 dynaudio kernel: hw-breakpoint: maximum watchpoint size is 8 bytes. Feb 14 10:11:59 dynaudio kernel: Serial: AMBA PL011 UART driver Feb 14 10:11:59 dynaudio kernel: bcm2835-mbox 3f00b880.mailbox: mailbox enabled Feb 14 10:11:59 dynaudio kernel: raspberrypi-firmware soc:firmware: Attached to firmware from 2024-11-11T15:50:32, variant start Feb 14 10:11:59 dynaudio kernel: raspberrypi-firmware soc:firmware: Firmware hash is 903570ba72a9e117f92e5499de439f59dd96e417 Feb 14 10:11:59 dynaudio kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Feb 14 10:11:59 dynaudio kernel: bcm2835-dma 3f007000.dma-controller: DMA legacy API manager, dmachans=0x1 Feb 14 10:11:59 dynaudio kernel: SCSI subsystem initialized Feb 14 10:11:59 dynaudio kernel: usbcore: registered new interface driver usbfs Feb 14 10:11:59 dynaudio kernel: usbcore: registered new interface driver hub Feb 14 10:11:59 dynaudio kernel: usbcore: registered new device driver usb Feb 14 10:11:59 dynaudio kernel: pps_core: LinuxPPS API ver. 1 registered Feb 14 10:11:59 dynaudio kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Feb 14 10:11:59 dynaudio kernel: PTP clock support registered Feb 14 10:11:59 dynaudio kernel: clocksource: Switched to clocksource arch_sys_counter Feb 14 10:11:59 dynaudio kernel: VFS: Disk quotas dquot_6.6.0 Feb 14 10:11:59 dynaudio kernel: VFS: Dquot-cache hash table entries: 1024 (order 0, 4096 bytes) Feb 14 10:11:59 dynaudio kernel: FS-Cache: Loaded Feb 14 10:11:59 dynaudio kernel: CacheFiles: Loaded Feb 14 10:11:59 dynaudio kernel: NET: Registered PF_INET protocol family Feb 14 10:11:59 dynaudio kernel: IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) Feb 14 10:11:59 dynaudio kernel: tcp_listen_portaddr_hash hash table entries: 512 (order: 0, 4096 bytes, linear) Feb 14 10:11:59 dynaudio kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 14 10:11:59 dynaudio kernel: TCP established hash table entries: 8192 (order: 3, 32768 bytes, linear) Feb 14 10:11:59 dynaudio kernel: TCP bind hash table entries: 8192 (order: 5, 131072 bytes, linear) Feb 14 10:11:59 dynaudio kernel: TCP: Hash tables configured (established 8192 bind 8192) Feb 14 10:11:59 dynaudio kernel: UDP hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:11:59 dynaudio kernel: UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) Feb 14 10:11:59 dynaudio kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Feb 14 10:11:59 dynaudio kernel: RPC: Registered named UNIX socket transport module. Feb 14 10:11:59 dynaudio kernel: RPC: Registered udp transport module. Feb 14 10:11:59 dynaudio kernel: RPC: Registered tcp transport module. Feb 14 10:11:59 dynaudio kernel: RPC: Registered tcp-with-tls transport module. Feb 14 10:11:59 dynaudio kernel: RPC: Registered tcp NFSv4.1 backchannel transport module. Feb 14 10:11:59 dynaudio kernel: Trying to unpack rootfs image as initramfs... Feb 14 10:11:59 dynaudio kernel: hw perfevents: enabled with armv7_cortex_a7 PMU driver, 7 counters available Feb 14 10:11:59 dynaudio kernel: Freeing initrd memory: 28088K Feb 14 10:11:59 dynaudio kernel: Initialise system trusted keyrings Feb 14 10:11:59 dynaudio kernel: workingset: timestamp_bits=14 max_order=18 bucket_order=4 Feb 14 10:11:59 dynaudio kernel: zbud: loaded Feb 14 10:11:59 dynaudio kernel: NFS: Registering the id_resolver key type Feb 14 10:11:59 dynaudio kernel: Key type id_resolver registered Feb 14 10:11:59 dynaudio kernel: Key type id_legacy registered Feb 14 10:11:59 dynaudio kernel: nfs4filelayout_init: NFSv4 File Layout Driver Registering... Feb 14 10:11:59 dynaudio kernel: nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... Feb 14 10:11:59 dynaudio kernel: Key type asymmetric registered Feb 14 10:11:59 dynaudio kernel: Asymmetric key parser 'x509' registered Feb 14 10:11:59 dynaudio kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 247) Feb 14 10:11:59 dynaudio kernel: io scheduler mq-deadline registered Feb 14 10:11:59 dynaudio kernel: io scheduler kyber registered Feb 14 10:11:59 dynaudio kernel: io scheduler bfq registered Feb 14 10:11:59 dynaudio kernel: pinctrl-bcm2835 3f200000.gpio: GPIO_OUT persistence: yes Feb 14 10:11:59 dynaudio kernel: bcm2708_fb soc:fb: FB found 1 display(s) Feb 14 10:11:59 dynaudio kernel: Console: switching to colour frame buffer device 80x30 Feb 14 10:11:59 dynaudio kernel: bcm2708_fb soc:fb: Registered framebuffer for display 0, size 640x480 Feb 14 10:11:59 dynaudio kernel: bcm2835-rng 3f104000.rng: hwrng registered Feb 14 10:11:59 dynaudio kernel: vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) Feb 14 10:11:59 dynaudio kernel: brd: module loaded Feb 14 10:11:59 dynaudio kernel: loop: module loaded Feb 14 10:11:59 dynaudio kernel: Loading iSCSI transport class v2.0-870. Feb 14 10:11:59 dynaudio kernel: usbcore: registered new interface driver lan78xx Feb 14 10:11:59 dynaudio kernel: usbcore: registered new interface driver smsc95xx Feb 14 10:11:59 dynaudio kernel: dwc_otg: version 3.00a 10-AUG-2012 (platform bus) Feb 14 10:11:59 dynaudio kernel: Core Release: 2.80a Feb 14 10:11:59 dynaudio kernel: Setting default values for core params Feb 14 10:11:59 dynaudio kernel: Finished setting default values for core params Feb 14 10:11:59 dynaudio kernel: Using Buffer DMA mode Feb 14 10:11:59 dynaudio kernel: Periodic Transfer Interrupt Enhancement - disabled Feb 14 10:11:59 dynaudio kernel: Multiprocessor Interrupt Enhancement - disabled Feb 14 10:11:59 dynaudio kernel: OTG VER PARAM: 0, OTG VER FLAG: 0 Feb 14 10:11:59 dynaudio kernel: Dedicated Tx FIFOs mode Feb 14 10:11:59 dynaudio kernel: Feb 14 10:11:59 dynaudio kernel: WARN::dwc_otg_hcd_init:1070: FIQ DMA bounce buffers: virt = b4104000 dma = 0xf4104000 len=9024 Feb 14 10:11:59 dynaudio kernel: FIQ FSM acceleration enabled for : Non-periodic Split Transactions Periodic Split Transactions High-Speed Isochronous Endpoints Interrupt/Control Split Transaction hack enabled Feb 14 10:11:59 dynaudio kernel: dwc_otg: Microframe scheduler enabled Feb 14 10:11:59 dynaudio kernel: Feb 14 10:11:59 dynaudio kernel: WARN::hcd_init_fiq:457: FIQ on core 1 Feb 14 10:11:59 dynaudio kernel: Feb 14 10:11:59 dynaudio kernel: WARN::hcd_init_fiq:458: FIQ ASM at 808886e4 length 36 Feb 14 10:11:59 dynaudio kernel: Feb 14 10:11:59 dynaudio kernel: WARN::hcd_init_fiq:496: MPHI regs_base at b8810000 Feb 14 10:11:59 dynaudio kernel: dwc_otg 3f980000.usb: DWC OTG Controller Feb 14 10:11:59 dynaudio kernel: dwc_otg 3f980000.usb: new USB bus registered, assigned bus number 1 Feb 14 10:11:59 dynaudio kernel: dwc_otg 3f980000.usb: irq 89, io mem 0x00000000 Feb 14 10:11:59 dynaudio kernel: Init: Port Power? op_state=1 Feb 14 10:11:59 dynaudio kernel: Init: Power Port (0) Feb 14 10:11:59 dynaudio kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 6.06 Feb 14 10:11:59 dynaudio kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Feb 14 10:11:59 dynaudio kernel: usb usb1: Product: DWC OTG Controller Feb 14 10:11:59 dynaudio kernel: usb usb1: Manufacturer: Linux 6.6.62-v7+ dwc_otg_hcd Feb 14 10:11:59 dynaudio kernel: usb usb1: SerialNumber: 3f980000.usb Feb 14 10:11:59 dynaudio kernel: hub 1-0:1.0: USB hub found Feb 14 10:11:59 dynaudio kernel: hub 1-0:1.0: 1 port detected Feb 14 10:11:59 dynaudio kernel: dwc_otg: FIQ enabled Feb 14 10:11:59 dynaudio kernel: dwc_otg: NAK holdoff enabled Feb 14 10:11:59 dynaudio kernel: dwc_otg: FIQ split-transaction FSM enabled Feb 14 10:11:59 dynaudio kernel: Module dwc_common_port init Feb 14 10:11:59 dynaudio kernel: usbcore: registered new interface driver usb-storage Feb 14 10:11:59 dynaudio kernel: mousedev: PS/2 mouse device common for all mice Feb 14 10:11:59 dynaudio kernel: sdhci: Secure Digital Host Controller Interface driver Feb 14 10:11:59 dynaudio kernel: sdhci: Copyright(c) Pierre Ossman Feb 14 10:11:59 dynaudio kernel: sdhci-pltfm: SDHCI platform and OF driver helper Feb 14 10:11:59 dynaudio kernel: ledtrig-cpu: registered to indicate activity on CPUs Feb 14 10:11:59 dynaudio kernel: hid: raw HID events driver (C) Jiri Kosina Feb 14 10:11:59 dynaudio kernel: usbcore: registered new interface driver usbhid Feb 14 10:11:59 dynaudio kernel: usbhid: USB HID core driver Feb 14 10:11:59 dynaudio kernel: Initializing XFRM netlink socket Feb 14 10:11:59 dynaudio kernel: NET: Registered PF_PACKET protocol family Feb 14 10:11:59 dynaudio kernel: Key type dns_resolver registered Feb 14 10:11:59 dynaudio kernel: Registering SWP/SWPB emulation handler Feb 14 10:11:59 dynaudio kernel: registered taskstats version 1 Feb 14 10:11:59 dynaudio kernel: Loading compiled-in X.509 certificates Feb 14 10:11:59 dynaudio kernel: Key type .fscrypt registered Feb 14 10:11:59 dynaudio kernel: Key type fscrypt-provisioning registered Feb 14 10:11:59 dynaudio kernel: uart-pl011 3f201000.serial: cts_event_workaround enabled Feb 14 10:11:59 dynaudio kernel: 3f201000.serial: ttyAMA1 at MMIO 0x3f201000 (irq = 114, base_baud = 0) is a PL011 rev2 Feb 14 10:11:59 dynaudio kernel: serial serial0: tty port ttyAMA1 registered Feb 14 10:11:59 dynaudio kernel: bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer Feb 14 10:11:59 dynaudio kernel: bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver Feb 14 10:11:59 dynaudio kernel: mmc-bcm2835 3f300000.mmcnr: mmc_debug:0 mmc_debug2:0 Feb 14 10:11:59 dynaudio kernel: mmc-bcm2835 3f300000.mmcnr: DMA channel allocated Feb 14 10:11:59 dynaudio kernel: sdhost: log_buf @ ab5722f0 (f4107000) Feb 14 10:11:59 dynaudio kernel: mmc0: sdhost-bcm2835 loaded - DMA enabled (>1) Feb 14 10:11:59 dynaudio kernel: of_cfs_init Feb 14 10:11:59 dynaudio kernel: of_cfs_init: OK Feb 14 10:11:59 dynaudio kernel: clk: Disabling unused clocks Feb 14 10:11:59 dynaudio kernel: Freeing unused kernel image (initmem) memory: 1024K Feb 14 10:11:59 dynaudio kernel: Run /init as init process Feb 14 10:11:59 dynaudio kernel: with arguments: Feb 14 10:11:59 dynaudio kernel: /init Feb 14 10:11:59 dynaudio kernel: splash Feb 14 10:11:59 dynaudio kernel: nodebug Feb 14 10:11:59 dynaudio kernel: with environment: Feb 14 10:11:59 dynaudio kernel: HOME=/ Feb 14 10:11:59 dynaudio kernel: TERM=linux Feb 14 10:11:59 dynaudio kernel: imgpart=UUID=8543fa12-4125-4451-a534-cec5480e0606 Feb 14 10:11:59 dynaudio kernel: imgfile=/volumio_current.sqsh Feb 14 10:11:59 dynaudio kernel: bootpart=UUID=9C48-E5A3 Feb 14 10:11:59 dynaudio kernel: datapart=UUID=f7cca1c6-391b-47a8-9c19-3b1948539def Feb 14 10:11:59 dynaudio kernel: uuidconfig=cmdline.txt Feb 14 10:11:59 dynaudio kernel: pcie_aspm=off Feb 14 10:11:59 dynaudio kernel: pci=pcie_bus_safe Feb 14 10:11:59 dynaudio kernel: bootdelay=7 Feb 14 10:11:59 dynaudio kernel: use_kmsg=no Feb 14 10:11:59 dynaudio kernel: Indeed it is in host mode hprt0 = 00021501 Feb 14 10:11:59 dynaudio kernel: mmc0: host does not support reading read-only switch, assuming write-enable Feb 14 10:11:59 dynaudio kernel: mmc0: Host Software Queue enabled Feb 14 10:11:59 dynaudio kernel: mmc0: new high speed SDHC card at address 59b4 Feb 14 10:11:59 dynaudio kernel: mmcblk0: mmc0:59b4 USD 15.0 GiB Feb 14 10:11:59 dynaudio kernel: mmcblk0: p1 p2 p3 Feb 14 10:11:59 dynaudio kernel: mmcblk0: mmc0:59b4 USD 15.0 GiB Feb 14 10:11:59 dynaudio kernel: mmc1: new high speed SDIO card at address 0001 Feb 14 10:11:59 dynaudio kernel: usb 1-1: new high-speed USB device number 2 using dwc_otg Feb 14 10:11:59 dynaudio kernel: Indeed it is in host mode hprt0 = 00001101 Feb 14 10:11:59 dynaudio kernel: usb 1-1: New USB device found, idVendor=0424, idProduct=9514, bcdDevice= 2.00 Feb 14 10:11:59 dynaudio kernel: usb 1-1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Feb 14 10:11:59 dynaudio kernel: hub 1-1:1.0: USB hub found Feb 14 10:11:59 dynaudio kernel: hub 1-1:1.0: 5 ports detected Feb 14 10:11:59 dynaudio kernel: usb 1-1.1: new high-speed USB device number 3 using dwc_otg Feb 14 10:11:59 dynaudio kernel: usb 1-1.1: New USB device found, idVendor=0424, idProduct=ec00, bcdDevice= 2.00 Feb 14 10:11:59 dynaudio kernel: usb 1-1.1: New USB device strings: Mfr=0, Product=0, SerialNumber=0 Feb 14 10:11:59 dynaudio kernel: smsc95xx v2.0.0 Feb 14 10:11:59 dynaudio kernel: SMSC LAN8700 usb-001:003:01: attached PHY driver (mii_bus:phy_addr=usb-001:003:01, irq=199) Feb 14 10:11:59 dynaudio kernel: smsc95xx 1-1.1:1.0 eth0: register 'smsc95xx' at usb-3f980000.usb-1.1, smsc95xx USB 2.0 Ethernet, b8:27:eb:e2:7b:c9 Feb 14 10:11:59 dynaudio kernel: fuse: init (API version 7.39) Feb 14 10:11:59 dynaudio kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Feb 14 10:11:59 dynaudio kernel: usbcore: registered new interface driver uas Feb 14 10:11:59 dynaudio kernel: EXT4-fs (mmcblk0p2): recovery complete Feb 14 10:11:59 dynaudio kernel: EXT4-fs (mmcblk0p2): mounted filesystem 8543fa12-4125-4451-a534-cec5480e0606 r/w with ordered data mode. Quota mode: none. Feb 14 10:11:59 dynaudio kernel: loop0: detected capacity change from 0 to 996360 Feb 14 10:11:59 dynaudio kernel: EXT4-fs (mmcblk0p3): recovery complete Feb 14 10:11:59 dynaudio kernel: EXT4-fs (mmcblk0p3): mounted filesystem f7cca1c6-391b-47a8-9c19-3b1948539def r/w with ordered data mode. Quota mode: none. Feb 14 10:11:59 dynaudio systemd[1]: System time before build time, advancing clock. Feb 14 10:11:59 dynaudio kernel: NET: Registered PF_INET6 protocol family Feb 14 10:11:59 dynaudio kernel: Segment Routing with IPv6 Feb 14 10:11:59 dynaudio kernel: In-situ OAM (IOAM) with IPv6 Feb 14 10:11:59 dynaudio systemd[1]: systemd 241 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid) Feb 14 10:11:59 dynaudio systemd[1]: Detected architecture arm. Feb 14 10:11:59 dynaudio systemd[1]: Set hostname to . Feb 14 10:11:59 dynaudio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Feb 14 10:11:59 dynaudio systemd[1]: Listening on Journal Socket (/dev/log). Feb 14 10:11:59 dynaudio systemd[1]: Listening on initctl Compatibility Named Pipe. Feb 14 10:11:59 dynaudio systemd[1]: Listening on Journal Audit Socket. Feb 14 10:11:59 dynaudio systemd[1]: Listening on fsck to fsckd communication Socket. Feb 14 10:11:59 dynaudio systemd[1]: Reached target System Time Synchronized. Feb 14 10:11:59 dynaudio systemd[1]: Started Forward Password Requests to Wall Directory Watch. Feb 14 10:11:59 dynaudio systemd-journald[306]: Journal started Feb 14 10:11:59 dynaudio systemd-journald[306]: Runtime journal (/run/log/journal/d9a3e155a633914cdbe1662066e99934) is 3.7M, max 30.0M, 26.2M free. Feb 14 10:11:59 dynaudio systemd[1]: Started Create list of required static device nodes for the current kernel. Feb 14 10:11:59 dynaudio systemd[1]: Mounted POSIX Message Queue File System. Feb 14 10:11:59 dynaudio systemd[1]: Mounted Kernel Debug File System. Feb 14 10:11:59 dynaudio systemd[1]: Started Remount Root and Kernel File Systems. Feb 14 10:11:59 dynaudio systemd[1]: Mounted RPC Pipe File System. Feb 14 10:11:59 dynaudio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Feb 14 10:11:59 dynaudio systemd[1]: Starting Create System Users... Feb 14 10:11:59 dynaudio systemd[1]: Starting Load/Save Random Seed... Feb 14 10:11:59 dynaudio systemd-modules-load[309]: Inserted module 'i2c_dev' Feb 14 10:11:59 dynaudio systemd[1]: Started Load Kernel Modules. Apr 07 07:59:43 dynaudio kernel: i2c_dev: i2c /dev entries driver Apr 07 07:59:43 dynaudio systemd[1]: Mounting FUSE Control File System... Apr 07 07:59:43 dynaudio fake-hwclock[305]: Mon Apr 7 06:59:43 UTC 2025 Apr 07 07:59:43 dynaudio systemd[1]: Starting Apply Kernel Variables... Apr 07 07:59:43 dynaudio systemd[1]: Mounting Kernel Configuration File System... Apr 07 07:59:43 dynaudio systemd[1]: Started Restore / save the current clock. Apr 07 07:59:43 dynaudio systemd[1]: Mounted FUSE Control File System. Apr 07 07:59:43 dynaudio systemd[1]: Mounted Kernel Configuration File System. Apr 07 07:59:43 dynaudio systemd[1]: Started Load/Save Random Seed. Apr 07 07:59:43 dynaudio systemd[1]: dynamicswap.service: Succeeded. Apr 07 07:59:43 dynaudio systemd[1]: Started Create System Users. Apr 07 07:59:43 dynaudio systemd[1]: Starting Create Static Device Nodes in /dev... Apr 07 07:59:43 dynaudio systemd[1]: Started Apply Kernel Variables. Apr 07 07:59:43 dynaudio systemd[1]: Started udev Coldplug all Devices. Apr 07 07:59:43 dynaudio systemd[1]: Starting Helper to synchronize boot up for ifupdown... Apr 07 07:59:43 dynaudio systemd[1]: Started Create Static Device Nodes in /dev. Apr 07 07:59:43 dynaudio systemd[1]: Reached target Local File Systems (Pre). Apr 07 07:59:43 dynaudio systemd[1]: var-log.mount: Directory /var/log to mount over is not empty, mounting anyway. Apr 07 07:59:43 dynaudio systemd[1]: Mounting /var/log... Apr 07 07:59:43 dynaudio systemd[1]: Mounting /var/spool/cups... Apr 07 07:59:43 dynaudio systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Apr 07 07:59:43 dynaudio systemd[1]: Mounting /tmp... Apr 07 07:59:43 dynaudio systemd[1]: Starting udev Kernel Device Manager... Apr 07 07:59:43 dynaudio systemd[1]: Mounted /var/log. Apr 07 07:59:43 dynaudio systemd[1]: Mounted /var/spool/cups. Apr 07 07:59:43 dynaudio systemd[1]: Mounted /tmp. Apr 07 07:59:43 dynaudio systemd[1]: Mounting /var/spool/cups/tmp... Apr 07 07:59:43 dynaudio systemd[1]: Starting Flush Journal to Persistent Storage... Apr 07 07:59:43 dynaudio systemd[1]: Mounted /var/spool/cups/tmp. Apr 07 07:59:43 dynaudio systemd-udevd[337]: Network interface NamePolicy= disabled on kernel command line, ignoring. Apr 07 07:59:43 dynaudio systemd-journald[306]: Runtime journal (/run/log/journal/d9a3e155a633914cdbe1662066e99934) is 7.5M, max 30.0M, 22.5M free. Apr 07 07:59:43 dynaudio systemd[1]: Started Flush Journal to Persistent Storage. Apr 07 07:59:43 dynaudio systemd[1]: Started udev Kernel Device Manager. Apr 07 07:59:43 dynaudio systemd[1]: Starting Show Plymouth Boot Screen... Apr 07 07:59:43 dynaudio systemd[1]: plymouth-start.service: Succeeded. Apr 07 07:59:43 dynaudio systemd[1]: Started Show Plymouth Boot Screen. Apr 07 07:59:43 dynaudio systemd[1]: Started Forward Password Requests to Plymouth Directory Watch. Apr 07 07:59:43 dynaudio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 07 07:59:43 dynaudio systemd[1]: Reached target Local Encrypted Volumes. Apr 07 07:59:43 dynaudio systemd[1]: Reached target Paths. Apr 07 07:59:43 dynaudio kernel: rpi-gpiomem 3f200000.gpiomem: window base 0x3f200000 size 0x00001000 Apr 07 07:59:43 dynaudio kernel: rpi-gpiomem 3f200000.gpiomem: initialised 1 regions as /dev/gpiomem Apr 07 07:59:43 dynaudio kernel: vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. Apr 07 07:59:43 dynaudio kernel: bcm2835_vc_sm_cma_probe: Videocore shared memory driver Apr 07 07:59:43 dynaudio kernel: [vc_sm_connected_init]: start Apr 07 07:59:43 dynaudio kernel: [vc_sm_connected_init]: installed successfully Apr 07 07:59:43 dynaudio kernel: mc: Linux media interface: v0.10 Apr 07 07:59:43 dynaudio kernel: videodev: Linux video capture interface: v2.00 Apr 07 07:59:43 dynaudio kernel: bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. Apr 07 07:59:43 dynaudio kernel: bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. Apr 07 07:59:43 dynaudio kernel: bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. Apr 07 07:59:43 dynaudio systemd[1]: Found device /dev/disk/by-uuid/9C48-E5A3. Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 Apr 07 07:59:43 dynaudio kernel: Bluetooth: Core ver 2.22 Apr 07 07:59:43 dynaudio kernel: NET: Registered PF_BLUETOOTH protocol family Apr 07 07:59:43 dynaudio kernel: Bluetooth: HCI device and connection manager initialized Apr 07 07:59:43 dynaudio kernel: Bluetooth: HCI socket layer initialized Apr 07 07:59:43 dynaudio kernel: Bluetooth: L2CAP socket layer initialized Apr 07 07:59:43 dynaudio kernel: Bluetooth: SCO socket layer initialized Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Register output node 0 with media controller Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Register capture node 1 with media controller Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Register capture node 2 with media controller Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Register capture node 3 with media controller Apr 07 07:59:43 dynaudio kernel: bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp Apr 07 07:59:44 dynaudio kernel: bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. Apr 07 07:59:44 dynaudio kernel: Bluetooth: HCI UART driver ver 2.3 Apr 07 07:59:44 dynaudio kernel: Bluetooth: HCI UART protocol H4 registered Apr 07 07:59:44 dynaudio kernel: Bluetooth: HCI UART protocol Three-wire (H5) registered Apr 07 07:59:44 dynaudio kernel: hci_uart_bcm serial0-0: supply vbat not found, using dummy regulator Apr 07 07:59:44 dynaudio kernel: hci_uart_bcm serial0-0: supply vddio not found, using dummy regulator Apr 07 07:59:44 dynaudio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video10 Apr 07 07:59:44 dynaudio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 decode Apr 07 07:59:44 dynaudio kernel: Bluetooth: HCI UART protocol Broadcom registered Apr 07 07:59:44 dynaudio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video11 Apr 07 07:59:44 dynaudio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode Apr 07 07:59:44 dynaudio kernel: snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. Apr 07 07:59:44 dynaudio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video12 Apr 07 07:59:44 dynaudio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 isp Apr 07 07:59:44 dynaudio kernel: snd_bcm2835: unknown parameter 'enable_compat_alsa' ignored Apr 07 07:59:44 dynaudio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Apr 07 07:59:44 dynaudio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video18 Apr 07 07:59:44 dynaudio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx Apr 07 07:59:44 dynaudio kernel: bcm2835_audio bcm2835_audio: card created with 4 channels Apr 07 07:59:44 dynaudio kernel: bcm2835-codec bcm2835-codec: Device registered as /dev/video31 Apr 07 07:59:44 dynaudio kernel: bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image Apr 07 07:59:44 dynaudio kernel: uart-pl011 3f201000.serial: no DMA platform data Apr 07 07:59:44 dynaudio kernel: cfg80211: Loading compiled-in X.509 certificates for regulatory database Apr 07 07:59:44 dynaudio kernel: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' Apr 07 07:59:44 dynaudio kernel: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' Apr 07 07:59:44 dynaudio kernel: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' Apr 07 07:59:44 dynaudio kernel: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' Apr 07 07:59:44 dynaudio systemd[1]: Starting File System Check on /dev/disk/by-uuid/9C48-E5A3... Apr 07 07:59:44 dynaudio systemd-udevd[350]: Using default interface naming scheme 'v240'. Apr 07 07:59:44 dynaudio systemd-udevd[350]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 07 07:59:44 dynaudio kernel: brcmfmac: F1 signature read @0x18000000=0x1541a9a6 Apr 07 07:59:44 dynaudio kernel: Bluetooth: hci0: BCM: chip id 94 Apr 07 07:59:44 dynaudio kernel: Bluetooth: hci0: BCM: features 0x2e Apr 07 07:59:44 dynaudio kernel: Bluetooth: hci0: BCM43430A1 Apr 07 07:59:44 dynaudio kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0000 Apr 07 07:59:44 dynaudio kernel: brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43430-sdio for chip BCM43430/1 Apr 07 07:59:44 dynaudio kernel: usbcore: registered new interface driver brcmfmac Apr 07 07:59:44 dynaudio kernel: Bluetooth: hci0: BCM43430A1 'brcm/BCM43430A1.hcd' Patch Apr 07 07:59:44 dynaudio systemd[1]: Started File System Check Daemon to report status. Apr 07 07:59:44 dynaudio kernel: wm8804 1-003b: revision E Apr 07 07:59:44 dynaudio systemd-udevd[348]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 07 07:59:44 dynaudio kernel: brcmfmac: brcmf_c_process_txcap_blob: no txcap_blob available (err=-2) Apr 07 07:59:44 dynaudio systemd-fsck[393]: fsck.fat 4.1 (2017-01-24) Apr 07 07:59:44 dynaudio systemd-fsck[393]: /dev/mmcblk0p1: 483 files, 167993/184582 clusters Apr 07 07:59:44 dynaudio 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 Apr 07 07:59:44 dynaudio systemd[1]: Started File System Check on /dev/disk/by-uuid/9C48-E5A3. Apr 07 07:59:45 dynaudio systemd-udevd[346]: Using default interface naming scheme 'v240'. Apr 07 07:59:45 dynaudio systemd-udevd[346]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Apr 07 07:59:45 dynaudio systemd[1]: Condition check resulted in SMSC9512/9514 Fast Ethernet Adapter being skipped. Apr 07 07:59:45 dynaudio kernel: Bluetooth: hci0: BCM: features 0x2e Apr 07 07:59:45 dynaudio kernel: Bluetooth: hci0: BCM43438A1 37.4MHz Raspberry Pi 3-0141 Apr 07 07:59:45 dynaudio kernel: Bluetooth: hci0: BCM43430A1 (001.002.009) build 0508 Apr 07 07:59:45 dynaudio systemd[1]: Created slice system-bthelper.slice. Apr 07 07:59:45 dynaudio systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Apr 07 07:59:45 dynaudio systemd[1]: Condition check resulted in Huge Pages File System being skipped. Apr 07 07:59:45 dynaudio systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Apr 07 07:59:45 dynaudio kernel: alsactl[493]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Apr 07 07:59:45 dynaudio kernel: alsactl[492]: memfd_create() called without MFD_EXEC or MFD_NOEXEC_SEAL set Apr 07 07:59:45 dynaudio systemd-udevd[349]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 1' failed with exit code 17. Apr 07 07:59:45 dynaudio systemd[1]: Starting Show Plymouth Boot Screen... Apr 07 07:59:45 dynaudio systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch. Apr 07 07:59:45 dynaudio systemd[1]: Mounting /boot... Apr 07 07:59:45 dynaudio systemd[1]: Starting Load/Save RF Kill Switch Status... Apr 07 07:59:45 dynaudio systemd[1]: plymouth-start.service: Succeeded. Apr 07 07:59:45 dynaudio systemd[1]: Started Show Plymouth Boot Screen. Apr 07 07:59:45 dynaudio systemd[1]: Condition check resulted in Dispatch Password Requests to Console Directory Watch being skipped. Apr 07 07:59:45 dynaudio systemd[1]: Mounted /boot. Apr 07 07:59:45 dynaudio systemd[1]: Reached target Local File Systems. Apr 07 07:59:45 dynaudio systemd[1]: Starting Preprocess NFS configuration... Apr 07 07:59:45 dynaudio systemd[1]: Starting Create Volatile Files and Directories... Apr 07 07:59:45 dynaudio systemd[1]: Starting Tell Plymouth To Write Out Runtime Data... Apr 07 07:59:45 dynaudio systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Apr 07 07:59:45 dynaudio systemd[1]: Started ifup for eth0. Apr 07 07:59:45 dynaudio systemd[1]: Started Load/Save RF Kill Switch Status. Apr 07 07:59:45 dynaudio systemd[1]: Found device /sys/subsystem/net/devices/wlan0. Apr 07 07:59:45 dynaudio systemd[1]: nfs-config.service: Succeeded. Apr 07 07:59:45 dynaudio systemd[1]: Started Preprocess NFS configuration. Apr 07 07:59:45 dynaudio systemd[1]: plymouth-read-write.service: Succeeded. Apr 07 07:59:45 dynaudio systemd[1]: Started Tell Plymouth To Write Out Runtime Data. Apr 07 07:59:45 dynaudio systemd[1]: Received SIGRTMIN+20 from PID 197 (plymouthd). Apr 07 07:59:45 dynaudio systemd[1]: Condition check resulted in RPC security service for NFS server being skipped. Apr 07 07:59:45 dynaudio systemd[1]: Condition check resulted in RPC security service for NFS client and server being skipped. Apr 07 07:59:45 dynaudio systemd[1]: Reached target NFS client services. Apr 07 07:59:45 dynaudio systemd[1]: Started ifup for wlan0. Apr 07 07:59:45 dynaudio systemd[1]: Started Create Volatile Files and Directories. Apr 07 07:59:45 dynaudio systemd[1]: Started Entropy daemon using the HAVEGE algorithm. Apr 07 07:59:45 dynaudio systemd[1]: Starting Update UTMP about System Boot/Shutdown... Apr 07 07:59:45 dynaudio systemd[1]: Condition check resulted in Network Time Synchronization being skipped. Apr 07 07:59:45 dynaudio systemd[1]: Starting RPC bind portmap service... Apr 07 07:59:45 dynaudio kernel: warning: `iwconfig' uses wireless extensions which will stop working for Wi-Fi 7 hardware; use nl80211 Apr 07 07:59:45 dynaudio systemd[1]: Started Update UTMP about System Boot/Shutdown. Apr 07 07:59:45 dynaudio systemd[1]: Reached target System Initialization. Apr 07 07:59:45 dynaudio systemd[1]: Started Daily Cleanup of Temporary Directories. Apr 07 07:59:45 dynaudio systemd[1]: Listening on D-Bus System Message Bus Socket. Apr 07 07:59:45 dynaudio systemd[1]: Started Daily apt download activities. Apr 07 07:59:45 dynaudio systemd[1]: Started Daily apt upgrade and clean activities. Apr 07 07:59:45 dynaudio systemd[1]: Reached target Timers. Apr 07 07:59:45 dynaudio kernel: 8021q: 802.1Q VLAN Support v1.8 Apr 07 07:59:45 dynaudio systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket. Apr 07 07:59:45 dynaudio systemd[1]: Listening on triggerhappy.socket. Apr 07 07:59:45 dynaudio systemd[1]: Reached target Sockets. Apr 07 07:59:45 dynaudio systemd[1]: Reached target Basic System. Apr 07 07:59:46 dynaudio systemd[1]: Started MPD Monitor Service. Apr 07 07:59:46 dynaudio systemd[1]: Starting Volumio Time Update Utility... Apr 07 07:59:46 dynaudio mpd_monitor.sh[561]: MPD Monitor Service: Starting MPD Monitor Service Apr 07 07:59:46 dynaudio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 07 07:59:46 dynaudio volumio-time-update[564]: volumio-time-update-util: Fetching time from Volumio... Apr 07 07:59:46 dynaudio systemd[1]: Starting Login Service... Apr 07 07:59:46 dynaudio systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped. Apr 07 07:59:46 dynaudio systemd[1]: Condition check resulted in Copy user wpa_supplicant.conf being skipped. Apr 07 07:59:46 dynaudio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Apr 07 07:59:46 dynaudio systemd[1]: Starting dhcpcd on all interfaces... Apr 07 07:59:46 dynaudio systemd[1]: Started Manage Sound Card State (restore and store). Apr 07 07:59:46 dynaudio systemd[1]: Starting Save/Restore Sound Card State... Apr 07 07:59:46 dynaudio alsactl[579]: alsactl 1.1.8 daemon started Apr 07 07:59:46 dynaudio systemd[1]: Condition check resulted in Volumio SSH enabler being skipped. Apr 07 07:59:46 dynaudio systemd[1]: Started D-Bus System Message Bus. Apr 07 07:59:46 dynaudio systemd[1]: Starting WPA supplicant... Apr 07 07:59:46 dynaudio systemd[1]: Started volumio-remote-updater.service. Apr 07 07:59:46 dynaudio systemd[1]: Starting Avahi mDNS/DNS-SD Stack... Apr 07 07:59:46 dynaudio systemd[1]: Starting triggerhappy global hotkey daemon... Apr 07 07:59:46 dynaudio systemd[1]: Started Volumio Iptables Module. Apr 07 07:59:46 dynaudio systemd[1]: Starting Wireless Services... Apr 07 07:59:46 dynaudio systemd[1]: Starting Configure Bluetooth Modems connected by UART... Apr 07 07:59:46 dynaudio systemd[1]: Started Volumio Log Rotation Service. Apr 07 07:59:46 dynaudio systemd[1]: Started RPC bind portmap service. Apr 07 07:59:46 dynaudio systemd[1]: Reached target RPC Port Mapper. Apr 07 07:59:46 dynaudio systemd[1]: Reached target Remote File Systems (Pre). Apr 07 07:59:46 dynaudio systemd[1]: Reached target Remote File Systems. Apr 07 07:59:46 dynaudio systemd[1]: Starting LSB: Load kernel modules needed to enable cpufreq scaling... Apr 07 07:59:46 dynaudio dhcpcd[577]: Not running dhcpcd because /etc/network/interfaces Apr 07 07:59:46 dynaudio dhcpcd[577]: defines some interfaces that will use a Apr 07 07:59:46 dynaudio dhcpcd[577]: DHCP client or static address Apr 07 07:59:46 dynaudio thd[586]: Unable to parse trigger line: Apr 07 07:59:46 dynaudio thd[586]: Unable to parse trigger line: KEY_MIN_INTERESTING 1 /usr/local/bin/volumio volume toggle Apr 07 07:59:46 dynaudio thd[586]: Unable to parse trigger line: Apr 07 07:59:46 dynaudio thd[586]: Found socket passed from systemd Apr 07 07:59:46 dynaudio systemd-logind[571]: New seat seat0. Apr 07 07:59:46 dynaudio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Apr 07 07:59:46 dynaudio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Apr 07 07:59:46 dynaudio sh[520]: eth0: waiting for carrier Apr 07 07:59:46 dynaudio sh[520]: eth0: carrier acquired Apr 07 07:59:46 dynaudio dhcpcd[538]: eth0: waiting for carrier Apr 07 07:59:46 dynaudio dhcpcd[538]: eth0: carrier acquired Apr 07 07:59:46 dynaudio sh[520]: DUID 00:01:00:01:2e:7c:57:94:b8:27:eb:e2:7b:c9 Apr 07 07:59:46 dynaudio sh[520]: eth0: IAID eb:e2:7b:c9 Apr 07 07:59:46 dynaudio sh[520]: eth0: adding address fe80::ba27:ebff:fee2:7bc9 Apr 07 07:59:46 dynaudio sh[520]: ipv6_addaddr1: Permission denied Apr 07 07:59:46 dynaudio dhcpcd[538]: DUID 00:01:00:01:2e:7c:57:94:b8:27:eb:e2:7b:c9 Apr 07 07:59:46 dynaudio dhcpcd[538]: eth0: IAID eb:e2:7b:c9 Apr 07 07:59:46 dynaudio dhcpcd[538]: eth0: adding address fe80::ba27:ebff:fee2:7bc9 Apr 07 07:59:46 dynaudio dhcpcd[538]: ipv6_addaddr1: Permission denied Apr 07 07:59:46 dynaudio mpd_monitor.sh[561]: MPD error: Connection refused Apr 07 07:59:46 dynaudio mpd_monitor.sh[561]: MPD Monitor Service: MPD Appears to be inactive, restarting Apr 07 07:59:46 dynaudio sh[520]: eth0: soliciting an IPv6 router Apr 07 07:59:46 dynaudio dhcpcd[538]: eth0: soliciting an IPv6 router Apr 07 07:59:46 dynaudio sh[520]: eth0: soliciting a DHCP lease Apr 07 07:59:46 dynaudio dhcpcd[538]: eth0: soliciting a DHCP lease Apr 07 07:59:46 dynaudio mpd_monitor.sh[561]: mpd: no process found Apr 07 07:59:46 dynaudio avahi-daemon[584]: Found user 'avahi' (UID 110) and group 'avahi' (GID 113). Apr 07 07:59:46 dynaudio avahi-daemon[584]: Successfully dropped root privileges. Apr 07 07:59:46 dynaudio avahi-daemon[584]: avahi-daemon 0.7 starting up. Apr 07 07:59:47 dynaudio haveged[541]: haveged: ver: 1.9.1; arch: generic; vend: ; build: (gcc 8.2.0 CTV); collect: 128K Apr 07 07:59:47 dynaudio haveged[541]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 11/40; sz: 14768/63488 Apr 07 07:59:47 dynaudio haveged[541]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B): last entropy estimate 8.00035 Apr 07 07:59:47 dynaudio haveged[541]: haveged: fills: 0, generated: 0 Apr 07 07:59:47 dynaudio systemd[1]: Started Login Service. Apr 07 07:59:47 dynaudio avahi-daemon[584]: Successfully called chroot(). Apr 07 07:59:47 dynaudio systemd[1]: Started triggerhappy global hotkey daemon. Apr 07 07:59:47 dynaudio avahi-daemon[584]: Successfully dropped remaining capabilities. Apr 07 07:59:47 dynaudio wpa_supplicant[582]: Successfully initialized wpa_supplicant Apr 07 07:59:47 dynaudio systemd[1]: Started Helper to synchronize boot up for ifupdown. Apr 07 07:59:47 dynaudio systemd[1]: dhcpcd.service: Control process exited, code=exited, status=6/NOTCONFIGURED Apr 07 07:59:47 dynaudio systemd[1]: dhcpcd.service: Failed with result 'exit-code'. Apr 07 07:59:47 dynaudio systemd[1]: Failed to start dhcpcd on all interfaces. Apr 07 07:59:47 dynaudio systemd[1]: Started Save/Restore Sound Card State. Apr 07 07:59:47 dynaudio systemd[1]: hciuart.service: Succeeded. Apr 07 07:59:47 dynaudio systemd[1]: Started Configure Bluetooth Modems connected by UART. Apr 07 07:59:47 dynaudio avahi-daemon[584]: Loading service file /services/volumio.service. Apr 07 07:59:47 dynaudio avahi-daemon[584]: Network interface enumeration completed. Apr 07 07:59:47 dynaudio avahi-daemon[584]: Server startup complete. Host name is dynaudio.local. Local service cookie is 222384812. Apr 07 07:59:47 dynaudio avahi-daemon[584]: Service "Dynaudio" (/services/volumio.service) successfully established. Apr 07 07:59:47 dynaudio volumio-remote-updater[583]: Error: No active session Apr 07 07:59:47 dynaudio volumio-remote-updater[583]: [2025-04-07 07:59:47] [info] asio async_connect error: system:111 (Connection refused) Apr 07 07:59:47 dynaudio volumio-remote-updater[583]: [2025-04-07 07:59:47] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 07 07:59:47 dynaudio volumio-remote-updater[583]: [2025-04-07 07:59:47] [error] handle_connect error: Underlying Transport Error Apr 07 07:59:47 dynaudio sh[529]: wlan0=wlan0 Apr 07 07:59:47 dynaudio systemd[1]: Started Avahi mDNS/DNS-SD Stack. Apr 07 07:59:47 dynaudio systemd[1]: Started WPA supplicant. Apr 07 07:59:47 dynaudio volumio[569]: Could not open config: /tmp/upmpdcli.conf Apr 07 07:59:47 dynaudio volumio-time-update[564]: volumio-time-update-util: Date not found in response Apr 07 07:59:47 dynaudio volumio-time-update[564]: volumio-time-update-util: Retrying in 5 seconds... Apr 07 07:59:47 dynaudio systemd[1]: Listening on mpd.socket. Apr 07 07:59:47 dynaudio systemd[1]: Starting Raspberry Pi bluetooth helper... Apr 07 07:59:47 dynaudio systemd[1]: Reached target Sound Card. Apr 07 07:59:47 dynaudio systemd[1]: Starting Raise network interfaces... Apr 07 07:59:47 dynaudio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 07 07:59:47 dynaudio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 07 07:59:47 dynaudio bthelper[670]: Raspberry Pi BDADDR already set Apr 07 07:59:47 dynaudio systemd[1]: Started Raspberry Pi bluetooth helper. Apr 07 07:59:47 dynaudio systemd[1]: Starting Bluetooth service... Apr 07 07:59:47 dynaudio systemd[1]: iptables.service: Succeeded. Apr 07 07:59:47 dynaudio loadcpufreq[591]: Loading cpufreq kernel modules...done (none). Apr 07 07:59:47 dynaudio systemd[1]: Started LSB: Load kernel modules needed to enable cpufreq scaling. Apr 07 07:59:47 dynaudio systemd[1]: Starting LSB: set CPUFreq kernel parameters... Apr 07 07:59:47 dynaudio systemd[1]: Started Raise network interfaces. Apr 07 07:59:47 dynaudio systemd[1]: Reached target Network. Apr 07 07:59:47 dynaudio systemd[1]: Reached target Network is Online. Apr 07 07:59:47 dynaudio systemd[1]: Starting LSB: Brings up/down network automatically... Apr 07 07:59:47 dynaudio systemd[1]: Starting Permit User Sessions... Apr 07 07:59:47 dynaudio systemd[1]: Starting /etc/rc.local Compatibility... Apr 07 07:59:47 dynaudio systemd[1]: Starting Music Player Daemon... Apr 07 07:59:47 dynaudio bluetoothd[721]: Bluetooth daemon 5.50 Apr 07 07:59:47 dynaudio bluetoothd[721]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Apr 07 07:59:47 dynaudio cpufrequtils[731]: CPUFreq Utilities: Setting ondemand CPUFreq governor...CPU0...CPU1...CPU2...CPU3...done. Apr 07 07:59:47 dynaudio systemd[1]: Starting Samba NMB Daemon... Apr 07 07:59:47 dynaudio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 07 07:59:47 dynaudio systemd[1]: Starting Network Time Service... Apr 07 07:59:47 dynaudio systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped. Apr 07 07:59:47 dynaudio bluetoothd[721]: Starting SDP server Apr 07 07:59:47 dynaudio bluetoothd[721]: Excluding (cli) sap Apr 07 07:59:48 dynaudio systemd[1]: Started LSB: set CPUFreq kernel parameters. Apr 07 07:59:48 dynaudio systemd[1]: Started Permit User Sessions. Apr 07 07:59:48 dynaudio systemd[1]: Started /etc/rc.local Compatibility. Apr 07 07:59:48 dynaudio systemd[1]: Started Bluetooth service. Apr 07 07:59:48 dynaudio systemd[1]: Reached target Bluetooth. Apr 07 07:59:48 dynaudio systemd[1]: Starting Hold until boot process finishes up... Apr 07 07:59:48 dynaudio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Apr 07 07:59:48 dynaudio kernel: Bluetooth: BNEP filters: protocol multicast Apr 07 07:59:48 dynaudio kernel: Bluetooth: BNEP socket layer initialized Apr 07 07:59:48 dynaudio systemd[1]: Starting Terminate Plymouth Boot Screen... Apr 07 07:59:48 dynaudio bluetoothd[721]: Bluetooth management interface 1.22 initialized Apr 07 07:59:48 dynaudio dbus-daemon[581]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=0 pid=721 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Apr 07 07:59:48 dynaudio kernel: Bluetooth: MGMT ver 1.22 Apr 07 07:59:48 dynaudio systemd[1]: plymouth-quit-wait.service: Succeeded. Apr 07 07:59:48 dynaudio systemd[1]: Started Hold until boot process finishes up. Apr 07 07:59:48 dynaudio systemd[1]: plymouth-quit.service: Succeeded. Apr 07 07:59:48 dynaudio systemd[1]: Started Terminate Plymouth Boot Screen. Apr 07 07:59:48 dynaudio systemd[1]: Received SIGRTMIN+21 from PID 197 (n/a). Apr 07 07:59:48 dynaudio systemd[1]: Received SIGRTMIN+21 from PID 197 (n/a). Apr 07 07:59:48 dynaudio bluetoothd[721]: Failed to set privacy: Rejected (0x0b) Apr 07 07:59:48 dynaudio ifplugd(eth0)[805]: ifplugd 0.28 initializing. Apr 07 07:59:48 dynaudio ifplugd(eth0)[805]: Using interface eth0/B8:27:EB:E2:7B:C9 with driver (version: 6.6.62-v7+) Apr 07 07:59:48 dynaudio ifplugd(eth0)[805]: Using detection mode: SIOCETHTOOL Apr 07 07:59:48 dynaudio ifplugd(eth0)[805]: Initialization complete, link beat detected. Apr 07 07:59:48 dynaudio ifplugd(eth0)[805]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 07 07:59:48 dynaudio ntpd[787]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 07 07:59:48 dynaudio ntpd[787]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 103:103 Apr 07 07:59:48 dynaudio ntpd[808]: proto: precision = 0.729 usec (-20) Apr 07 07:59:48 dynaudio ntpd[808]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 07 07:59:48 dynaudio ntpd[808]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2024-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 07 07:59:48 dynaudio ntpd[808]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 101 days ago Apr 07 07:59:48 dynaudio ntpd[808]: Listen and drop on 0 v6wildcard [::]:123 Apr 07 07:59:48 dynaudio ntpd[808]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 07 07:59:48 dynaudio ntpd[808]: Listen normally on 2 lo 127.0.0.1:123 Apr 07 07:59:48 dynaudio ntpd[808]: Listening on routing socket on fd #19 for interface updates Apr 07 07:59:48 dynaudio ntpd[808]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 07 07:59:48 dynaudio ntpd[808]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 07 07:59:48 dynaudio ifplugd(eth0)[805]: client: ifup: waiting for lock on /run/network/ifstate.eth0 Apr 07 07:59:48 dynaudio systemd[1]: Started Network Time Service. Apr 07 07:59:48 dynaudio systemd[1]: Starting Hostname Service... Apr 07 07:59:48 dynaudio systemd[1]: Started Getty on tty1. Apr 07 07:59:48 dynaudio systemd[1]: Reached target Login Prompts. Apr 07 07:59:48 dynaudio sudo[776]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 07 07:59:48 dynaudio sudo[776]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 07:59:48 dynaudio sudo[776]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Apr 07 07:59:48 dynaudio sudo[776]: pam_unix(sudo:session): session closed for user root Apr 07 07:59:48 dynaudio dbus-daemon[581]: [system] Successfully activated service 'org.freedesktop.hostname1' Apr 07 07:59:48 dynaudio systemd[1]: Started Hostname Service. Apr 07 07:59:49 dynaudio ntpd[808]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Apr 07 07:59:49 dynaudio nmbd[789]: [2025/04/07 07:59:49.554551, 0] ../source3/nmbd/asyncdns.c:158(start_async_dns) Apr 07 07:59:49 dynaudio nmbd[789]: started asyncdns process 821 Apr 07 07:59:49 dynaudio nmbd[789]: [2025/04/07 07:59:49.556949, 0] ../lib/util/become_daemon.c:149(daemon_status) Apr 07 07:59:49 dynaudio nmbd[789]: daemon_status: STATUS=daemon 'nmbd' : No local IPv4 non-loopback interfaces available, waiting for interface ... Apr 07 07:59:49 dynaudio nmbd[789]: [2025/04/07 07:59:49.557139, 0] ../source3/nmbd/nmbd_subnetdb.c:254(create_subnets) Apr 07 07:59:49 dynaudio nmbd[789]: NOTE: NetBIOS name resolution is not supported for Internet Protocol Version 6 (IPv6). Apr 07 07:59:49 dynaudio wireless.js[588]: Cleaning previous... Apr 07 07:59:49 dynaudio sh[520]: eth0: offered 192.168.1.213 from 192.168.1.1 `ecosystem.home.cisco.com' Apr 07 07:59:49 dynaudio sh[520]: eth0: ignoring offer of 192.168.1.213 from 192.168.1.1 `ecosystem.home.cisco.com' Apr 07 07:59:49 dynaudio dhcpcd[538]: eth0: offered 192.168.1.213 from 192.168.1.1 `ecosystem.home.cisco.com' Apr 07 07:59:49 dynaudio dhcpcd[538]: eth0: ignoring offer of 192.168.1.213 from 192.168.1.1 `ecosystem.home.cisco.com' Apr 07 07:59:49 dynaudio sh[520]: eth0: probing address 192.168.1.213/24 Apr 07 07:59:49 dynaudio dhcpcd[538]: eth0: probing address 192.168.1.213/24 Apr 07 07:59:50 dynaudio sudo[829]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 07 07:59:50 dynaudio sudo[829]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 07:59:50 dynaudio sudo[829]: pam_unix(sudo:session): session closed for user root Apr 07 07:59:50 dynaudio sudo[831]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 07 07:59:50 dynaudio sudo[831]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 07:59:50 dynaudio ntpd[808]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Apr 07 07:59:50 dynaudio sudo[831]: pam_unix(sudo:session): session closed for user root Apr 07 07:59:50 dynaudio systemd[1]: systemd-rfkill.service: Succeeded. Apr 07 07:59:50 dynaudio wireless.js[588]: Stopped aP Apr 07 07:59:50 dynaudio wireless.js[588]: Wireless Networking DISABLED, not starting wireless flow Apr 07 07:59:50 dynaudio systemd[1]: wireless.service: Succeeded. Apr 07 07:59:50 dynaudio systemd[1]: Started Wireless Services. Apr 07 07:59:50 dynaudio systemd[1]: Started Volumio Backend Module. Apr 07 07:59:50 dynaudio systemd[1]: Started Volumio Cpu Tweaker. Apr 07 07:59:50 dynaudio volumio-cpu-tweak[840]: Setting RT Priority for mpd Apr 07 07:59:50 dynaudio volumio-cpu-tweak[840]: Setting MPD Affinity Apr 07 07:59:51 dynaudio volumio-cpu-tweak[840]: pid 819's current affinity mask: f Apr 07 07:59:51 dynaudio volumio-cpu-tweak[840]: pid 819's new affinity mask: 3 Apr 07 07:59:51 dynaudio volumio-cpu-tweak[840]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Apr 07 07:59:51 dynaudio volumio-cpu-tweak[840]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Apr 07 07:59:51 dynaudio systemd[1]: volumio_cpu_tweak.service: Succeeded. Apr 07 07:59:51 dynaudio ntpd[808]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Apr 07 07:59:52 dynaudio volumio-remote-updater[583]: [2025-04-07 07:59:52] [info] asio async_connect error: system:111 (Connection refused) Apr 07 07:59:52 dynaudio volumio-remote-updater[583]: [2025-04-07 07:59:52] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 07 07:59:52 dynaudio volumio-remote-updater[583]: [2025-04-07 07:59:52] [error] handle_connect error: Underlying Transport Error Apr 07 07:59:52 dynaudio ntpd[808]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Apr 07 07:59:52 dynaudio volumio-time-update[564]: volumio-time-update-util: Fetching time from Volumio... Apr 07 07:59:52 dynaudio volumio-time-update[564]: volumio-time-update-util: Date not found in response Apr 07 07:59:52 dynaudio volumio-time-update[564]: volumio-time-update-util: Retrying in 5 seconds... Apr 07 07:59:52 dynaudio mpd[819]: Apr 07 07:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 07 07:59:52 dynaudio systemd[1]: Started Music Player Daemon. Apr 07 07:59:52 dynaudio mpd_monitor.sh[561]: MPD Monitor Service: MPD restarted due to no mpc output. Apr 07 07:59:52 dynaudio bthelper[670]: Changing power off succeeded Apr 07 07:59:52 dynaudio bthelper[670]: [CHG] Controller B8:27:EB:48:D1:63 Class: 0x0000041c Apr 07 07:59:52 dynaudio bthelper[670]: Changing power on succeeded Apr 07 07:59:53 dynaudio volumio[839]: info: ------------------------------------------- Apr 07 07:59:53 dynaudio volumio[839]: info: ----- Volumio3 ---- Apr 07 07:59:53 dynaudio volumio[839]: info: ------------------------------------------- Apr 07 07:59:53 dynaudio volumio[839]: info: ----- System startup ---- Apr 07 07:59:54 dynaudio volumio[839]: info: ------------------------------------------- Apr 07 07:59:55 dynaudio volumio[839]: info: MYVOLUMIO Environment detected Apr 07 07:59:55 dynaudio sh[520]: eth0: leased 192.168.1.213 for 86400 seconds Apr 07 07:59:55 dynaudio sh[520]: eth0: adding route to 192.168.1.0/24 Apr 07 07:59:55 dynaudio sh[520]: eth0: adding default route via 192.168.1.1 Apr 07 07:59:55 dynaudio dhcpcd[538]: eth0: leased 192.168.1.213 for 86400 seconds Apr 07 07:59:55 dynaudio avahi-daemon[584]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.213. Apr 07 07:59:55 dynaudio avahi-daemon[584]: New relevant interface eth0.IPv4 for mDNS. Apr 07 07:59:55 dynaudio dhcpcd[538]: eth0: adding route to 192.168.1.0/24 Apr 07 07:59:55 dynaudio dhcpcd[538]: eth0: adding default route via 192.168.1.1 Apr 07 07:59:55 dynaudio avahi-daemon[584]: Registering new address record for 192.168.1.213 on eth0.IPv4. Apr 07 07:59:55 dynaudio sh[520]: forked to background, child pid 900 Apr 07 07:59:55 dynaudio dhcpcd[538]: forked to background, child pid 900 Apr 07 07:59:55 dynaudio volumio[839]: info: Plugin folders cleanup Apr 07 07:59:55 dynaudio volumio[839]: info: Scanning into folder /volumio/app/plugins/ Apr 07 07:59:55 dynaudio volumio[839]: info: Scanning category audio_interface Apr 07 07:59:55 dynaudio volumio[839]: info: Scanning category miscellanea Apr 07 07:59:55 dynaudio volumio[839]: info: Scanning category music_service Apr 07 07:59:55 dynaudio volumio[839]: info: Scanning category plugins.json Apr 07 07:59:55 dynaudio volumio[839]: info: Scanning category system_controller Apr 07 07:59:55 dynaudio volumio[839]: info: Scanning category user_interface Apr 07 07:59:55 dynaudio volumio[839]: info: Scanning into folder /data/plugins/ Apr 07 07:59:55 dynaudio volumio[839]: info: Scanning category music_service Apr 07 07:59:55 dynaudio volumio[839]: info: Plugin folders cleanup completed Apr 07 07:59:55 dynaudio volumio[839]: info: ------------------------------------------- Apr 07 07:59:55 dynaudio volumio[839]: info: ----- Core plugins startup ---- Apr 07 07:59:55 dynaudio volumio[839]: info: ------------------------------------------- Apr 07 07:59:55 dynaudio volumio[839]: info: Loading plugins from folder /volumio/app/plugins/ Apr 07 07:59:55 dynaudio volumio[839]: info: Adding plugin upnp to MyMusic Plugins Apr 07 07:59:55 dynaudio volumio[839]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 07 07:59:55 dynaudio volumio[839]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 07 07:59:55 dynaudio volumio[839]: info: Loading plugins from folder /data/plugins/ Apr 07 07:59:55 dynaudio volumio[839]: info: Loading plugin "system"... Apr 07 07:59:55 dynaudio volumio[839]: info: Loading plugin "appearance"... Apr 07 07:59:55 dynaudio ifplugd(eth0)[805]: client: ifup: interface eth0 already configured Apr 07 07:59:55 dynaudio sh[520]: eth0=eth0 Apr 07 07:59:55 dynaudio ifplugd(eth0)[805]: Program executed successfully. Apr 07 07:59:55 dynaudio ifplugd[770]: Network Interface Plugging Daemon...start eth0...done. Apr 07 07:59:55 dynaudio systemd[1]: Started LSB: Brings up/down network automatically. Apr 07 07:59:56 dynaudio ntpd[808]: Listen normally on 3 eth0 192.168.1.213:123 Apr 07 07:59:56 dynaudio ntpd[808]: new interface(s) found: waking up resolver Apr 07 07:59:57 dynaudio volumio[839]: info: Loading plugin "network"... Apr 07 07:59:57 dynaudio volumio[839]: info: Refreshing Cached IP Addresses Apr 07 07:59:57 dynaudio sudo[930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 07 07:59:57 dynaudio sudo[930]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 07:59:57 dynaudio sudo[930]: pam_unix(sudo:session): session closed for user root Apr 07 07:59:57 dynaudio sudo[932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 07 07:59:57 dynaudio sudo[932]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 07:59:57 dynaudio volumio[839]: info: Loading plugin "services"... Apr 07 07:59:57 dynaudio volumio[839]: info: Loading plugin "alsa_controller"... Apr 07 07:59:57 dynaudio sudo[932]: pam_unix(sudo:session): session closed for user root Apr 07 07:59:57 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 07:59:57 dynaudio volumio[839]: info: Loading plugin "wizard"... Apr 07 07:59:57 dynaudio volumio[839]: info: Loading plugin "networkfs"... Apr 07 07:59:57 dynaudio volumio[839]: info: Starting Udev Watcher for removable devices Apr 07 07:59:57 dynaudio volumio[839]: info: Ignoring mount for partition: boot Apr 07 07:59:57 dynaudio volumio[839]: info: Ignoring mount for partition: volumio Apr 07 07:59:57 dynaudio volumio[839]: info: Ignoring mount for partition: volumio_data Apr 07 07:59:57 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 07:59:57 dynaudio volumio[839]: info: Loading plugin "volumio_command_line_client"... Apr 07 07:59:57 dynaudio volumio[839]: info: Loading plugin "upnp"... Apr 07 07:59:57 dynaudio volumio[839]: info: [1744009197435] Starting Upmpd Daemon Apr 07 07:59:57 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 07:59:57 dynaudio volumio[839]: info: Loading plugin "my_music"... Apr 07 07:59:57 dynaudio volumio[839]: info: Loading plugin "mpd"... Apr 07 07:59:57 dynaudio volumio-time-update[564]: volumio-time-update-util: Fetching time from Volumio... Apr 07 07:59:57 dynaudio volumio-time-update[564]: volumio-time-update-util: Setting system time to: 2025-04-07 08:00:19 Apr 07 07:59:57 dynaudio sudo[957]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-04-07 08:00:19 Apr 07 07:59:57 dynaudio sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 07:59:57 dynaudio dbus-daemon[581]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.9' (uid=0 pid=958 comm="timedatectl set-time 2025-04-07 08:00:19 ") Apr 07 07:59:57 dynaudio systemd[1]: Starting Time & Date Service... Apr 07 07:59:57 dynaudio dbus-daemon[581]: [system] Successfully activated service 'org.freedesktop.timedate1' Apr 07 07:59:57 dynaudio systemd[1]: Started Time & Date Service. Apr 07 08:00:19 dynaudio systemd-timedated[959]: Changed local time to Mon Apr 7 08:00:19 2025 Apr 07 08:00:19 dynaudio sudo[957]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:19 dynaudio volumio-time-update[564]: volumio-time-update-util: System time updated successfully. Apr 07 08:00:19 dynaudio systemd[1]: Started Volumio Time Update Utility. Apr 07 08:00:19 dynaudio volumio[839]: info: Loading plugin "upnp_browser"... Apr 07 08:00:19 dynaudio volumio[839]: info: Loading plugin "alarm-clock"... Apr 07 08:00:19 dynaudio volumio[839]: info: Loading plugin "airplay_emulation"... Apr 07 08:00:19 dynaudio volumio[839]: info: Starting Shairport Sync Apr 07 08:00:19 dynaudio volumio[839]: info: Loading plugin "last_100"... Apr 07 08:00:19 dynaudio volumio[839]: info: Loading plugin "webradio"... Apr 07 08:00:19 dynaudio volumio[839]: info: Loading plugin "i2s_dacs"... Apr 07 08:00:20 dynaudio volumio[839]: info: Loading plugin "volumiodiscovery"... Apr 07 08:00:20 dynaudio volumio[839]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 07 08:00:20 dynaudio volumio[839]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 08:00:20 dynaudio volumio[839]: *** WARNING *** For more information see Apr 07 08:00:20 dynaudio volumio[839]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 07 08:00:20 dynaudio node[839]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 07 08:00:20 dynaudio volumio[839]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 08:00:20 dynaudio volumio[839]: *** WARNING *** For more information see Apr 07 08:00:20 dynaudio node[839]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 08:00:20 dynaudio node[839]: *** WARNING *** For more information see Apr 07 08:00:20 dynaudio node[839]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 07 08:00:20 dynaudio node[839]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 07 08:00:20 dynaudio node[839]: *** WARNING *** For more information see Apr 07 08:00:20 dynaudio volumio[839]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 07 08:00:20 dynaudio volumio[839]: info: Discovery: Started advertising with name: Dynaudio Apr 07 08:00:20 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 08:00:20 dynaudio volumio[839]: info: Loading plugin "outputs"... Apr 07 08:00:20 dynaudio volumio[839]: info: Loading plugin "albumart"... Apr 07 08:00:20 dynaudio volumio[839]: info: Plugin example_plugin is not enabled Apr 07 08:00:20 dynaudio volumio[839]: info: Loading plugin "inputs"... Apr 07 08:00:20 dynaudio volumio[839]: info: Loading plugin "updater_comm"... Apr 07 08:00:20 dynaudio volumio[839]: info: Plugin mpdemulation is not enabled Apr 07 08:00:20 dynaudio volumio[839]: info: Loading plugin "rest_api"... Apr 07 08:00:20 dynaudio volumio[839]: info: Loading plugin "websocket"... Apr 07 08:00:20 dynaudio volumio[839]: info: Starting Socket.io Server version 2.3.0 Apr 07 08:00:20 dynaudio volumio[839]: info: Loading plugin "radio_paradise"... Apr 07 08:00:20 dynaudio volumio[839]: Forking 3 albumart workers Apr 07 08:00:20 dynaudio nmbd[789]: [2025/04/07 08:00:20.702751, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 07 08:00:20 dynaudio nmbd[789]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 07 08:00:20 dynaudio systemd[1]: Started Samba NMB Daemon. Apr 07 08:00:20 dynaudio systemd[1]: Starting Samba Winbind Daemon... Apr 07 08:00:20 dynaudio volumio-remote-updater[583]: [2025-04-07 08:00:20] [connect] Successful connection Apr 07 08:00:21 dynaudio volumio[839]: info: Applying required configuration parameters for plugin radio_paradise Apr 07 08:00:21 dynaudio volumio[839]: info: [1744009221099] [RadioParadise] API delay: 5 Apr 07 08:00:21 dynaudio volumio[839]: info: Loading i18n strings for locale en Apr 07 08:00:21 dynaudio volumio[839]: Updating browse sources language Apr 07 08:00:21 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 08:00:21 dynaudio winbindd[990]: [2025/04/07 08:00:21.181986, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 07 08:00:21 dynaudio winbindd[990]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 07 08:00:21 dynaudio winbindd[990]: [2025/04/07 08:00:21.219531, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 07 08:00:21 dynaudio winbindd[990]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 07 08:00:21 dynaudio systemd[1]: Started Samba Winbind Daemon. Apr 07 08:00:21 dynaudio systemd[1]: Starting Samba SMB Daemon... Apr 07 08:00:21 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 08:00:21 dynaudio volumio[839]: info: CoreCommandRouter::initPlayerControls Apr 07 08:00:21 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:21 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:21 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:21 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:21 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:21 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:21 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:21 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:21 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 08:00:21 dynaudio volumio[839]: Express server listening on port 3000 Apr 07 08:00:21 dynaudio volumio[839]: [Metrics] WebUI: 7s 518.31ms Apr 07 08:00:21 dynaudio volumio[839]: info: CoreStateMachine::resetVolumioState Apr 07 08:00:21 dynaudio volumio[839]: info: CoreStateMachine::getcurrentVolume Apr 07 08:00:21 dynaudio volumio[839]: info: CoreCommandRouter::volumioRetrievevolume Apr 07 08:00:21 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:00:21 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:21 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:00:21 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:00:21 dynaudio volumio[839]: info: Volumio Network Manager: Network status updated: 1 Apr 07 08:00:21 dynaudio volumio-remote-updater[583]: [2025-04-07 08:00:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744009220 101 Apr 07 08:00:21 dynaudio volumio[839]: Starting albumart workers Apr 07 08:00:21 dynaudio volumio[839]: 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: 1 Apr 07 08:00:22 dynaudio smbd[995]: [2025/04/07 08:00:22.020144, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 07 08:00:22 dynaudio smbd[995]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 07 08:00:22 dynaudio systemd[1]: Started Samba SMB Daemon. Apr 07 08:00:22 dynaudio systemd[1]: Reached target Multi-User System. Apr 07 08:00:22 dynaudio systemd[1]: Reached target Graphical Interface. Apr 07 08:00:22 dynaudio volumio[839]: Starting albumart workers Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:22 dynaudio volumio[839]: info: Reloading queue from file Apr 07 08:00:22 dynaudio volumio[839]: info: CoreStateMachine::setRepeat false single undefined Apr 07 08:00:22 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:00:22 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:00:22 dynaudio volumio[839]: info: CoreStateMachine::setRandom false Apr 07 08:00:22 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:00:22 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:00:22 dynaudio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 07 08:00:22 dynaudio volumio[839]: info: Setting Device type: Raspberry PI Apr 07 08:00:22 dynaudio volumio[839]: info: Completed loading Core Plugins Apr 07 08:00:22 dynaudio volumio[839]: info: Preparing to generate the ALSA configuration file Apr 07 08:00:22 dynaudio volumio[839]: info: Asound.conf file written Apr 07 08:00:22 dynaudio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 07 08:00:22 dynaudio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 07 08:00:22 dynaudio systemd[1]: Startup finished in 13.964s (kernel) + 19.416s (userspace) = 33.381s. Apr 07 08:00:22 dynaudio sudo[1013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 07 08:00:22 dynaudio sudo[1013]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:22 dynaudio volumio[839]: Starting albumart workers Apr 07 08:00:22 dynaudio sudo[1013]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:22 dynaudio volumio[839]: info: Output device has changed, restarting MPD Apr 07 08:00:22 dynaudio sudo[1018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 08:00:22 dynaudio sudo[1018]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:22 dynaudio volumio[839]: info: Output device has changed, restarting Shairport Sync Apr 07 08:00:22 dynaudio sudo[1018]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:22 dynaudio sudo[1020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 08:00:22 dynaudio sudo[1020]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:22 dynaudio volumio[839]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 08:00:22 dynaudio volumio[839]: info: ___________ START PLUGINS ___________ Apr 07 08:00:22 dynaudio systemd[1]: Stopping Music Player Daemon... Apr 07 08:00:22 dynaudio volumio[839]: info: ControllerMpd::onStart: Initializing MPD Apr 07 08:00:22 dynaudio volumio[839]: info: Creating MPD Configuration file Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 08:00:22 dynaudio volumio[839]: info: [1744009222463] CoreMusicLibrary::Adding element Media Servers Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 08:00:22 dynaudio sudo[1027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 08:00:22 dynaudio sudo[1027]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:22 dynaudio sudo[1029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 08:00:22 dynaudio sudo[1029]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:22 dynaudio sudo[1027]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:22 dynaudio systemd[1]: mpd.service: Succeeded. Apr 07 08:00:22 dynaudio systemd[1]: Stopped Music Player Daemon. Apr 07 08:00:22 dynaudio volumio[839]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:22 dynaudio systemd[1]: Starting Music Player Daemon... Apr 07 08:00:22 dynaudio volumio[839]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 08:00:22 dynaudio volumio[839]: info: [1744009222731] CoreMusicLibrary::Adding element Last_100 Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 08:00:22 dynaudio volumio[839]: info: [1744009222748] CoreMusicLibrary::Adding element Webradio Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 08:00:22 dynaudio volumio[839]: info: Initializing BBC Radios Apr 07 08:00:22 dynaudio sudo[1035]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 07 08:00:22 dynaudio sudo[1035]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:22 dynaudio sudo[1035]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 08:00:22 dynaudio volumio[839]: info: [1744009222943] CoreMusicLibrary::Adding element Radio Paradise Apr 07 08:00:22 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 08:00:22 dynaudio volumio[839]: Cannot find translation for source Radio Paradise Apr 07 08:00:22 dynaudio volumio[839]: info: Volumio Calling Home Apr 07 08:00:23 dynaudio volumio[839]: info: Discovery: adding 3ed9d9c5-1436-4e68-aff6-e23a507152cd Apr 07 08:00:23 dynaudio volumio[839]: info: Discovery: Found device Dynaudio Apr 07 08:00:23 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:00:23 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:23 dynaudio volumio[839]: info: MPD Permissions set Apr 07 08:00:23 dynaudio volumio[839]: info: MPD Permissions set Apr 07 08:00:23 dynaudio volumio[839]: info: Volumio called home Apr 07 08:00:23 dynaudio volumio[839]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 07 08:00:23 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:23 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:23 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:23 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:23 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:23 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:23 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:23 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:23 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:23 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:23 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:23 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:23 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:23 dynaudio volumio[839]: info: Starting Shairport Sync Apr 07 08:00:23 dynaudio volumio[839]: info: Starting Shairport Sync Apr 07 08:00:23 dynaudio volumio[839]: info: Starting Shairport Sync Apr 07 08:00:23 dynaudio sudo[1087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 07 08:00:23 dynaudio sudo[1087]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:23 dynaudio sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 07 08:00:23 dynaudio sudo[1092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 07 08:00:23 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:00:23 dynaudio sudo[1092]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:23 dynaudio sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:23 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:23 dynaudio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 07 08:00:23 dynaudio systemd[1]: shairport-sync.service: Succeeded. Apr 07 08:00:23 dynaudio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 07 08:00:23 dynaudio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 07 08:00:23 dynaudio sudo[1087]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:23 dynaudio volumio[839]: info: Shairport-Sync Started Apr 07 08:00:23 dynaudio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 07 08:00:23 dynaudio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 07 08:00:23 dynaudio systemd[1]: shairport-sync.service: Succeeded. Apr 07 08:00:23 dynaudio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 07 08:00:23 dynaudio volumio[839]: Error adding Membership: Error: addMembership EINVAL Apr 07 08:00:23 dynaudio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 07 08:00:23 dynaudio sudo[1089]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:23 dynaudio volumio[839]: info: Shairport-Sync Started Apr 07 08:00:23 dynaudio sudo[1092]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:23 dynaudio volumio[839]: info: Shairport-Sync Started Apr 07 08:00:24 dynaudio mpd[1042]: Apr 07 08:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 07 08:00:24 dynaudio systemd[1]: Started Music Player Daemon. Apr 07 08:00:24 dynaudio sudo[1029]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:24 dynaudio sudo[1020]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:24 dynaudio volumio[839]: info: Completed starting Core Plugins Apr 07 08:00:24 dynaudio volumio[839]: info: ------------------------------------------- Apr 07 08:00:24 dynaudio volumio[839]: info: ----- MyVolumio plugins startup ---- Apr 07 08:00:24 dynaudio volumio[839]: info: ------------------------------------------- Apr 07 08:00:24 dynaudio volumio[839]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 07 08:00:24 dynaudio volumio[839]: error: MPD error: The expression evaluated to a falsy value: Apr 07 08:00:24 dynaudio volumio[839]: assert.ok(self.idling) Apr 07 08:00:24 dynaudio volumio[839]: error: The expression evaluated to a falsy value: Apr 07 08:00:24 dynaudio volumio[839]: assert.ok(self.idling) Apr 07 08:00:24 dynaudio volumio[839]: info: MPD running with PID1042 Apr 07 08:00:24 dynaudio volumio[839]: ,establishing connection Apr 07 08:00:24 dynaudio volumio[839]: error: MPD error: The expression evaluated to a falsy value: Apr 07 08:00:24 dynaudio volumio[839]: assert.ok(self.idling) Apr 07 08:00:24 dynaudio volumio[839]: error: The expression evaluated to a falsy value: Apr 07 08:00:24 dynaudio volumio[839]: assert.ok(self.idling) Apr 07 08:00:24 dynaudio volumio[839]: error: updateQueue error: null Apr 07 08:00:28 dynaudio sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 07 08:00:28 dynaudio sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:28 dynaudio sudo[1119]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:28 dynaudio sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 07 08:00:28 dynaudio sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:28 dynaudio sudo[1121]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:28 dynaudio sudo[1125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 07 08:00:28 dynaudio sudo[1125]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:28 dynaudio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 07 08:00:28 dynaudio sudo[1125]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:28 dynaudio volumio[839]: info: Upmpdcli Daemon Started Apr 07 08:00:28 dynaudio volumio[1127]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 07 08:00:29 dynaudio volumio[839]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 07 08:00:33 dynaudio volumio[839]: info: Adding plugin bluetooth to MyMusic Plugins Apr 07 08:00:33 dynaudio volumio[839]: info: Adding plugin multiroom to MyMusic Plugins Apr 07 08:00:33 dynaudio volumio[839]: info: Adding plugin metavolumio to MyMusic Plugins Apr 07 08:00:33 dynaudio volumio[839]: info: Adding plugin cd_controller to MyMusic Plugins Apr 07 08:00:33 dynaudio volumio[839]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 07 08:00:33 dynaudio volumio[839]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 07 08:00:33 dynaudio volumio[839]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 07 08:00:34 dynaudio volumio[839]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 07 08:00:34 dynaudio volumio[839]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 07 08:00:34 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:34 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:34 dynaudio volumio[839]: info: Starting MyVolumio Remote Streaming Endpoints Apr 07 08:00:34 dynaudio volumio[839]: info: MyVolumio login type: Token Apr 07 08:00:34 dynaudio volumio[839]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 07 08:00:34 dynaudio volumio[839]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 07 08:00:35 dynaudio volumio[839]: info: Starting Streaming Service Transparent Proxy Apr 07 08:00:35 dynaudio volumio[839]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 07 08:00:35 dynaudio volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 07 08:00:35 dynaudio volumio[839]: info: Streaming services startup Apr 07 08:00:35 dynaudio volumio[839]: info: Starting Streaming Daemon Apr 07 08:00:36 dynaudio sudo[1153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 07 08:00:36 dynaudio sudo[1153]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:36 dynaudio systemd[1]: systemd-fsckd.service: Succeeded. Apr 07 08:00:36 dynaudio sudo[1153]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:36 dynaudio volumio[839]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 07 08:00:36 dynaudio volumio[839]: error: Cannot start Volumio Streaming Daemon Apr 07 08:00:36 dynaudio volumio[839]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 07 08:00:36 dynaudio volumio[839]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 07 08:00:36 dynaudio volumio[839]: error: MyVolumio Custom Token format not valid, refreshing it Apr 07 08:00:36 dynaudio volumio[839]: info: MyVolumio login type: Token Apr 07 08:00:37 dynaudio volumio[839]: STREAMING PROXY: Starting server on port 3245 Apr 07 08:00:37 dynaudio volumio[839]: Node JS runtime: 14 Apr 07 08:00:37 dynaudio volumio[839]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 07 08:00:38 dynaudio volumio[839]: info: MyVolumio token set successfully Apr 07 08:00:38 dynaudio volumio[839]: info: MYVOLUMIO: Adding device Apr 07 08:00:38 dynaudio volumio[839]: info: MYVOLUMIO: Evaluating Server Apr 07 08:00:38 dynaudio volumio[839]: info: MyVolumio Plan changed: premium Apr 07 08:00:38 dynaudio volumio[839]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 07 08:00:38 dynaudio volumio[839]: info: Removing browser output: myVolumio user plan is not superstar Apr 07 08:00:38 dynaudio volumio[839]: info: Removing audio output: Apr 07 08:00:38 dynaudio volumio[839]: info: MYVOLUMIO: Adding device Apr 07 08:00:38 dynaudio volumio[839]: info: MYVOLUMIO: Evaluating Server Apr 07 08:00:38 dynaudio volumio[839]: info: Remote config written successfully Apr 07 08:00:38 dynaudio volumio[839]: info: Starting Tunnel 1 Apr 07 08:00:38 dynaudio volumio[839]: info: Starting Tunnel Connection Checker Apr 07 08:00:38 dynaudio volumio[839]: info: Completed starting MyVolumio Plugin Apr 07 08:00:39 dynaudio volumio[839]: info: MYVolumio Device enabled Apr 07 08:00:39 dynaudio volumio[839]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 07 08:00:39 dynaudio volumio[839]: info: MyVolumio status changed Apr 07 08:00:39 dynaudio volumio[839]: info: Streaming services startup Apr 07 08:00:39 dynaudio volumio[839]: info: Starting Streaming Daemon Apr 07 08:00:39 dynaudio sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 07 08:00:39 dynaudio sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:39 dynaudio volumio[839]: info: Setting Geolocation for MyVolumio to eu8 Apr 07 08:00:39 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:39 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:39 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:39 dynaudio sudo[1207]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:39 dynaudio volumio[839]: error: Cannot start Volumio Streaming Daemon Apr 07 08:00:39 dynaudio volumio[839]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 07 08:00:39 dynaudio volumio[839]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 07 08:00:39 dynaudio volumio[839]: verbose: New Socket.io Connection to 192.168.1.213 from 192.168.1.50 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 3 Apr 07 08:00:39 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 07 08:00:39 dynaudio volumio[839]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 07 08:00:39 dynaudio volumio[839]: info: [1744009239379] Starting BluetoothController Apr 07 08:00:39 dynaudio volumio[839]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 07 08:00:39 dynaudio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 07 08:00:41 dynaudio volumio[839]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Apr 07 08:00:41 dynaudio sudo[1214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 07 08:00:41 dynaudio sudo[1214]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:41 dynaudio sudo[1214]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:41 dynaudio volumio[839]: info: MRS: MultiRoom plugin initialized Apr 07 08:00:41 dynaudio volumio[839]: info: MRS: STOPPING SNAPCLIENT Apr 07 08:00:41 dynaudio volumio[839]: info: MRS: Snap server stop Apr 07 08:00:41 dynaudio sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 07 08:00:41 dynaudio volumio[839]: info: MRS: STOPPING volumioStreaming Apr 07 08:00:41 dynaudio sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:41 dynaudio sudo[1232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 07 08:00:41 dynaudio sudo[1232]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:41 dynaudio sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 07 08:00:41 dynaudio sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:41 dynaudio sudo[1230]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:41 dynaudio volumio[839]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 07 08:00:41 dynaudio sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 07 08:00:41 dynaudio sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:41 dynaudio sudo[1239]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:41 dynaudio sudo[1236]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:41 dynaudio sudo[1232]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:41 dynaudio volumio[839]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 07 08:00:41 dynaudio volumio[839]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 07 08:00:41 dynaudio volumio[839]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 07 08:00:41 dynaudio volumio[839]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 07 08:00:41 dynaudio volumio[839]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Apr 07 08:00:41 dynaudio volumio[839]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 07 08:00:41 dynaudio volumio[839]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 07 08:00:41 dynaudio volumio[839]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 07 08:00:41 dynaudio volumio[839]: info: Preparing to generate the ALSA configuration file Apr 07 08:00:41 dynaudio volumio[839]: info: Updating MyVolumio device info Apr 07 08:00:41 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:41 dynaudio volumio[839]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 07 08:00:41 dynaudio volumio[839]: info: Reading ALSA contributions from plugins. Apr 07 08:00:41 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 07 08:00:41 dynaudio volumio[839]: info: Setting Geolocation for MyVolumio to eu3 Apr 07 08:00:41 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:41 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:41 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:41 dynaudio volumio[839]: info: MRS: Removed streaming files Apr 07 08:00:41 dynaudio volumio[839]: info: MRS: volumioStreaming STOPPED Apr 07 08:00:41 dynaudio volumio[839]: info: MRS: SNAPSERVER STOPPED Apr 07 08:00:42 dynaudio volumio[839]: info: MRS: SNAPCLIENT STOPPED Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetVisibleSources Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:00:42 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 07 08:00:42 dynaudio volumio[839]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 07 08:00:42 dynaudio volumio[839]: info: Received Get System Info Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 07 08:00:42 dynaudio volumio[839]: info: Discovery: Getting this device information Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:00:42 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:00:42 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:42 dynaudio volumio[839]: info: Listing playlists Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 07 08:00:42 dynaudio volumio[839]: info: Discovery: Getting this device information Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:00:42 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 07 08:00:42 dynaudio volumio[839]: info: Asound.conf file written Apr 07 08:00:42 dynaudio sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 07 08:00:42 dynaudio sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:42 dynaudio sudo[1242]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:42 dynaudio volumio[839]: info: Output device has changed, restarting MPD Apr 07 08:00:42 dynaudio sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 07 08:00:42 dynaudio volumio[839]: info: Output device has changed, restarting Shairport Sync Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:42 dynaudio sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:42 dynaudio sudo[1247]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:42 dynaudio sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 07 08:00:42 dynaudio sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:42 dynaudio volumio[839]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 07 08:00:42 dynaudio systemd[1]: Stopping Music Player Daemon... Apr 07 08:00:42 dynaudio volumio[839]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:42 dynaudio systemd[1]: mpd.service: Succeeded. Apr 07 08:00:42 dynaudio systemd[1]: Stopped Music Player Daemon. Apr 07 08:00:42 dynaudio volumio[839]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 07 08:00:42 dynaudio volumio[839]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 07 08:00:42 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output for this device Apr 07 08:00:42 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:00:42 dynaudio volumio[839]: info: Adding audio output: Apr 07 08:00:42 dynaudio volumio[839]: info: Adding audio output: Apr 07 08:00:42 dynaudio sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Dynaudio Apr 07 08:00:42 dynaudio systemd[1]: Starting Music Player Daemon... Apr 07 08:00:42 dynaudio sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:42 dynaudio volumio[839]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:42 dynaudio sudo[1259]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:42 dynaudio volumio[839]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 07 08:00:42 dynaudio volumio[839]: info: Adding METAVOLUMIO REST API Endpoints Apr 07 08:00:42 dynaudio volumio[839]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 07 08:00:42 dynaudio volumio[839]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 07 08:00:42 dynaudio volumio[839]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 07 08:00:42 dynaudio volumio[839]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 07 08:00:42 dynaudio volumio[839]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Apr 07 08:00:42 dynaudio volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 07 08:00:42 dynaudio volumio[839]: info: Preparing CD Folders Apr 07 08:00:42 dynaudio volumio[839]: info: Adding CD REST API Endpoints Apr 07 08:00:42 dynaudio volumio[839]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 07 08:00:42 dynaudio volumio[839]: info: Starting UDEV Watcher for CD Apr 07 08:00:42 dynaudio volumio[839]: info: Detecting CD presence with UDEV Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 07 08:00:42 dynaudio volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 07 08:00:42 dynaudio volumio[839]: info: Adding inputs REST Endpoints Apr 07 08:00:42 dynaudio volumio[839]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 07 08:00:42 dynaudio volumio[839]: info: Scanning Audio Inputs Apr 07 08:00:42 dynaudio volumio[839]: info: Checking against Known Cards name Apr 07 08:00:42 dynaudio volumio[839]: info: Checking against Known Cards name Apr 07 08:00:42 dynaudio volumio[839]: info: Adding Server instance for streaming Apr 07 08:00:42 dynaudio volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 07 08:00:42 dynaudio sudo[1257]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 07 08:00:42 dynaudio sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:42 dynaudio volumio[839]: error: Hi Res Audio Failed Login: Missing Login Data Apr 07 08:00:42 dynaudio volumio[839]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 07 08:00:42 dynaudio volumio[839]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 07 08:00:42 dynaudio volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 07 08:00:42 dynaudio sudo[1257]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:42 dynaudio volumio[839]: info: Refreshing TIDAL token Apr 07 08:00:42 dynaudio volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 07 08:00:42 dynaudio volumio[839]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Apr 07 08:00:42 dynaudio volumio[839]: info: Stopping AccessToken refresher cron for QOBUZ Apr 07 08:00:42 dynaudio sudo[1269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 07 08:00:42 dynaudio sudo[1269]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:42 dynaudio sudo[1269]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:42 dynaudio volumio[839]: info: AccessToken refresher cron started for QOBUZ Apr 07 08:00:42 dynaudio volumio[839]: info: Adding QOBUZ REST API Endpoints Apr 07 08:00:42 dynaudio volumio[839]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 07 08:00:42 dynaudio volumio[839]: info: Successfully Added MyVolumio device Apr 07 08:00:42 dynaudio volumio[839]: info: Successfully Added MyVolumio device Apr 07 08:00:42 dynaudio volumio[839]: info: Successfully Updated MyVolumio device Apr 07 08:00:42 dynaudio volumio[839]: info: Bluetooth name changed to Dynaudio Apr 07 08:00:42 dynaudio volumio[839]: info: MPD Permissions set Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:42 dynaudio volumio[839]: info: Starting Shairport Sync Apr 07 08:00:42 dynaudio sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 07 08:00:42 dynaudio sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:42 dynaudio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 07 08:00:42 dynaudio systemd[1]: shairport-sync.service: Succeeded. Apr 07 08:00:42 dynaudio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 07 08:00:42 dynaudio volumio[839]: info: Updating MyVolumio device info Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:42 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:43 dynaudio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 07 08:00:43 dynaudio sudo[1277]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:43 dynaudio volumio[839]: info: Shairport-Sync Started Apr 07 08:00:43 dynaudio volumio[839]: info: Access Token successfully retrieved Apr 07 08:00:43 dynaudio volumio[839]: info: Successfully retrieved User Session From TIDAL Apr 07 08:00:43 dynaudio volumio[839]: info: Successfully Updated MyVolumio device Apr 07 08:00:43 dynaudio volumio[839]: info: Successfully retrieved User Subscription From TIDAL Apr 07 08:00:43 dynaudio volumio[839]: info: Adding TIDAL to Browse Sources Apr 07 08:00:43 dynaudio volumio[839]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 07 08:00:43 dynaudio volumio[839]: info: [1744009243432] CoreMusicLibrary::Adding element TIDAL Apr 07 08:00:43 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 08:00:43 dynaudio volumio[839]: Cannot find translation for source Radio Paradise Apr 07 08:00:43 dynaudio volumio[839]: Cannot find translation for source TIDAL Apr 07 08:00:43 dynaudio volumio[839]: info: Stopping AccessToken refresher cron Apr 07 08:00:43 dynaudio volumio[839]: info: AccessToken refresher cron started Apr 07 08:00:43 dynaudio volumio[839]: info: Adding TIDAL REST API Endpoints Apr 07 08:00:43 dynaudio volumio[839]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 07 08:00:43 dynaudio volumio[839]: info: CoreCommandRouter::volumioPlay Apr 07 08:00:43 dynaudio volumio[839]: info: CoreStateMachine::play index undefined Apr 07 08:00:43 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 07 08:00:43 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:43 dynaudio volumio[839]: info: CoreStateMachine::startPlaybackTimer Apr 07 08:00:43 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:43 dynaudio volumio[839]: info: [1744009243765] ControllerTidal::clearAddPlayTrack Apr 07 08:00:43 dynaudio volumio[839]: info: Getting stream with soundQuality HI_RES Apr 07 08:00:43 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop Apr 07 08:00:43 dynaudio volumio[839]: error: MPD error: Error: This socket has been ended by the other party Apr 07 08:00:43 dynaudio volumio[839]: error: This socket has been ended by the other party {"code":"EPIPE"} Apr 07 08:00:43 dynaudio volumio[839]: error: MPD error: Error: This socket has been ended by the other party Apr 07 08:00:43 dynaudio volumio[839]: error: This socket has been ended by the other party {"code":"EPIPE"} Apr 07 08:00:43 dynaudio volumio[839]: error: MPD error: Error: This socket has been ended by the other party Apr 07 08:00:43 dynaudio volumio[839]: error: This socket has been ended by the other party {"code":"EPIPE"} Apr 07 08:00:43 dynaudio sudo[1287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 07 08:00:43 dynaudio sudo[1287]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:44 dynaudio systemd[1]: Started MyVolumio SSH Tunnel. Apr 07 08:00:44 dynaudio sudo[1287]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:44 dynaudio volumio[839]: info: Remote SSH Started Apr 07 08:00:44 dynaudio autossh[1290]: port set to 0, monitoring disabled Apr 07 08:00:44 dynaudio autossh[1290]: starting ssh (count 1) Apr 07 08:00:44 dynaudio autossh[1290]: ssh child pid is 1293 Apr 07 08:00:44 dynaudio mpd[1267]: Apr 07 08:00 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 07 08:00:44 dynaudio systemd[1]: Started Music Player Daemon. Apr 07 08:00:44 dynaudio sudo[1249]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:44 dynaudio volumiossh-tunnel[1289]: Warning: Permanently added '[eu3.myvolumio.org]:2222,[167.71.136.233]:2222' (RSA) to the list of known hosts. Apr 07 08:00:44 dynaudio volumio[839]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Apr 07 08:00:44 dynaudio volumio[839]: error: updateQueue error: null Apr 07 08:00:44 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:00:44 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:45 dynaudio sudo[1297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 07 08:00:45 dynaudio sudo[1297]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:45 dynaudio systemd[1]: Started Volumio Bluetooth Module. Apr 07 08:00:45 dynaudio sudo[1297]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:45 dynaudio volumio[839]: info: Volumio BT Module successfully started Apr 07 08:00:45 dynaudio sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Apr 07 08:00:45 dynaudio sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:45 dynaudio volumiobt[1299]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Apr 07 08:00:45 dynaudio sudo[1300]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:45 dynaudio volumio[839]: info: TidalConnect service stoped! Apr 07 08:00:45 dynaudio volumio[839]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 07 08:00:45 dynaudio volumio[839]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 07 08:00:45 dynaudio sudo[1312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 07 08:00:45 dynaudio sudo[1312]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:45 dynaudio systemd[1]: Started Volumio Tidal Connect Service. Apr 07 08:00:45 dynaudio sudo[1312]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:45 dynaudio volumio[1127]: ............................................................................................................................++++ Apr 07 08:00:46 dynaudio volumio[839]: info: Executing endpoint tc_getconfig Apr 07 08:00:46 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 07 08:00:46 dynaudio vtcs[1314]: STARTING TidalConnect services, version: 1.5.0.45 Apr 07 08:00:46 dynaudio vtcs[1314]: STARTED TidalConnect services. Apr 07 08:00:46 dynaudio volumio[839]: info: Executing endpoint tc_connect Apr 07 08:00:46 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 07 08:00:46 dynaudio volumio[839]: info: Connecting to TidalConnect Apr 07 08:00:46 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:00:46 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:00:46 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:46 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:00:46 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:00:46 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:00:46 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:00:46 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:46 dynaudio volumio[839]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Apr 07 08:00:46 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:00:46 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:00:46 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:46 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:00:46 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:00:46 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:00:46 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:46 dynaudio volumio[839]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Apr 07 08:00:46 dynaudio sudo[1333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Apr 07 08:00:46 dynaudio sudo[1333]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:00:46 dynaudio volumiobt[1299]: pulseaudio: no process found Apr 07 08:00:46 dynaudio sudo[1333]: pam_unix(sudo:session): session closed for user root Apr 07 08:00:46 dynaudio dbus-daemon[581]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.21' (uid=1000 pid=1337 comm="/usr/bin/pulseaudio --high-priority=true ") Apr 07 08:00:46 dynaudio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Apr 07 08:00:46 dynaudio volumio[839]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 07 08:00:46 dynaudio dbus-daemon[581]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Apr 07 08:00:46 dynaudio systemd[1]: Started RealtimeKit Scheduling Policy Service. Apr 07 08:00:46 dynaudio rtkit-daemon[1338]: Successfully called chroot. Apr 07 08:00:46 dynaudio rtkit-daemon[1338]: Successfully dropped privileges. Apr 07 08:00:46 dynaudio rtkit-daemon[1338]: Successfully limited resources. Apr 07 08:00:46 dynaudio rtkit-daemon[1338]: Running. Apr 07 08:00:46 dynaudio rtkit-daemon[1338]: Canary thread running. Apr 07 08:00:46 dynaudio rtkit-daemon[1338]: Watchdog thread running. Apr 07 08:00:46 dynaudio dbus-daemon[581]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.22' (uid=0 pid=1338 comm="/usr/lib/rtkit/rtkit-daemon ") Apr 07 08:00:46 dynaudio systemd[1]: Starting Authorization Manager... Apr 07 08:00:46 dynaudio volumio[839]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 07 08:00:46 dynaudio polkitd[1342]: started daemon version 0.105 using authority implementation `local' version `0.105' Apr 07 08:00:46 dynaudio dbus-daemon[581]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Apr 07 08:00:46 dynaudio systemd[1]: Started Authorization Manager. Apr 07 08:00:47 dynaudio pulseaudio[1337]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 07 08:00:47 dynaudio pulseaudio[1337]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Apr 07 08:00:47 dynaudio volumiobt[1299]: Applying permissions Apr 07 08:00:47 dynaudio volumiobt[1299]: Setting BT discoverable and pairable Apr 07 08:00:47 dynaudio bluetoothd[721]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSource Apr 07 08:00:47 dynaudio bluetoothd[721]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSink Apr 07 08:00:47 dynaudio pulseaudio[1337]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Apr 07 08:00:47 dynaudio kernel: Bluetooth: RFCOMM TTY layer initialized Apr 07 08:00:47 dynaudio kernel: Bluetooth: RFCOMM socket layer initialized Apr 07 08:00:47 dynaudio kernel: Bluetooth: RFCOMM ver 1.11 Apr 07 08:00:47 dynaudio volumiobt[1299]: [CHG] Controller B8:27:EB:48:D1:63 UUIDs: 00001112-0000-1000-8000-00805f9b34fb Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [91B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [55B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [117B blob data] Apr 07 08:00:47 dynaudio volumiobt[1299]: [bluetooth]# -e power on Apr 07 08:00:47 dynaudio volumiobt[1299]: Invalid command in menu main: -e Apr 07 08:00:47 dynaudio volumiobt[1299]: Apr 07 08:00:47 dynaudio volumiobt[1299]: Use "help" for a list of available commands in a menu. Apr 07 08:00:47 dynaudio volumiobt[1299]: Use "menu " if you want to enter any submenu. Apr 07 08:00:47 dynaudio volumiobt[1299]: Use "back" if you want to return to menu main. Apr 07 08:00:47 dynaudio volumiobt[1299]: [bluetooth]# agent on Apr 07 08:00:47 dynaudio volumiobt[1299]: Agent is already registered Apr 07 08:00:47 dynaudio volumiobt[1299]: [bluetooth]# discoverable on Apr 07 08:00:47 dynaudio volumiobt[1299]: [bluetooth]# pairable on Apr 07 08:00:47 dynaudio volumiobt[1299]: [bluetooth]# agent NoInputNoOutput Apr 07 08:00:47 dynaudio volumiobt[1299]: Agent is already registered Apr 07 08:00:47 dynaudio volumiobt[1299]: [bluetooth]# default-agent Apr 07 08:00:47 dynaudio volumiobt[1299]: [bluetooth]# quit Apr 07 08:00:47 dynaudio volumiobt[1299]: [59B blob data] Apr 07 08:00:47 dynaudio volumio[839]: info: MRS: Getting audio outputs on start Apr 07 08:00:47 dynaudio volumio[839]: info: MRS: Requesting all other devices output Apr 07 08:00:48 dynaudio volumio[839]: info: TidalConnect service started! Apr 07 08:00:48 dynaudio volumio[839]: [Metrics] CommandRouter: 33s 812.36ms Apr 07 08:00:48 dynaudio volumio[839]: info: CoreCommandRouter::volumiosetStartupVolume Apr 07 08:00:48 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:48 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:48 dynaudio volumio[839]: info: CoreCommandRouter::Close All Modals sent Apr 07 08:00:48 dynaudio volumio[839]: info: CoreCommandRouter::Close All Modals sent Apr 07 08:00:49 dynaudio systemd[1]: systemd-timedated.service: Succeeded. Apr 07 08:00:49 dynaudio volumio[839]: info: CoreCommandRouter::volumioPlay Apr 07 08:00:49 dynaudio volumio[839]: info: CoreStateMachine::play index undefined Apr 07 08:00:49 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 07 08:00:49 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:49 dynaudio volumio[839]: info: CoreStateMachine::startPlaybackTimer Apr 07 08:00:49 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:49 dynaudio volumio[839]: info: [1744009249188] ControllerTidal::clearAddPlayTrack Apr 07 08:00:49 dynaudio volumio[839]: info: Getting stream with soundQuality HI_RES Apr 07 08:00:49 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop Apr 07 08:00:49 dynaudio volumio[839]: info: sendMpdCommand stop took 2 milliseconds Apr 07 08:00:49 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand clear Apr 07 08:00:49 dynaudio volumio[839]: info: Apr 07 08:00:49 dynaudio volumio[839]: ---------------------------- MPD announces system playlist update Apr 07 08:00:49 dynaudio volumio[839]: info: Ignoring MPD Status Update Apr 07 08:00:49 dynaudio volumio[839]: info: sendMpdCommand clear took 5 milliseconds Apr 07 08:00:49 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES" Apr 07 08:00:49 dynaudio volumio[839]: error: updateQueue error: null Apr 07 08:00:49 dynaudio volumio[839]: info: Apr 07 08:00:49 dynaudio volumio[839]: ---------------------------- MPD announces system playlist update Apr 07 08:00:49 dynaudio volumio[839]: info: Ignoring MPD Status Update Apr 07 08:00:49 dynaudio volumio[839]: info: ------------------------------ 11ms Apr 07 08:00:49 dynaudio volumio[839]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES" took 7 milliseconds Apr 07 08:00:49 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 07 08:00:49 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand play Apr 07 08:00:49 dynaudio volumio[839]: info: ------------------------------ 10ms Apr 07 08:00:49 dynaudio volumio[839]: info: sendMpdCommand play took 8 milliseconds Apr 07 08:00:49 dynaudio volumio[839]: STREAMING PROXY: Handling url /?data=tidal://song/106412031&soundQuality=HI_RES Apr 07 08:00:49 dynaudio volumio[839]: info: Executing endpoint getStreamUrltidal Apr 07 08:00:49 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 07 08:00:49 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 07 08:00:49 dynaudio volumio[839]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 07 08:00:49 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:00:49 dynaudio volumio[839]: info: getStreamUrl took 456 milliseconds Apr 07 08:00:49 dynaudio volumio[839]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEicxY2QwZmM4MTEwMDUxZTQ2Y2IxOTYwZTZmMmRiYTdhOF82Mi5tcDQ/0.flac?Expires=1744012733&Signature=vItLAkM4IPpsE0OhlgvMKpYwjfCU2jLiw7kr0DPW~boGPeh9hZ5Ns3xRmrwCvjOj6xThcnPbn2VR3czMqBEWcUQ7XxFQ1v4D-7ZxBuB--tEREuLTNyhe9Eit3WHI5neaw78A~2p6hjUjUCjCZLp4Jd0hi7plTAM0CGFGTLDsSF9--3WM6LhOdBSM6hVEBhS4bO4IZnkk~AwJ4maiqH8neQ~OKPW6qNIaqi2P3RNKwfEPBrznJKPbgJYaYhHz1eZ4jwXt-XBEo8HtNPlh-xMczmJI1ZJMbkumDOXFqXV~uTmp13ghhE~CKVBkQXZpNgNPnweR-Iuv7kFtzSC9KULYhw__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 07 08:00:49 dynaudio volumio[839]: STREAMING PROXY: Response: 200, length: 17385359 Apr 07 08:00:49 dynaudio volumio[839]: info: Apr 07 08:00:49 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:00:49 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:00:49 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:00:49 dynaudio volumio[839]: info: Apr 07 08:00:49 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:00:49 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 07 08:00:49 dynaudio volumio[839]: info: sendMpdCommand status took 30 milliseconds Apr 07 08:00:49 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:00:49 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:00:49 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:00:49 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 07 08:00:49 dynaudio volumio[839]: info: sendMpdCommand clearerror took 8 milliseconds Apr 07 08:00:49 dynaudio volumio[839]: info: sendMpdCommand status took 7 milliseconds Apr 07 08:00:49 dynaudio volumio[839]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 07 08:00:49 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:00:49 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 07 08:00:49 dynaudio volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 07 08:00:49 dynaudio volumio[839]: info: ControllerMpd::pushState Apr 07 08:00:49 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:00:49 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:49 dynaudio volumio[839]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":104,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"106412031&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES","trackType":"tidal"} Apr 07 08:00:49 dynaudio volumio[839]: verbose: CURRENT POSITION 0 Apr 07 08:00:49 dynaudio volumio[839]: info: CoreStateMachine::syncState stateService pause Apr 07 08:00:49 dynaudio volumio[839]: info: CoreStateMachine::syncState currentStatus stop Apr 07 08:00:49 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:00:49 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:00:49 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:00:49 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:00:49 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:00:49 dynaudio volumio[839]: info: ------------------------------ 100ms Apr 07 08:00:49 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 07 08:00:49 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 07 08:00:49 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 07 08:00:49 dynaudio volumio[839]: info: sendMpdCommand playlistinfo took 73 milliseconds Apr 07 08:00:49 dynaudio volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 07 08:00:50 dynaudio volumio[839]: info: ControllerMpd::pushState Apr 07 08:00:50 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:00:50 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:50 dynaudio volumio[839]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":104,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"106412031&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES","trackType":"tidal"} Apr 07 08:00:50 dynaudio volumio[839]: verbose: CURRENT POSITION 0 Apr 07 08:00:50 dynaudio volumio[839]: info: CoreStateMachine::syncState stateService pause Apr 07 08:00:50 dynaudio volumio[839]: info: CoreStateMachine::syncState currentStatus stop Apr 07 08:00:50 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:00:50 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:00:50 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:00:50 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:00:50 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:00:50 dynaudio volumio[839]: info: ------------------------------ 138ms Apr 07 08:00:50 dynaudio volumio[839]: info: CoreCommandRouter::volumioPlay Apr 07 08:00:50 dynaudio volumio[839]: info: CoreStateMachine::play index undefined Apr 07 08:00:50 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 07 08:00:50 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:50 dynaudio volumio[839]: info: CoreStateMachine::startPlaybackTimer Apr 07 08:00:50 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:50 dynaudio volumio[839]: info: [1744009250202] ControllerTidal::clearAddPlayTrack Apr 07 08:00:50 dynaudio volumio[839]: info: Getting stream with soundQuality HI_RES Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop Apr 07 08:00:50 dynaudio volumio[839]: info: Apr 07 08:00:50 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:00:50 dynaudio volumio[839]: info: sendMpdCommand stop took 5 milliseconds Apr 07 08:00:50 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand clear Apr 07 08:00:50 dynaudio volumio[839]: info: Apr 07 08:00:50 dynaudio volumio[839]: ---------------------------- MPD announces system playlist update Apr 07 08:00:50 dynaudio volumio[839]: info: Ignoring MPD Status Update Apr 07 08:00:50 dynaudio volumio[839]: info: sendMpdCommand status took 4 milliseconds Apr 07 08:00:50 dynaudio volumio[839]: info: sendMpdCommand clear took 4 milliseconds Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES" Apr 07 08:00:50 dynaudio volumio[839]: error: updateQueue error: null Apr 07 08:00:50 dynaudio volumio[839]: info: ------------------------------ 7ms Apr 07 08:00:50 dynaudio volumio[839]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 07 08:00:50 dynaudio volumio[839]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 07 08:00:50 dynaudio volumio[839]: info: ------------------------------ 14ms Apr 07 08:00:50 dynaudio volumio[839]: STREAMING PROXY: Client dropped request, destroying Apr 07 08:00:50 dynaudio volumio[839]: info: Apr 07 08:00:50 dynaudio volumio[839]: ---------------------------- MPD announces system playlist update Apr 07 08:00:50 dynaudio volumio[839]: info: Ignoring MPD Status Update Apr 07 08:00:50 dynaudio volumio[839]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES" took 8 milliseconds Apr 07 08:00:50 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand play Apr 07 08:00:50 dynaudio volumio[839]: info: ------------------------------ 5ms Apr 07 08:00:50 dynaudio volumio[839]: info: sendMpdCommand play took 4 milliseconds Apr 07 08:00:50 dynaudio volumio[839]: STREAMING PROXY: Handling url /?data=tidal://song/106412031&soundQuality=HI_RES Apr 07 08:00:50 dynaudio volumio[839]: info: Executing endpoint getStreamUrltidal Apr 07 08:00:50 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 07 08:00:50 dynaudio volumio[839]: info: getStreamUrl took 404 milliseconds Apr 07 08:00:50 dynaudio volumio[839]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEicxY2QwZmM4MTEwMDUxZTQ2Y2IxOTYwZTZmMmRiYTdhOF82Mi5tcDQ/0.flac?Expires=1744012733&Signature=vItLAkM4IPpsE0OhlgvMKpYwjfCU2jLiw7kr0DPW~boGPeh9hZ5Ns3xRmrwCvjOj6xThcnPbn2VR3czMqBEWcUQ7XxFQ1v4D-7ZxBuB--tEREuLTNyhe9Eit3WHI5neaw78A~2p6hjUjUCjCZLp4Jd0hi7plTAM0CGFGTLDsSF9--3WM6LhOdBSM6hVEBhS4bO4IZnkk~AwJ4maiqH8neQ~OKPW6qNIaqi2P3RNKwfEPBrznJKPbgJYaYhHz1eZ4jwXt-XBEo8HtNPlh-xMczmJI1ZJMbkumDOXFqXV~uTmp13ghhE~CKVBkQXZpNgNPnweR-Iuv7kFtzSC9KULYhw__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 07 08:00:50 dynaudio volumio[839]: STREAMING PROXY: Response: 200, length: 17385359 Apr 07 08:00:50 dynaudio volumio[839]: info: Apr 07 08:00:50 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:00:50 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:00:50 dynaudio volumio[839]: info: Apr 07 08:00:50 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:00:50 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 07 08:00:50 dynaudio volumio[839]: info: sendMpdCommand status took 12 milliseconds Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand clearerror Apr 07 08:00:50 dynaudio volumio[839]: info: sendMpdCommand status took 28 milliseconds Apr 07 08:00:50 dynaudio volumio[839]: info: sendMpdCommand clearerror took 20 milliseconds Apr 07 08:00:50 dynaudio volumio[839]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 07 08:00:50 dynaudio volumio[839]: info: ControllerMpd::pushState Apr 07 08:00:50 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:00:50 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:50 dynaudio volumio[839]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":104,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"106412031&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES","trackType":"tidal"} Apr 07 08:00:50 dynaudio volumio[839]: verbose: CURRENT POSITION 0 Apr 07 08:00:50 dynaudio volumio[839]: info: CoreStateMachine::syncState stateService pause Apr 07 08:00:50 dynaudio volumio[839]: info: CoreStateMachine::syncState currentStatus stop Apr 07 08:00:50 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:00:50 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:00:50 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:00:50 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:00:50 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:00:50 dynaudio volumio[839]: info: ------------------------------ 73ms Apr 07 08:00:50 dynaudio volumio[839]: info: sendMpdCommand clearerror took 56 milliseconds Apr 07 08:00:50 dynaudio volumio[839]: info: sendMpdCommand playlistinfo took 54 milliseconds Apr 07 08:00:50 dynaudio volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 07 08:00:50 dynaudio volumio[839]: info: ControllerMpd::pushState Apr 07 08:00:50 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:00:50 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:50 dynaudio volumio[839]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":104,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"106412031&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES","trackType":"tidal"} Apr 07 08:00:50 dynaudio volumio[839]: verbose: CURRENT POSITION 0 Apr 07 08:00:50 dynaudio volumio[839]: info: CoreStateMachine::syncState stateService pause Apr 07 08:00:50 dynaudio volumio[839]: info: CoreStateMachine::syncState currentStatus stop Apr 07 08:00:50 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:00:50 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:00:50 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:00:50 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:00:50 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:00:50 dynaudio volumio[839]: info: ------------------------------ 130ms Apr 07 08:00:52 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 08:00:52 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 07 08:00:52 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 07 08:00:53 dynaudio volumio-remote-updater[583]: No test mode Apr 07 08:00:53 dynaudio volumio-remote-updater[583]: No alpha test mode Apr 07 08:00:53 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 07 08:00:53 dynaudio volumio[839]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 07 08:00:53 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 07 08:00:54 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:54 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 07 08:00:54 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 07 08:00:54 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 07 08:00:54 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 07 08:00:54 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 07 08:00:54 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetBrowseSources Apr 07 08:00:54 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 08:00:54 dynaudio volumio[839]: STREAMING PROXY: Client dropped request, destroying Apr 07 08:00:55 dynaudio volumio[839]: info: BOOT COMPLETED Apr 07 08:00:57 dynaudio volumio[839]: info: CoreCommandRouter::volumioPlay Apr 07 08:00:57 dynaudio volumio[839]: info: CoreStateMachine::play index undefined Apr 07 08:00:57 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 07 08:00:57 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:57 dynaudio volumio[839]: info: CoreStateMachine::startPlaybackTimer Apr 07 08:00:57 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:57 dynaudio volumio[839]: info: [1744009257419] ControllerTidal::clearAddPlayTrack Apr 07 08:00:57 dynaudio volumio[839]: info: Getting stream with soundQuality HI_RES Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop Apr 07 08:00:57 dynaudio volumio[839]: info: Apr 07 08:00:57 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:00:57 dynaudio volumio[839]: info: sendMpdCommand stop took 6 milliseconds Apr 07 08:00:57 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand clear Apr 07 08:00:57 dynaudio volumio[839]: info: Apr 07 08:00:57 dynaudio volumio[839]: ---------------------------- MPD announces system playlist update Apr 07 08:00:57 dynaudio volumio[839]: info: Ignoring MPD Status Update Apr 07 08:00:57 dynaudio volumio[839]: info: sendMpdCommand status took 2 milliseconds Apr 07 08:00:57 dynaudio volumio[839]: info: sendMpdCommand clear took 3 milliseconds Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES" Apr 07 08:00:57 dynaudio volumio[839]: error: updateQueue error: null Apr 07 08:00:57 dynaudio volumio[839]: info: Apr 07 08:00:57 dynaudio volumio[839]: ---------------------------- MPD announces system playlist update Apr 07 08:00:57 dynaudio volumio[839]: info: Ignoring MPD Status Update Apr 07 08:00:57 dynaudio volumio[839]: info: ------------------------------ 5ms Apr 07 08:00:57 dynaudio volumio[839]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 07 08:00:57 dynaudio volumio[839]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES" took 3 milliseconds Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 07 08:00:57 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand play Apr 07 08:00:57 dynaudio volumio[839]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 07 08:00:57 dynaudio volumio[839]: info: ------------------------------ 12ms Apr 07 08:00:57 dynaudio volumio[839]: info: ------------------------------ 5ms Apr 07 08:00:57 dynaudio volumio[839]: STREAMING PROXY: Handling url /?data=tidal://song/106412031&soundQuality=HI_RES Apr 07 08:00:57 dynaudio volumio[839]: info: Executing endpoint getStreamUrltidal Apr 07 08:00:57 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 07 08:00:57 dynaudio volumio[839]: info: sendMpdCommand play took 20 milliseconds Apr 07 08:00:57 dynaudio volumio[839]: info: getStreamUrl took 314 milliseconds Apr 07 08:00:57 dynaudio volumio[839]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEicxY2QwZmM4MTEwMDUxZTQ2Y2IxOTYwZTZmMmRiYTdhOF82Mi5tcDQ/0.flac?Expires=1744012733&Signature=vItLAkM4IPpsE0OhlgvMKpYwjfCU2jLiw7kr0DPW~boGPeh9hZ5Ns3xRmrwCvjOj6xThcnPbn2VR3czMqBEWcUQ7XxFQ1v4D-7ZxBuB--tEREuLTNyhe9Eit3WHI5neaw78A~2p6hjUjUCjCZLp4Jd0hi7plTAM0CGFGTLDsSF9--3WM6LhOdBSM6hVEBhS4bO4IZnkk~AwJ4maiqH8neQ~OKPW6qNIaqi2P3RNKwfEPBrznJKPbgJYaYhHz1eZ4jwXt-XBEo8HtNPlh-xMczmJI1ZJMbkumDOXFqXV~uTmp13ghhE~CKVBkQXZpNgNPnweR-Iuv7kFtzSC9KULYhw__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 07 08:00:57 dynaudio volumio[839]: STREAMING PROXY: Response: 200, length: 17385359 Apr 07 08:00:57 dynaudio volumio[839]: info: Apr 07 08:00:57 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:00:57 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:00:57 dynaudio volumio[839]: info: Apr 07 08:00:57 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:00:57 dynaudio volumio[839]: info: sendMpdCommand status took 4 milliseconds Apr 07 08:00:57 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 07 08:00:57 dynaudio volumio[839]: info: sendMpdCommand status took 3 milliseconds Apr 07 08:00:57 dynaudio volumio[839]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 07 08:00:57 dynaudio volumio[839]: info: ControllerMpd::pushState Apr 07 08:00:57 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:00:57 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:57 dynaudio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":104,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"106412031&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES","trackType":"tidal"} Apr 07 08:00:57 dynaudio volumio[839]: verbose: CURRENT POSITION 0 Apr 07 08:00:57 dynaudio volumio[839]: info: CoreStateMachine::syncState stateService play Apr 07 08:00:57 dynaudio volumio[839]: info: CoreStateMachine::syncState currentStatus stop Apr 07 08:00:57 dynaudio volumio[839]: info: ------------------------------ 14ms Apr 07 08:00:57 dynaudio volumio[839]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 07 08:00:57 dynaudio volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 07 08:00:57 dynaudio volumio[839]: info: ControllerMpd::pushState Apr 07 08:00:57 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:00:57 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:00:57 dynaudio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":104,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"106412031&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES","trackType":"tidal"} Apr 07 08:00:57 dynaudio volumio[839]: verbose: CURRENT POSITION 0 Apr 07 08:00:57 dynaudio volumio[839]: info: CoreStateMachine::syncState stateService play Apr 07 08:00:57 dynaudio volumio[839]: info: CoreStateMachine::syncState currentStatus play Apr 07 08:00:57 dynaudio volumio[839]: info: Received an update from plugin. extracting info from payload Apr 07 08:00:57 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:00:57 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:00:57 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:00:57 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:00:57 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:00:57 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:00:57 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:00:57 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:00:57 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:00:57 dynaudio volumio[839]: info: ------------------------------ 61ms Apr 07 08:01:00 dynaudio volumio[839]: info: CoreCommandRouter::volumioPause Apr 07 08:01:00 dynaudio volumio[839]: info: CoreStateMachine::pause Apr 07 08:01:00 dynaudio volumio[839]: info: CoreStateMachine::stPlaybackTimer Apr 07 08:01:00 dynaudio volumio[839]: info: CoreStateMachine::servicePause Apr 07 08:01:00 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:01:00 dynaudio volumio[839]: info: CoreCommandRouter::servicePause Apr 07 08:01:00 dynaudio volumio[839]: info: [1744009260823] ControllerTidal::pause Apr 07 08:01:00 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 07 08:01:00 dynaudio volumio[839]: info: ControllerMpd::pause Apr 07 08:01:00 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand pause Apr 07 08:01:00 dynaudio volumio[839]: info: sendMpdCommand pause took 12 milliseconds Apr 07 08:01:00 dynaudio volumio[839]: info: Apr 07 08:01:00 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:01:00 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:01:00 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:01:00 dynaudio volumio[839]: info: sendMpdCommand status took 2 milliseconds Apr 07 08:01:00 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:01:00 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 07 08:01:00 dynaudio volumio[839]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 07 08:01:00 dynaudio volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 07 08:01:00 dynaudio volumio[839]: info: ControllerMpd::pushState Apr 07 08:01:00 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:01:00 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:01:00 dynaudio volumio[839]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":4357,"duration":104,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1237 Kbps","isStreaming":false,"title":"106412031&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES","trackType":"tidal"} Apr 07 08:01:00 dynaudio volumio[839]: verbose: CURRENT POSITION 0 Apr 07 08:01:00 dynaudio volumio[839]: info: CoreStateMachine::syncState stateService pause Apr 07 08:01:00 dynaudio volumio[839]: info: CoreStateMachine::syncState currentStatus pause Apr 07 08:01:00 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:01:00 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:01:00 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:01:00 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:01:00 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:01:00 dynaudio volumio[839]: info: CoreStateMachine::stPlaybackTimer Apr 07 08:01:00 dynaudio volumio[839]: info: ------------------------------ 43ms Apr 07 08:01:01 dynaudio volumio[839]: STREAMING PROXY: Client dropped request, destroying Apr 07 08:01:13 dynaudio volumio[1127]: ..................................................................................................................................................................................................++++ Apr 07 08:01:13 dynaudio volumio[1127]: e is 65537 (0x010001) Apr 07 08:01:13 dynaudio volumio[1127]: writing RSA key Apr 07 08:01:14 dynaudio ntpd[808]: Soliciting pool server 193.57.144.50 Apr 07 08:01:15 dynaudio ntpd[808]: Soliciting pool server 178.79.140.210 Apr 07 08:01:16 dynaudio ntpd[808]: Soliciting pool server 131.111.8.63 Apr 07 08:01:17 dynaudio ntpd[808]: Soliciting pool server 62.3.77.2 Apr 07 08:01:17 dynaudio ntpd[808]: Soliciting pool server 185.83.169.27 Apr 07 08:01:18 dynaudio ntpd[808]: Soliciting pool server 129.250.35.251 Apr 07 08:01:18 dynaudio ntpd[808]: Soliciting pool server 162.159.200.1 Apr 07 08:01:18 dynaudio ntpd[808]: Soliciting pool server 213.210.39.123 Apr 07 08:01:19 dynaudio ntpd[808]: Soliciting pool server 139.143.5.30 Apr 07 08:01:19 dynaudio ntpd[808]: Soliciting pool server 178.215.228.24 Apr 07 08:01:19 dynaudio ntpd[808]: Soliciting pool server 85.199.214.98 Apr 07 08:01:20 dynaudio ntpd[808]: Soliciting pool server 95.215.175.2 Apr 07 08:01:20 dynaudio ntpd[808]: Soliciting pool server 139.162.219.252 Apr 07 08:01:20 dynaudio ntpd[808]: Soliciting pool server 85.199.214.102 Apr 07 08:01:21 dynaudio ntpd[808]: Soliciting pool server 82.219.4.30 Apr 07 08:01:21 dynaudio ntpd[808]: Soliciting pool server 185.103.119.60 Apr 07 08:01:23 dynaudio ntpd[808]: Soliciting pool server 2a01:7e00::f03c:91ff:fe96:f129 Apr 07 08:14:53 dynaudio systemd[1]: Starting Cleanup of Temporary Directories... Apr 07 08:14:53 dynaudio volumio[839]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 07 08:14:53 dynaudio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Apr 07 08:14:53 dynaudio systemd[1]: Started Cleanup of Temporary Directories. Apr 07 08:17:05 dynaudio volumio[839]: verbose: New Socket.io Connection to 192.168.1.213 from 192.168.1.50 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Apr 07 08:17:05 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 07 08:17:05 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetVisibleSources Apr 07 08:17:05 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 08:17:05 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:17:05 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 07 08:17:05 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 07 08:17:05 dynaudio volumio[839]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 07 08:17:05 dynaudio volumio[839]: info: Received Get System Info Apr 07 08:17:05 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 07 08:17:05 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 07 08:17:05 dynaudio volumio[839]: info: Discovery: Getting this device information Apr 07 08:17:05 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:17:05 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 07 08:17:05 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:17:05 dynaudio volumio[839]: info: Listing playlists Apr 07 08:17:05 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 07 08:17:10 dynaudio volumio[839]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 07 08:17:10 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 07 08:17:11 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 07 08:17:11 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 07 08:17:11 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Apr 07 08:17:11 dynaudio volumio[839]: info: Received Get System Version Apr 07 08:17:11 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 08:17:14 dynaudio volumio[839]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 07 08:17:14 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 07 08:17:14 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 07 08:17:14 dynaudio sudo[3632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 07 08:17:14 dynaudio sudo[3632]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:17:14 dynaudio sudo[3637]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 07 08:17:14 dynaudio sudo[3632]: pam_unix(sudo:session): session closed for user root Apr 07 08:17:14 dynaudio sudo[3637]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:17:14 dynaudio sudo[3637]: pam_unix(sudo:session): session closed for user root Apr 07 08:17:14 dynaudio sudo[3644]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 07 08:17:14 dynaudio sudo[3644]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:17:14 dynaudio sudo[3644]: pam_unix(sudo:session): session closed for user root Apr 07 08:17:14 dynaudio sudo[3652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 07 08:17:14 dynaudio sudo[3652]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:17:14 dynaudio sudo[3652]: pam_unix(sudo:session): session closed for user root Apr 07 08:17:14 dynaudio sudo[3657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 07 08:17:14 dynaudio sudo[3657]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:17:14 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 07 08:17:14 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 07 08:17:14 dynaudio sudo[3657]: pam_unix(sudo:session): session closed for user root Apr 07 08:17:14 dynaudio sudo[3660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 07 08:17:14 dynaudio sudo[3660]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:17:14 dynaudio sudo[3660]: pam_unix(sudo:session): session closed for user root Apr 07 08:17:50 dynaudio volumio[839]: verbose: New Socket.io Connection to 192.168.1.213 from 192.168.1.50 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 Apr 07 08:17:50 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:17:50 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetQueue Apr 07 08:17:50 dynaudio volumio[839]: info: CoreStateMachine::getQueue Apr 07 08:17:50 dynaudio volumio[839]: info: CorePlayQueue::getQueue Apr 07 08:17:50 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Apr 07 08:17:50 dynaudio volumio[839]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Apr 07 08:17:50 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Apr 07 08:17:50 dynaudio volumio[839]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Apr 07 08:17:50 dynaudio volumio[839]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Apr 07 08:17:50 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Apr 07 08:22:41 dynaudio volumio[839]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Apr 07 08:22:41 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Apr 07 08:22:42 dynaudio sudo[4386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Apr 07 08:22:42 dynaudio sudo[4386]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:22:42 dynaudio sudo[4386]: pam_unix(sudo:session): session closed for user root Apr 07 08:22:52 dynaudio sudo[4463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Apr 07 08:22:52 dynaudio sudo[4463]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:22:52 dynaudio sudo[4463]: pam_unix(sudo:session): session closed for user root Apr 07 08:22:53 dynaudio volumio[839]: info: Log sent successfully, reply: {"status":"OK","link":"http://logs.volumio.org/volumio/rv8LraQ.html"} Apr 07 08:22:53 dynaudio ntpd[808]: 129.250.35.251 local addr 192.168.1.213 -> Apr 07 08:23:55 dynaudio volumio[839]: verbose: New Socket.io Connection to 192.168.1.213 from 192.168.1.50 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Apr 07 08:23:55 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 07 08:23:55 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetVisibleSources Apr 07 08:23:55 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 08:23:55 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:23:55 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 07 08:23:55 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 07 08:23:55 dynaudio volumio[839]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 07 08:23:55 dynaudio volumio[839]: info: Received Get System Info Apr 07 08:23:55 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 07 08:23:55 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 07 08:23:55 dynaudio volumio[839]: info: Discovery: Getting this device information Apr 07 08:23:55 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:23:55 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 07 08:23:55 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:23:55 dynaudio volumio[839]: info: Listing playlists Apr 07 08:23:55 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 07 08:23:59 dynaudio volumio[839]: info: CoreCommandRouter::volumioPlay Apr 07 08:23:59 dynaudio volumio[839]: info: CoreStateMachine::play index undefined Apr 07 08:23:59 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 07 08:23:59 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:23:59 dynaudio volumio[839]: info: CoreStateMachine::startPlaybackTimer Apr 07 08:23:59 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:23:59 dynaudio volumio[839]: info: [1744010639159] ControllerTidal::resume Apr 07 08:23:59 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 07 08:23:59 dynaudio volumio[839]: info: ControllerMpd::resume Apr 07 08:23:59 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand play Apr 07 08:23:59 dynaudio volumio[839]: info: sendMpdCommand play took 5 milliseconds Apr 07 08:23:59 dynaudio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Apr 07 08:23:59 dynaudio volumio[839]: info: Apr 07 08:23:59 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:23:59 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:23:59 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:23:59 dynaudio volumio[839]: info: sendMpdCommand status took 2 milliseconds Apr 07 08:23:59 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:23:59 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 07 08:23:59 dynaudio volumio[839]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 07 08:23:59 dynaudio volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 07 08:23:59 dynaudio volumio[839]: info: ControllerMpd::pushState Apr 07 08:23:59 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:23:59 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:23:59 dynaudio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5607,"duration":104,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1218 Kbps","isStreaming":false,"title":"106412031&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/106412031&soundQuality=HI_RES","trackType":"tidal"} Apr 07 08:23:59 dynaudio volumio[839]: verbose: CURRENT POSITION 0 Apr 07 08:23:59 dynaudio volumio[839]: info: CoreStateMachine::syncState stateService play Apr 07 08:23:59 dynaudio volumio[839]: info: CoreStateMachine::syncState currentStatus pause Apr 07 08:23:59 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:23:59 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:23:59 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:23:59 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:23:59 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:23:59 dynaudio volumio[839]: info: ------------------------------ 35ms Apr 07 08:24:09 dynaudio volumio[839]: info: CoreCommandRouter::volumioNext Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::next Apr 07 08:24:09 dynaudio volumio[839]: info: ControllerMpd::next Apr 07 08:24:09 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand next Apr 07 08:24:09 dynaudio volumio[839]: info: Apr 07 08:24:09 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:24:09 dynaudio volumio[839]: info: sendMpdCommand next took 7 milliseconds Apr 07 08:24:09 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:24:09 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:24:09 dynaudio volumio[839]: info: sendMpdCommand status took 1 milliseconds Apr 07 08:24:09 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:24:09 dynaudio volumio[839]: info: ControllerMpd::pushState Apr 07 08:24:09 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:24:09 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:24:09 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:24:09 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:24:09 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:24:09 dynaudio volumio[839]: info: CorePlayQueue::getTrack 0 Apr 07 08:24:09 dynaudio volumio[839]: 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} Apr 07 08:24:09 dynaudio volumio[839]: verbose: CURRENT POSITION 0 Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::syncState stateService stop Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::syncState currentStatus play Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::play index undefined Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:24:09 dynaudio volumio[839]: info: CorePlayQueue::getTrack 1 Apr 07 08:24:09 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:24:09 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:24:09 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:24:09 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:24:09 dynaudio volumio[839]: info: CorePlayQueue::getTrack 1 Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::startPlaybackTimer Apr 07 08:24:09 dynaudio volumio[839]: info: CorePlayQueue::getTrack 1 Apr 07 08:24:09 dynaudio volumio[839]: info: [1744010649383] ControllerTidal::clearAddPlayTrack Apr 07 08:24:09 dynaudio volumio[839]: info: Getting stream with soundQuality HI_RES Apr 07 08:24:09 dynaudio volumio[839]: info: ------------------------------ 53ms Apr 07 08:24:09 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:24:09 dynaudio volumio[839]: info: CorePlayQueue::getTrack 1 Apr 07 08:24:09 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:24:09 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:24:09 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:24:09 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:24:09 dynaudio volumio[839]: info: sendMpdCommand stop took 23 milliseconds Apr 07 08:24:09 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand clear Apr 07 08:24:09 dynaudio volumio[839]: info: Apr 07 08:24:09 dynaudio volumio[839]: ---------------------------- MPD announces system playlist update Apr 07 08:24:09 dynaudio volumio[839]: info: Ignoring MPD Status Update Apr 07 08:24:09 dynaudio volumio[839]: info: sendMpdCommand clear took 27 milliseconds Apr 07 08:24:09 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/88632421&soundQuality=HI_RES" Apr 07 08:24:09 dynaudio volumio[839]: error: updateQueue error: null Apr 07 08:24:09 dynaudio volumio[839]: info: Apr 07 08:24:09 dynaudio volumio[839]: ---------------------------- MPD announces system playlist update Apr 07 08:24:09 dynaudio volumio[839]: info: Ignoring MPD Status Update Apr 07 08:24:09 dynaudio volumio[839]: info: ------------------------------ 5ms Apr 07 08:24:09 dynaudio volumio[839]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/88632421&soundQuality=HI_RES" took 3 milliseconds Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 07 08:24:09 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand play Apr 07 08:24:09 dynaudio volumio[839]: info: ------------------------------ 4ms Apr 07 08:24:09 dynaudio volumio[839]: info: sendMpdCommand play took 2 milliseconds Apr 07 08:24:09 dynaudio volumio[839]: STREAMING PROXY: Handling url /?data=tidal://song/88632421&soundQuality=HI_RES Apr 07 08:24:09 dynaudio volumio[839]: info: Executing endpoint getStreamUrltidal Apr 07 08:24:09 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 07 08:24:09 dynaudio volumio[839]: info: CoreCommandRouter::volumioNext Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::next Apr 07 08:24:09 dynaudio volumio[839]: info: ControllerMpd::next Apr 07 08:24:09 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand next Apr 07 08:24:09 dynaudio volumio[839]: info: sendMpdCommand next took 1 milliseconds Apr 07 08:24:09 dynaudio volumio[839]: info: Apr 07 08:24:09 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:24:09 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:24:09 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:24:09 dynaudio volumio[839]: info: sendMpdCommand status took 1 milliseconds Apr 07 08:24:09 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:24:09 dynaudio volumio[839]: info: ControllerMpd::pushState Apr 07 08:24:09 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:24:09 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:24:09 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:24:09 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:24:09 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:24:09 dynaudio volumio[839]: info: CorePlayQueue::getTrack 1 Apr 07 08:24:09 dynaudio volumio[839]: 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} Apr 07 08:24:09 dynaudio volumio[839]: verbose: CURRENT POSITION 1 Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::syncState stateService stop Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::syncState currentStatus stop Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:24:09 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:24:09 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:24:09 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:24:09 dynaudio volumio[839]: info: No code Apr 07 08:24:09 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:24:09 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:24:09 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:24:09 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:24:09 dynaudio volumio[839]: info: ------------------------------ 57ms Apr 07 08:24:09 dynaudio volumio[839]: info: getStreamUrl took 321 milliseconds Apr 07 08:24:09 dynaudio volumio[839]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEicwMjIwOTE2M2Q2OWExYTNjYzM5NzhiOTRhMDZlZDM2N182Mi5tcDQ/0.flac?Expires=1744014249&Signature=IJzwyfo7pmzfuIvbKxpwTpdx9wy8w7USUwAinq7AEH3ofL3SaC3b93gF-SfMwbO66l2SvIWU-E3l49lV3MdQIvz3ZJAgmX8R0Vl5YjBINOO6IMufXZDHhikBzyanJyymfoQcarXlSdp2h1ZsqVV2pKDIfpWIoP0G-ib2m~Eled11HPaxsuKZ4TM3BA6Zw71Hro3lBSNTtoCQEDYG7VtlwAG7IhQYF2g8PkLjcLKoxgVV~hhWVjZN0DLjdK~mxZeIUJPC8MCs9YkbbFm~I6BX-JZ5FFU3n4VQC1TVyWWA9AiiO-0bz95oJa1Ro35edCixk6Z~4th9HiiAp9SmXxNh2Q__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 07 08:24:10 dynaudio volumio[839]: STREAMING PROXY: Response: 200, length: 36941017 Apr 07 08:24:12 dynaudio volumio[839]: info: CoreCommandRouter::volumioPlay Apr 07 08:24:12 dynaudio volumio[839]: info: CoreStateMachine::play index undefined Apr 07 08:24:12 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 07 08:24:12 dynaudio volumio[839]: info: CorePlayQueue::getTrack 1 Apr 07 08:24:12 dynaudio volumio[839]: info: CoreStateMachine::startPlaybackTimer Apr 07 08:24:12 dynaudio volumio[839]: info: CorePlayQueue::getTrack 1 Apr 07 08:24:12 dynaudio volumio[839]: info: [1744010652144] ControllerTidal::clearAddPlayTrack Apr 07 08:24:12 dynaudio volumio[839]: info: Getting stream with soundQuality HI_RES Apr 07 08:24:12 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop Apr 07 08:24:12 dynaudio volumio[839]: info: sendMpdCommand stop took 1 milliseconds Apr 07 08:24:12 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand clear Apr 07 08:24:12 dynaudio volumio[839]: info: Apr 07 08:24:12 dynaudio volumio[839]: ---------------------------- MPD announces system playlist update Apr 07 08:24:12 dynaudio volumio[839]: info: Ignoring MPD Status Update Apr 07 08:24:12 dynaudio volumio[839]: info: sendMpdCommand clear took 2 milliseconds Apr 07 08:24:12 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/88632421&soundQuality=HI_RES" Apr 07 08:24:12 dynaudio volumio[839]: error: updateQueue error: null Apr 07 08:24:12 dynaudio volumio[839]: info: Apr 07 08:24:12 dynaudio volumio[839]: ---------------------------- MPD announces system playlist update Apr 07 08:24:12 dynaudio volumio[839]: info: Ignoring MPD Status Update Apr 07 08:24:12 dynaudio volumio[839]: info: ------------------------------ 4ms Apr 07 08:24:12 dynaudio volumio[839]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/88632421&soundQuality=HI_RES" took 3 milliseconds Apr 07 08:24:12 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 07 08:24:12 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand play Apr 07 08:24:12 dynaudio volumio[839]: info: ------------------------------ 3ms Apr 07 08:24:12 dynaudio volumio[839]: info: sendMpdCommand play took 2 milliseconds Apr 07 08:24:12 dynaudio volumio[839]: STREAMING PROXY: Handling url /?data=tidal://song/88632421&soundQuality=HI_RES Apr 07 08:24:12 dynaudio volumio[839]: info: Executing endpoint getStreamUrltidal Apr 07 08:24:12 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 07 08:24:12 dynaudio volumio[839]: info: getStreamUrl took 297 milliseconds Apr 07 08:24:12 dynaudio volumio[839]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEicwMjIwOTE2M2Q2OWExYTNjYzM5NzhiOTRhMDZlZDM2N182Mi5tcDQ/0.flac?Expires=1744014249&Signature=IJzwyfo7pmzfuIvbKxpwTpdx9wy8w7USUwAinq7AEH3ofL3SaC3b93gF-SfMwbO66l2SvIWU-E3l49lV3MdQIvz3ZJAgmX8R0Vl5YjBINOO6IMufXZDHhikBzyanJyymfoQcarXlSdp2h1ZsqVV2pKDIfpWIoP0G-ib2m~Eled11HPaxsuKZ4TM3BA6Zw71Hro3lBSNTtoCQEDYG7VtlwAG7IhQYF2g8PkLjcLKoxgVV~hhWVjZN0DLjdK~mxZeIUJPC8MCs9YkbbFm~I6BX-JZ5FFU3n4VQC1TVyWWA9AiiO-0bz95oJa1Ro35edCixk6Z~4th9HiiAp9SmXxNh2Q__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 07 08:24:12 dynaudio volumio[839]: STREAMING PROXY: Response: 200, length: 36941017 Apr 07 08:24:12 dynaudio volumio[839]: info: Apr 07 08:24:12 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:24:12 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:24:12 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:24:12 dynaudio volumio[839]: info: Apr 07 08:24:12 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:24:12 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:24:12 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:24:12 dynaudio volumio[839]: info: sendMpdCommand status took 4 milliseconds Apr 07 08:24:12 dynaudio volumio[839]: info: sendMpdCommand status took 2 milliseconds Apr 07 08:24:12 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:24:12 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 07 08:24:12 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:24:12 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 07 08:24:12 dynaudio volumio[839]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 07 08:24:12 dynaudio volumio[839]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 07 08:24:12 dynaudio volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 07 08:24:12 dynaudio volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 07 08:24:12 dynaudio volumio[839]: info: ControllerMpd::pushState Apr 07 08:24:12 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:24:12 dynaudio volumio[839]: info: CorePlayQueue::getTrack 1 Apr 07 08:24:12 dynaudio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":186,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"88632421&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88632421&soundQuality=HI_RES","trackType":"tidal"} Apr 07 08:24:12 dynaudio volumio[839]: verbose: CURRENT POSITION 1 Apr 07 08:24:12 dynaudio volumio[839]: info: CoreStateMachine::syncState stateService play Apr 07 08:24:12 dynaudio volumio[839]: info: CoreStateMachine::syncState currentStatus stop Apr 07 08:24:12 dynaudio volumio[839]: info: ControllerMpd::pushState Apr 07 08:24:12 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:24:12 dynaudio volumio[839]: info: CorePlayQueue::getTrack 1 Apr 07 08:24:12 dynaudio volumio[839]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":186,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"88632421&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/88632421&soundQuality=HI_RES","trackType":"tidal"} Apr 07 08:24:12 dynaudio volumio[839]: verbose: CURRENT POSITION 1 Apr 07 08:24:12 dynaudio volumio[839]: info: CoreStateMachine::syncState stateService play Apr 07 08:24:12 dynaudio volumio[839]: info: CoreStateMachine::syncState currentStatus play Apr 07 08:24:12 dynaudio volumio[839]: info: Received an update from plugin. extracting info from payload Apr 07 08:24:12 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:24:12 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:24:12 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:24:12 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:24:12 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:24:12 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:24:12 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:24:12 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:24:12 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:24:12 dynaudio volumio[839]: info: ------------------------------ 67ms Apr 07 08:24:12 dynaudio volumio[839]: info: ------------------------------ 120ms Apr 07 08:24:15 dynaudio volumio[839]: info: CoreCommandRouter::volumioNext Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::next Apr 07 08:24:15 dynaudio volumio[839]: info: [1744010655128] ControllerTidal::next Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::next Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::stop Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::stPlaybackTimer Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::updateTrackBlock Apr 07 08:24:15 dynaudio volumio[839]: info: CorePlayQueue::getTrackBlock Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:24:15 dynaudio volumio[839]: info: CorePlayQueue::getTrack 1 Apr 07 08:24:15 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:24:15 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:24:15 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:24:15 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::serviceStop Apr 07 08:24:15 dynaudio volumio[839]: info: CorePlayQueue::getTrack 1 Apr 07 08:24:15 dynaudio volumio[839]: info: CoreCommandRouter::serviceStop Apr 07 08:24:15 dynaudio volumio[839]: info: [1744010655157] ControllerTidal::stop Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 07 08:24:15 dynaudio volumio[839]: info: ControllerMpd::stop Apr 07 08:24:15 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop Apr 07 08:24:15 dynaudio volumio[839]: STREAMING PROXY: Client dropped request, destroying Apr 07 08:24:15 dynaudio volumio[839]: info: Apr 07 08:24:15 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:24:15 dynaudio volumio[839]: info: sendMpdCommand stop took 38 milliseconds Apr 07 08:24:15 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:24:15 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::play index undefined Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 07 08:24:15 dynaudio volumio[839]: info: CorePlayQueue::getTrack 2 Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::startPlaybackTimer Apr 07 08:24:15 dynaudio volumio[839]: info: CorePlayQueue::getTrack 2 Apr 07 08:24:15 dynaudio volumio[839]: info: [1744010655204] ControllerTidal::clearAddPlayTrack Apr 07 08:24:15 dynaudio volumio[839]: info: Getting stream with soundQuality HI_RES Apr 07 08:24:15 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand stop Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::updateTrackBlock Apr 07 08:24:15 dynaudio volumio[839]: info: CorePlayQueue::getTrackBlock Apr 07 08:24:15 dynaudio volumio[839]: info: sendMpdCommand status took 11 milliseconds Apr 07 08:24:15 dynaudio volumio[839]: info: sendMpdCommand stop took 4 milliseconds Apr 07 08:24:15 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:24:15 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 07 08:24:15 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand clear Apr 07 08:24:15 dynaudio volumio[839]: info: Apr 07 08:24:15 dynaudio volumio[839]: ---------------------------- MPD announces system playlist update Apr 07 08:24:15 dynaudio volumio[839]: info: Ignoring MPD Status Update Apr 07 08:24:15 dynaudio volumio[839]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 07 08:24:15 dynaudio volumio[839]: info: sendMpdCommand clear took 3 milliseconds Apr 07 08:24:15 dynaudio volumio[839]: verbose: ControllerMpd::parseTrackInfo Apr 07 08:24:15 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/98089668&soundQuality=HI_RES" Apr 07 08:24:15 dynaudio volumio[839]: info: ControllerMpd::pushState Apr 07 08:24:15 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:24:15 dynaudio volumio[839]: info: CorePlayQueue::getTrack 2 Apr 07 08:24:15 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:24:15 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:24:15 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:24:15 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:24:15 dynaudio volumio[839]: info: CorePlayQueue::getTrack 2 Apr 07 08:24:15 dynaudio volumio[839]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received mpd Apr 07 08:24:15 dynaudio volumio[839]: info: ------------------------------ 51ms Apr 07 08:24:15 dynaudio volumio[839]: error: updateQueue error: null Apr 07 08:24:15 dynaudio volumio[839]: info: Apr 07 08:24:15 dynaudio volumio[839]: ---------------------------- MPD announces system playlist update Apr 07 08:24:15 dynaudio volumio[839]: info: Ignoring MPD Status Update Apr 07 08:24:15 dynaudio volumio[839]: info: ------------------------------ 37ms Apr 07 08:24:15 dynaudio volumio[839]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/98089668&soundQuality=HI_RES" took 34 milliseconds Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 07 08:24:15 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand play Apr 07 08:24:15 dynaudio volumio[839]: STREAMING PROXY: Handling url /?data=tidal://song/98089668&soundQuality=HI_RES Apr 07 08:24:15 dynaudio volumio[839]: info: ------------------------------ 15ms Apr 07 08:24:15 dynaudio volumio[839]: info: sendMpdCommand play took 13 milliseconds Apr 07 08:24:15 dynaudio volumio[839]: info: Executing endpoint getStreamUrltidal Apr 07 08:24:15 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Apr 07 08:24:15 dynaudio volumio[839]: info: CoreCommandRouter::volumioNext Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::next Apr 07 08:24:15 dynaudio volumio[839]: info: ControllerMpd::next Apr 07 08:24:15 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand next Apr 07 08:24:15 dynaudio volumio[839]: info: sendMpdCommand next took 1 milliseconds Apr 07 08:24:15 dynaudio volumio[839]: info: Apr 07 08:24:15 dynaudio volumio[839]: ---------------------------- MPD announces state update: player Apr 07 08:24:15 dynaudio volumio[839]: info: ControllerMpd::getState Apr 07 08:24:15 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand status Apr 07 08:24:15 dynaudio volumio[839]: info: sendMpdCommand status took 1 milliseconds Apr 07 08:24:15 dynaudio volumio[839]: verbose: ControllerMpd::parseState Apr 07 08:24:15 dynaudio volumio[839]: info: ControllerMpd::pushState Apr 07 08:24:15 dynaudio volumio[839]: info: CoreCommandRouter::servicePushState Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:24:15 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 07 08:24:15 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:24:15 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:24:15 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:24:15 dynaudio volumio[839]: info: CorePlayQueue::getTrack 2 Apr 07 08:24:15 dynaudio volumio[839]: 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} Apr 07 08:24:15 dynaudio volumio[839]: verbose: CURRENT POSITION 2 Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::syncState stateService stop Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::syncState currentStatus stop Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:24:15 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:24:15 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:24:15 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:24:15 dynaudio volumio[839]: info: No code Apr 07 08:24:15 dynaudio volumio[839]: info: CoreStateMachine::pushState Apr 07 08:24:15 dynaudio volumio[839]: info: CoreCommandRouter::volumioPushState Apr 07 08:24:15 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output update for this device Apr 07 08:24:15 dynaudio volumio[839]: info: MRS: Pushing multiroomSync output Apr 07 08:24:15 dynaudio volumio[839]: info: ------------------------------ 59ms Apr 07 08:24:15 dynaudio volumio[839]: info: getStreamUrl took 388 milliseconds Apr 07 08:24:15 dynaudio volumio[839]: STREAMING PROXY: Got real url: http://sp-pr-cf.audio.tidal.com/mediatracks/CAEaKwgDEicxM2RjOTZjMzczYThiYWViNDg4YTJkMDUwZjI2MjFhNl82Mi5tcDQ/0.flac?Expires=1744014255&Signature=gdQUJN8ZHAEDAw8~rHA1IZpP1QmxVhrMMU5gNXwDkYzQUg8NwR72dOXNwki2SS5Jdff6riBIYn3IfsabNAo4yT5eHBi6uVIDwnNpyNqsXw6B-GUzyzYOCcrqfpks3xR2rK3i4XdRpeCaFYBHuF1x3x3JxtfroORr5MZUNbca46qIi6EEznZIb98u3BwROZ5yYC2cBnZuR2p1T4nAQMWRQGuCtGV~7GGvVOpmGbTqj1ago~GQuhIu65tqU8a0CqEOgFPwz4BTKKg4Y99L~R-WPXa-TDueBdqAf2w1K4geCDZINawvIJ78GsaVoHMS5oBSuYJn5oL2qelBQiTJzNqayA__&Key-Pair-Id=K14LZCZ9QUI4JL Apr 07 08:24:16 dynaudio volumio[839]: STREAMING PROXY: Response: 200, length: 72364033 Apr 07 08:24:19 dynaudio volumio[839]: info: CoreCommandRouter::volumioPause Apr 07 08:24:19 dynaudio volumio[839]: info: CoreStateMachine::pause Apr 07 08:24:21 dynaudio volumio[839]: info: CoreCommandRouter::volumioPause Apr 07 08:24:21 dynaudio volumio[839]: info: CoreStateMachine::pause Apr 07 08:24:26 dynaudio volumio[839]: info: CoreCommandRouter::volumioNext Apr 07 08:24:26 dynaudio volumio[839]: info: CoreStateMachine::next Apr 07 08:24:26 dynaudio volumio[839]: info: ControllerMpd::next Apr 07 08:24:26 dynaudio volumio[839]: verbose: ControllerMpd::sendMpdCommand next Apr 07 08:24:27 dynaudio volumio[839]: info: CoreCommandRouter::volumioPause Apr 07 08:24:27 dynaudio volumio[839]: info: CoreStateMachine::pause Apr 07 08:24:28 dynaudio volumio[839]: info: CoreCommandRouter::volumioPause Apr 07 08:24:28 dynaudio volumio[839]: info: CoreStateMachine::pause Apr 07 08:24:28 dynaudio volumio[839]: info: CoreCommandRouter::volumioPause Apr 07 08:24:28 dynaudio volumio[839]: info: CoreStateMachine::pause Apr 07 08:24:55 dynaudio volumio[839]: verbose: New Socket.io Connection to 192.168.1.213 from 192.168.1.50 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 Apr 07 08:24:55 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:24:55 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetQueue Apr 07 08:24:55 dynaudio volumio[839]: info: CoreStateMachine::getQueue Apr 07 08:24:55 dynaudio volumio[839]: info: CorePlayQueue::getQueue Apr 07 08:24:55 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Apr 07 08:24:55 dynaudio volumio[839]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Apr 07 08:24:55 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Apr 07 08:24:55 dynaudio volumio[839]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Apr 07 08:24:55 dynaudio volumio[839]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Apr 07 08:24:55 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Apr 07 08:26:31 dynaudio volumio[839]: verbose: New Socket.io Connection to 192.168.1.213 from 192.168.1.50 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Apr 07 08:26:31 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 07 08:26:31 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetVisibleSources Apr 07 08:26:31 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 07 08:26:31 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:26:31 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 07 08:26:31 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 07 08:26:31 dynaudio volumio[839]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 07 08:26:31 dynaudio volumio[839]: info: Received Get System Info Apr 07 08:26:31 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 07 08:26:31 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 07 08:26:31 dynaudio volumio[839]: info: Discovery: Getting this device information Apr 07 08:26:31 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:26:31 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 07 08:26:31 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:26:31 dynaudio volumio[839]: info: Listing playlists Apr 07 08:26:31 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 07 08:26:34 dynaudio volumio[839]: info: CoreCommandRouter::volumioPause Apr 07 08:26:34 dynaudio volumio[839]: info: CoreStateMachine::pause Apr 07 08:26:34 dynaudio volumio[839]: info: CoreCommandRouter::volumioPause Apr 07 08:26:34 dynaudio volumio[839]: info: CoreStateMachine::pause Apr 07 08:26:35 dynaudio volumio[839]: info: CoreCommandRouter::volumioPause Apr 07 08:26:35 dynaudio volumio[839]: info: CoreStateMachine::pause Apr 07 08:26:38 dynaudio volumio[839]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 07 08:26:38 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 07 08:26:38 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 07 08:26:38 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 07 08:26:38 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Apr 07 08:26:38 dynaudio volumio[839]: info: Received Get System Version Apr 07 08:26:38 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 07 08:26:48 dynaudio volumio[839]: verbose: New Socket.io Connection to 192.168.1.213 from 192.168.1.50 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3.1 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 6 Apr 07 08:26:48 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetState Apr 07 08:26:48 dynaudio volumio[839]: info: CoreCommandRouter::volumioGetQueue Apr 07 08:26:48 dynaudio volumio[839]: info: CoreStateMachine::getQueue Apr 07 08:26:48 dynaudio volumio[839]: info: CorePlayQueue::getQueue Apr 07 08:26:48 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Apr 07 08:26:48 dynaudio volumio[839]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Apr 07 08:26:48 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Apr 07 08:26:48 dynaudio volumio[839]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Apr 07 08:26:48 dynaudio volumio[839]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Apr 07 08:26:48 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Apr 07 08:28:26 dynaudio volumio[839]: info: CALLMETHOD: system_controller system sendBugReport [object Object] Apr 07 08:28:26 dynaudio volumio[839]: info: CoreCommandRouter::executeOnPlugin: system , sendBugReport Apr 07 08:28:27 dynaudio sudo[5288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig Apr 07 08:28:27 dynaudio sudo[5288]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 07 08:28:27 dynaudio sudo[5288]: pam_unix(sudo:session): session closed for user root Apr 07 08:28:33 dynaudio sudo[5349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl -p 7 Apr 07 08:28:33 dynaudio sudo[5349]: pam_unix(sudo:session): session opened for user root by (uid=0)