-- Logs begin at Sun 2024-07-14 18:39:32 AEST, end at Sun 2024-07-14 19:07:08 AEST. -- Jul 14 19:06:13 q volumio[993]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 14 19:06:13 q volumio[993]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 14 19:06:27 q volumio[993]: info: FusionDsp - Stopping FusionDsp service Jul 14 19:06:27 q volumio[993]: info: camilladsp stopping service pid 1992... Jul 14 19:06:27 q volumio[993]: info: camilladsp service terminated, instance 1 Jul 14 19:06:27 q volumio[993]: info: Disabling plugin fusiondsp Jul 14 19:06:27 q volumio[993]: info: Preparing to generate the ALSA configuration file Jul 14 19:06:27 q sudo[6360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Jul 14 19:06:27 q sudo[6360]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 19:06:27 q systemd[1]: Stopping FusionDsp Daemon... Jul 14 19:06:27 q volumio[1220]: Backend configuration: Jul 14 19:06:27 q volumio[1220]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jul 14 19:06:27 q volumio[1220]: active_config_txt: null Jul 14 19:06:27 q volumio[1220]: camilla_host: 127.0.0.1 Jul 14 19:06:27 q volumio[1220]: camilla_port: 9876 Jul 14 19:06:27 q volumio[1220]: coeff_dir: /data/INTERNAL/FusionDsp/filters Jul 14 19:06:27 q volumio[1220]: config_dir: /data/configuration/audio_interface/fusiondsp Jul 14 19:06:27 q volumio[1220]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Jul 14 19:06:27 q volumio[1220]: log_file: /tmp/camilladsp.log Jul 14 19:06:27 q volumio[1220]: on_get_active_config: null Jul 14 19:06:27 q volumio[1220]: on_set_active_config: null Jul 14 19:06:27 q volumio[1220]: port: 5011 Jul 14 19:06:27 q volumio[1220]: supported_capture_types: null Jul 14 19:06:27 q volumio[1220]: supported_playback_types: null Jul 14 19:06:27 q volumio[1220]: update_config_symlink: false Jul 14 19:06:27 q volumio[1220]: update_config_txt: false Jul 14 19:06:27 q volumio[1220]: ======== Running on http://0.0.0.0:5011 ======== Jul 14 19:06:27 q volumio[1220]: (Press CTRL+C to quit) Jul 14 19:06:27 q volumio[993]: info: Asound.conf file written Jul 14 19:06:27 q sudo[6363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jul 14 19:06:27 q sudo[6363]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 19:06:27 q sudo[6363]: pam_unix(sudo:session): session closed for user root Jul 14 19:06:27 q volumio[993]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Jul 14 19:06:27 q volumio[993]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Jul 14 19:06:27 q volumio[993]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Jul 14 19:06:27 q volumio[993]: info: Output device has changed, restarting MPD Jul 14 19:06:27 q systemd[1]: fusiondsp.service: Succeeded. Jul 14 19:06:27 q systemd[1]: Stopped FusionDsp Daemon. Jul 14 19:06:27 q sudo[6360]: pam_unix(sudo:session): session closed for user root Jul 14 19:06:27 q sudo[6369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 14 19:06:27 q sudo[6369]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 19:06:27 q sudo[6369]: pam_unix(sudo:session): session closed for user root Jul 14 19:06:27 q volumio[993]: info: Output device has changed, restarting Shairport Sync Jul 14 19:06:27 q volumio[993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 19:06:27 q volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 19:06:27 q sudo[6372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 14 19:06:27 q sudo[6372]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 19:06:27 q systemd[1]: Stopping Music Player Daemon... Jul 14 19:06:27 q volumio[993]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 14 19:06:27 q volumio[993]: info: Done. Jul 14 19:06:27 q volumio[993]: info: MPD Permissions set Jul 14 19:06:27 q volumio[993]: info: FusionDsp - Reporting Fusion DSP Disabled Jul 14 19:06:27 q volumio[993]: info: Removing fusiondspeq DSP Signal Path Element Jul 14 19:06:27 q volumio[993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 19:06:27 q volumio[993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 19:06:27 q volumio[993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 19:06:27 q volumio[993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 19:06:27 q volumio[993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 19:06:27 q volumio[993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 19:06:27 q volumio[993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 19:06:27 q volumio[993]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 14 19:06:27 q volumio[993]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 14 19:06:27 q volumio[993]: info: Starting Shairport Sync Jul 14 19:06:27 q sudo[6382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 14 19:06:27 q sudo[6382]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 19:06:27 q systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 14 19:06:27 q systemd[1]: shairport-sync.service: Succeeded. Jul 14 19:06:27 q systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 14 19:06:28 q systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 14 19:06:28 q sudo[6382]: pam_unix(sudo:session): session closed for user root Jul 14 19:06:28 q volumio[993]: info: Shairport-Sync Started Jul 14 19:06:28 q systemd[1]: mpd.service: Succeeded. Jul 14 19:06:28 q systemd[1]: Stopped Music Player Daemon. Jul 14 19:06:28 q systemd[1]: Starting Music Player Daemon... Jul 14 19:06:28 q sudo[6388]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 14 19:06:28 q sudo[6388]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 14 19:06:28 q sudo[6388]: pam_unix(sudo:session): session closed for user root Jul 14 19:06:29 q volumio[993]: info: CoreCommandRouter::volumioPause Jul 14 19:06:29 q volumio[993]: info: CoreStateMachine::pause Jul 14 19:06:29 q volumio[993]: info: CoreStateMachine::stPlaybackTimer Jul 14 19:06:29 q volumio[993]: info: CoreStateMachine::servicePause Jul 14 19:06:29 q volumio[993]: info: CorePlayQueue::getTrack 301 Jul 14 19:06:29 q volumio[993]: info: CoreCommandRouter::servicePause Jul 14 19:06:29 q volumio[993]: info: ControllerMpd::pause Jul 14 19:06:29 q volumio[993]: verbose: ControllerMpd::sendMpdCommand pause Jul 14 19:06:29 q volumio[993]: error: MPD error: Error: This socket has been ended by the other party Jul 14 19:06:29 q volumio[993]: error: This socket has been ended by the other party {"code":"EPIPE"} Jul 14 19:06:29 q volumio[993]: error: MPD error: Error: This socket has been ended by the other party Jul 14 19:06:29 q volumio[993]: error: This socket has been ended by the other party {"code":"EPIPE"} Jul 14 19:06:29 q volumio[993]: error: MPD error: Error: This socket has been ended by the other party Jul 14 19:06:29 q volumio[993]: error: This socket has been ended by the other party {"code":"EPIPE"} Jul 14 19:06:30 q mpd[6390]: Jul 14 19:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 14 19:06:30 q systemd[1]: Started Music Player Daemon. Jul 14 19:06:30 q sudo[6372]: pam_unix(sudo:session): session closed for user root Jul 14 19:06:30 q volumio[993]: error: updateQueue error: null Jul 14 19:06:38 q volumio[993]: info: CoreCommandRouter::volumioPause Jul 14 19:06:38 q volumio[993]: info: CoreStateMachine::pause Jul 14 19:06:45 q volumio[993]: info: CoreCommandRouter::volumioPause Jul 14 19:06:45 q volumio[993]: info: CoreStateMachine::pause Jul 14 19:06:47 q volumio[993]: info: CoreCommandRouter::volumioGetQueue Jul 14 19:06:47 q volumio[993]: info: CoreStateMachine::getQueue Jul 14 19:06:47 q volumio[993]: info: CorePlayQueue::getQueue Jul 14 19:06:59 q systemd[1]: volumio-kiosk.service: State 'stop-sigterm' timed out. Killing. Jul 14 19:06:59 q systemd[1]: volumio-kiosk.service: Killing process 6125 (startx) with signal SIGKILL. Jul 14 19:06:59 q systemd[1]: volumio-kiosk.service: Killing process 6150 (xinit) with signal SIGKILL. Jul 14 19:06:59 q systemd[1]: volumio-kiosk.service: Killing process 6151 (Xorg) with signal SIGKILL. Jul 14 19:06:59 q systemd[1]: volumio-kiosk.service: Main process exited, code=killed, status=9/KILL Jul 14 19:06:59 q systemd[1]: volumio-kiosk.service: Failed with result 'timeout'. Jul 14 19:06:59 q systemd[1]: Stopped Volumio Kiosk. Jul 14 19:06:59 q volumio[993]: error: touch_display: Error setting screensaver timeout: Error: Command failed: /usr/bin/xset -display :1 s off +dpms dpms 0 0 120000 Jul 14 19:06:59 q volumio[993]: /usr/bin/xset: unable to open display ":1" Jul 14 19:06:59 q volumio[993]: error: touch_display: Error determining the video outputs: Error: Command failed: /usr/bin/xrandr -display :1 Jul 14 19:06:59 q volumio[993]: Can't open display :1 Jul 14 19:06:59 q volumio[993]: error: touch_display: Error waking up the screen: Error: Command failed: /usr/bin/xset -display :1 s reset dpms force on Jul 14 19:06:59 q volumio[993]: /usr/bin/xset: unable to open display ":1" Jul 14 19:06:59 q volumio[993]: error: touch_display: Error waking up the screen: Error: Command failed: /usr/bin/xset -display :1 s reset dpms force on Jul 14 19:06:59 q volumio[993]: /usr/bin/xset: unable to open display ":1" Jul 14 19:06:59 q systemd[1]: Started Volumio Kiosk. Jul 14 19:06:59 q sudo[6265]: pam_unix(sudo:session): session closed for user root Jul 14 19:06:59 q sudo[6241]: pam_unix(sudo:session): session closed for user root Jul 14 19:06:59 q sudo[6288]: pam_unix(sudo:session): session closed for user root Jul 14 19:06:59 q volumio[993]: error: touch_display: Error setting screensaver timeout: Error: Command failed: /usr/bin/xset -display :1 s off +dpms dpms 0 0 0 Jul 14 19:06:59 q volumio[993]: /usr/bin/xset: unable to open display ":1" Jul 14 19:06:59 q volumio[993]: error: touch_display: Error setting screensaver timeout: Error: Command failed: /usr/bin/xset -display :1 s off +dpms dpms 0 0 0 Jul 14 19:06:59 q volumio[993]: /usr/bin/xset: unable to open display ":1" Jul 14 19:06:59 q volumio[993]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Jul 14 19:06:59 q volumio[993]: info: touch_display: Volumio Kiosk restarted. Jul 14 19:06:59 q startx[6394]: X.Org X Server 1.20.4 Jul 14 19:06:59 q startx[6394]: X Protocol Version 11, Revision 0 Jul 14 19:06:59 q startx[6394]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jul 14 19:06:59 q startx[6394]: Current Operating System: Linux q 6.1.77-v7+ #1730 SMP Thu Feb 8 15:17:51 GMT 2024 armv7l Jul 14 19:06:59 q startx[6394]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=826680f7-a407-4ab9-8bd5-1855bda526fd imgfile=/volumio_current.sqsh bootpart=UUID=FD90-ED9E datapart=UUID=5b5661ea-db8b-4afc-aede-9be34ecc4a17 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jul 14 19:06:59 q startx[6394]: Build Date: 04 April 2023 07:50:56AM Jul 14 19:06:59 q startx[6394]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jul 14 19:06:59 q startx[6394]: Current version of pixman: 0.36.0 Jul 14 19:06:59 q startx[6394]: Before reporting problems, check http://wiki.x.org Jul 14 19:06:59 q startx[6394]: to make sure that you have the latest version. Jul 14 19:06:59 q startx[6394]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 14 19:06:59 q startx[6394]: (++) from command line, (!!) notice, (II) informational, Jul 14 19:06:59 q startx[6394]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 14 19:06:59 q startx[6394]: (==) Log file: "/var/log/Xorg.2.log", Time: Sun Jul 14 19:06:59 2024 Jul 14 19:06:59 q startx[6394]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jul 14 19:06:59 q startx[6394]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 14 19:06:59 q volumio[993]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Jul 14 19:06:59 q volumio[993]: info: touch_display: Volumio Kiosk restarted. Jul 14 19:06:59 q volumio[993]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Jul 14 19:06:59 q volumio[993]: info: touch_display: Volumio Kiosk restarted. Jul 14 19:06:59 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:06:59 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:06:59 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:06:59 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:06:59 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:06:59 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:00 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:00 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:00 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:00 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:00 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:00 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:00 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:00 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:00 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:00 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:00 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:00 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:00 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:00 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:00 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:01 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:01 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:01 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:01 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:01 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:01 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:01 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:01 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:01 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:01 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:01 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:01 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:02 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:02 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:02 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:02 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:02 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:02 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:02 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:02 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:02 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:02 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:02 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:02 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:02 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:02 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:02 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:03 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:03 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:03 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:03 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:03 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:03 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:03 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:03 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:03 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:03 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:03 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:03 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:03 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:03 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:03 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:04 q volumio[993]: info: CoreCommandRouter::volumioPlay Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::play index 301 Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::stop Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::updateTrackBlock Jul 14 19:07:04 q volumio[993]: info: CorePlayQueue::getTrackBlock Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::stPlaybackTimer Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::pushState Jul 14 19:07:04 q volumio[993]: info: CorePlayQueue::getTrack 301 Jul 14 19:07:04 q volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 19:07:04 q volumio[993]: info: CoreCommandRouter::volumioPushState Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::serviceStop Jul 14 19:07:04 q volumio[993]: info: CorePlayQueue::getTrack 301 Jul 14 19:07:04 q volumio[993]: info: CoreCommandRouter::serviceStop Jul 14 19:07:04 q volumio[993]: info: ControllerMpd::stop Jul 14 19:07:04 q volumio[993]: verbose: ControllerMpd::sendMpdCommand stop Jul 14 19:07:04 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:04 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:04 q volumio[993]: info: sendMpdCommand stop took 25 milliseconds Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::play index undefined Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 14 19:07:04 q volumio[993]: info: CorePlayQueue::getTrack 301 Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::startPlaybackTimer Jul 14 19:07:04 q volumio[993]: info: CorePlayQueue::getTrack 301 Jul 14 19:07:04 q volumio[993]: verbose: ControllerMpd::clearAddPlayTracks USB/NEW_VOLUME/Master Music/KIWI - Smashproof - Brother_xvid.mp3 Jul 14 19:07:04 q volumio[993]: verbose: ControllerMpd::sendMpdCommand stop Jul 14 19:07:04 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:04 q volumio[993]: info: sendMpdCommand stop took 35 milliseconds Jul 14 19:07:04 q volumio[993]: verbose: ControllerMpd::sendMpdCommand clear Jul 14 19:07:04 q volumio[993]: info: Jul 14 19:07:04 q volumio[993]: ---------------------------- MPD announces system playlist update Jul 14 19:07:04 q volumio[993]: info: Ignoring MPD Status Update Jul 14 19:07:04 q volumio[993]: info: sendMpdCommand clear took 5 milliseconds Jul 14 19:07:04 q volumio[993]: verbose: ControllerMpd::sendMpdCommand add "USB/NEW_VOLUME/Master Music/KIWI - Smashproof - Brother_xvid.mp3" Jul 14 19:07:04 q volumio[993]: error: updateQueue error: null Jul 14 19:07:04 q volumio[993]: info: Jul 14 19:07:04 q volumio[993]: ---------------------------- MPD announces system playlist update Jul 14 19:07:04 q volumio[993]: info: Ignoring MPD Status Update Jul 14 19:07:04 q volumio[993]: info: ------------------------------ 18ms Jul 14 19:07:04 q volumio[993]: info: sendMpdCommand add "USB/NEW_VOLUME/Master Music/KIWI - Smashproof - Brother_xvid.mp3" took 16 milliseconds Jul 14 19:07:04 q volumio[993]: verbose: ControllerMpd::sendMpdCommand play Jul 14 19:07:04 q volumio[993]: info: Jul 14 19:07:04 q volumio[993]: ---------------------------- MPD announces state update: player Jul 14 19:07:04 q volumio[993]: info: ------------------------------ 17ms Jul 14 19:07:04 q volumio[993]: info: sendMpdCommand play took 15 milliseconds Jul 14 19:07:04 q volumio[993]: info: ControllerMpd::getState Jul 14 19:07:04 q volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jul 14 19:07:04 q kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jul 14 19:07:04 q volumio[993]: info: Jul 14 19:07:04 q volumio[993]: ---------------------------- MPD announces state update: player Jul 14 19:07:04 q volumio[993]: info: sendMpdCommand status took 115 milliseconds Jul 14 19:07:04 q volumio[993]: info: ControllerMpd::getState Jul 14 19:07:04 q volumio[993]: verbose: ControllerMpd::sendMpdCommand status Jul 14 19:07:04 q volumio[993]: verbose: ControllerMpd::parseState Jul 14 19:07:04 q volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 19:07:04 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:04 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:04 q volumio[993]: info: sendMpdCommand status took 18 milliseconds Jul 14 19:07:04 q volumio[993]: info: sendMpdCommand playlistinfo took 18 milliseconds Jul 14 19:07:04 q volumio[993]: verbose: ControllerMpd::parseState Jul 14 19:07:04 q volumio[993]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 14 19:07:04 q volumio[993]: verbose: ControllerMpd::parseTrackInfo Jul 14 19:07:04 q volumio[993]: info: ControllerMpd::pushState Jul 14 19:07:04 q volumio[993]: info: CoreCommandRouter::servicePushState Jul 14 19:07:04 q volumio[993]: info: CorePlayQueue::getTrack 301 Jul 14 19:07:04 q volumio[993]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"KIWI - Smashproof - Brother_xvid.mp3","artist":null,"album":null,"uri":"USB/NEW_VOLUME/Master Music/KIWI - Smashproof - Brother_xvid.mp3","trackType":"mp3"} Jul 14 19:07:04 q volumio[993]: verbose: CURRENT POSITION 301 Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::syncState stateService play Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::syncState currentStatus stop Jul 14 19:07:04 q volumio[993]: info: ------------------------------ 143ms Jul 14 19:07:04 q volumio[993]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 14 19:07:04 q volumio[993]: verbose: ControllerMpd::parseTrackInfo Jul 14 19:07:04 q volumio[993]: info: ControllerMpd::pushState Jul 14 19:07:04 q volumio[993]: info: CoreCommandRouter::servicePushState Jul 14 19:07:04 q volumio[993]: info: CorePlayQueue::getTrack 301 Jul 14 19:07:04 q volumio[993]: verbose: STATE SERVICE {"status":"play","position":0,"seek":880,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"KIWI - Smashproof - Brother_xvid.mp3","artist":null,"album":null,"uri":"USB/NEW_VOLUME/Master Music/KIWI - Smashproof - Brother_xvid.mp3","trackType":"mp3"} Jul 14 19:07:04 q volumio[993]: verbose: CURRENT POSITION 301 Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::syncState stateService play Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::syncState currentStatus play Jul 14 19:07:04 q volumio[993]: info: Received an update from plugin. extracting info from payload Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::pushState Jul 14 19:07:04 q volumio[993]: info: CorePlayQueue::getTrack 301 Jul 14 19:07:04 q volumio[993]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 14 19:07:04 q volumio[993]: info: CoreCommandRouter::volumioPushState Jul 14 19:07:04 q volumio[993]: info: CoreStateMachine::pushState Jul 14 19:07:04 q volumio[993]: info: CorePlayQueue::getTrack 301 Jul 14 19:07:04 q volumio[993]: info: CoreCommandRouter::volumioPushState Jul 14 19:07:04 q volumio[993]: info: ------------------------------ 69ms Jul 14 19:07:04 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:04 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:04 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:04 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:04 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:04 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:04 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:04 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:04 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:04 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:05 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:05 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:05 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:05 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:05 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:05 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:05 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:05 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:05 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:05 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:05 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:05 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:05 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:05 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:05 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:06 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:06 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:06 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:06 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:06 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:06 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:06 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:06 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:06 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:06 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:06 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:06 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:06 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:07 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:07 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:07 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:07 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:07 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:07 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:07 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:07 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:07 q volumio[993]: info: touch_display: X display number found: 2 Jul 14 19:07:07 q volumio[993]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X2 Jul 14 19:07:07 q volumio[993]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X2 Jul 14 19:07:07 q volumio[993]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X2 Jul 14 19:07:07 q volumio[993]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 14 19:07:07 q volumio[993]: Error: connect EISCONN /tmp/.X11-unix/X2 Jul 14 19:07:07 q volumio[993]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 14 19:07:07 q volumio[993]: errno: -106, Jul 14 19:07:07 q volumio[993]: code: 'EISCONN', Jul 14 19:07:07 q volumio[993]: syscall: 'connect', Jul 14 19:07:07 q volumio[993]: address: '/tmp/.X11-unix/X2' Jul 14 19:07:07 q volumio[993]: } Jul 14 19:07:07 q volumio[993]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 14 19:07:08 q sudo[6741]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-14 19:06 Jul 14 19:07:08 q sudo[6741]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"