-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Fri 2025-10-24 13:03:08 +07. -- Oct 24 13:02:00 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 24 13:02:01 bego kernel: usb 1-1.4: new high-speed USB device number 6 using xhci_hcd Oct 24 13:02:01 bego kernel: usb 1-1.4: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.07 Oct 24 13:02:01 bego kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Oct 24 13:02:01 bego kernel: usb 1-1.4: Product: E50 Oct 24 13:02:01 bego kernel: usb 1-1.4: Manufacturer: Topping Oct 24 13:02:01 bego kernel: usb 1-1.4: 1:3 : unsupported format bits 0x100000000 Oct 24 13:02:01 bego kernel: hid-generic 0003:152A:8750.0004: hiddev96,hidraw0: USB HID v1.10 Device [Topping E50] on usb-0000:01:00.0-1.4/input3 Oct 24 13:02:01 bego volumio[961]: info: Oct 24 13:02:01 bego volumio[961]: ---------------------------- USB Audio Device Attached Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::Close All Modals sent Oct 24 13:02:01 bego volumio[961]: info: Preparing to save Alsa Options, stopping services first Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::volumioGetState Oct 24 13:02:01 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::volumioPause Oct 24 13:02:01 bego volumio[961]: info: CoreStateMachine::pause Oct 24 13:02:01 bego volumio[961]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"E50","alsacard":"E50"},"i2s":false} Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 24 13:02:01 bego volumio[961]: info: Device 5 does not have any Mixer Control Available Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 24 13:02:01 bego volumio[961]: info: Updating Volume Controller Parameters: Device: 5 Name: E50 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 24 13:02:01 bego volumio[961]: info: Disabling external Volume Control Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::volumioGetState Oct 24 13:02:01 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:01 bego volumio[961]: info: CoreStateMachine::pushState Oct 24 13:02:01 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 13:02:01 bego volumio[961]: info: Preparing to generate the ALSA configuration file Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 24 13:02:01 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 24 13:02:01 bego volumio[961]: info: Asound.conf file unchanged, so no further update is needed Oct 24 13:02:01 bego volumio[961]: info: Output device has changed, restarting MPD Oct 24 13:02:02 bego sudo[26601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 24 13:02:02 bego sudo[26601]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:02 bego sudo[26601]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:02 bego volumio[961]: info: Output device has changed, restarting Shairport Sync Oct 24 13:02:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:02:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 13:02:02 bego sudo[26604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 24 13:02:02 bego sudo[26604]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:02 bego systemd[1]: Stopping Music Player Daemon... Oct 24 13:02:02 bego volumio[961]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 24 13:02:02 bego systemd[1]: mpd.service: Succeeded. Oct 24 13:02:02 bego systemd[1]: Stopped Music Player Daemon. Oct 24 13:02:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 24 13:02:02 bego volumio[961]: info: MPD Permissions set Oct 24 13:02:02 bego volumio[961]: info: CoreCommandRouter::volumioGetState Oct 24 13:02:02 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:02:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:02:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:02:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:02:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:02:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:02:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:02:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:02:02 bego systemd[1]: Starting Music Player Daemon... Oct 24 13:02:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 24 13:02:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 24 13:02:02 bego volumio[961]: info: Starting Shairport Sync Oct 24 13:02:02 bego sudo[26620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 24 13:02:02 bego sudo[26620]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:02 bego systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 24 13:02:02 bego sudo[26609]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 24 13:02:02 bego systemd[1]: shairport-sync.service: Succeeded. Oct 24 13:02:02 bego sudo[26609]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:02 bego systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 24 13:02:02 bego sudo[26609]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:02 bego systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 24 13:02:02 bego sudo[26620]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:02 bego volumio[961]: info: Shairport-Sync Started Oct 24 13:02:03 bego mpd[26628]: Oct 24 13:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 24 13:02:03 bego systemd[1]: Started Music Player Daemon. Oct 24 13:02:03 bego sudo[26604]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:03 bego volumio[961]: error: updateQueue error: null Oct 24 13:02:05 bego volumio[961]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 24 13:02:05 bego volumio[961]: info: CoreStateMachine::getcurrentVolume Oct 24 13:02:05 bego volumio[961]: info: CoreCommandRouter::volumioRetrievevolume Oct 24 13:02:05 bego volumio[961]: info: CoreCommandRouter::volumioGetState Oct 24 13:02:05 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:05 bego volumio[961]: info: CoreStateMachine::pushState Oct 24 13:02:05 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:05 bego volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 13:02:13 bego sudo[26667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 13:02:13 bego sudo[26667]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:13 bego sudo[26667]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:13 bego sudo[26670]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 13:02:13 bego sudo[26670]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:13 bego sudo[26670]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:13 bego sudo[26673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 13:02:13 bego sudo[26673]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:13 bego sudo[26673]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:13 bego sudo[26676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 13:02:13 bego sudo[26676]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:13 bego sudo[26676]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:13 bego volumio[961]: verbose: New Socket.io Connection to 192.168.1.202 from 192.168.1.121 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:144.0) Gecko/20100101 Firefox/144.0 Engine version: 3 Transport: polling Total Clients: 5 Oct 24 13:02:13 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 24 13:02:13 bego volumio[961]: info: CoreCommandRouter::volumioGetVisibleSources Oct 24 13:02:14 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 24 13:02:14 bego volumio[961]: info: CoreCommandRouter::volumioGetState Oct 24 13:02:14 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:14 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 24 13:02:14 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 24 13:02:14 bego volumio[961]: info: Received Get System Info Oct 24 13:02:14 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 13:02:14 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 13:02:14 bego volumio[961]: info: Discovery: Getting this device information Oct 24 13:02:14 bego volumio[961]: info: CoreCommandRouter::volumioGetState Oct 24 13:02:14 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:14 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 13:02:14 bego volumio[961]: info: CoreCommandRouter::volumioGetState Oct 24 13:02:14 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:14 bego volumio[961]: info: Listing playlists Oct 24 13:02:14 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 24 13:02:14 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 24 13:02:14 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 24 13:02:14 bego volumio[961]: info: CoreCommandRouter::volumioGetState Oct 24 13:02:14 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:15 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 24 13:02:15 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 24 13:02:15 bego volumio[961]: info: Received Get System Info Oct 24 13:02:15 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 13:02:15 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 13:02:15 bego volumio[961]: info: Discovery: Getting this device information Oct 24 13:02:15 bego volumio[961]: info: CoreCommandRouter::volumioGetState Oct 24 13:02:15 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:15 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 13:02:17 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 24 13:02:17 bego volumio[961]: info: Received Get System Info Oct 24 13:02:17 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 13:02:17 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 13:02:17 bego volumio[961]: info: Discovery: Getting this device information Oct 24 13:02:17 bego volumio[961]: info: CoreCommandRouter::volumioGetState Oct 24 13:02:17 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:17 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 13:02:23 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 24 13:02:24 bego go-librespot[1273]: time="2025-10-24T13:02:24+07:00" level=trace msg="sent dealer ping" Oct 24 13:02:24 bego go-librespot[1273]: time="2025-10-24T13:02:24+07:00" level=trace msg="received dealer pong" Oct 24 13:02:32 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 24 13:02:32 bego volumio[961]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 24 13:02:32 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 24 13:02:32 bego volumio[961]: info: Received Get System Version Oct 24 13:02:32 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 24 13:02:32 bego volumio[961]: info: Received Get System Info Oct 24 13:02:32 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 24 13:02:32 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 24 13:02:32 bego volumio[961]: info: Discovery: Getting this device information Oct 24 13:02:32 bego volumio[961]: info: CoreCommandRouter::volumioGetState Oct 24 13:02:32 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:32 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 24 13:02:39 bego volumio[961]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 13:02:39 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 24 13:02:39 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 24 13:02:39 bego sudo[26752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 24 13:02:39 bego sudo[26752]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:39 bego sudo[26752]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:39 bego sudo[26758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 24 13:02:39 bego sudo[26758]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:39 bego sudo[26758]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:39 bego sudo[26766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 24 13:02:39 bego sudo[26766]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:39 bego sudo[26766]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:39 bego sudo[26772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 24 13:02:39 bego sudo[26772]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:39 bego sudo[26772]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:39 bego sudo[26778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 24 13:02:39 bego sudo[26778]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:39 bego sudo[26778]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:39 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 24 13:02:39 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 24 13:02:39 bego sudo[26781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 24 13:02:39 bego sudo[26781]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:39 bego sudo[26781]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:39 bego sudo[26784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 24 13:02:39 bego sudo[26784]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:43 bego sudo[26784]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:47 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 24 13:02:47 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 24 13:02:47 bego sudo[26799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 24 13:02:47 bego sudo[26799]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 13:02:51 bego sudo[26799]: pam_unix(sudo:session): session closed for user root Oct 24 13:02:53 bego volumio[961]: info: CoreCommandRouter::volumioGetState Oct 24 13:02:53 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:54 bego go-librespot[1273]: time="2025-10-24T13:02:54+07:00" level=trace msg="sent dealer ping" Oct 24 13:02:54 bego go-librespot[1273]: time="2025-10-24T13:02:54+07:00" level=trace msg="received dealer pong" Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 13:02:54 bego volumio[961]: info: Retrieving Cloud Streaming UI Oct 24 13:02:54 bego volumio[961]: info: Getting Tidal Cloud Configuration Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 13:02:54 bego volumio[961]: info: Getting Qobuz Cloud Configuration Oct 24 13:02:54 bego volumio[961]: info: Asking plugin for UI Config Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 13:02:54 bego volumio[961]: info: Getting Spotify Cloud Configuration Oct 24 13:02:54 bego volumio[961]: info: Asking plugin for UI Config Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 13:02:54 bego volumio[961]: info: Saving Spotify Acccount Oct 24 13:02:54 bego volumio[961]: info: Got Tidal Cloud Configuration Oct 24 13:02:54 bego volumio[961]: info: Got it Oct 24 13:02:54 bego volumio[961]: info: Got it Oct 24 13:02:54 bego volumio[961]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 24 13:02:54 bego volumio[961]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::volumioGetBrowseSources Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::volumioGetBrowseSources Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::volumioGetBrowseSources Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 13:02:54 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 24 13:02:58 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 24 13:02:58 bego volumio[961]: verbose: ControllerMpd::sendMpdCommand update Oct 24 13:02:58 bego volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 13:02:58 bego volumio[961]: info: Oct 24 13:02:58 bego volumio[961]: ---------------------------- MPD announces state update: update Oct 24 13:02:58 bego volumio[961]: info: sendMpdCommand update took 8 milliseconds Oct 24 13:02:58 bego volumio[961]: info: ControllerMpd::getState Oct 24 13:02:58 bego volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 13:02:58 bego volumio[961]: info: sendMpdCommand status took 10 milliseconds Oct 24 13:02:58 bego volumio[961]: info: sendMpdCommand status took 6 milliseconds Oct 24 13:02:58 bego volumio[961]: verbose: ControllerMpd::parseState Oct 24 13:02:58 bego volumio[961]: info: Command Router : Notfying DB Updatetrue Oct 24 13:02:58 bego volumio[961]: verbose: ControllerMpd::parseState Oct 24 13:02:58 bego volumio[961]: info: ControllerMpd::pushState Oct 24 13:02:58 bego volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 13:02:58 bego volumio[961]: info: CoreStateMachine::pushState Oct 24 13:02:58 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:58 bego volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 13:02:58 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:58 bego volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 13:02:58 bego volumio[961]: verbose: CURRENT POSITION 0 Oct 24 13:02:58 bego volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 13:02:58 bego volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 13:02:58 bego volumio[961]: info: CoreStateMachine::pushState Oct 24 13:02:58 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:58 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 13:02:58 bego volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 13:02:58 bego volumio[961]: info: No code Oct 24 13:02:58 bego volumio[961]: info: CoreStateMachine::pushState Oct 24 13:02:58 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:58 bego volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 13:02:58 bego volumio[961]: info: ------------------------------ 111ms Oct 24 13:02:58 bego volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 13:02:58 bego volumio[961]: info: Oct 24 13:02:58 bego volumio[961]: ---------------------------- MPD announces state update: update Oct 24 13:02:58 bego volumio[961]: info: ControllerMpd::getState Oct 24 13:02:58 bego volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 13:02:58 bego volumio[961]: info: sendMpdCommand status took 10 milliseconds Oct 24 13:02:58 bego volumio[961]: info: sendMpdCommand status took 10 milliseconds Oct 24 13:02:58 bego volumio[961]: verbose: ControllerMpd::parseState Oct 24 13:02:58 bego volumio[961]: info: Command Router : Notfying DB Updatefalse Oct 24 13:02:58 bego volumio[961]: info: CoreCommandRouter::Close All Modals sent Oct 24 13:02:58 bego volumio[961]: verbose: ControllerMpd::parseState Oct 24 13:02:58 bego volumio[961]: info: ControllerMpd::pushState Oct 24 13:02:58 bego volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 13:02:58 bego volumio[961]: info: CoreStateMachine::pushState Oct 24 13:02:58 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:58 bego volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 13:02:58 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:58 bego volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 13:02:58 bego volumio[961]: verbose: CURRENT POSITION 0 Oct 24 13:02:58 bego volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 13:02:58 bego volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 13:02:58 bego volumio[961]: info: CoreStateMachine::pushState Oct 24 13:02:58 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:58 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 13:02:58 bego volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 13:02:58 bego volumio[961]: info: No code Oct 24 13:02:58 bego volumio[961]: info: CoreStateMachine::pushState Oct 24 13:02:58 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:58 bego volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 13:02:58 bego volumio[961]: info: ------------------------------ 83ms Oct 24 13:02:58 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 13:02:59 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 24 13:02:59 bego volumio[961]: verbose: ControllerMpd::sendMpdCommand rescan Oct 24 13:02:59 bego volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 13:02:59 bego volumio[961]: info: Oct 24 13:02:59 bego volumio[961]: ---------------------------- MPD announces state update: update Oct 24 13:02:59 bego volumio[961]: info: sendMpdCommand rescan took 4 milliseconds Oct 24 13:02:59 bego volumio[961]: info: ControllerMpd::getState Oct 24 13:02:59 bego volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 13:02:59 bego volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 13:02:59 bego volumio[961]: info: Oct 24 13:02:59 bego volumio[961]: ---------------------------- MPD announces state update: update Oct 24 13:02:59 bego volumio[961]: info: ControllerMpd::getState Oct 24 13:02:59 bego volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 13:02:59 bego volumio[961]: info: sendMpdCommand status took 6 milliseconds Oct 24 13:02:59 bego volumio[961]: info: sendMpdCommand status took 4 milliseconds Oct 24 13:02:59 bego volumio[961]: verbose: ControllerMpd::parseState Oct 24 13:02:59 bego volumio[961]: info: Command Router : Notfying DB Updatefalse Oct 24 13:02:59 bego volumio[961]: info: CoreCommandRouter::Close All Modals sent Oct 24 13:02:59 bego volumio[961]: verbose: ControllerMpd::parseState Oct 24 13:02:59 bego volumio[961]: info: ControllerMpd::pushState Oct 24 13:02:59 bego volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 13:02:59 bego volumio[961]: info: CoreStateMachine::pushState Oct 24 13:02:59 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:59 bego volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 13:02:59 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:59 bego volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 13:02:59 bego volumio[961]: verbose: CURRENT POSITION 0 Oct 24 13:02:59 bego volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 13:02:59 bego volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 13:02:59 bego volumio[961]: info: CoreStateMachine::pushState Oct 24 13:02:59 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:59 bego volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 13:02:59 bego volumio[961]: info: No code Oct 24 13:02:59 bego volumio[961]: info: CoreStateMachine::pushState Oct 24 13:02:59 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:59 bego volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 13:02:59 bego volumio[961]: info: ------------------------------ 85ms Oct 24 13:02:59 bego volumio[961]: info: sendMpdCommand status took 85 milliseconds Oct 24 13:02:59 bego volumio[961]: info: sendMpdCommand status took 84 milliseconds Oct 24 13:02:59 bego volumio[961]: verbose: ControllerMpd::parseState Oct 24 13:02:59 bego volumio[961]: info: Command Router : Notfying DB Updatefalse Oct 24 13:02:59 bego volumio[961]: info: CoreCommandRouter::Close All Modals sent Oct 24 13:02:59 bego volumio[961]: verbose: ControllerMpd::parseState Oct 24 13:02:59 bego volumio[961]: info: ControllerMpd::pushState Oct 24 13:02:59 bego volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 13:02:59 bego volumio[961]: info: CoreStateMachine::pushState Oct 24 13:02:59 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:59 bego volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 13:02:59 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:59 bego volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 13:02:59 bego volumio[961]: verbose: CURRENT POSITION 0 Oct 24 13:02:59 bego volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 13:02:59 bego volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 13:02:59 bego volumio[961]: info: CoreStateMachine::pushState Oct 24 13:02:59 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:59 bego volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 13:02:59 bego volumio[961]: info: No code Oct 24 13:02:59 bego volumio[961]: info: CoreStateMachine::pushState Oct 24 13:02:59 bego volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 13:02:59 bego volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 13:02:59 bego volumio[961]: info: ------------------------------ 156ms Oct 24 13:03:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 24 13:03:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 24 13:03:02 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 13:03:03 bego volumio[961]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L BEGO-QNAP.local. -m SMB3_11 Oct 24 13:03:06 bego volumio[961]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 24 13:03:07 bego volumio[961]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 13:03:07 bego volumio[961]: Error: dns service error: unknown Oct 24 13:03:07 bego volumio[961]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Oct 24 13:03:07 bego volumio[961]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Oct 24 13:03:07 bego volumio[961]: errorCode: -65537 Oct 24 13:03:07 bego volumio[961]: } Oct 24 13:03:07 bego volumio[961]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 13:03:08 bego sudo[26919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-24 13:02 Oct 24 13:03:08 bego sudo[26919]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"