-- Logs begin at Fri 2024-07-05 14:11:46 CEST, end at Fri 2024-07-05 14:13:06 CEST. -- Jul 05 14:12:00 deqx-gen-4 wireless.js[4523]: trying... Jul 05 14:12:00 deqx-gen-4 sudo[5530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 05 14:12:00 deqx-gen-4 sudo[5530]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:00 deqx-gen-4 sudo[5530]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:00 deqx-gen-4 kernel: meson_uart ffd24000.serial: ttyS1 use xtal(24M) 24000000 change 115200 to 2000000 Jul 05 14:12:00 deqx-gen-4 systemd[1]: Starting Load/Save RF Kill Switch Status... Jul 05 14:12:00 deqx-gen-4 systemd[1]: Started Load/Save RF Kill Switch Status. Jul 05 14:12:00 deqx-gen-4 systemd[1]: Starting Bluetooth service... Jul 05 14:12:00 deqx-gen-4 bluetoothd[5551]: Bluetooth daemon 5.50 Jul 05 14:12:00 deqx-gen-4 bluetoothd[5551]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Jul 05 14:12:00 deqx-gen-4 systemd[1]: Started Bluetooth service. Jul 05 14:12:00 deqx-gen-4 systemd[1]: Reached target Bluetooth. Jul 05 14:12:00 deqx-gen-4 bluetoothd[5551]: Starting SDP server Jul 05 14:12:00 deqx-gen-4 bluetoothd[5551]: Excluding (cli) sap Jul 05 14:12:00 deqx-gen-4 dbus-daemon[4733]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.6' (uid=0 pid=5551 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap " label="kernel") Jul 05 14:12:00 deqx-gen-4 bluetoothd[5551]: Bluetooth management interface 1.14 initialized Jul 05 14:12:00 deqx-gen-4 systemd[1]: Starting Hostname Service... Jul 05 14:12:00 deqx-gen-4 kernel: IPVS: Creating netns size=1928 id=2 Jul 05 14:12:01 deqx-gen-4 dbus-daemon[4733]: [system] Successfully activated service 'org.freedesktop.hostname1' Jul 05 14:12:01 deqx-gen-4 systemd[1]: Started Hostname Service. Jul 05 14:12:01 deqx-gen-4 wireless.js[4523]: trying... Jul 05 14:12:01 deqx-gen-4 sudo[5602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 05 14:12:01 deqx-gen-4 sudo[5602]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:01 deqx-gen-4 sudo[5602]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:02 deqx-gen-4 wireless.js[4523]: trying... Jul 05 14:12:02 deqx-gen-4 sudo[5605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 05 14:12:02 deqx-gen-4 sudo[5605]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:02 deqx-gen-4 sudo[5605]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:03 deqx-gen-4 wireless.js[4523]: trying... Jul 05 14:12:03 deqx-gen-4 sudo[5608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 05 14:12:03 deqx-gen-4 sudo[5608]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:03 deqx-gen-4 sudo[5608]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:03 deqx-gen-4 dhcpcd[5424]: wlan0: using static address 192.168.0.155/24 Jul 05 14:12:03 deqx-gen-4 avahi-daemon[4666]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.155. Jul 05 14:12:03 deqx-gen-4 dhcpcd[5424]: wlan0: adding route to 192.168.0.0/24 Jul 05 14:12:03 deqx-gen-4 dhcpcd[5424]: wlan0: adding default route via 192.168.0.1 Jul 05 14:12:03 deqx-gen-4 avahi-daemon[4666]: New relevant interface wlan0.IPv4 for mDNS. Jul 05 14:12:03 deqx-gen-4 avahi-daemon[4666]: Registering new address record for 192.168.0.155 on wlan0.IPv4. Jul 05 14:12:04 deqx-gen-4 volumio-remote-updater[4622]: [2024-07-05 14:12:04] [info] asio async_connect error: system:111 (Connection refused) Jul 05 14:12:04 deqx-gen-4 volumio-remote-updater[4622]: [2024-07-05 14:12:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jul 05 14:12:04 deqx-gen-4 volumio-remote-updater[4622]: [2024-07-05 14:12:04] [error] handle_connect error: Underlying Transport Error Jul 05 14:12:04 deqx-gen-4 wireless.js[4523]: trying... Jul 05 14:12:04 deqx-gen-4 sudo[5629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jul 05 14:12:04 deqx-gen-4 sudo[5629]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:04 deqx-gen-4 sudo[5629]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:04 deqx-gen-4 wireless.js[4523]: Connected to: ----THELOLW AP2 Jul 05 14:12:04 deqx-gen-4 wireless.js[4523]: ---- Jul 05 14:12:04 deqx-gen-4 sudo[5632]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 14:12:04 deqx-gen-4 sudo[5632]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:04 deqx-gen-4 sudo[5632]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:04 deqx-gen-4 wireless.js[4523]: ... joined AP, wlan0 IPv4 is 192.168.0.155, ipV6 is undefined Jul 05 14:12:04 deqx-gen-4 wireless.js[4523]: It's done! AP Jul 05 14:12:04 deqx-gen-4 systemd[1]: Started Wireless Services. Jul 05 14:12:04 deqx-gen-4 systemd[1]: Started Volumio Backend Module. Jul 05 14:12:04 deqx-gen-4 systemd[1]: Started Start Volumio Kiosk. Jul 05 14:12:04 deqx-gen-4 systemd[1]: Started Volumio Cpu Tweaker. Jul 05 14:12:04 deqx-gen-4 systemd[1]: Reached target Multi-User System. Jul 05 14:12:04 deqx-gen-4 systemd[1]: Reached target Graphical Interface. Jul 05 14:12:04 deqx-gen-4 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: Starting Volumio Kiosk with arguments for cursor display Jul 05 14:12:04 deqx-gen-4 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jul 05 14:12:04 deqx-gen-4 systemd[1]: Started Update UTMP about System Runlevel Changes. Jul 05 14:12:04 deqx-gen-4 systemd[1]: Startup finished in 6.355s (kernel) + 19.001s (userspace) = 25.357s. Jul 05 14:12:04 deqx-gen-4 volumio-cpu-tweak[5647]: Setting RT Priority for mpd Jul 05 14:12:04 deqx-gen-4 volumio-cpu-tweak[5647]: chrt: cannot obtain the list of tasks: No such file or directory Jul 05 14:12:04 deqx-gen-4 volumio-cpu-tweak[5647]: Setting MPD Affinity Jul 05 14:12:04 deqx-gen-4 volumio-cpu-tweak[5647]: pid 3's current affinity mask: 1 Jul 05 14:12:04 deqx-gen-4 volumio-cpu-tweak[5647]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Jul 05 14:12:04 deqx-gen-4 volumio-cpu-tweak[5647]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Jul 05 14:12:04 deqx-gen-4 systemd[1]: volumio_cpu_tweak.service: Succeeded. Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: X.Org X Server 1.20.4 Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: X Protocol Version 11, Revision 0 Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: Build Operating System: Linux 4.19.0-18-armmp-lpae armv7l Debian Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: Current Operating System: Linux deqx-gen-4 4.9.241 #4 SMP PREEMPT Tue Oct 17 09:27:12 CEST 2023 aarch64 Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: Kernel command line: imgpart=UUID=9a686ccb-06eb-4ec9-abc8-088506fd99ec imgfile=/volumio_current.sqsh bootpart=UUID=005B-3610 datapart=UUID=f4c4c2c8-7ee5-47ec-9c34-f674872d10fe bootconfig=env.system.txt hwdevice=VIMX console=tty0 console=ttyS0,115200n8 no_console_suspend consoleblank=0 logo=osd0,loaded,0x3d800000,1080p60hz vout=1080p60hz,enable hdmimode=1080p60hz plymouth.ignore-serial-consoles fsck.repair=yes net.ifnames=0 ddr_size= wol_enable= mac=02:00:00:0c:07:01 fan=auto hwver=Unknow coherent_pool=2M quiet loglevel=0 splash bootdelay=3 Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: Build Date: 18 December 2021 09:05:36AM Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: xorg-server 2:1.20.4-1+deb10u4 (https://www.debian.org/support) Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: Current version of pixman: 0.36.0 Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: Before reporting problems, check http://wiki.x.org Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: to make sure that you have the latest version. Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: (++) from command line, (!!) notice, (II) informational, Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: (==) Log file: "/var/log/Xorg.0.log", Time: Fri Jul 5 14:12:04 2024 Jul 05 14:12:04 deqx-gen-4 startkiosk.sh[5639]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 05 14:12:05 deqx-gen-4 kernel: fb: osd[0] canvas.idx =0x40 Jul 05 14:12:05 deqx-gen-4 kernel: fb: osd[0] canvas.addr=0x70000000 Jul 05 14:12:05 deqx-gen-4 kernel: fb: osd[0] canvas.width=7680 Jul 05 14:12:05 deqx-gen-4 kernel: fb: osd[0] canvas.height=1080 Jul 05 14:12:05 deqx-gen-4 kernel: fb: osd[0] frame.width=1920 Jul 05 14:12:05 deqx-gen-4 kernel: fb: osd[0] frame.height=1080 Jul 05 14:12:05 deqx-gen-4 kernel: fb: osd[0] out_addr_id =0x1 Jul 05 14:12:05 deqx-gen-4 kernel: fb: osd[0] enable: 0 (Xorg) Jul 05 14:12:05 deqx-gen-4 systemd[1]: systemd-rfkill.service: Succeeded. Jul 05 14:12:06 deqx-gen-4 kernel: fb: osd[0] enable: 1 (Xorg) Jul 05 14:12:07 deqx-gen-4 kernel: fb: osd[0] enable: 1 (Xorg) Jul 05 14:12:07 deqx-gen-4 volumio[5638]: info: ------------------------------------------- Jul 05 14:12:07 deqx-gen-4 volumio[5638]: info: ----- Volumio3 ---- Jul 05 14:12:07 deqx-gen-4 volumio[5638]: info: ------------------------------------------- Jul 05 14:12:07 deqx-gen-4 volumio[5638]: info: ----- System startup ---- Jul 05 14:12:07 deqx-gen-4 volumio[5638]: info: ------------------------------------------- Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: MYVOLUMIO Environment detected Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Plugin folders cleanup Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Scanning into folder /volumio/app/plugins/ Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Scanning category audio_interface Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Scanning category miscellanea Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Scanning category music_service Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Scanning category plugins.json Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Scanning category system_controller Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Scanning category user_interface Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Scanning into folder /data/plugins/ Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Plugin folders cleanup completed Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: ------------------------------------------- Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: ----- Core plugins startup ---- Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: ------------------------------------------- Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Loading plugins from folder /volumio/app/plugins/ Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Adding plugin bluetooth to MyMusic Plugins Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Adding plugin multiroom to MyMusic Plugins Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Adding plugin upnp to MyMusic Plugins Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Adding plugin metavolumio to MyMusic Plugins Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Adding plugin cd_controller to MyMusic Plugins Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Loading plugins from folder /data/plugins/ Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Loading plugin "system"... Jul 05 14:12:09 deqx-gen-4 volumio[5638]: info: Loading plugin "appearance"... Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: Loading plugin "network"... Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: Refreshing Cached IP Addresses Jul 05 14:12:11 deqx-gen-4 sudo[5850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 14:12:11 deqx-gen-4 sudo[5850]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: Loading plugin "services"... Jul 05 14:12:11 deqx-gen-4 sudo[5853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 14:12:11 deqx-gen-4 sudo[5850]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:11 deqx-gen-4 sudo[5853]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: Loading plugin "alsa_controller"... Jul 05 14:12:11 deqx-gen-4 sudo[5853]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:11 deqx-gen-4 sudo[5859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 05 14:12:11 deqx-gen-4 sudo[5859]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:11 deqx-gen-4 kernel: wl_escan_set_scan: ioctl_ver=2 Jul 05 14:12:11 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:11 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:11 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:11 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:11 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:11 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:11 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: Loading plugin "wizard"... Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: Loading plugin "networkfs"... Jul 05 14:12:11 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: Starting Udev Watcher for removable devices Jul 05 14:12:11 deqx-gen-4 sudo[5873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.200/Qmultimedia /mnt/NAS/Qmultimedia Jul 05 14:12:11 deqx-gen-4 sudo[5873]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: Ignoring mount for partition: BOOT Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: Ignoring mount for partition: volumio Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: Ignoring mount for partition: volumio_data Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: Loading plugin "volumio_command_line_client"... Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: Loading plugin "upnp"... Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: [1720181531624] Starting Upmpd Daemon Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: Loading plugin "my_music"... Jul 05 14:12:11 deqx-gen-4 volumio[5638]: info: Loading plugin "mpd"... Jul 05 14:12:11 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:11 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:11 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:11 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:11 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:11 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:11 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:12 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:12 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:12 deqx-gen-4 volumio[5638]: info: Loading plugin "upnp_browser"... Jul 05 14:12:12 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:12 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:12 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:12 deqx-gen-4 volumio[5638]: info: Loading plugin "bluetooth"... Jul 05 14:12:12 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:13 deqx-gen-4 volumio[5638]: info: [1720181533000] Starting BluetoothController Jul 05 14:12:13 deqx-gen-4 volumio[5638]: info: Loading plugin "alarm-clock"... Jul 05 14:12:13 deqx-gen-4 volumio[5638]: info: Loading plugin "manifestui"... Jul 05 14:12:13 deqx-gen-4 sudo[5873]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:13 deqx-gen-4 kernel: CIFS VFS: cifs_mount failed w/return code = -95 Jul 05 14:12:13 deqx-gen-4 volumio[5638]: info: Loading plugin "metavolumio"... Jul 05 14:12:13 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:14 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:14 deqx-gen-4 volumio-remote-updater[4622]: [2024-07-05 14:12:14] [connect] Successful connection Jul 05 14:12:14 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:15 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:15 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 8 Jul 05 14:12:15 deqx-gen-4 volumio[5638]: info: Loading plugin "airplay_emulation"... Jul 05 14:12:15 deqx-gen-4 volumio[5638]: info: Starting Shairport Sync Jul 05 14:12:15 deqx-gen-4 volumio[5638]: info: Loading plugin "cd_controller"... Jul 05 14:12:15 deqx-gen-4 kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc050c33000 drv_status 0x0 e_type 69 e_states 0 Jul 05 14:12:15 deqx-gen-4 sudo[5859]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:15 deqx-gen-4 volumio[5638]: info: Loading plugin "last_100"... Jul 05 14:12:15 deqx-gen-4 volumio[5638]: info: Loading plugin "streaming_services"... Jul 05 14:12:18 deqx-gen-4 volumio[5638]: info: Starting Streaming Service Transparent Proxy Jul 05 14:12:18 deqx-gen-4 volumio[5638]: info: Loading plugin "tidalconnect"... Jul 05 14:12:18 deqx-gen-4 volumio[5638]: info: Loading plugin "webradio"... Jul 05 14:12:19 deqx-gen-4 systemd[1]: systemd-fsckd.service: Succeeded. Jul 05 14:12:19 deqx-gen-4 volumio[5638]: info: Loading plugin "i2s_dacs"... Jul 05 14:12:19 deqx-gen-4 volumio[5638]: info: I2S DAC not set, start Auto-detection Jul 05 14:12:19 deqx-gen-4 volumio[5638]: info: Loading plugin "volumiodiscovery"... Jul 05 14:12:19 deqx-gen-4 volumio[5638]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:19 deqx-gen-4 volumio[5638]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:19 deqx-gen-4 node[5638]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:19 deqx-gen-4 volumio[5638]: *** WARNING *** For more information see Jul 05 14:12:19 deqx-gen-4 volumio[5638]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:19 deqx-gen-4 volumio[5638]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:19 deqx-gen-4 volumio[5638]: *** WARNING *** For more information see Jul 05 14:12:19 deqx-gen-4 node[5638]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:19 deqx-gen-4 node[5638]: *** WARNING *** For more information see Jul 05 14:12:19 deqx-gen-4 node[5638]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:19 deqx-gen-4 node[5638]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:19 deqx-gen-4 node[5638]: *** WARNING *** For more information see Jul 05 14:12:19 deqx-gen-4 volumio[5638]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 14:12:19 deqx-gen-4 volumio[5638]: info: Discovery: Started advertising with name: DEQX Gen-4 Jul 05 14:12:19 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:19 deqx-gen-4 volumio[5638]: info: Loading plugin "multiroom"... Jul 05 14:12:20 deqx-gen-4 volumio[5638]: STREAMING PROXY: Starting server on port 3245 Jul 05 14:12:20 deqx-gen-4 volumio[5638]: Node JS runtime: 14 Jul 05 14:12:21 deqx-gen-4 volumio[5638]: info: Applying required configuration parameters for plugin multiroom Jul 05 14:12:21 deqx-gen-4 sudo[6076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jul 05 14:12:21 deqx-gen-4 sudo[6076]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:21 deqx-gen-4 sudo[6076]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:21 deqx-gen-4 volumio[5638]: info: MRS: MultiRoom plugin initialized Jul 05 14:12:21 deqx-gen-4 volumio[5638]: info: MRS: STOPPING SNAPCLIENT Jul 05 14:12:21 deqx-gen-4 volumio[5638]: info: MRS: Snap server stop Jul 05 14:12:21 deqx-gen-4 volumio[5638]: info: MRS: STOPPING volumioStreaming Jul 05 14:12:21 deqx-gen-4 sudo[6092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jul 05 14:12:21 deqx-gen-4 sudo[6092]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:21 deqx-gen-4 sudo[6094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jul 05 14:12:21 deqx-gen-4 sudo[6094]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:22 deqx-gen-4 volumio[5638]: info: Loading plugin "outputs"... Jul 05 14:12:22 deqx-gen-4 sudo[6099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jul 05 14:12:22 deqx-gen-4 sudo[6099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:22 deqx-gen-4 sudo[6092]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:22 deqx-gen-4 volumio[5638]: info: Loading plugin "albumart"... Jul 05 14:12:22 deqx-gen-4 sudo[6094]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:22 deqx-gen-4 sudo[6100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jul 05 14:12:22 deqx-gen-4 sudo[6100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:22 deqx-gen-4 sudo[6100]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:22 deqx-gen-4 volumio[5638]: info: Plugin example_plugin is not enabled Jul 05 14:12:22 deqx-gen-4 volumio[5638]: info: Loading plugin "hi_res_audio"... Jul 05 14:12:22 deqx-gen-4 sudo[6099]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:22 deqx-gen-4 volumio[5638]: Forking 3 albumart workers Jul 05 14:12:24 deqx-gen-4 volumio[5638]: Starting albumart workers Jul 05 14:12:24 deqx-gen-4 volumio[5638]: Starting albumart workers Jul 05 14:12:24 deqx-gen-4 volumio[5638]: Starting albumart workers Jul 05 14:12:24 deqx-gen-4 volumio[5638]: info: Applying required configuration parameters for plugin hi_res_audio Jul 05 14:12:24 deqx-gen-4 volumio[5638]: info: Loading plugin "inputs"... Jul 05 14:12:24 deqx-gen-4 volumio[5638]: info: Loading plugin "qobuz"... Jul 05 14:12:26 deqx-gen-4 volumio[5638]: info: Loading plugin "smart_inputs"... Jul 05 14:12:26 deqx-gen-4 volumio[5638]: info: Loading plugin "tidal"... Jul 05 14:12:28 deqx-gen-4 volumio[5638]: info: Loading plugin "deqx_control"... Jul 05 14:12:29 deqx-gen-4 volumio[5638]: info: Applying required configuration parameters for plugin deqx_control Jul 05 14:12:29 deqx-gen-4 volumio[5638]: info: Loading plugin "oem_helper"... Jul 05 14:12:29 deqx-gen-4 volumio-remote-updater[4622]: [2024-07-05 14:12:29] [connect] Successful connection Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: Applying required configuration parameters for plugin oem_helper Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: Loading plugin "updater_comm"... Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: Plugin mpdemulation is not enabled Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: Loading plugin "rest_api"... Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: Loading plugin "websocket"... Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: Loading i18n strings for locale sv Jul 05 14:12:30 deqx-gen-4 volumio[5638]: Updating browse sources language Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::initPlayerControls Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:30 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:30 deqx-gen-4 volumio[5638]: Express server listening on port 3000 Jul 05 14:12:30 deqx-gen-4 volumio[5638]: [Metrics] WebUI: 24s 190.09ms Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreStateMachine::resetVolumioState Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getcurrentVolume Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:31 deqx-gen-4 systemd[1]: systemd-hostnamed.service: Succeeded. Jul 05 14:12:31 deqx-gen-4 sudo[6161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 14:12:31 deqx-gen-4 sudo[6161]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:31 deqx-gen-4 volumio[5638]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: Completed loading Core Plugins Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: Preparing to generate the ALSA configuration file Jul 05 14:12:31 deqx-gen-4 sudo[6161]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:31 deqx-gen-4 sudo[6165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 14:12:31 deqx-gen-4 sudo[6165]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:31 deqx-gen-4 sudo[6165]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:31 deqx-gen-4 volumio[5638]: Unable to parse: Jul 05 14:12:31 deqx-gen-4 volumio[5638]: Simple mixer control 'Audio hdmi-out mute',0 Jul 05 14:12:31 deqx-gen-4 volumio[5638]: Capabilities: pswitch pswitch-joined Jul 05 14:12:31 deqx-gen-4 volumio[5638]: Playback channels: Mono Jul 05 14:12:31 deqx-gen-4 volumio[5638]: Mono: Playback [on] Jul 05 14:12:31 deqx-gen-4 volumio[5638]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: MRS: Removed streaming files Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: MRS: volumioStreaming STOPPED Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: MRS: SNAPSERVER STOPPED Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: MRS: SNAPCLIENT STOPPED Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: Cannot mount NAS Qmultimedia at system boot, trial number 1 ,retrying in 5 seconds Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: Reading ALSA contributions from plugins. Jul 05 14:12:31 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 1 Jul 05 14:12:31 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 2 Jul 05 14:12:31 deqx-gen-4 volumio-remote-updater[4622]: [2024-07-05 14:12:31] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720181549 101 Jul 05 14:12:31 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Jul 05 14:12:31 deqx-gen-4 volumio[5638]: Unable to parse: Jul 05 14:12:31 deqx-gen-4 volumio[5638]: Simple mixer control 'Audio hdmi-out mute',0 Jul 05 14:12:31 deqx-gen-4 volumio[5638]: Capabilities: pswitch pswitch-joined Jul 05 14:12:31 deqx-gen-4 volumio[5638]: Playback channels: Mono Jul 05 14:12:31 deqx-gen-4 volumio[5638]: Mono: Playback [on] Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: VolumeController:: Volume=undefined Mute =false Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreStateMachine::updateTrackBlock Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrackBlock Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreStateMachine::setRepeat false single undefined Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreStateMachine::setRandom false Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:31 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 4 Jul 05 14:12:31 deqx-gen-4 volumio[5638]: Unable to parse: Jul 05 14:12:31 deqx-gen-4 volumio[5638]: Simple mixer control 'Audio hdmi-out mute',0 Jul 05 14:12:31 deqx-gen-4 volumio[5638]: Capabilities: pswitch pswitch-joined Jul 05 14:12:31 deqx-gen-4 volumio[5638]: Playback channels: Mono Jul 05 14:12:31 deqx-gen-4 volumio[5638]: Mono: Playback [on] Jul 05 14:12:31 deqx-gen-4 volumio[5638]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jul 05 14:12:31 deqx-gen-4 sudo[6193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 05 14:12:31 deqx-gen-4 sudo[6193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:31 deqx-gen-4 systemd[1]: Started UPnP Renderer front-end to MPD. Jul 05 14:12:31 deqx-gen-4 sudo[6193]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: Reloading queue from file Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: Discovery: adding 45fc25a4-65bf-4aa2-8476-fe596892b704 Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: Discovery: Found device DEQX Gen-4 Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output for this device Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: Adding audio output: Jul 05 14:12:31 deqx-gen-4 volumio[5638]: info: Adding audio output: Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Upmpdcli Daemon Started Jul 05 14:12:32 deqx-gen-4 volumio[5638]: Unable to parse: Jul 05 14:12:32 deqx-gen-4 volumio[5638]: Simple mixer control 'Audio hdmi-out mute',0 Jul 05 14:12:32 deqx-gen-4 volumio[5638]: Capabilities: pswitch pswitch-joined Jul 05 14:12:32 deqx-gen-4 volumio[5638]: Playback channels: Mono Jul 05 14:12:32 deqx-gen-4 volumio[5638]: Mono: Playback [on] Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: VolumeController:: Volume=undefined Mute =false Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Asound.conf file unchanged, so no further update is needed Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Output device has changed, restarting MPD Jul 05 14:12:32 deqx-gen-4 sudo[6217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:32 deqx-gen-4 sudo[6217]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Output device has changed, restarting Shairport Sync Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:32 deqx-gen-4 sudo[6217]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:32 deqx-gen-4 sudo[6220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:32 deqx-gen-4 sudo[6220]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:32 deqx-gen-4 systemd[1]: Listening on mpd.socket. Jul 05 14:12:32 deqx-gen-4 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: ___________ START PLUGINS ___________ Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: ControllerMpd::onStart: Initializing MPD Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Creating MPD Configuration file Jul 05 14:12:32 deqx-gen-4 kernel: aml_tdm_open Jul 05 14:12:32 deqx-gen-4 kernel: Not init audio effects Jul 05 14:12:32 deqx-gen-4 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 05 14:12:32 deqx-gen-4 kernel: tdm playback mute: 1, lane_cnt = 8 Jul 05 14:12:32 deqx-gen-4 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 05 14:12:32 deqx-gen-4 sudo[6239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:32 deqx-gen-4 sudo[6239]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:32 deqx-gen-4 sudo[6239]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: [1720181552288] CoreMusicLibrary::Adding element Mediaservrar Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:32 deqx-gen-4 sudo[6244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:32 deqx-gen-4 sudo[6244]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Adding Manifest REST API Endpoints Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Adding METAVOLUMIO REST API Endpoints Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jul 05 14:12:32 deqx-gen-4 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 14:12:32 deqx-gen-4 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:32 deqx-gen-4 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:32 deqx-gen-4 sudo[6253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name DEQX Gen-4 Jul 05 14:12:32 deqx-gen-4 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:32 deqx-gen-4 sudo[6253]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:32 deqx-gen-4 sudo[6253]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Preparing CD Folders Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Adding CD REST API Endpoints Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Starting UDEV Watcher for CD Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Detecting CD presence with UDEV Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: [1720181552554] CoreMusicLibrary::Adding element Last_100 Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Streaming services startup Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Starting Streaming Daemon Jul 05 14:12:32 deqx-gen-4 sudo[6280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 05 14:12:32 deqx-gen-4 sudo[6280]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:32 deqx-gen-4 sudo[6280]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: [1720181552704] CoreMusicLibrary::Adding element Webradio Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jul 05 14:12:32 deqx-gen-4 sudo[6289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 05 14:12:32 deqx-gen-4 sudo[6289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:32 deqx-gen-4 sudo[6289]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Adding HIGHRESAUDIO REST API Endpoints Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Adding inputs REST Endpoints Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jul 05 14:12:32 deqx-gen-4 kernel: aml_tdm_open Jul 05 14:12:32 deqx-gen-4 kernel: Not init audio effects Jul 05 14:12:32 deqx-gen-4 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 05 14:12:32 deqx-gen-4 kernel: tdm playback mute: 1, lane_cnt = 8 Jul 05 14:12:32 deqx-gen-4 kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Scanning Audio Inputs Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Checking against Known Cards name Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Checking against Known Cards name Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Checking against Known Cards name Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Checking against Known Cards name Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Checking against Known Cards name Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Adding Server instance for streaming Jul 05 14:12:32 deqx-gen-4 volumio[5638]: info: Refreshing TIDAL token Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: Volumio Calling Home Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: Stopping AccessToken refresher cron for QOBUZ Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: AccessToken refresher cron started for QOBUZ Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: Adding QOBUZ REST API Endpoints Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jul 05 14:12:33 deqx-gen-4 volumio[5638]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: Discovery: this is already registered, 45fc25a4-65bf-4aa2-8476-fe596892b704 Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: Discovery: Found device DEQX Gen-4 Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:33 deqx-gen-4 volumio[5638]: error: Cannot start Volumio Streaming Daemon Jul 05 14:12:33 deqx-gen-4 volumio[5638]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 05 14:12:33 deqx-gen-4 volumio[5638]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: Bluetooth name changed to DEQX Gen-4 Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: MPD Permissions set Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: MPD Permissions set Jul 05 14:12:33 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 5 Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: Volumio called home Jul 05 14:12:33 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:33 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 7 Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: Starting Shairport Sync Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: Starting Shairport Sync Jul 05 14:12:33 deqx-gen-4 sudo[6326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:33 deqx-gen-4 sudo[6326]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:33 deqx-gen-4 volumio[5638]: info: Starting Shairport Sync Jul 05 14:12:33 deqx-gen-4 sudo[6328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:33 deqx-gen-4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:12:33 deqx-gen-4 sudo[6328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:33 deqx-gen-4 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:12:33 deqx-gen-4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:33 deqx-gen-4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:33 deqx-gen-4 sudo[6326]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:33 deqx-gen-4 sudo[6331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:33 deqx-gen-4 sudo[6331]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:33 deqx-gen-4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:12:33 deqx-gen-4 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 05 14:12:33 deqx-gen-4 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:12:33 deqx-gen-4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:33 deqx-gen-4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:33 deqx-gen-4 sudo[6328]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:33 deqx-gen-4 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:12:34 deqx-gen-4 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 05 14:12:34 deqx-gen-4 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:12:34 deqx-gen-4 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:34 deqx-gen-4 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:34 deqx-gen-4 sudo[6331]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:34 deqx-gen-4 volumio[5638]: info: Shairport-Sync Started Jul 05 14:12:34 deqx-gen-4 volumio[5638]: Error adding Membership: Error: addMembership EINVAL Jul 05 14:12:34 deqx-gen-4 volumio[5638]: info: MRS: Found cast device: S70CDS-d49a1638645f2c6357126eafe373042f Jul 05 14:12:34 deqx-gen-4 volumio[5638]: info: Adding audio output: Jul 05 14:12:34 deqx-gen-4 volumio[5638]: info: MRS: Found cast device: S70CDS-d49a1638645f2c6357126eafe373042f Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 8 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: info: Shairport-Sync Started Jul 05 14:12:34 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:34 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 9 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 10 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: info: Access Token successfully retrieved Jul 05 14:12:34 deqx-gen-4 volumio[5638]: error: Hi Res Audio Failed Login: [object Object] Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 11 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 12 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 13 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 14 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 15 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 16 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: info: Successfully retrieved User Session From TIDAL Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 17 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 18 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 19 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 20 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 21 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 22 Jul 05 14:12:34 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 23 Jul 05 14:12:35 deqx-gen-4 volumio[5638]: info: Successfully retrieved User Subscription From TIDAL Jul 05 14:12:35 deqx-gen-4 volumio[5638]: info: Adding TIDAL to Browse Sources Jul 05 14:12:35 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:35 deqx-gen-4 volumio[5638]: info: [1720181555026] CoreMusicLibrary::Adding element TIDAL Jul 05 14:12:35 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:35 deqx-gen-4 volumio[5638]: Cannot find translation for source TIDAL Jul 05 14:12:35 deqx-gen-4 volumio[5638]: info: Stopping AccessToken refresher cron Jul 05 14:12:35 deqx-gen-4 volumio[5638]: info: AccessToken refresher cron started Jul 05 14:12:35 deqx-gen-4 volumio[5638]: info: Adding TIDAL REST API Endpoints Jul 05 14:12:35 deqx-gen-4 volumio[5638]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jul 05 14:12:35 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 24 Jul 05 14:12:35 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 25 Jul 05 14:12:35 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 26 Jul 05 14:12:35 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 27 Jul 05 14:12:35 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 28 Jul 05 14:12:35 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 29 Jul 05 14:12:35 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 30 Jul 05 14:12:35 deqx-gen-4 sudo[6358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jul 05 14:12:35 deqx-gen-4 sudo[6358]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:35 deqx-gen-4 systemd[1]: Started Volumio Bluetooth Module. Jul 05 14:12:35 deqx-gen-4 sudo[6358]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:35 deqx-gen-4 volumio[5638]: info: Volumio BT Module successfully started Jul 05 14:12:35 deqx-gen-4 sudo[6373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jul 05 14:12:35 deqx-gen-4 sudo[6373]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:35 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 31 Jul 05 14:12:35 deqx-gen-4 volumiobt[6360]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jul 05 14:12:35 deqx-gen-4 sudo[6373]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:35 deqx-gen-4 mpd[6254]: Jul 05 14:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 05 14:12:35 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 32 Jul 05 14:12:35 deqx-gen-4 systemd[1]: Started Music Player Daemon. Jul 05 14:12:35 deqx-gen-4 sudo[6220]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:35 deqx-gen-4 sudo[6244]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:35 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 33 Jul 05 14:12:35 deqx-gen-4 volumio[5638]: error: MPD error: The expression evaluated to a falsy value: Jul 05 14:12:35 deqx-gen-4 volumio[5638]: assert.ok(self.idling) Jul 05 14:12:35 deqx-gen-4 volumio[5638]: error: The expression evaluated to a falsy value: Jul 05 14:12:35 deqx-gen-4 volumio[5638]: assert.ok(self.idling) Jul 05 14:12:35 deqx-gen-4 volumio[5638]: error: updateQueue error: null Jul 05 14:12:35 deqx-gen-4 volumio[5638]: info: TidalConnect service stoped! Jul 05 14:12:35 deqx-gen-4 volumio[5638]: info: MPD running with PID6254 Jul 05 14:12:35 deqx-gen-4 volumio[5638]: ,establishing connection Jul 05 14:12:35 deqx-gen-4 volumio[5638]: error: updateQueue error: null Jul 05 14:12:35 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 34 Jul 05 14:12:35 deqx-gen-4 volumio[5638]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 05 14:12:35 deqx-gen-4 volumio[5638]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 05 14:12:35 deqx-gen-4 sudo[6403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 05 14:12:35 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to 192.168.0.155 from 192.168.0.55 UA: Mozilla/5.0 (iPad; CPU OS 17_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) CriOS/126.0.6478.153 Mobile/15E148 Safari/604.1 Total Clients: 35 Jul 05 14:12:35 deqx-gen-4 sudo[6403]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:36 deqx-gen-4 systemd[1]: Started Volumio Tidal Connect Service. Jul 05 14:12:36 deqx-gen-4 sudo[6403]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: Received Get System Info Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: Discovery: Getting this device information Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: Received Get System Info Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: Discovery: Getting this device information Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: Listing playlists Jul 05 14:12:36 deqx-gen-4 volumio[6195]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 05 14:12:36 deqx-gen-4 sudo[6453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.200/Qmultimedia /mnt/NAS/Qmultimedia Jul 05 14:12:36 deqx-gen-4 sudo[6453]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:36 deqx-gen-4 kernel: CIFS VFS: cifs_mount failed w/return code = -95 Jul 05 14:12:36 deqx-gen-4 sudo[6453]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: Cannot mount NAS Qmultimedia at system boot, trial number 2 ,retrying in 5 seconds Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: Executing endpoint tc_getconfig Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 05 14:12:36 deqx-gen-4 sudo[6461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jul 05 14:12:36 deqx-gen-4 sudo[6461]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:36 deqx-gen-4 vtcs[6405]: STARTING TidalConnect services, version: 1.3.0.19 Jul 05 14:12:36 deqx-gen-4 volumiobt[6360]: pulseaudio: no process found Jul 05 14:12:36 deqx-gen-4 sudo[6461]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:36 deqx-gen-4 vtcs[6405]: STARTED TidalConnect services. Jul 05 14:12:36 deqx-gen-4 dbus-daemon[4733]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.22' (uid=1000 pid=6469 comm="/usr/bin/pulseaudio --high-priority=true " label="kernel") Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: Executing endpoint tc_connect Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: Connecting to TidalConnect Jul 05 14:12:36 deqx-gen-4 systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jul 05 14:12:36 deqx-gen-4 volumio[5638]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::servicePushState Jul 05 14:12:36 deqx-gen-4 kernel: IPVS: Creating netns size=1928 id=3 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jul 05 14:12:36 deqx-gen-4 volumio[5638]: verbose: CURRENT POSITION 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState stateService stop Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState currentStatus stop Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:36 deqx-gen-4 dbus-daemon[4733]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:36 deqx-gen-4 systemd[1]: Started RealtimeKit Scheduling Policy Service. Jul 05 14:12:36 deqx-gen-4 rtkit-daemon[6475]: Successfully called chroot. Jul 05 14:12:36 deqx-gen-4 rtkit-daemon[6475]: Successfully dropped privileges. Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:36 deqx-gen-4 rtkit-daemon[6475]: Successfully limited resources. Jul 05 14:12:36 deqx-gen-4 rtkit-daemon[6475]: Running. Jul 05 14:12:36 deqx-gen-4 rtkit-daemon[6475]: Watchdog thread running. Jul 05 14:12:36 deqx-gen-4 rtkit-daemon[6475]: Canary thread running. Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: No code Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:36 deqx-gen-4 dbus-daemon[4733]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.23' (uid=0 pid=6475 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel") Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::servicePushState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jul 05 14:12:36 deqx-gen-4 volumio[5638]: verbose: CURRENT POSITION 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState stateService stop Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState currentStatus stop Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: No code Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:36 deqx-gen-4 systemd[1]: Starting Authorization Manager... Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:36 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:36 deqx-gen-4 volumio[5638]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 05 14:12:36 deqx-gen-4 polkitd[6516]: started daemon version 0.105 using authority implementation `local' version `0.105' Jul 05 14:12:36 deqx-gen-4 dbus-daemon[4733]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jul 05 14:12:36 deqx-gen-4 systemd[1]: Started Authorization Manager. Jul 05 14:12:37 deqx-gen-4 pulseaudio[6469]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 05 14:12:37 deqx-gen-4 pulseaudio[6469]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: Applying permissions Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: Setting BT discoverable and pairable Jul 05 14:12:37 deqx-gen-4 bluetoothd[5551]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSource Jul 05 14:12:37 deqx-gen-4 bluetoothd[5551]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSink Jul 05 14:12:37 deqx-gen-4 pulseaudio[6469]: 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 Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: Agent registered Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: [bluetooth]# -e power on Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: Invalid command in menu main: -e Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: Use "help" for a list of available commands in a menu. Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: Use "menu " if you want to enter any submenu. Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: Use "back" if you want to return to menu main. Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: [bluetooth]# agent on Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: Agent is already registered Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: [bluetooth]# discoverable on Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: [bluetooth]# pairable on Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: [bluetooth]# agent NoInputNoOutput Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: Agent is already registered Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: [bluetooth]# default-agent Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: [bluetooth]# quit Jul 05 14:12:37 deqx-gen-4 volumiobt[6360]: [59B blob data] Jul 05 14:12:37 deqx-gen-4 volumio[5638]: info: MRS: Getting audio outputs on start Jul 05 14:12:37 deqx-gen-4 volumio[5638]: info: MRS: Requesting all other devices output Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: Executing endpoint metavolumio Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: Executing endpoint metavolumio Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: Executing endpoint metavolumio Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPlay Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: CoreStateMachine::play index undefined Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: CoreStateMachine::startPlaybackTimer Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: [1720181558939] ControllerUPNPBrowser::clearAddPlayTrack Jul 05 14:12:38 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand stop Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: sendMpdCommand stop took 3 milliseconds Jul 05 14:12:38 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand clear Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: sendMpdCommand clear took 1 milliseconds Jul 05 14:12:38 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.200:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1767435.flac" Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:38 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces system playlist update Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: Ignoring MPD Status Update Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:38 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces system playlist update Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: Ignoring MPD Status Update Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:38 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces system playlist update Jul 05 14:12:38 deqx-gen-4 volumio[5638]: info: Ignoring MPD Status Update Jul 05 14:12:38 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.200:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1767435.flac" Jul 05 14:12:39 deqx-gen-4 volumio[5638]: error: updateQueue error: null Jul 05 14:12:39 deqx-gen-4 volumio[5638]: error: updateQueue error: null Jul 05 14:12:39 deqx-gen-4 volumio[5638]: error: updateQueue error: null Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:39 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces system playlist update Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Ignoring MPD Status Update Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 55ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 53ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 51ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand add "http://192.168.0.200:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1767435.flac" took 7 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:39 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces system playlist update Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Ignoring MPD Status Update Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:39 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces system playlist update Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Ignoring MPD Status Update Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 14ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand play took 10 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 9ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 7ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:39 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces state update: player Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::getState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:39 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces state update: player Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::getState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:39 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces state update: player Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::getState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: TidalConnect service started! Jul 05 14:12:39 deqx-gen-4 kernel: aml_tdm_open Jul 05 14:12:39 deqx-gen-4 kernel: Not init audio effects Jul 05 14:12:39 deqx-gen-4 kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Jul 05 14:12:39 deqx-gen-4 kernel: set mclk:24576000, mpll:49152000, get mclk:24575987, mpll:49151974 Jul 05 14:12:39 deqx-gen-4 kernel: asoc aml_dai_set_tdm_fmt, 0x4001, ffffffc03d27b418, id(1), clksel(1) Jul 05 14:12:39 deqx-gen-4 kernel: aml_dai_set_tdm_fmt(), fmt not change Jul 05 14:12:39 deqx-gen-4 kernel: dump_pcm_setting(ffffffc03d27b418) Jul 05 14:12:39 deqx-gen-4 kernel: pcm_mode(1) Jul 05 14:12:39 deqx-gen-4 kernel: sysclk(24576000) Jul 05 14:12:39 deqx-gen-4 kernel: sysclk_bclk_ratio(4) Jul 05 14:12:39 deqx-gen-4 kernel: bclk(6144000) Jul 05 14:12:39 deqx-gen-4 kernel: bclk_lrclk_ratio(64) Jul 05 14:12:39 deqx-gen-4 kernel: lrclk(96000) Jul 05 14:12:39 deqx-gen-4 kernel: tx_mask(0x3) Jul 05 14:12:39 deqx-gen-4 kernel: rx_mask(0x3) Jul 05 14:12:39 deqx-gen-4 kernel: slots(2) Jul 05 14:12:39 deqx-gen-4 kernel: slot_width(32) Jul 05 14:12:39 deqx-gen-4 kernel: lane_mask_in(0x2) Jul 05 14:12:39 deqx-gen-4 kernel: lane_mask_out(0x1) Jul 05 14:12:39 deqx-gen-4 kernel: lane_oe_mask_in(0x0) Jul 05 14:12:39 deqx-gen-4 kernel: lane_oe_mask_out(0x0) Jul 05 14:12:39 deqx-gen-4 kernel: lane_lb_mask_in(0x0) Jul 05 14:12:39 deqx-gen-4 kernel: aml_dai_set_tdm_sysclk(), mpll no change, keep clk Jul 05 14:12:39 deqx-gen-4 kernel: aml_dai_set_tdm_sysclk(), mclk no change, keep clk Jul 05 14:12:39 deqx-gen-4 kernel: set mclk:24576000, mpll:49152000, get mclk:24575987, mpll:49151974 Jul 05 14:12:39 deqx-gen-4 kernel: aml_dai_set_clkdiv, div 4, clksel(1) Jul 05 14:12:39 deqx-gen-4 kernel: aml_dai_set_bclk_ratio, select I2S mode Jul 05 14:12:39 deqx-gen-4 kernel: aml_dai_tdm_hw_params(), enable mclk for TDM-B Jul 05 14:12:39 deqx-gen-4 kernel: aml_tdm_prepare(), reset fddr Jul 05 14:12:39 deqx-gen-4 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 05 14:12:39 deqx-gen-4 kernel: spdif_info: rate: 96000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xa00, ch1_r:0xa00 Jul 05 14:12:39 deqx-gen-4 kernel: hdmitx: hw: set audio Jul 05 14:12:39 deqx-gen-4 kernel: hdmitx: hw: hdmitx tx_aud_src = 0 Jul 05 14:12:39 deqx-gen-4 kernel: hdmitx: fs = 5, cd = 4, tmds_clk = 148352 Jul 05 14:12:39 deqx-gen-4 kernel: hdmitx: hw: aud_n_para = 11648 Jul 05 14:12:39 deqx-gen-4 kernel: hdmitx: hw: set channel status Jul 05 14:12:39 deqx-gen-4 kernel: hdmitx: audio: Audio Type: PCM Jul 05 14:12:39 deqx-gen-4 kernel: hdmitx: audio: set audio param Jul 05 14:12:39 deqx-gen-4 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 05 14:12:39 deqx-gen-4 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 05 14:12:39 deqx-gen-4 kernel: aml_tdm_prepare(), reset fddr Jul 05 14:12:39 deqx-gen-4 kernel: spdif_a fifo ctrl, frddr:0 type:1, 16 bits, chmask 0x3, swap 0x10 Jul 05 14:12:39 deqx-gen-4 kernel: spdif_info: rate: 96000, channel status ch0_l:0x100, ch0_r:0x100, ch1_l:0xa00, ch1_r:0xa00 Jul 05 14:12:39 deqx-gen-4 kernel: audio_ddr_mngr: frddr_set_sharebuffer_enable sel:1, dst_src:3 Jul 05 14:12:39 deqx-gen-4 kernel: tdm playback mute: 0, lane_cnt = 8 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Completed starting Core Plugins Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------------------- Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ----- MyVolumio plugins startup ---- Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------------------- Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:39 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces state update: player Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand status took 31 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::getState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand status took 30 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand status took 26 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:39 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces state update: player Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::getState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:39 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces state update: player Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::getState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:39 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces system playlist update Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Ignoring MPD Status Update Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:39 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces state update: player Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand status took 24 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::getState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand playlistinfo took 18 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand playlistinfo took 17 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand status took 14 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand status took 10 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::servicePushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":310,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Look For The Good","artist":"Jason Mraz","album":"Look For The Good","uri":"http://192.168.0.200:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1767435.flac","trackType":"flac"} Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: CURRENT POSITION 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState stateService play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState currentStatus stop Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::servicePushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":310,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Look For The Good","artist":"Jason Mraz","album":"Look For The Good","uri":"http://192.168.0.200:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1767435.flac","trackType":"flac"} Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: CURRENT POSITION 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState stateService play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Received an update from plugin. extracting info from payload Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::servicePushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":310,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Look For The Good","artist":"Jason Mraz","album":"Look For The Good","uri":"http://192.168.0.200:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1767435.flac","trackType":"flac"} Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: CURRENT POSITION 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState stateService play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Received an update from plugin. extracting info from payload Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 137ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 153ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 151ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:39 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces system playlist update Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Ignoring MPD Status Update Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:39 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces state update: player Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::getState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:39 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces system playlist update Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Ignoring MPD Status Update Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Jul 05 14:12:39 deqx-gen-4 volumio[5638]: ---------------------------- MPD announces state update: player Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::getState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand status Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 117ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand status took 113 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand playlistinfo took 109 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand playlistinfo took 104 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand playlistinfo took 101 milliseconds Jul 05 14:12:39 deqx-gen-4 kernel: asoc-aml-card auge_sound: tdm playback enable Jul 05 14:12:39 deqx-gen-4 kernel: spdif_a is set to enable Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 17ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand status took 16 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 14ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand status took 12 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::servicePushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":310,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Look For The Good","artist":"Jason Mraz","album":"Look For The Good","uri":"http://192.168.0.200:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1767435.flac","trackType":"flac"} Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: CURRENT POSITION 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState stateService play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Received an update from plugin. extracting info from payload Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::servicePushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":310,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2620 Kbps","isStreaming":false,"title":"Look For The Good","artist":"Jason Mraz","album":"Look For The Good","uri":"http://192.168.0.200:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1767435.flac","trackType":"flac"} Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: CURRENT POSITION 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState stateService play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Received an update from plugin. extracting info from payload Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::servicePushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1228,"duration":310,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2775 Kbps","isStreaming":false,"title":"Look For The Good","artist":"Jason Mraz","album":"Look For The Good","uri":"http://192.168.0.200:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1767435.flac","trackType":"flac"} Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: CURRENT POSITION 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState stateService play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Received an update from plugin. extracting info from payload Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 274ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 258ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 255ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand playlistinfo took 146 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand playlistinfo took 143 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: sendMpdCommand playlistinfo took 142 milliseconds Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: ControllerMpd::parseTrackInfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::servicePushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1244,"duration":310,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2998 Kbps","isStreaming":false,"title":"Look For The Good","artist":"Jason Mraz","album":"Look For The Good","uri":"http://192.168.0.200:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1767435.flac","trackType":"flac"} Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: CURRENT POSITION 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState stateService play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Received an update from plugin. extracting info from payload Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::servicePushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1364,"duration":310,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2939 Kbps","isStreaming":false,"title":"Look For The Good","artist":"Jason Mraz","album":"Look For The Good","uri":"http://192.168.0.200:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1767435.flac","trackType":"flac"} Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: CURRENT POSITION 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState stateService play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Received an update from plugin. extracting info from payload Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ControllerMpd::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::servicePushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1364,"duration":310,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2939 Kbps","isStreaming":false,"title":"Look For The Good","artist":"Jason Mraz","album":"Look For The Good","uri":"http://192.168.0.200:9000/disk/NON-DLNA-OP01-FLAGS01700000/O0$1$8I1767435.flac","trackType":"flac"} Jul 05 14:12:39 deqx-gen-4 volumio[5638]: verbose: CURRENT POSITION 0 Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState stateService play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::syncState currentStatus play Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: Received an update from plugin. extracting info from payload Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::pushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output update for this device Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: MRS: Pushing multiroomSync output Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 366ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 265ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: ------------------------------ 263ms Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:12:39 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:12:40 deqx-gen-4 volumio[5638]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 05 14:12:41 deqx-gen-4 sudo[6561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.200/Qmultimedia /mnt/NAS/Qmultimedia Jul 05 14:12:41 deqx-gen-4 sudo[6561]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:41 deqx-gen-4 kernel: fb: mem_free_work, free memory: addr:800000 Jul 05 14:12:41 deqx-gen-4 sudo[6561]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:41 deqx-gen-4 kernel: CIFS VFS: cifs_mount failed w/return code = -95 Jul 05 14:12:41 deqx-gen-4 volumio[5638]: info: Cannot mount NAS Qmultimedia at system boot, trial number 3 ,retrying in 5 seconds Jul 05 14:12:46 deqx-gen-4 sudo[6580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.200/Qmultimedia /mnt/NAS/Qmultimedia Jul 05 14:12:46 deqx-gen-4 sudo[6580]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:47 deqx-gen-4 kernel: CIFS VFS: cifs_mount failed w/return code = -95 Jul 05 14:12:47 deqx-gen-4 sudo[6580]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:47 deqx-gen-4 volumio[5638]: info: Cannot mount NAS Qmultimedia at system boot, trial number 4 ,retrying in 5 seconds Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 05 14:12:49 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 05 14:12:51 deqx-gen-4 volumio[6195]: .............................................................................................................................................................................................++++ Jul 05 14:12:51 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 05 14:12:51 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 05 14:12:51 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:51 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:51 deqx-gen-4 volumio[5638]: info: Starting MyVolumio Remote Streaming Endpoints Jul 05 14:12:51 deqx-gen-4 volumio[5638]: info: MyVolumio login type: Token Jul 05 14:12:51 deqx-gen-4 volumio[5638]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 05 14:12:51 deqx-gen-4 volumio[5638]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Jul 05 14:12:52 deqx-gen-4 sudo[6640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.200/Qmultimedia /mnt/NAS/Qmultimedia Jul 05 14:12:52 deqx-gen-4 sudo[6640]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:53 deqx-gen-4 kernel: CIFS VFS: cifs_mount failed w/return code = -95 Jul 05 14:12:53 deqx-gen-4 sudo[6640]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:53 deqx-gen-4 volumio[5638]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: Retrieving Cloud Streaming UI Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: Getting Tidal Cloud Configuration Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: Getting Qobuz Cloud Configuration Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: Asking plugin for UI Config Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: Getting Spotify Cloud Configuration Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: Asking plugin for UI Config Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: Saving Spotify Acccount Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: Got it Jul 05 14:12:54 deqx-gen-4 volumio[5638]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: Got Tidal Cloud Configuration Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: Got it Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetBrowseSources Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetBrowseSources Jul 05 14:12:54 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetBrowseSources Jul 05 14:12:55 deqx-gen-4 volumio[5638]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 05 14:12:55 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:12:55 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 05 14:12:56 deqx-gen-4 ntpd[5179]: Soliciting pool server 192.121.108.99 Jul 05 14:12:57 deqx-gen-4 volumio[5638]: info: MyVolumio token set successfully Jul 05 14:12:57 deqx-gen-4 volumio[5638]: info: MYVOLUMIO: Adding device Jul 05 14:12:57 deqx-gen-4 volumio[5638]: info: MYVOLUMIO: Evaluating Server Jul 05 14:12:58 deqx-gen-4 volumio[5638]: info: MyVolumio status changed Jul 05 14:12:58 deqx-gen-4 volumio[5638]: info: Streaming services startup Jul 05 14:12:58 deqx-gen-4 volumio[5638]: info: Starting Streaming Daemon Jul 05 14:12:58 deqx-gen-4 volumio[5638]: info: Removing browser output: myVolumio user plan is not superstar Jul 05 14:12:58 deqx-gen-4 volumio[5638]: info: Removing audio output: Jul 05 14:12:58 deqx-gen-4 volumio[5638]: info: Stoppping Tunnel 1 Jul 05 14:12:58 deqx-gen-4 sudo[6679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 05 14:12:58 deqx-gen-4 sudo[6679]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:58 deqx-gen-4 sudo[6682]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 05 14:12:58 deqx-gen-4 sudo[6679]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:58 deqx-gen-4 sudo[6682]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:58 deqx-gen-4 volumio[5638]: error: Cannot start Volumio Streaming Daemon Jul 05 14:12:58 deqx-gen-4 volumio[5638]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 05 14:12:58 deqx-gen-4 volumio[5638]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 05 14:12:58 deqx-gen-4 sudo[6682]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:58 deqx-gen-4 volumio[5638]: info: Remote SSH Stopped Jul 05 14:12:58 deqx-gen-4 ntpd[5179]: Soliciting pool server 193.182.111.13 Jul 05 14:12:59 deqx-gen-4 volumio[5638]: error: MyVolumio Plugin failed to start in a timely fashion Jul 05 14:12:59 deqx-gen-4 volumio[5638]: info: BOOT COMPLETED Jul 05 14:12:59 deqx-gen-4 volumio[5638]: [Metrics] CommandRouter: 51s 168.84ms Jul 05 14:12:59 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumiosetStartupVolume Jul 05 14:12:59 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:59 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:59 deqx-gen-4 ntpd[5179]: Soliciting pool server 194.58.204.20 Jul 05 14:12:59 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::Close All Modals sent Jul 05 14:12:59 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::Close All Modals sent Jul 05 14:12:59 deqx-gen-4 volumio[5638]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jul 05 14:12:59 deqx-gen-4 volumio[5638]: No protocol specified Jul 05 14:12:59 deqx-gen-4 volumio[5638]: xcb_connection_has_error() returned true Jul 05 14:12:59 deqx-gen-4 volumio[5638]: 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 Jul 05 14:12:59 deqx-gen-4 volumio[5638]: aplay: main:828: audio open error: Device or resource busy Jul 05 14:12:59 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 14:12:59 deqx-gen-4 volumio[5638]: info: Setting Geolocation for MyVolumio to eu7 Jul 05 14:12:59 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:59 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:59 deqx-gen-4 ntpd[5179]: Soliciting pool server 194.58.200.20 Jul 05 14:13:00 deqx-gen-4 ntpd[5179]: Soliciting pool server 194.58.206.148 Jul 05 14:13:00 deqx-gen-4 ntpd[5179]: Soliciting pool server 194.58.205.148 Jul 05 14:13:00 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 05 14:13:00 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 05 14:13:00 deqx-gen-4 volumio[5638]: No protocol specified Jul 05 14:13:00 deqx-gen-4 volumio[5638]: xcb_connection_has_error() returned true Jul 05 14:13:00 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 05 14:13:00 deqx-gen-4 volumio[5638]: info: Updating MyVolumio device info Jul 05 14:13:00 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:13:01 deqx-gen-4 ntpd[5179]: Soliciting pool server 82.197.236.121 Jul 05 14:13:01 deqx-gen-4 ntpd[5179]: Soliciting pool server 162.159.200.1 Jul 05 14:13:01 deqx-gen-4 volumio[5638]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 05 14:13:01 deqx-gen-4 ntpd[5179]: Soliciting pool server 194.58.202.20 Jul 05 14:13:01 deqx-gen-4 volumio[6195]: ............................................................................................................................++++ Jul 05 14:13:01 deqx-gen-4 volumio[6195]: e is 65537 (0x010001) Jul 05 14:13:01 deqx-gen-4 volumio[6195]: writing RSA key Jul 05 14:13:02 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetState Jul 05 14:13:02 deqx-gen-4 volumio[5638]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 05 14:13:03 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:13:03 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:13:03 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 05 14:13:03 deqx-gen-4 ntpd[5179]: Soliciting pool server 85.24.237.72 Jul 05 14:13:03 deqx-gen-4 volumio-remote-updater[4622]: No test mode Jul 05 14:13:03 deqx-gen-4 volumio-remote-updater[4622]: No alpha test mode Jul 05 14:13:03 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 05 14:13:03 deqx-gen-4 volumio[5638]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 05 14:13:03 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 05 14:13:03 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetQueue Jul 05 14:13:03 deqx-gen-4 volumio[5638]: info: CoreStateMachine::getQueue Jul 05 14:13:03 deqx-gen-4 volumio[5638]: info: CorePlayQueue::getQueue Jul 05 14:13:04 deqx-gen-4 ntpd[5179]: Soliciting pool server 13.51.141.188 Jul 05 14:13:04 deqx-gen-4 volumio[5638]: info: Executing endpoint metavolumio Jul 05 14:13:04 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 05 14:13:04 deqx-gen-4 volumio[5638]: info: Executing endpoint metavolumio Jul 05 14:13:04 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 05 14:13:04 deqx-gen-4 volumio[5638]: info: Executing endpoint metavolumio Jul 05 14:13:04 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jul 05 14:13:05 deqx-gen-4 ntpd[5179]: Soliciting pool server 82.197.236.121 Jul 05 14:13:05 deqx-gen-4 volumio[5638]: info: MYVOLUMIO: Adding device Jul 05 14:13:05 deqx-gen-4 volumio[5638]: info: MYVOLUMIO: Evaluating Server Jul 05 14:13:04 deqx-gen-4 ntpd[5179]: receive: Unexpected origin timestamp 0xea3261d2.2085832d does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xea3261d0.ed2cd92a Jul 05 14:13:04 deqx-gen-4 ntpd[5179]: receive: Unexpected origin timestamp 0xea3261d2.207cc679 does not match aorg 0000000000.00000000 from server@13.51.141.188 xmt 0xea3261d0.eca02fc1 Jul 05 14:13:04 deqx-gen-4 ntpd[5179]: receive: Unexpected origin timestamp 0xea3261d2.208d5283 does not match aorg 0000000000.00000000 from server@193.182.111.13 xmt 0xea3261d0.edab5439 Jul 05 14:13:04 deqx-gen-4 ntpd[5179]: receive: Unexpected origin timestamp 0xea3261d2.20885482 does not match aorg 0000000000.00000000 from server@194.58.205.148 xmt 0xea3261d0.eda2a875 Jul 05 14:13:05 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 05 14:13:05 deqx-gen-4 volumio[5638]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 36 Jul 05 14:13:05 deqx-gen-4 volumio[5638]: info: CoreCommandRouter::volumioGetState Jul 05 14:13:05 deqx-gen-4 volumio[5638]: error: Failed to ping endpoint us1.myvolumio.org : unknown error Jul 05 14:13:05 deqx-gen-4 volumio[5638]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:13:05 deqx-gen-4 volumio[5638]: Error: Unable to resolve or reject the same promise twice Jul 05 14:13:05 deqx-gen-4 volumio[5638]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 05 14:13:05 deqx-gen-4 volumio[5638]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32086) Jul 05 14:13:05 deqx-gen-4 volumio[5638]: at Socket.emit (events.js:412:35) Jul 05 14:13:05 deqx-gen-4 volumio[5638]: at endReadableNT (internal/streams/readable.js:1333:12) Jul 05 14:13:05 deqx-gen-4 volumio[5638]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Jul 05 14:13:05 deqx-gen-4 volumio[5638]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:13:06 deqx-gen-4 sudo[6776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-05 14:12 Jul 05 14:13:06 deqx-gen-4 sudo[6776]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="900c37ee0cafd620e4a37a1118cc5316b54b45b3" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="ca3c0885e14bcba58c57ef609685ae76316b61d6" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="deqx" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 16 Apr 2024 02:17:33 PM CEST" VOLUMIO_VERSION="3.624" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_HASH="ca1050ae12f3dc501a57f6b4c284f5d2"