-- Logs begin at Thu 2024-10-03 21:46:57 +07, end at Sun 2024-10-06 11:20:18 +07. -- Oct 06 11:19:00 volumio kernel: usb 1-6: device descriptor read/64, error -71 Oct 06 11:19:00 volumio kernel: usb 1-6: new full-speed USB device number 5 using xhci_hcd Oct 06 11:19:00 volumio kernel: usb 1-6: device descriptor read/64, error -71 Oct 06 11:19:01 volumio kernel: usb 1-6: new high-speed USB device number 6 using xhci_hcd Oct 06 11:19:02 volumio kernel: usb 1-6: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.06 Oct 06 11:19:02 volumio kernel: usb 1-6: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Oct 06 11:19:02 volumio kernel: usb 1-6: Product: E50 Oct 06 11:19:02 volumio kernel: usb 1-6: Manufacturer: Topping Oct 06 11:19:02 volumio kernel: usb 1-6: 1:3 : unsupported format bits 0x100000000 Oct 06 11:19:02 volumio kernel: hid-generic 0003:152A:8750.0002: hiddev0,hidraw0: USB HID v1.10 Device [Topping E50] on usb-0000:00:14.0-6/input3 Oct 06 11:19:02 volumio volumio[841]: info: Oct 06 11:19:02 volumio volumio[841]: ---------------------------- USB Audio Device Attached Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::Close All Modals sent Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 06 11:19:02 volumio volumio[841]: info: Device 5 does not have any Mixer Control Available Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 06 11:19:02 volumio volumio[841]: info: Updating Volume Controller Parameters: Device: 5 Name: E50 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 06 11:19:02 volumio volumio[841]: info: Disabling external Volume Control Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 11:19:02 volumio volumio[841]: info: CoreStateMachine::pushState Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::volumioPushState Oct 06 11:19:02 volumio volumio[841]: info: Preparing to generate the ALSA configuration file Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 06 11:19:02 volumio volumio[841]: info: Asound.conf file unchanged, so no further update is needed Oct 06 11:19:02 volumio volumio[841]: info: Output device has changed, restarting MPD Oct 06 11:19:02 volumio sudo[27526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 06 11:19:02 volumio sudo[27526]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 11:19:02 volumio volumio[841]: info: Output device has changed, restarting Shairport Sync Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:02 volumio sudo[27526]: pam_unix(sudo:session): session closed for user root Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 11:19:02 volumio sudo[27529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 06 11:19:02 volumio sudo[27529]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 11:19:02 volumio systemd[1]: Stopping Music Player Daemon... Oct 06 11:19:02 volumio volumio[841]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 06 11:19:02 volumio volumio[841]: info: MPD Permissions set Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::volumioGetQueue Oct 06 11:19:02 volumio volumio[841]: info: CoreStateMachine::getQueue Oct 06 11:19:02 volumio volumio[841]: info: CorePlayQueue::getQueue Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:02 volumio systemd[1]: mpd.service: Succeeded. Oct 06 11:19:02 volumio systemd[1]: Stopped Music Player Daemon. Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:02 volumio systemd[1]: Starting Music Player Daemon... Oct 06 11:19:02 volumio volumio[841]: info: Starting Shairport Sync Oct 06 11:19:02 volumio sudo[27544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 06 11:19:02 volumio sudo[27544]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 11:19:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 06 11:19:02 volumio sudo[27535]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 06 11:19:02 volumio sudo[27535]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 11:19:02 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 06 11:19:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 06 11:19:02 volumio sudo[27535]: pam_unix(sudo:session): session closed for user root Oct 06 11:19:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 06 11:19:02 volumio sudo[27544]: pam_unix(sudo:session): session closed for user root Oct 06 11:19:02 volumio volumio[841]: info: Shairport-Sync Started Oct 06 11:19:02 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 06 11:19:02 volumio mpd[27548]: Oct 06 11:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 06 11:19:02 volumio systemd[1]: Started Music Player Daemon. Oct 06 11:19:02 volumio sudo[27529]: pam_unix(sudo:session): session closed for user root Oct 06 11:19:02 volumio volumio[841]: error: updateQueue error: null Oct 06 11:19:06 volumio volumio[841]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 06 11:19:06 volumio volumio[841]: info: CoreStateMachine::getcurrentVolume Oct 06 11:19:06 volumio volumio[841]: info: CoreCommandRouter::volumioRetrievevolume Oct 06 11:19:06 volumio volumio[841]: info: CoreStateMachine::pushState Oct 06 11:19:06 volumio volumio[841]: info: CoreCommandRouter::volumioPushState Oct 06 11:19:06 volumio volumio[841]: info: CoreCommandRouter::volumioGetQueue Oct 06 11:19:06 volumio volumio[841]: info: CoreStateMachine::getQueue Oct 06 11:19:06 volumio volumio[841]: info: CorePlayQueue::getQueue Oct 06 11:19:14 volumio volumio[841]: error: Upnp client error: Error: This socket has been ended by the other party Oct 06 11:19:27 volumio kernel: usb 1-6: USB disconnect, device number 6 Oct 06 11:19:27 volumio volumio[841]: info: Oct 06 11:19:27 volumio volumio[841]: ---------------------------- USB Audio Device Detached Oct 06 11:19:27 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Oct 06 11:19:27 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 06 11:19:27 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 06 11:19:28 volumio kernel: usb 1-6: new high-speed USB device number 7 using xhci_hcd Oct 06 11:19:28 volumio kernel: usb 1-6: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.06 Oct 06 11:19:28 volumio kernel: usb 1-6: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Oct 06 11:19:28 volumio kernel: usb 1-6: Product: E50 Oct 06 11:19:28 volumio kernel: usb 1-6: Manufacturer: Topping Oct 06 11:19:28 volumio kernel: usb 1-6: 1:3 : unsupported format bits 0x100000000 Oct 06 11:19:28 volumio kernel: hid-generic 0003:152A:8750.0003: hiddev0,hidraw0: USB HID v1.10 Device [Topping E50] on usb-0000:00:14.0-6/input3 Oct 06 11:19:28 volumio volumio[841]: info: Oct 06 11:19:28 volumio volumio[841]: ---------------------------- USB Audio Device Attached Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::Close All Modals sent Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 06 11:19:28 volumio volumio[841]: info: Device 5 does not have any Mixer Control Available Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 06 11:19:28 volumio volumio[841]: info: Updating Volume Controller Parameters: Device: 5 Name: E50 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 06 11:19:28 volumio volumio[841]: info: Disabling external Volume Control Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 11:19:28 volumio volumio[841]: info: CoreStateMachine::pushState Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::volumioPushState Oct 06 11:19:28 volumio volumio[841]: info: Preparing to generate the ALSA configuration file Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 06 11:19:28 volumio volumio[841]: info: Asound.conf file unchanged, so no further update is needed Oct 06 11:19:28 volumio volumio[841]: info: Output device has changed, restarting MPD Oct 06 11:19:28 volumio sudo[27605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 06 11:19:28 volumio sudo[27605]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 11:19:28 volumio volumio[841]: info: Output device has changed, restarting Shairport Sync Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 11:19:28 volumio sudo[27605]: pam_unix(sudo:session): session closed for user root Oct 06 11:19:28 volumio sudo[27608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 06 11:19:28 volumio sudo[27608]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 11:19:28 volumio systemd[1]: Stopping Music Player Daemon... Oct 06 11:19:28 volumio volumio[841]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 06 11:19:28 volumio systemd[1]: mpd.service: Succeeded. Oct 06 11:19:28 volumio systemd[1]: Stopped Music Player Daemon. Oct 06 11:19:28 volumio volumio[841]: info: MPD Permissions set Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::volumioGetQueue Oct 06 11:19:28 volumio volumio[841]: info: CoreStateMachine::getQueue Oct 06 11:19:28 volumio volumio[841]: info: CorePlayQueue::getQueue Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:28 volumio systemd[1]: Starting Music Player Daemon... Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 06 11:19:28 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 06 11:19:28 volumio volumio[841]: info: Starting Shairport Sync Oct 06 11:19:28 volumio sudo[27613]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 06 11:19:28 volumio sudo[27613]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 11:19:28 volumio sudo[27613]: pam_unix(sudo:session): session closed for user root Oct 06 11:19:28 volumio sudo[27621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 06 11:19:28 volumio sudo[27621]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 11:19:28 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 06 11:19:28 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 06 11:19:28 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 06 11:19:28 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 06 11:19:28 volumio sudo[27621]: pam_unix(sudo:session): session closed for user root Oct 06 11:19:28 volumio volumio[841]: info: Shairport-Sync Started Oct 06 11:19:29 volumio mpd[27623]: Oct 06 11:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 06 11:19:29 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 06 11:19:29 volumio systemd[1]: Started Music Player Daemon. Oct 06 11:19:29 volumio sudo[27608]: pam_unix(sudo:session): session closed for user root Oct 06 11:19:29 volumio volumio[841]: error: updateQueue error: null Oct 06 11:19:32 volumio volumio[841]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 06 11:19:32 volumio volumio[841]: info: CoreStateMachine::getcurrentVolume Oct 06 11:19:32 volumio volumio[841]: info: CoreCommandRouter::volumioRetrievevolume Oct 06 11:19:32 volumio volumio[841]: info: CoreStateMachine::pushState Oct 06 11:19:32 volumio volumio[841]: info: CoreCommandRouter::volumioPushState Oct 06 11:19:32 volumio volumio[841]: info: CoreCommandRouter::volumioGetQueue Oct 06 11:19:32 volumio volumio[841]: info: CoreStateMachine::getQueue Oct 06 11:19:32 volumio volumio[841]: info: CorePlayQueue::getQueue Oct 06 11:19:40 volumio ntpd[22556]: Soliciting pool server 2401:5f80:5001:1f::31f Oct 06 11:19:43 volumio volumio[841]: error: Upnp client error: Error: This socket has been ended by the other party Oct 06 11:19:46 volumio volumio[841]: info: Clearing queue after UPNP request Oct 06 11:19:46 volumio volumio[841]: info: CoreStateMachine::ClearQueue Oct 06 11:19:46 volumio volumio[841]: info: CoreStateMachine::stop Oct 06 11:19:46 volumio volumio[841]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 06 11:19:46 volumio volumio[841]: info: CoreStateMachine::stPlaybackTimer Oct 06 11:19:46 volumio volumio[841]: info: CoreStateMachine::updateTrackBlock Oct 06 11:19:46 volumio volumio[841]: info: CorePlayQueue::getTrackBlock Oct 06 11:19:46 volumio volumio[841]: info: CoreStateMachine::pushState Oct 06 11:19:46 volumio volumio[841]: info: CorePlayQueue::getTrack 0 Oct 06 11:19:46 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 11:19:46 volumio volumio[841]: info: CoreCommandRouter::volumioPushState Oct 06 11:19:46 volumio volumio[841]: info: CoreStateMachine::serviceStop Oct 06 11:19:46 volumio volumio[841]: info: CorePlayQueue::getTrack 0 Oct 06 11:19:46 volumio volumio[841]: info: ControllerMpd::stop Oct 06 11:19:46 volumio volumio[841]: verbose: ControllerMpd::sendMpdCommand stop Oct 06 11:19:46 volumio volumio[841]: info: CorePlayQueue::clearPlayQueue Oct 06 11:19:46 volumio volumio[841]: info: CorePlayQueue::saveQueue Oct 06 11:19:46 volumio volumio[841]: info: CoreCommandRouter::volumioPushState Oct 06 11:19:46 volumio volumio[841]: info: CoreCommandRouter::volumioPushQueue Oct 06 11:19:46 volumio volumio[841]: info: Oct 06 11:19:46 volumio volumio[841]: ---------------------------- MPD announces system playlist update Oct 06 11:19:46 volumio volumio[841]: info: Ignoring MPD Status Update Oct 06 11:19:46 volumio volumio[841]: info: sendMpdCommand stop took 2 milliseconds Oct 06 11:19:46 volumio volumio[841]: error: updateQueue error: null Oct 06 11:19:46 volumio volumio[841]: info: ------------------------------ 2ms Oct 06 11:19:46 volumio volumio[841]: info: Starting UPNP Playback Oct 06 11:19:46 volumio volumio[841]: info: Preparing playback through UPNP Oct 06 11:19:46 volumio volumio[841]: info: CoreCommandRouter::volumioGetState Oct 06 11:19:46 volumio volumio[841]: info: CorePlayQueue::getTrack 0 Oct 06 11:19:46 volumio volumio[841]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 06 11:19:46 volumio volumio[841]: info: Oct 06 11:19:46 volumio volumio[841]: ---------------------------- MPD announces system playlist update Oct 06 11:19:46 volumio volumio[841]: info: Ignoring MPD Status Update Oct 06 11:19:46 volumio volumio[841]: info: ------------------------------ 1ms Oct 06 11:19:46 volumio volumio[841]: info: Oct 06 11:19:46 volumio volumio[841]: ---------------------------- MPD announces system playlist update Oct 06 11:19:46 volumio volumio[841]: info: Ignoring MPD Status Update Oct 06 11:19:46 volumio volumio[841]: info: ------------------------------ 0ms Oct 06 11:19:46 volumio volumio[841]: info: Oct 06 11:19:46 volumio volumio[841]: ---------------------------- MPD announces system playlist update Oct 06 11:19:46 volumio volumio[841]: info: Ignoring MPD Status Update Oct 06 11:19:46 volumio volumio[841]: info: ------------------------------ 0ms Oct 06 11:19:46 volumio volumio[841]: info: Oct 06 11:19:46 volumio volumio[841]: ---------------------------- MPD announces system playlist update Oct 06 11:19:46 volumio volumio[841]: info: Ignoring MPD Status Update Oct 06 11:19:46 volumio volumio[841]: info: ------------------------------ 1ms Oct 06 11:19:46 volumio volumio[841]: info: Oct 06 11:19:46 volumio volumio[841]: ---------------------------- MPD announces system playlist update Oct 06 11:19:46 volumio volumio[841]: info: Ignoring MPD Status Update Oct 06 11:19:46 volumio volumio[841]: info: ------------------------------ 1ms Oct 06 11:19:46 volumio volumio[841]: info: Oct 06 11:19:46 volumio volumio[841]: ---------------------------- MPD announces system playlist update Oct 06 11:19:46 volumio volumio[841]: info: Ignoring MPD Status Update Oct 06 11:19:46 volumio volumio[841]: info: ------------------------------ 0ms Oct 06 11:19:47 volumio volumio[841]: info: Oct 06 11:19:47 volumio volumio[841]: ---------------------------- MPD announces state update: player Oct 06 11:19:47 volumio volumio[841]: info: ControllerMpd::getState Oct 06 11:19:47 volumio volumio[841]: verbose: ControllerMpd::sendMpdCommand status Oct 06 11:19:47 volumio volumio[841]: info: Oct 06 11:19:47 volumio volumio[841]: ---------------------------- MPD announces state update: player Oct 06 11:19:47 volumio volumio[841]: info: sendMpdCommand status took 25 milliseconds Oct 06 11:19:47 volumio volumio[841]: info: ControllerMpd::getState Oct 06 11:19:47 volumio volumio[841]: verbose: ControllerMpd::sendMpdCommand status Oct 06 11:19:47 volumio volumio[841]: verbose: ControllerMpd::parseState Oct 06 11:19:47 volumio volumio[841]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 11:19:47 volumio volumio[841]: info: sendMpdCommand status took 1 milliseconds Oct 06 11:19:47 volumio volumio[841]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 06 11:19:47 volumio volumio[841]: verbose: ControllerMpd::parseState Oct 06 11:19:47 volumio volumio[841]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 11:19:47 volumio volumio[841]: verbose: ControllerMpd::parseTrackInfo Oct 06 11:19:47 volumio volumio[841]: info: ControllerMpd::pushState Oct 06 11:19:47 volumio volumio[841]: info: CoreCommandRouter::servicePushState Oct 06 11:19:47 volumio volumio[841]: verbose: In UPNP mode Oct 06 11:19:47 volumio volumio[841]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":415,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Roll Downs","artist":"Fat Coda Studios","album":"Drums for Modern Dance Class (Horton Technique)","uri":"http://192.168.1.63:10528/tidal/48466186.flac","trackType":"tidal"} Oct 06 11:19:47 volumio volumio[841]: verbose: CURRENT POSITION 0 Oct 06 11:19:47 volumio volumio[841]: info: CoreStateMachine::syncState stateService play Oct 06 11:19:47 volumio volumio[841]: info: CoreStateMachine::syncState currentStatus stop Oct 06 11:19:47 volumio volumio[841]: info: ------------------------------ 27ms Oct 06 11:19:47 volumio volumio[841]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 06 11:19:47 volumio volumio[841]: verbose: ControllerMpd::parseTrackInfo Oct 06 11:19:47 volumio volumio[841]: info: ControllerMpd::pushState Oct 06 11:19:47 volumio volumio[841]: info: CoreCommandRouter::servicePushState Oct 06 11:19:47 volumio volumio[841]: verbose: In UPNP mode Oct 06 11:19:47 volumio volumio[841]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":415,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"570 Kbps","isStreaming":false,"title":"Roll Downs","artist":"Fat Coda Studios","album":"Drums for Modern Dance Class (Horton Technique)","uri":"http://192.168.1.63:10528/tidal/48466186.flac","trackType":"tidal"} Oct 06 11:19:47 volumio volumio[841]: verbose: CURRENT POSITION 0 Oct 06 11:19:47 volumio volumio[841]: info: CoreStateMachine::syncState stateService play Oct 06 11:19:47 volumio volumio[841]: info: CoreStateMachine::syncState currentStatus play Oct 06 11:19:47 volumio volumio[841]: info: Received an update from plugin. extracting info from payload Oct 06 11:19:47 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 06 11:19:47 volumio volumio[841]: info: CoreStateMachine::pushState Oct 06 11:19:47 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 06 11:19:47 volumio volumio[841]: info: CoreCommandRouter::volumioPushState Oct 06 11:19:47 volumio volumio[841]: info: CoreStateMachine::pushState Oct 06 11:19:47 volumio volumio[841]: info: CoreCommandRouter::volumioPushState Oct 06 11:19:47 volumio volumio[841]: info: ------------------------------ 10ms Oct 06 11:19:47 volumio volumio[841]: info: CoreCommandRouter::volumioGetQueue Oct 06 11:19:47 volumio volumio[841]: info: CoreStateMachine::getQueue Oct 06 11:19:47 volumio volumio[841]: info: CorePlayQueue::getQueue Oct 06 11:19:47 volumio volumio[841]: info: CoreCommandRouter::volumioGetQueue Oct 06 11:19:47 volumio volumio[841]: info: CoreStateMachine::getQueue Oct 06 11:19:47 volumio volumio[841]: info: CorePlayQueue::getQueue Oct 06 11:19:54 volumio volumio[841]: info: Oct 06 11:19:54 volumio volumio[841]: ---------------------------- MPD announces state update: player Oct 06 11:19:54 volumio volumio[841]: info: ControllerMpd::getState Oct 06 11:19:54 volumio volumio[841]: verbose: ControllerMpd::sendMpdCommand status Oct 06 11:19:54 volumio volumio[841]: info: sendMpdCommand status took 0 milliseconds Oct 06 11:19:54 volumio volumio[841]: verbose: ControllerMpd::parseState Oct 06 11:19:54 volumio volumio[841]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 06 11:19:54 volumio volumio[841]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 06 11:19:54 volumio volumio[841]: verbose: ControllerMpd::parseTrackInfo Oct 06 11:19:54 volumio volumio[841]: info: ControllerMpd::pushState Oct 06 11:19:54 volumio volumio[841]: info: CoreCommandRouter::servicePushState Oct 06 11:19:54 volumio volumio[841]: info: CoreStateMachine::pushState Oct 06 11:19:54 volumio volumio[841]: info: CoreCommandRouter::volumioPushState Oct 06 11:19:54 volumio volumio[841]: info: CorePlayQueue::getTrack 0 Oct 06 11:19:54 volumio volumio[841]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Roll Downs","artist":"Fat Coda Studios","album":"Drums for Modern Dance Class (Horton Technique)","uri":"http://192.168.1.63:10528/tidal/48466186.flac","trackType":"tidal"} Oct 06 11:19:54 volumio volumio[841]: verbose: CURRENT POSITION 0 Oct 06 11:19:54 volumio volumio[841]: info: CoreStateMachine::syncState stateService stop Oct 06 11:19:54 volumio volumio[841]: info: CoreStateMachine::syncState currentStatus play Oct 06 11:19:54 volumio volumio[841]: info: CoreCommandRouter::volumioPushState Oct 06 11:19:54 volumio volumio[841]: info: CoreStateMachine::stPlaybackTimer Oct 06 11:19:54 volumio volumio[841]: info: ------------------------------ 3ms Oct 06 11:19:54 volumio volumio[841]: info: CoreCommandRouter::volumioGetQueue Oct 06 11:19:54 volumio volumio[841]: info: CoreStateMachine::getQueue Oct 06 11:19:54 volumio volumio[841]: info: CorePlayQueue::getQueue Oct 06 11:19:59 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 11:19:59 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 11:19:59 volumio volumio[841]: info: Discovery: Getting this device information Oct 06 11:19:59 volumio volumio[841]: info: CoreCommandRouter::volumioGetState Oct 06 11:19:59 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 11:19:59 volumio volumio[841]: verbose: New Socket.io Connection to 192.168.1.3:3000 from 192.168.1.63 UA: Dart/3.4 (dart:io) Total Clients: 8 Oct 06 11:19:59 volumio volumio[841]: info: CoreCommandRouter::volumioGetState Oct 06 11:19:59 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 06 11:19:59 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 06 11:20:00 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 06 11:20:00 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 06 11:20:00 volumio volumio[841]: info: Discovery: Getting this device information Oct 06 11:20:00 volumio volumio[841]: info: CoreCommandRouter::volumioGetState Oct 06 11:20:00 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 06 11:20:04 volumio sudo[27707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 11:20:04 volumio sudo[27707]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 11:20:04 volumio sudo[27707]: pam_unix(sudo:session): session closed for user root Oct 06 11:20:04 volumio sudo[27710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 11:20:04 volumio sudo[27710]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 11:20:04 volumio sudo[27710]: pam_unix(sudo:session): session closed for user root Oct 06 11:20:04 volumio volumio[841]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.63 UA: Mozilla/5.0 (Linux; Android 10; ELS-NX9 Build/HUAWEIELS-N29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/92.0.4515.105 Mobile Safari/537.36 Total Clients: 9 Oct 06 11:20:04 volumio sudo[27713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 06 11:20:04 volumio sudo[27713]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 11:20:04 volumio sudo[27713]: pam_unix(sudo:session): session closed for user root Oct 06 11:20:04 volumio sudo[27716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 06 11:20:04 volumio sudo[27716]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 11:20:04 volumio sudo[27716]: pam_unix(sudo:session): session closed for user root Oct 06 11:20:04 volumio volumio[841]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.63 UA: Mozilla/5.0 (Linux; Android 10; ELS-NX9 Build/HUAWEIELS-N29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/92.0.4515.105 Mobile Safari/537.36 Total Clients: 10 Oct 06 11:20:05 volumio volumio[841]: info: CoreCommandRouter::volumioGetState Oct 06 11:20:05 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 06 11:20:05 volumio volumio[841]: info: Listing playlists Oct 06 11:20:05 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 06 11:20:05 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 06 11:20:05 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 06 11:20:05 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 06 11:20:06 volumio volumio[841]: info: CoreCommandRouter::volumioGetVisibleSources Oct 06 11:20:06 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 06 11:20:06 volumio volumio[841]: info: CoreCommandRouter::volumioGetQueue Oct 06 11:20:06 volumio volumio[841]: info: CoreStateMachine::getQueue Oct 06 11:20:06 volumio volumio[841]: info: CorePlayQueue::getQueue Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 11:20:09 volumio volumio[841]: info: Retrieving Cloud Streaming UI Oct 06 11:20:09 volumio volumio[841]: info: Getting Tidal Cloud Configuration Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 11:20:09 volumio volumio[841]: info: Getting Qobuz Cloud Configuration Oct 06 11:20:09 volumio volumio[841]: info: Asking plugin for UI Config Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 11:20:09 volumio volumio[841]: info: Getting Spotify Cloud Configuration Oct 06 11:20:09 volumio volumio[841]: info: Asking plugin for UI Config Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 11:20:09 volumio volumio[841]: info: Saving Spotify Acccount Oct 06 11:20:09 volumio volumio[841]: info: Got Tidal Cloud Configuration Oct 06 11:20:09 volumio volumio[841]: info: Got it Oct 06 11:20:09 volumio volumio[841]: info: Got it Oct 06 11:20:09 volumio volumio[841]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 06 11:20:09 volumio volumio[841]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::volumioGetBrowseSources Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::volumioGetBrowseSources Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::volumioGetBrowseSources Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 11:20:09 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 06 11:20:10 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 06 11:20:11 volumio volumio[841]: info: Disabling MyMusic plugin upnp Oct 06 11:20:11 volumio sudo[27720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 06 11:20:11 volumio sudo[27720]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 06 11:20:11 volumio volumio[841]: error: Upnp client error: Error: This socket has been ended by the other party Oct 06 11:20:11 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 06 11:20:13 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 11:20:17 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 06 11:20:18 volumio volumio[841]: info: Enabling MyMusic plugin upnp Oct 06 11:20:18 volumio volumio[841]: info: Enabling plugin upnp Oct 06 11:20:18 volumio volumio[841]: info: Loading plugin "upnp"... Oct 06 11:20:18 volumio volumio[841]: info: [1728188418007] Starting Upmpd Daemon Oct 06 11:20:18 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 06 11:20:18 volumio volumio[841]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 11:20:18 volumio volumio[841]: Error: listen EADDRINUSE: address already in use :::6599 Oct 06 11:20:18 volumio volumio[841]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Oct 06 11:20:18 volumio volumio[841]: at listenInCluster (net.js:1379:12) Oct 06 11:20:18 volumio volumio[841]: at Server.listen (net.js:1465:7) Oct 06 11:20:18 volumio volumio[841]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 06 11:20:18 volumio volumio[841]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 06 11:20:18 volumio volumio[841]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 06 11:20:18 volumio volumio[841]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 06 11:20:18 volumio volumio[841]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Oct 06 11:20:18 volumio volumio[841]: code: 'EADDRINUSE', Oct 06 11:20:18 volumio volumio[841]: errno: -98, Oct 06 11:20:18 volumio volumio[841]: syscall: 'listen', Oct 06 11:20:18 volumio volumio[841]: address: '::', Oct 06 11:20:18 volumio volumio[841]: port: 6599 Oct 06 11:20:18 volumio volumio[841]: } Oct 06 11:20:18 volumio volumio[841]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 06 11:20:18 volumio sudo[27731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-06 11:19 Oct 06 11:20:18 volumio sudo[27731]: 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="9552505bba4239c163ac9ee7b0b87a56e0dd0574" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="3d3fc502273a5c173ba19f72c1a952bdb2bdd3b0" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 Feb 2024 07:28:53 PM CET" VOLUMIO_VERSION="3.616" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="f11d51aa83fa91106999927181e8c0f7"