-- 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"