-- Logs begin at Tue 2025-01-21 02:23:13 CST, end at Tue 2025-03-18 10:47:39 CST. --
Mar 18 10:46:00 smsl-n100 volumio[942]: info: TidalConnect service stoped!
Mar 18 10:46:00 smsl-n100 volumio[942]: info: Got Tidal Cloud Configuration
Mar 18 10:46:00 smsl-n100 volumio[942]: info: Got it
Mar 18 10:46:00 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:46:00 smsl-n100 volumio[942]: info: TidalConnect service stoped!
Mar 18 10:46:00 smsl-n100 volumio[942]: info: Executing endpoint tc_getconfig
Mar 18 10:46:00 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Mar 18 10:46:00 smsl-n100 vtcs[1874]: STARTING TidalConnect services, version: 1.4.0.34
Mar 18 10:46:00 smsl-n100 volumio[942]: info: Asound.conf file written
Mar 18 10:46:00 smsl-n100 sudo[1886]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Mar 18 10:46:00 smsl-n100 sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:00 smsl-n100 sudo[1886]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:00 smsl-n100 vtcs[1874]: STARTED TidalConnect services.
Mar 18 10:46:00 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:46:00 smsl-n100 volumio[942]: No state is present for card Out
Mar 18 10:46:00 smsl-n100 volumio[942]: Found hardware: "I2S_Out" "" "" "" ""
Mar 18 10:46:00 smsl-n100 volumio[942]: Hardware is initialized using a generic method
Mar 18 10:46:00 smsl-n100 volumio[942]: No state is present for card Out
Mar 18 10:46:00 smsl-n100 volumio[942]: No state is present for card SPDIF
Mar 18 10:46:00 smsl-n100 volumio[942]: Found hardware: "SPDIF" "" "" "" ""
Mar 18 10:46:00 smsl-n100 volumio[942]: Hardware is initialized using a generic method
Mar 18 10:46:00 smsl-n100 volumio[942]: No state is present for card SPDIF
Mar 18 10:46:00 smsl-n100 volumio[942]: info: Output device has changed, restarting MPD
Mar 18 10:46:00 smsl-n100 sudo[1898]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 18 10:46:00 smsl-n100 sudo[1898]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:00 smsl-n100 volumio[942]: info: Output device has changed, restarting Shairport Sync
Mar 18 10:46:00 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:00 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:46:00 smsl-n100 sudo[1898]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:00 smsl-n100 sudo[1901]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 18 10:46:00 smsl-n100 sudo[1901]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:00 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:46:00 smsl-n100 volumio[942]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 18 10:46:00 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:46:00 smsl-n100 volumio[942]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Mar 18 10:46:00 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Mar 18 10:46:00 smsl-n100 systemd[1]: mpd.service: Succeeded.
Mar 18 10:46:00 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:00 smsl-n100 systemd[1]: Stopped Music Player Daemon.
Mar 18 10:46:00 smsl-n100 volumio[1377]: ......................................................................................................................................................................++++
Mar 18 10:46:00 smsl-n100 volumio[1377]: e is 65537 (0x010001)
Mar 18 10:46:00 smsl-n100 systemd[1]: Starting Music Player Daemon...
Mar 18 10:46:00 smsl-n100 volumio[942]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes
Mar 18 10:46:00 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:00 smsl-n100 volumio[1377]: writing RSA key
Mar 18 10:46:00 smsl-n100 sudo[1915]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 18 10:46:00 smsl-n100 sudo[1915]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:00 smsl-n100 sudo[1917]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 18 10:46:00 smsl-n100 sudo[1917]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:00 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service...
Mar 18 10:46:01 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM
Mar 18 10:46:01 smsl-n100 systemd[1]: vtcs.service: Succeeded.
Mar 18 10:46:01 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service.
Mar 18 10:46:01 smsl-n100 sudo[1906]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Mar 18 10:46:01 smsl-n100 sudo[1906]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:01 smsl-n100 sudo[1906]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:01 smsl-n100 sudo[1915]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:01 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:46:01 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:46:01 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:46:01 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:46:01 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:46:01 smsl-n100 sudo[1917]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:01 smsl-n100 volumio[942]: info: Executing endpoint tc_connect
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Mar 18 10:46:01 smsl-n100 volumio[942]: info: Connecting to TidalConnect
Mar 18 10:46:01 smsl-n100 volumio[942]: info: MPD Permissions set
Mar 18 10:46:01 smsl-n100 volumio[942]: info: RAAT: Requesting Headphone Status
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:01 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:01 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:01 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:01 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:46:01 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:01 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:01 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:01 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:01 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:01 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:46:01 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:01 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:46:01 smsl-n100 volumio[942]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Mar 18 10:46:01 smsl-n100 volumio[942]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect
Mar 18 10:46:01 smsl-n100 sudo[1932]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Mar 18 10:46:01 smsl-n100 sudo[1932]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: Not Reporting Auto name since its the default one
Mar 18 10:46:01 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service.
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:01 smsl-n100 sudo[1932]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:01 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:01 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:01 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:01 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:46:01 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:01 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:01 smsl-n100 volumio[942]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Mar 18 10:46:01 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable
Mar 18 10:46:02 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:46:02 smsl-n100 volumio[942]: info: Starting Shairport Sync
Mar 18 10:46:02 smsl-n100 sudo[1950]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service
Mar 18 10:46:02 smsl-n100 sudo[1950]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:02 smsl-n100 volumio[942]: info: Preparing to generate the ALSA configuration file
Mar 18 10:46:02 smsl-n100 sudo[1954]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 18 10:46:02 smsl-n100 sudo[1950]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:02 smsl-n100 sudo[1954]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:02 smsl-n100 sudo[1956]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service
Mar 18 10:46:02 smsl-n100 sudo[1956]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:02 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Mar 18 10:46:02 smsl-n100 systemd[1]: shairport-sync.service: Succeeded.
Mar 18 10:46:02 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:02 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Mar 18 10:46:02 smsl-n100 sudo[1954]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:02 smsl-n100 volumio[942]: info: Executing endpoint tc_getconfig
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Mar 18 10:46:02 smsl-n100 vtcs[1938]: STARTING TidalConnect services, version: 1.4.0.34
Mar 18 10:46:02 smsl-n100 volumio[942]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Mar 18 10:46:02 smsl-n100 volumio[942]: info: Reading ALSA contributions from plugins.
Mar 18 10:46:02 smsl-n100 systemd[1]: Stopping RAAT DAEMON...
Mar 18 10:46:02 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:46:02 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:46:02 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM
Mar 18 10:46:02 smsl-n100 systemd[1]: raat-daemon.service: Succeeded.
Mar 18 10:46:02 smsl-n100 systemd[1]: Stopped RAAT DAEMON.
Mar 18 10:46:02 smsl-n100 systemd[1]: Started RAAT DAEMON.
Mar 18 10:46:02 smsl-n100 sudo[1956]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:02 smsl-n100 volumio[942]: info: TidalConnect service started!
Mar 18 10:46:02 smsl-n100 volumio[942]: info: Shairport-Sync Started
Mar 18 10:46:02 smsl-n100 volumio[942]: info: TidalConnect service started!
Mar 18 10:46:02 smsl-n100 volumio[942]: info: BOOT COMPLETED
Mar 18 10:46:02 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:46:02 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:46:02 smsl-n100 vtcs[1938]: STARTED TidalConnect services.
Mar 18 10:46:02 smsl-n100 volumio[942]: info: Raat Daemon started successfully
Mar 18 10:46:02 smsl-n100 volumio[942]: info: Executing endpoint tc_connect
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Mar 18 10:46:02 smsl-n100 volumio[942]: info: Connecting to TidalConnect
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreCommandRouter::servicePushState
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:02 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:02 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:02 smsl-n100 volumio[942]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Mar 18 10:46:02 smsl-n100 volumio[942]: verbose: CURRENT POSITION 0
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   stateService stop
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   currentStatus stop
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:02 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:02 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:02 smsl-n100 volumio[942]: info: No code
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:02 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:02 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreCommandRouter::servicePushState
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:02 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:02 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:02 smsl-n100 volumio[942]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Mar 18 10:46:02 smsl-n100 volumio[942]: verbose: CURRENT POSITION 0
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   stateService stop
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   currentStatus stop
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:02 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:02 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:02 smsl-n100 volumio[942]: info: No code
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:02 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:02 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:02 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:03 smsl-n100 volumio[942]: info: Executing endpoint restartRAATSocket
Mar 18 10:46:03 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection
Mar 18 10:46:03 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:03 smsl-n100 volumio[942]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Mar 18 10:46:03 smsl-n100 volumio[942]: info: Asound.conf file written
Mar 18 10:46:03 smsl-n100 sudo[1990]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Mar 18 10:46:03 smsl-n100 sudo[1990]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:03 smsl-n100 sudo[1990]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:03 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:46:03 smsl-n100 volumio[942]: No state is present for card Out
Mar 18 10:46:03 smsl-n100 volumio[942]: Found hardware: "I2S_Out" "" "" "" ""
Mar 18 10:46:03 smsl-n100 volumio[942]: Hardware is initialized using a generic method
Mar 18 10:46:03 smsl-n100 volumio[942]: No state is present for card Out
Mar 18 10:46:03 smsl-n100 volumio[942]: No state is present for card SPDIF
Mar 18 10:46:03 smsl-n100 volumio[942]: Found hardware: "SPDIF" "" "" "" ""
Mar 18 10:46:03 smsl-n100 volumio[942]: Hardware is initialized using a generic method
Mar 18 10:46:03 smsl-n100 volumio[942]: No state is present for card SPDIF
Mar 18 10:46:03 smsl-n100 volumio[942]: info: Output device has changed, restarting MPD
Mar 18 10:46:03 smsl-n100 sudo[1995]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 18 10:46:03 smsl-n100 volumio[942]: info: Output device has changed, restarting Shairport Sync
Mar 18 10:46:03 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:03 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:46:03 smsl-n100 sudo[1995]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:03 smsl-n100 sudo[1995]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:03 smsl-n100 sudo[1997]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 18 10:46:03 smsl-n100 sudo[1997]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:03 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Mar 18 10:46:03 smsl-n100 systemd[1]: mpd.service: Succeeded.
Mar 18 10:46:03 smsl-n100 systemd[1]: Stopped Music Player Daemon.
Mar 18 10:46:03 smsl-n100 systemd[1]: Starting Music Player Daemon...
Mar 18 10:46:03 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:46:03 smsl-n100 volumio[942]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 18 10:46:03 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:46:03 smsl-n100 volumio[942]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Mar 18 10:46:03 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:03 smsl-n100 volumio[942]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes
Mar 18 10:46:03 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:03 smsl-n100 vtcs[1938]: [2025-03-18 10:46:03.791] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE
Mar 18 10:46:03 smsl-n100 vtcs[1938]: [2025-03-18 10:46:03.795] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=7 errorno=104 error=Connection reset by peer
Mar 18 10:46:03 smsl-n100 vtcs[1938]: [2025-03-18 10:46:03.795] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected
Mar 18 10:46:03 smsl-n100 sudo[2007]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 18 10:46:03 smsl-n100 sudo[2007]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:03 smsl-n100 sudo[2003]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Mar 18 10:46:03 smsl-n100 sudo[2003]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:03 smsl-n100 sudo[2003]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:03 smsl-n100 sudo[2010]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 18 10:46:03 smsl-n100 sudo[2010]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:04 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service...
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM
Mar 18 10:46:04 smsl-n100 systemd[1]: vtcs.service: Succeeded.
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service.
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: Not Reporting Auto name since its the default one
Mar 18 10:46:04 smsl-n100 sudo[2007]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:04 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:46:04 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:46:04 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:46:04 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:46:04 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:46:04 smsl-n100 sudo[2010]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:04 smsl-n100 volumio[942]: info: MPD Permissions set
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:04 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:04 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:04 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:04 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:46:04 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:04 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:04 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:04 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:04 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:04 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:46:04 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:04 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:46:04 smsl-n100 volumio[942]: info: TidalConnect service stoped!
Mar 18 10:46:04 smsl-n100 volumio[942]: info: TidalConnect service stoped!
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: Not Reporting Auto name since its the default one
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable
Mar 18 10:46:04 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:46:04 smsl-n100 systemd[1]: systemd-hostnamed.service: Succeeded.
Mar 18 10:46:04 smsl-n100 sudo[2039]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service
Mar 18 10:46:04 smsl-n100 sudo[2039]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:04 smsl-n100 volumio[942]: info: TidalConnect service started!
Mar 18 10:46:04 smsl-n100 volumio[942]: info: Starting Shairport Sync
Mar 18 10:46:04 smsl-n100 sudo[2039]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:04 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable
Mar 18 10:46:04 smsl-n100 sudo[2047]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service
Mar 18 10:46:04 smsl-n100 sudo[2047]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:04 smsl-n100 sudo[2049]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 18 10:46:04 smsl-n100 sudo[2049]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:05 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Mar 18 10:46:05 smsl-n100 systemd[1]: Stopping RAAT DAEMON...
Mar 18 10:46:05 smsl-n100 systemd[1]: shairport-sync.service: Succeeded.
Mar 18 10:46:05 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:46:05 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Mar 18 10:46:05 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM
Mar 18 10:46:05 smsl-n100 systemd[1]: raat-daemon.service: Succeeded.
Mar 18 10:46:05 smsl-n100 systemd[1]: Stopped RAAT DAEMON.
Mar 18 10:46:05 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Mar 18 10:46:05 smsl-n100 systemd[1]: mpd.service: Failed with result 'protocol'.
Mar 18 10:46:05 smsl-n100 systemd[1]: Failed to start Music Player Daemon.
Mar 18 10:46:05 smsl-n100 volumio[942]: info: RAAT: Requesting Headphone Status
Mar 18 10:46:05 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus
Mar 18 10:46:05 smsl-n100 sudo[1997]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:05 smsl-n100 sudo[1901]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:05 smsl-n100 sudo[2057]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service
Mar 18 10:46:05 smsl-n100 sudo[2057]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:05 smsl-n100 sudo[1783]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:05 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:46:05 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:46:05 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:46:05 smsl-n100 systemd[1]: Starting Music Player Daemon...
Mar 18 10:46:05 smsl-n100 volumio[942]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Mar 18 10:46:05 smsl-n100 systemd[1]: Started RAAT DAEMON.
Mar 18 10:46:05 smsl-n100 volumio[942]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect
Mar 18 10:46:05 smsl-n100 sudo[2047]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:05 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Mar 18 10:46:05 smsl-n100 sudo[2049]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:05 smsl-n100 sudo[2057]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:05 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:46:05 smsl-n100 sudo[2068]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service
Mar 18 10:46:05 smsl-n100 sudo[2068]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:05 smsl-n100 volumio[942]: info: Shairport-Sync Started
Mar 18 10:46:05 smsl-n100 volumio[942]: info: Raat Daemon started successfully
Mar 18 10:46:05 smsl-n100 volumio[942]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service
Mar 18 10:46:05 smsl-n100 volumio[942]: Job for mpd.service failed because the service did not take the steps required by its unit configuration.
Mar 18 10:46:05 smsl-n100 volumio[942]: See "systemctl status mpd.service" and "journalctl -xe" for details.
Mar 18 10:46:05 smsl-n100 volumio[942]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service
Mar 18 10:46:05 smsl-n100 volumio[942]: Job for mpd.service failed because the service did not take the steps required by its unit configuration.
Mar 18 10:46:05 smsl-n100 volumio[942]: See "systemctl status mpd.service" and "journalctl -xe" for details.
Mar 18 10:46:05 smsl-n100 volumio[942]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service
Mar 18 10:46:05 smsl-n100 volumio[942]: Job for mpd.service failed because the service did not take the steps required by its unit configuration.
Mar 18 10:46:05 smsl-n100 volumio[942]: See "systemctl status mpd.service" and "journalctl -xe" for details.
Mar 18 10:46:05 smsl-n100 sudo[2067]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Mar 18 10:46:05 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:05 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:05 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:05 smsl-n100 sudo[2067]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:05 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:05 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:05 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:05 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:46:05 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:05 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:46:05 smsl-n100 systemd[1]: Stopping RAAT DAEMON...
Mar 18 10:46:05 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM
Mar 18 10:46:05 smsl-n100 systemd[1]: raat-daemon.service: Succeeded.
Mar 18 10:46:05 smsl-n100 systemd[1]: Stopped RAAT DAEMON.
Mar 18 10:46:05 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:46:05 smsl-n100 systemd[1]: Started RAAT DAEMON.
Mar 18 10:46:05 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:46:05 smsl-n100 sudo[2068]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:05 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:46:05 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service.
Mar 18 10:46:05 smsl-n100 volumio[942]: info: Executing endpoint restartRAATSocket
Mar 18 10:46:05 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection
Mar 18 10:46:05 smsl-n100 sudo[2059]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Mar 18 10:46:05 smsl-n100 volumio[942]: info: Raat Daemon started successfully
Mar 18 10:46:05 smsl-n100 sudo[2059]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:05 smsl-n100 sudo[2067]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:05 smsl-n100 sudo[2059]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:05 smsl-n100 volumio[942]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Mar 18 10:46:05 smsl-n100 volumio[942]: info: Executing endpoint restartRAATSocket
Mar 18 10:46:05 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:06 smsl-n100 volumio[942]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetBrowseSources
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 18 10:46:06 smsl-n100 volumio[942]: info: Executing endpoint tc_getconfig
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Mar 18 10:46:06 smsl-n100 vtcs[2082]: STARTING TidalConnect services, version: 1.4.0.34
Mar 18 10:46:06 smsl-n100 vtcs[2082]: STARTED TidalConnect services.
Mar 18 10:46:06 smsl-n100 volumio[942]: info: Executing endpoint tc_connect
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Mar 18 10:46:06 smsl-n100 volumio[942]: info: Connecting to TidalConnect
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::servicePushState
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:06 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:06 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:06 smsl-n100 volumio[942]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Mar 18 10:46:06 smsl-n100 volumio[942]: verbose: CURRENT POSITION 0
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   stateService stop
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   currentStatus stop
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:06 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:06 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:06 smsl-n100 volumio[942]: info: No code
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:06 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:06 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::servicePushState
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:06 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:06 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:06 smsl-n100 volumio[942]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false}
Mar 18 10:46:06 smsl-n100 volumio[942]: verbose: CURRENT POSITION 0
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   stateService stop
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   currentStatus stop
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:06 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:06 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:06 smsl-n100 volumio[942]: info: No code
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:06 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:06 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule
Mar 18 10:46:06 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 18 10:46:07 smsl-n100 volumio[942]: info: TidalConnect service stoped!
Mar 18 10:46:07 smsl-n100 volumio[942]: info: TidalConnect service stoped!
Mar 18 10:46:07 smsl-n100 sudo[2129]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 18 10:46:07 smsl-n100 sudo[2129]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:07 smsl-n100 sudo[2129]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:07 smsl-n100 sudo[2131]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 18 10:46:07 smsl-n100 sudo[2131]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:07 smsl-n100 sudo[2131]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:07 smsl-n100 volumio[942]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Mar 18 10:46:07 smsl-n100 volumio[942]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect
Mar 18 10:46:07 smsl-n100 volumio[942]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8
Mar 18 10:46:07 smsl-n100 sudo[2139]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Mar 18 10:46:07 smsl-n100 sudo[2139]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 18 10:46:07 smsl-n100 sudo[2139]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetVisibleSources
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Mar 18 10:46:07 smsl-n100 volumio[942]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom
Mar 18 10:46:07 smsl-n100 volumio[942]: info: Received Get System Info
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 18 10:46:07 smsl-n100 volumio[942]: info: Discovery: Getting this device information
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:07 smsl-n100 volumio[942]: info: RAAT: Requesting Headphone Status
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus
Mar 18 10:46:07 smsl-n100 volumio[942]: info: Listing playlists
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:07 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:07 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:07 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:07 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:07 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:46:07 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:07 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:46:08 smsl-n100 sudo[2143]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 18 10:46:08 smsl-n100 volumio[942]: info: RAAT: Requesting Headphone Status
Mar 18 10:46:08 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus
Mar 18 10:46:08 smsl-n100 sudo[2143]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:08 smsl-n100 sudo[2143]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:08 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:08 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:08 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:08 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:08 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:08 smsl-n100 sudo[2146]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 18 10:46:08 smsl-n100 sudo[2146]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:08 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:08 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:46:08 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:08 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:46:08 smsl-n100 sudo[2146]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:08 smsl-n100 volumio[942]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 8
Mar 18 10:46:08 smsl-n100 volumio[942]: info: TidalConnect service started!
Mar 18 10:46:08 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetVisibleSources
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetQueue
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CoreStateMachine::getQueue
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CorePlayQueue::getQueue
Mar 18 10:46:09 smsl-n100 volumio[942]: info: Listing playlists
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Mar 18 10:46:09 smsl-n100 volumio[942]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom
Mar 18 10:46:09 smsl-n100 volumio[942]: info: Received Get System Info
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 18 10:46:09 smsl-n100 volumio[942]: info: Discovery: Getting this device information
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:46:09 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:09 smsl-n100 mpd[2091]: Mar 18 10:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Mar 18 10:46:09 smsl-n100 systemd[1]: Started Music Player Daemon.
Mar 18 10:46:09 smsl-n100 volumio[942]: error: MPD error: The expression evaluated to a falsy value:
Mar 18 10:46:09 smsl-n100 volumio[942]:   assert.ok(self.idling)
Mar 18 10:46:09 smsl-n100 volumio[942]: error: The expression evaluated to a falsy value:
Mar 18 10:46:09 smsl-n100 volumio[942]:   assert.ok(self.idling)
Mar 18 10:46:09 smsl-n100 mpd_monitor.sh[566]: MPD restarted due to no mpc output.
Mar 18 10:46:09 smsl-n100 volumio[942]: error: MPD error: The expression evaluated to a falsy value:
Mar 18 10:46:09 smsl-n100 volumio[942]:   assert.ok(self.idling)
Mar 18 10:46:09 smsl-n100 volumio[942]: error: The expression evaluated to a falsy value:
Mar 18 10:46:09 smsl-n100 volumio[942]:   assert.ok(self.idling)
Mar 18 10:46:09 smsl-n100 volumio[942]: error: updateQueue error: null
Mar 18 10:46:10 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 18 10:46:10 smsl-n100 volumio[942]: info: Received Get System Info
Mar 18 10:46:10 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 18 10:46:10 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 18 10:46:10 smsl-n100 volumio[942]: info: Discovery: Getting this device information
Mar 18 10:46:10 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:46:10 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:10 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 18 10:46:10 smsl-n100 volumio[942]: info: TidalConnect service started!
Mar 18 10:46:15 smsl-n100 kernel: usb 2-1.4: USB disconnect, device number 5
Mar 18 10:46:15 smsl-n100 kernel: ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
Mar 18 10:46:15 smsl-n100 kernel: ch341 2-1.4:1.0: device disconnected
Mar 18 10:46:17 smsl-n100 kernel: usb 2-1.4: new high-speed USB device number 6 using ehci-platform
Mar 18 10:46:17 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Mar 18 10:46:17 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Mar 18 10:46:17 smsl-n100 volumio-remote-updater[574]: No test mode
Mar 18 10:46:17 smsl-n100 volumio-remote-updater[574]: No alpha test mode
Mar 18 10:46:17 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Mar 18 10:46:17 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Mar 18 10:46:17 smsl-n100 kernel: usb 2-1.4: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.23
Mar 18 10:46:17 smsl-n100 kernel: usb 2-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=2
Mar 18 10:46:17 smsl-n100 kernel: usb 2-1.4: Product: SMSL USB AUDIO
Mar 18 10:46:17 smsl-n100 kernel: usb 2-1.4: Manufacturer: SMSL
Mar 18 10:46:17 smsl-n100 kernel: hid-generic 0003:152A:85DD.0001: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-ff5c0000.usb-1.4/input3
Mar 18 10:46:17 smsl-n100 kernel: usb 2-1.4: 1:3 : unsupported format bits 0x100000000
Mar 18 10:46:17 smsl-n100 kernel: usbcore: registered new interface driver snd-usb-audio
Mar 18 10:46:18 smsl-n100 systemd-udevd[2151]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99.
Mar 18 10:46:18 smsl-n100 volumio[942]: info:
Mar 18 10:46:18 smsl-n100 volumio[942]: ---------------------------- USB Audio Device Attached
Mar 18 10:46:18 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach
Mar 18 10:46:18 smsl-n100 volumio-remote-updater[574]: No test mode
Mar 18 10:46:18 smsl-n100 volumio-remote-updater[574]: No alpha test mode
Mar 18 10:46:18 smsl-n100 volumio[942]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES


Improved translations
 
Fix Multiroom Buffer Sizes
 
Increase results for Kana-Kanji Conversion
 
Fix for streaming interruptions


NEW ADDITIONS


Add network status on the serial API
 ","title":"Update v3.789","updateavailable":true}
Mar 18 10:46:18 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Mar 18 10:46:18 smsl-n100 kernel: usb 2-1.4: USB disconnect, device number 6
Mar 18 10:46:18 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Mar 18 10:46:18 smsl-n100 volumio[942]: info:
Mar 18 10:46:18 smsl-n100 volumio[942]: ---------------------------- USB Audio Device Detached
Mar 18 10:46:18 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach
Mar 18 10:46:18 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 18 10:46:18 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:46:18 smsl-n100 kernel: usb 2-1.4: new full-speed USB device number 7 using ehci-platform
Mar 18 10:46:18 smsl-n100 kernel: usb 2-1.4: New USB device found, idVendor=1a86, idProduct=7523, bcdDevice= 2.64
Mar 18 10:46:18 smsl-n100 kernel: usb 2-1.4: New USB device strings: Mfr=0, Product=2, SerialNumber=0
Mar 18 10:46:18 smsl-n100 kernel: usb 2-1.4: Product: USB Serial
Mar 18 10:46:18 smsl-n100 kernel: ch341 2-1.4:1.0: ch341-uart converter detected
Mar 18 10:46:18 smsl-n100 kernel: usb 2-1.4: ch341-uart converter now attached to ttyUSB0
Mar 18 10:46:18 smsl-n100 volumio[942]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES


Improved translations
 
Fix Multiroom Buffer Sizes
 
Increase results for Kana-Kanji Conversion
 
Fix for streaming interruptions


NEW ADDITIONS


Add network status on the serial API
 ","title":"Update v3.789","updateavailable":true}
Mar 18 10:46:18 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Mar 18 10:46:18 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Mar 18 10:46:19 smsl-n100 kernel: usb 2-1.4: USB disconnect, device number 7
Mar 18 10:46:19 smsl-n100 kernel: ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
Mar 18 10:46:19 smsl-n100 kernel: ch341 2-1.4:1.0: device disconnected
Mar 18 10:46:21 smsl-n100 kernel: usb 2-1.4: new high-speed USB device number 8 using ehci-platform
Mar 18 10:46:21 smsl-n100 kernel: usb 2-1.4: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.23
Mar 18 10:46:21 smsl-n100 kernel: usb 2-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=2
Mar 18 10:46:21 smsl-n100 kernel: usb 2-1.4: Product: SMSL USB AUDIO
Mar 18 10:46:21 smsl-n100 kernel: usb 2-1.4: Manufacturer: SMSL
Mar 18 10:46:21 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule
Mar 18 10:46:21 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 18 10:46:21 smsl-n100 kernel: usb 2-1.4: 1:3 : unsupported format bits 0x100000000
Mar 18 10:46:21 smsl-n100 kernel: hid-generic 0003:152A:85DD.0002: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-ff5c0000.usb-1.4/input3
Mar 18 10:46:21 smsl-n100 systemd[1]: systemd-timedated.service: Succeeded.
Mar 18 10:46:21 smsl-n100 systemd-udevd[2169]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99.
Mar 18 10:46:21 smsl-n100 volumio[942]: info:
Mar 18 10:46:21 smsl-n100 volumio[942]: ---------------------------- USB Audio Device Attached
Mar 18 10:46:21 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach
Mar 18 10:46:21 smsl-n100 volumio-remote-updater[574]: No test mode
Mar 18 10:46:21 smsl-n100 volumio-remote-updater[574]: No alpha test mode
Mar 18 10:46:21 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Mar 18 10:46:21 smsl-n100 kernel: usb 2-1.4: USB disconnect, device number 8
Mar 18 10:46:22 smsl-n100 volumio[942]: info:
Mar 18 10:46:22 smsl-n100 volumio[942]: ---------------------------- USB Audio Device Detached
Mar 18 10:46:22 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach
Mar 18 10:46:22 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 18 10:46:22 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:46:22 smsl-n100 kernel: usb 2-1.4: new full-speed USB device number 9 using ehci-platform
Mar 18 10:46:22 smsl-n100 volumio[942]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES


Improved translations
 
Fix Multiroom Buffer Sizes
 
Increase results for Kana-Kanji Conversion
 
Fix for streaming interruptions


NEW ADDITIONS


Add network status on the serial API
 ","title":"Update v3.789","updateavailable":true}
Mar 18 10:46:22 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Mar 18 10:46:22 smsl-n100 kernel: usb 2-1.4: New USB device found, idVendor=1a86, idProduct=7523, bcdDevice= 2.64
Mar 18 10:46:22 smsl-n100 kernel: usb 2-1.4: New USB device strings: Mfr=0, Product=2, SerialNumber=0
Mar 18 10:46:22 smsl-n100 kernel: usb 2-1.4: Product: USB Serial
Mar 18 10:46:22 smsl-n100 kernel: ch341 2-1.4:1.0: ch341-uart converter detected
Mar 18 10:46:22 smsl-n100 kernel: usb 2-1.4: ch341-uart converter now attached to ttyUSB0
Mar 18 10:46:22 smsl-n100 kernel: usb 2-1.4: USB disconnect, device number 9
Mar 18 10:46:22 smsl-n100 kernel: ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
Mar 18 10:46:22 smsl-n100 kernel: ch341 2-1.4:1.0: device disconnected
Mar 18 10:46:23 smsl-n100 kernel: usb 2-1.4: new full-speed USB device number 10 using ehci-platform
Mar 18 10:46:23 smsl-n100 kernel: usb 2-1.4: New USB device found, idVendor=1a86, idProduct=7523, bcdDevice= 2.64
Mar 18 10:46:23 smsl-n100 kernel: usb 2-1.4: New USB device strings: Mfr=0, Product=2, SerialNumber=0
Mar 18 10:46:23 smsl-n100 kernel: usb 2-1.4: Product: USB Serial
Mar 18 10:46:23 smsl-n100 kernel: ch341 2-1.4:1.0: ch341-uart converter detected
Mar 18 10:46:23 smsl-n100 kernel: usb 2-1.4: ch341-uart converter now attached to ttyUSB0
Mar 18 10:46:32 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:46:32 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Mar 18 10:46:32 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Mar 18 10:46:32 smsl-n100 sudo[2214]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Mar 18 10:46:32 smsl-n100 sudo[2214]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:32 smsl-n100 sudo[2214]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:32 smsl-n100 sudo[2220]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Mar 18 10:46:32 smsl-n100 sudo[2220]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:32 smsl-n100 sudo[2220]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:32 smsl-n100 sudo[2228]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Mar 18 10:46:32 smsl-n100 sudo[2228]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:32 smsl-n100 sudo[2228]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:32 smsl-n100 sudo[2233]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Mar 18 10:46:32 smsl-n100 sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:32 smsl-n100 sudo[2233]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:32 smsl-n100 sudo[2239]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 18 10:46:32 smsl-n100 sudo[2239]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:32 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Mar 18 10:46:32 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Mar 18 10:46:32 smsl-n100 sudo[2239]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:32 smsl-n100 sudo[2242]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 18 10:46:32 smsl-n100 sudo[2242]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:32 smsl-n100 sudo[2242]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:32 smsl-n100 sudo[2246]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Mar 18 10:46:32 smsl-n100 sudo[2246]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:37 smsl-n100 sudo[2246]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:39 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings
Mar 18 10:46:39 smsl-n100 volumio[942]: info: Saving new wireless network
Mar 18 10:46:39 smsl-n100 sudo[2301]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf
Mar 18 10:46:39 smsl-n100 sudo[2301]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:39 smsl-n100 sudo[2301]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:39 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart
Mar 18 10:46:39 smsl-n100 volumio[942]: info: Discovery: Restarting Advertising due to device name change
Mar 18 10:46:39 smsl-n100 sudo[2305]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service
Mar 18 10:46:39 smsl-n100 sudo[2305]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:39 smsl-n100 systemd[1]: Starting Wireless Services...
Mar 18 10:46:39 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:46:40 smsl-n100 wireless.js[2309]: Cleaning previous...
Mar 18 10:46:40 smsl-n100 systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server...
Mar 18 10:46:40 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:46:40 smsl-n100 volumio[942]: info: Discovery: A device disappeared from network
Mar 18 10:46:40 smsl-n100 volumio[942]: info: Discovery: A device disappeared from network
Mar 18 10:46:40 smsl-n100 dnsmasq[941]: exiting on receipt of SIGTERM
Mar 18 10:46:40 smsl-n100 systemd[1]: dnsmasq.service: Succeeded.
Mar 18 10:46:40 smsl-n100 systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server.
Mar 18 10:46:40 smsl-n100 systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator...
Mar 18 10:46:40 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:46:41 smsl-n100 systemd[1]: hostapd.service: Succeeded.
Mar 18 10:46:41 smsl-n100 systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator.
Mar 18 10:46:41 smsl-n100 sudo[2327]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Mar 18 10:46:41 smsl-n100 sudo[2327]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:41 smsl-n100 avahi-daemon[567]: Withdrawing address record for 192.168.211.1 on wlan0.
Mar 18 10:46:41 smsl-n100 avahi-daemon[567]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1.
Mar 18 10:46:41 smsl-n100 avahi-daemon[567]: Joining mDNS multicast group on interface wlan0.IPv4 with address 169.254.24.211.
Mar 18 10:46:41 smsl-n100 avahi-daemon[567]: Withdrawing address record for 169.254.24.211 on wlan0.
Mar 18 10:46:41 smsl-n100 avahi-daemon[567]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.24.211.
Mar 18 10:46:41 smsl-n100 avahi-daemon[567]: Interface wlan0.IPv4 no longer relevant for mDNS.
Mar 18 10:46:41 smsl-n100 sudo[2327]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:41 smsl-n100 sudo[2329]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Mar 18 10:46:41 smsl-n100 sudo[2329]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:41 smsl-n100 dhcpcd[977]: wlan0: carrier lost
Mar 18 10:46:41 smsl-n100 sudo[2329]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:41 smsl-n100 dhcpcd[977]: wlan0: deleting route to 169.254.0.0/16
Mar 18 10:46:41 smsl-n100 wireless.js[2309]: Stopped aP
Mar 18 10:46:41 smsl-n100 sudo[2365]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Mar 18 10:46:41 smsl-n100 sudo[2365]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:41 smsl-n100 sudo[2365]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:41 smsl-n100 sudo[2377]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get
Mar 18 10:46:41 smsl-n100 sudo[2377]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:41 smsl-n100 sudo[2377]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:41 smsl-n100 sudo[2387]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up
Mar 18 10:46:41 smsl-n100 sudo[2387]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:41 smsl-n100 sudo[2387]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:41 smsl-n100 sudo[2389]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan
Mar 18 10:46:41 smsl-n100 sudo[2389]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:42 smsl-n100 dhcpcd[977]: wlan0: carrier acquired
Mar 18 10:46:42 smsl-n100 dhcpcd[977]: wlan0: IAID 2b:ee:c8:de
Mar 18 10:46:42 smsl-n100 dhcpcd[977]: wlan0: soliciting a DHCP lease
Mar 18 10:46:42 smsl-n100 dhcpcd[977]: wlan0: soliciting an IPv6 router
Mar 18 10:46:43 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Mar 18 10:46:43 smsl-n100 volumio[942]: info: CURURI: music-library
Mar 18 10:46:43 smsl-n100 volumio[942]: info: Preload queue cleared
Mar 18 10:46:43 smsl-n100 ntpd[976]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=117 secs
Mar 18 10:46:43 smsl-n100 ntpd[976]: Deleting interface #5 wlan0, 169.254.24.211#123, interface stats: received=0, sent=0, dropped=0, active_time=106 secs
Mar 18 10:46:43 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Mar 18 10:46:43 smsl-n100 volumio[942]: info: CURURI: music-library/USB
Mar 18 10:46:43 smsl-n100 volumio[942]: info: Preload queue cleared
Mar 18 10:46:44 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Mar 18 10:46:44 smsl-n100 volumio[942]: info: CURURI: music-library/USB/PKBACK#_001
Mar 18 10:46:44 smsl-n100 volumio[942]: info: Preload queue cleared
Mar 18 10:46:44 smsl-n100 volumio[942]: info: Preloading song: music-library/USB/PKBACK#_001/1_正弦波.wav
Mar 18 10:46:44 smsl-n100 volumio[942]: info: Exploding uri music-library/USB/PKBACK#_001/1_正弦波.wav in service mpd
Mar 18 10:46:44 smsl-n100 volumio[942]: info: ALBUMART /albumart?cacheid=397&web=%CE%B4%D6%AA%3F%3F%3F%3F%3F%3F/%CE%B4%D6%AA%3F%3F%C6%AC%3F%3F%20(2011%2F6%2F26%2016%3A15%3A04)/extralarge&path=%2Fmnt%2FUSB%2FPKBACK%23_001%2F1_%E6%AD%A3%E5%BC%A6%E6%B3%A2.wav&metadata=false
Mar 18 10:46:44 smsl-n100 volumio[942]: info: URI /mnt/USB/PKBACK#_001/1_正弦波.wav
Mar 18 10:46:44 smsl-n100 volumio[942]: info: Discovery: Started advertising with name: SMSL N100
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Preload queue cleared
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioReplaceandPlayItems
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::ClearQueue
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::stop
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::clearPlayQueue
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::saveQueue
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushQueue
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::addQueueItems
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::addQueueItems
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Preload queue cleared
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Adding Item to queue: music-library/USB/PKBACK#_001/1_正弦波.wav
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Using cached record of: music-library/USB/PKBACK#_001/1_正弦波.wav
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushQueue
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::saveQueue
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::updateTrackBlock
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrackBlock
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPlay
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::play index 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::stop
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::play index undefined
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::startPlaybackTimer
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetVisibleSources
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::clearAddPlayTracks USB/PKBACK#_001/1_正弦波.wav
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::sendMpdCommand stop
Mar 18 10:46:45 smsl-n100 volumio[942]: info: sendMpdCommand stop took 23 milliseconds
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::sendMpdCommand clear
Mar 18 10:46:45 smsl-n100 volumio[942]: info:
Mar 18 10:46:45 smsl-n100 volumio[942]: ---------------------------- MPD announces system playlist update
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Ignoring MPD Status Update
Mar 18 10:46:45 smsl-n100 volumio[942]: info: sendMpdCommand clear took 9 milliseconds
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::sendMpdCommand add "USB/PKBACK#_001/1_正弦波.wav"
Mar 18 10:46:45 smsl-n100 volumio[942]: info:
Mar 18 10:46:45 smsl-n100 volumio[942]: ---------------------------- MPD announces system playlist update
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Ignoring MPD Status Update
Mar 18 10:46:45 smsl-n100 volumio[942]: info:
Mar 18 10:46:45 smsl-n100 volumio[942]: ---------------------------- MPD announces system playlist update
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Ignoring MPD Status Update
Mar 18 10:46:45 smsl-n100 volumio[942]: error: updateQueue error: null
Mar 18 10:46:45 smsl-n100 volumio[942]: info:
Mar 18 10:46:45 smsl-n100 volumio[942]: ---------------------------- MPD announces system playlist update
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Ignoring MPD Status Update
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ------------------------------ 31ms
Mar 18 10:46:45 smsl-n100 volumio[942]: info: sendMpdCommand add "USB/PKBACK#_001/1_正弦波.wav" took 24 milliseconds
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ------------------------------ 22ms
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ------------------------------ 18ms
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::sendMpdCommand play
Mar 18 10:46:45 smsl-n100 volumio[942]: info:
Mar 18 10:46:45 smsl-n100 volumio[942]: ---------------------------- MPD announces system playlist update
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Ignoring MPD Status Update
Mar 18 10:46:45 smsl-n100 volumio[942]: info:
Mar 18 10:46:45 smsl-n100 volumio[942]: ---------------------------- MPD announces system playlist update
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Ignoring MPD Status Update
Mar 18 10:46:45 smsl-n100 volumio[942]: info:
Mar 18 10:46:45 smsl-n100 volumio[942]: ---------------------------- MPD announces state update: player
Mar 18 10:46:45 smsl-n100 volumio[942]: info:
Mar 18 10:46:45 smsl-n100 volumio[942]: ---------------------------- MPD announces state update: player
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ------------------------------ 45ms
Mar 18 10:46:45 smsl-n100 volumio[942]: info: sendMpdCommand play took 34 milliseconds
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ControllerMpd::getState
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::sendMpdCommand status
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ------------------------------ 36ms
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ControllerMpd::getState
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::sendMpdCommand status
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ------------------------------ 23ms
Mar 18 10:46:45 smsl-n100 volumio[942]: info:
Mar 18 10:46:45 smsl-n100 volumio[942]: ---------------------------- MPD announces state update: player
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ControllerMpd::getState
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::sendMpdCommand status
Mar 18 10:46:45 smsl-n100 volumio[942]: info:
Mar 18 10:46:45 smsl-n100 volumio[942]: ---------------------------- MPD announces state update: player
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ControllerMpd::getState
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::sendMpdCommand status
Mar 18 10:46:45 smsl-n100 volumio[942]: info: sendMpdCommand status took 25 milliseconds
Mar 18 10:46:45 smsl-n100 volumio[942]: info: sendMpdCommand status took 25 milliseconds
Mar 18 10:46:45 smsl-n100 volumio[942]: info: sendMpdCommand status took 16 milliseconds
Mar 18 10:46:45 smsl-n100 volumio[942]: info: sendMpdCommand status took 11 milliseconds
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::parseState
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::parseState
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::parseState
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::parseState
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 18 10:46:45 smsl-n100 volumio[942]: info:
Mar 18 10:46:45 smsl-n100 volumio[942]: ---------------------------- MPD announces state update: player
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ControllerMpd::getState
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::sendMpdCommand status
Mar 18 10:46:45 smsl-n100 volumio[942]: info: sendMpdCommand playlistinfo took 33 milliseconds
Mar 18 10:46:45 smsl-n100 volumio[942]: info: sendMpdCommand playlistinfo took 32 milliseconds
Mar 18 10:46:45 smsl-n100 volumio[942]: info: sendMpdCommand playlistinfo took 21 milliseconds
Mar 18 10:46:45 smsl-n100 volumio[942]: info: sendMpdCommand playlistinfo took 20 milliseconds
Mar 18 10:46:45 smsl-n100 volumio[942]: info: sendMpdCommand status took 13 milliseconds
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::parseTrackInfo
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::parseTrackInfo
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::parseTrackInfo
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::parseTrackInfo
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::parseState
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ControllerMpd::pushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::servicePushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"}
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: CURRENT POSITION 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   stateService play
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   currentStatus stop
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ControllerMpd::pushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::servicePushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"}
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: CURRENT POSITION 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   stateService play
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   currentStatus play
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Received an update from plugin. extracting info from payload
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ControllerMpd::pushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::servicePushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"}
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: CURRENT POSITION 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   stateService play
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   currentStatus play
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Received an update from plugin. extracting info from payload
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ControllerMpd::pushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::servicePushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1098,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"}
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: CURRENT POSITION 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   stateService play
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   currentStatus play
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Received an update from plugin. extracting info from payload
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ------------------------------ 226ms
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ------------------------------ 317ms
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ------------------------------ 303ms
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ------------------------------ 299ms
Mar 18 10:46:45 smsl-n100 volumio[942]: info: sendMpdCommand playlistinfo took 224 milliseconds
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: ControllerMpd::parseTrackInfo
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ControllerMpd::pushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::servicePushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":60,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"??Ŀ 2","artist":"δ֪??????","album":"δ֪??Ƭ?? (2011/6/26 16:15:04)","uri":"USB/PKBACK#_001/1_正弦波.wav","trackType":"wav"}
Mar 18 10:46:45 smsl-n100 volumio[942]: verbose: CURRENT POSITION 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   stateService play
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   currentStatus play
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Received an update from plugin. extracting info from payload
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:45 smsl-n100 volumio[942]: info: ------------------------------ 336ms
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:46:45 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:45 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:46:45 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:46:45 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Discovery: adding f27ca38c-d778-4dd6-9a9e-5cb4a4817be3
Mar 18 10:46:45 smsl-n100 volumio[942]: info: Discovery: Found device SMSL N100
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:46:45 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:46:45 smsl-n100 volumio[942]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 18 10:46:45 smsl-n100 volumio[942]: Error: The value NaN is not a number
Mar 18 10:46:45 smsl-n100 volumio[942]:     at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20)
Mar 18 10:46:45 smsl-n100 volumio[942]:     at Config.set (/volumio/node_modules/v-conf/index.js:153:25)
Mar 18 10:46:45 smsl-n100 volumio[942]:     at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25)
Mar 18 10:46:45 smsl-n100 volumio[942]:     at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10)
Mar 18 10:46:45 smsl-n100 volumio[942]:     at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12)
Mar 18 10:46:45 smsl-n100 volumio[942]:     at Browser.emit (events.js:400:28)
Mar 18 10:46:45 smsl-n100 volumio[942]:     at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14)
Mar 18 10:46:45 smsl-n100 volumio[942]:     at next (/volumio/node_modules/mdns/lib/browser.js:106:7)
Mar 18 10:46:45 smsl-n100 volumio[942]:     at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5)
Mar 18 10:46:45 smsl-n100 volumio[942]:     at next (/volumio/node_modules/mdns/lib/browser.js:109:21)
Mar 18 10:46:45 smsl-n100 volumio[942]:     at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11
Mar 18 10:46:45 smsl-n100 volumio[942]:     at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7)
Mar 18 10:46:45 smsl-n100 volumio[942]:     at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9)
Mar 18 10:46:45 smsl-n100 volumio[942]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 18 10:46:46 smsl-n100 sudo[2389]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:46 smsl-n100 wireless.js[2309]: Start wireless flow
Mar 18 10:46:46 smsl-n100 wireless.js[2309]: Stopped hotspot (if there)..
Mar 18 10:46:46 smsl-n100 sudo[2407]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0
Mar 18 10:46:46 smsl-n100 sudo[2407]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:46 smsl-n100 sudo[2407]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:46 smsl-n100 sudo[2409]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down
Mar 18 10:46:46 smsl-n100 sudo[2409]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:46 smsl-n100 dhcpcd[977]: wlan0: carrier lost
Mar 18 10:46:46 smsl-n100 sudo[2409]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:46 smsl-n100 wireless.js[2309]: DHCP IP
Mar 18 10:46:46 smsl-n100 wireless.js[2309]: Start ap
Mar 18 10:46:46 smsl-n100 wpa_supplicant[2416]: Successfully initialized wpa_supplicant
Mar 18 10:46:46 smsl-n100 sudo[2419]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd
Mar 18 10:46:46 smsl-n100 sudo[2419]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:46 smsl-n100 dhcpcd[2428]: sending commands to master dhcpcd process
Mar 18 10:46:46 smsl-n100 sudo[2419]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:46 smsl-n100 dhcpcd[977]: wlan0: carrier acquired
Mar 18 10:46:46 smsl-n100 dhcpcd[977]: wlan0: IAID 2b:ee:c8:de
Mar 18 10:46:47 smsl-n100 wireless.js[2309]: trying...
Mar 18 10:46:47 smsl-n100 dhcpcd[977]: control command: /sbin/dhcpcd
Mar 18 10:46:47 smsl-n100 dhcpcd[977]: wlan0: carrier lost
Mar 18 10:46:47 smsl-n100 sudo[2448]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Mar 18 10:46:47 smsl-n100 sudo[2446]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-18 10:45
Mar 18 10:46:47 smsl-n100 sudo[2446]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:47 smsl-n100 sudo[2448]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:47 smsl-n100 sudo[2448]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:47 smsl-n100 sudo[2446]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:48 smsl-n100 wireless.js[2309]: trying...
Mar 18 10:46:48 smsl-n100 sudo[2485]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Mar 18 10:46:48 smsl-n100 sudo[2485]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:48 smsl-n100 sudo[2485]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:49 smsl-n100 wireless.js[2309]: trying...
Mar 18 10:46:49 smsl-n100 sudo[2488]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Mar 18 10:46:49 smsl-n100 sudo[2488]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:49 smsl-n100 sudo[2488]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:50 smsl-n100 wireless.js[2309]: trying...
Mar 18 10:46:50 smsl-n100 sudo[2494]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Mar 18 10:46:50 smsl-n100 sudo[2494]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:50 smsl-n100 sudo[2494]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:51 smsl-n100 wireless.js[2309]: trying...
Mar 18 10:46:51 smsl-n100 sudo[2497]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Mar 18 10:46:51 smsl-n100 sudo[2497]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:51 smsl-n100 sudo[2497]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:51 smsl-n100 wpa_supplicant[2431]: wlan0: Trying to associate with f4:2a:7d:d8:0f:b9 (SSID='TP-LINK_0FB7' freq=5765 MHz)
Mar 18 10:46:52 smsl-n100 wpa_supplicant[2431]: wlan0: Associated with f4:2a:7d:d8:0f:b9
Mar 18 10:46:52 smsl-n100 wpa_supplicant[2431]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Mar 18 10:46:52 smsl-n100 wpa_supplicant[2431]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN
Mar 18 10:46:52 smsl-n100 wpa_supplicant[2431]: wlan0: WPA: Key negotiation completed with f4:2a:7d:d8:0f:b9 [PTK=CCMP GTK=CCMP]
Mar 18 10:46:52 smsl-n100 wpa_supplicant[2431]: wlan0: CTRL-EVENT-CONNECTED - Connection to f4:2a:7d:d8:0f:b9 completed [id=0 id_str=]
Mar 18 10:46:52 smsl-n100 dhcpcd[977]: wlan0: carrier acquired
Mar 18 10:46:52 smsl-n100 dhcpcd[977]: wlan0: IAID 2b:ee:c8:de
Mar 18 10:46:52 smsl-n100 wireless.js[2309]: trying...
Mar 18 10:46:52 smsl-n100 sudo[2503]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Mar 18 10:46:52 smsl-n100 sudo[2503]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:52 smsl-n100 sudo[2503]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:52 smsl-n100 dhcpcd[977]: wlan0: soliciting an IPv6 router
Mar 18 10:46:52 smsl-n100 dhcpcd[977]: wlan0: soliciting a DHCP lease
Mar 18 10:46:53 smsl-n100 wireless.js[2309]: trying...
Mar 18 10:46:53 smsl-n100 sudo[2506]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Mar 18 10:46:53 smsl-n100 sudo[2506]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:53 smsl-n100 sudo[2506]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:53 smsl-n100 dhcpcd[977]: wlan0: offered 192.168.0.24 from 192.168.0.1
Mar 18 10:46:53 smsl-n100 dhcpcd[977]: wlan0: probing address 192.168.0.24/24
Mar 18 10:46:54 smsl-n100 wireless.js[2309]: trying...
Mar 18 10:46:54 smsl-n100 sudo[2509]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Mar 18 10:46:54 smsl-n100 sudo[2509]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:54 smsl-n100 sudo[2509]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:54 smsl-n100 volumio-remote-updater[574]: [2025-03-18 02:46:54] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Mar 18 10:46:54 smsl-n100 volumio-remote-updater[574]: [2025-03-18 02:46:54] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Mar 18 10:46:54 smsl-n100 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE
Mar 18 10:46:54 smsl-n100 vtcs[2082]: [2025-03-18 10:46:54.520] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected
Mar 18 10:46:54 smsl-n100 sudo[2305]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:54 smsl-n100 systemd[1]: volumio.service: Failed with result 'exit-code'.
Mar 18 10:46:54 smsl-n100 systemd[1]: Started dynamicswap service.
Mar 18 10:46:54 smsl-n100 systemd[1]: dynamicswap.service: Succeeded.
Mar 18 10:46:54 smsl-n100 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart.
Mar 18 10:46:54 smsl-n100 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1.
Mar 18 10:46:54 smsl-n100 systemd[1]: Started dynamicswap service.
Mar 18 10:46:54 smsl-n100 systemd[1]: Stopped Volumio Backend Module.
Mar 18 10:46:54 smsl-n100 systemd[1]: dynamicswap.service: Succeeded.
Mar 18 10:46:55 smsl-n100 wireless.js[2309]: trying...
Mar 18 10:46:55 smsl-n100 sudo[2524]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Mar 18 10:46:55 smsl-n100 sudo[2524]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:55 smsl-n100 sudo[2524]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:56 smsl-n100 wireless.js[2309]: trying...
Mar 18 10:46:56 smsl-n100 sudo[2527]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Mar 18 10:46:56 smsl-n100 sudo[2527]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:56 smsl-n100 sudo[2527]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:57 smsl-n100 wireless.js[2309]: trying...
Mar 18 10:46:57 smsl-n100 sudo[2530]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Mar 18 10:46:57 smsl-n100 sudo[2530]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:57 smsl-n100 sudo[2530]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:58 smsl-n100 dhcpcd[977]: wlan0: leased 192.168.0.24 for 172800 seconds
Mar 18 10:46:58 smsl-n100 avahi-daemon[567]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.24.
Mar 18 10:46:58 smsl-n100 avahi-daemon[567]: New relevant interface wlan0.IPv4 for mDNS.
Mar 18 10:46:58 smsl-n100 dhcpcd[977]: wlan0: adding route to 192.168.0.0/24
Mar 18 10:46:58 smsl-n100 avahi-daemon[567]: Registering new address record for 192.168.0.24 on wlan0.IPv4.
Mar 18 10:46:58 smsl-n100 dhcpcd[977]: wlan0: adding default route via 192.168.0.1
Mar 18 10:46:58 smsl-n100 kernel: usb 2-1.4: USB disconnect, device number 10
Mar 18 10:46:58 smsl-n100 kernel: ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0
Mar 18 10:46:58 smsl-n100 kernel: ch341 2-1.4:1.0: device disconnected
Mar 18 10:46:58 smsl-n100 wireless.js[2309]: trying...
Mar 18 10:46:58 smsl-n100 systemd[1]: Stopping Network Time Service...
Mar 18 10:46:58 smsl-n100 ntpd[976]: ntpd exiting on signal 15 (Terminated)
Mar 18 10:46:58 smsl-n100 ntpd[976]: 193.182.111.143 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 systemd[1]: ntp.service: Succeeded.
Mar 18 10:46:58 smsl-n100 ntpd[976]: 193.182.111.12 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 ntpd[976]: 193.182.111.142 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 ntpd[976]: 202.118.1.130 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 ntpd[976]: 193.182.111.141 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 systemd[1]: Stopped Network Time Service.
Mar 18 10:46:58 smsl-n100 ntpd[976]: 162.159.200.123 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 ntpd[976]: 111.230.189.174 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 ntpd[976]: 185.209.85.222 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 ntpd[976]: 78.46.102.180 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 ntpd[976]: 162.159.200.1 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 ntpd[976]: 113.207.49.85 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 ntpd[976]: 119.28.183.184 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 ntpd[976]: 45.76.221.157 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 ntpd[976]: 116.203.151.74 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 ntpd[976]: 84.16.67.12 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 ntpd[976]: 5.79.108.34 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 ntpd[976]: 193.182.111.14 local addr 192.168.0.23 -> 
Mar 18 10:46:58 smsl-n100 systemd[1]: Starting Network Time Service...
Mar 18 10:46:58 smsl-n100 sudo[2564]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r
Mar 18 10:46:58 smsl-n100 sudo[2564]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:58 smsl-n100 sudo[2564]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:58 smsl-n100 wireless.js[2309]: Connected to: ----TP-LINK_0FB7
Mar 18 10:46:58 smsl-n100 wireless.js[2309]: ----
Mar 18 10:46:58 smsl-n100 ntpd[2567]: ntpd 4.2.8p12@1.3728-o (1): Starting
Mar 18 10:46:58 smsl-n100 ntpd[2567]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103
Mar 18 10:46:58 smsl-n100 systemd[1]: Started Network Time Service.
Mar 18 10:46:58 smsl-n100 ntpd[2576]: proto: precision = 1.166 usec (-20)
Mar 18 10:46:58 smsl-n100 ntpd[2576]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Mar 18 10:46:58 smsl-n100 ntpd[2576]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Mar 18 10:46:58 smsl-n100 ntpd[2576]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 812 days ago
Mar 18 10:46:58 smsl-n100 ntpd[2576]: Listen and drop on 0 v6wildcard [::]:123
Mar 18 10:46:58 smsl-n100 ntpd[2576]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Mar 18 10:46:58 smsl-n100 ntpd[2576]: Listen normally on 2 lo 127.0.0.1:123
Mar 18 10:46:58 smsl-n100 ntpd[2576]: Listen normally on 3 eth0 192.168.0.23:123
Mar 18 10:46:58 smsl-n100 ntpd[2576]: Listen normally on 4 wlan0 192.168.0.24:123
Mar 18 10:46:58 smsl-n100 ntpd[2576]: Listening on routing socket on fd #21 for interface updates
Mar 18 10:46:58 smsl-n100 ntpd[2576]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized
Mar 18 10:46:58 smsl-n100 ntpd[2576]: kernel reports TIME_ERROR: 0x4041: Clock Unsynchronized
Mar 18 10:46:58 smsl-n100 sudo[2575]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 18 10:46:58 smsl-n100 sudo[2575]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:46:58 smsl-n100 kernel: usb 2-1.4: new high-speed USB device number 11 using ehci-platform
Mar 18 10:46:58 smsl-n100 sudo[2575]: pam_unix(sudo:session): session closed for user root
Mar 18 10:46:58 smsl-n100 wireless.js[2309]: ... joined AP, wlan0 IPv4 is 192.168.0.24, ipV6 is undefined
Mar 18 10:46:58 smsl-n100 wireless.js[2309]: It's done! AP
Mar 18 10:46:58 smsl-n100 iwconfig[2581]: Error for wireless request "Set Power Management" (8B2C) :
Mar 18 10:46:58 smsl-n100 iwconfig[2581]:     SET failed on device wlan0 ; Operation not permitted.
Mar 18 10:46:58 smsl-n100 systemd[1]: Started Wireless Services.
Mar 18 10:46:58 smsl-n100 systemd[1]: Started Volumio Backend Module.
Mar 18 10:46:58 smsl-n100 kernel: usb 2-1.4: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.23
Mar 18 10:46:58 smsl-n100 kernel: usb 2-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=2
Mar 18 10:46:58 smsl-n100 kernel: usb 2-1.4: Product: SMSL USB AUDIO
Mar 18 10:46:58 smsl-n100 kernel: usb 2-1.4: Manufacturer: SMSL
Mar 18 10:46:58 smsl-n100 kernel: usb 2-1.4: 1:3 : unsupported format bits 0x100000000
Mar 18 10:46:58 smsl-n100 kernel: hid-generic 0003:152A:85DD.0003: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-ff5c0000.usb-1.4/input3
Mar 18 10:46:58 smsl-n100 systemd-udevd[2590]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99.
Mar 18 10:46:58 smsl-n100 systemd-udevd[2588]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7.
Mar 18 10:46:59 smsl-n100 ntpd[2576]: Soliciting pool server 193.182.111.143
Mar 18 10:46:59 smsl-n100 volumio-remote-updater[574]: [2025-03-18 02:46:59] [info] asio async_connect error: system:111 (Connection refused)
Mar 18 10:46:59 smsl-n100 volumio-remote-updater[574]: [2025-03-18 02:46:59] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected)
Mar 18 10:46:59 smsl-n100 volumio-remote-updater[574]: [2025-03-18 02:46:59] [error] handle_connect error: Underlying Transport Error
Mar 18 10:47:00 smsl-n100 ntpd[2576]: Soliciting pool server 162.159.200.123
Mar 18 10:47:00 smsl-n100 ntpd[2576]: Soliciting pool server 162.159.200.1
Mar 18 10:47:01 smsl-n100 ntpd[2576]: Soliciting pool server 185.209.85.222
Mar 18 10:47:01 smsl-n100 ntpd[2576]: Soliciting pool server 117.80.231.60
Mar 18 10:47:01 smsl-n100 ntpd[2576]: Soliciting pool server 193.182.111.12
Mar 18 10:47:01 smsl-n100 volumio[2582]: info: -------------------------------------------
Mar 18 10:47:01 smsl-n100 volumio[2582]: info: -----            Volumio3              ----
Mar 18 10:47:01 smsl-n100 volumio[2582]: info: -------------------------------------------
Mar 18 10:47:01 smsl-n100 volumio[2582]: info: -----          System startup          ----
Mar 18 10:47:01 smsl-n100 volumio[2582]: info: -------------------------------------------
Mar 18 10:47:02 smsl-n100 ntpd[2576]: Soliciting pool server 193.182.111.142
Mar 18 10:47:02 smsl-n100 ntpd[2576]: Soliciting pool server 108.59.2.24
Mar 18 10:47:02 smsl-n100 ntpd[2576]: Soliciting pool server 116.203.151.74
Mar 18 10:47:02 smsl-n100 ntpd[2576]: Soliciting pool server 172.105.240.243
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: MYVOLUMIO Environment detected
Mar 18 10:47:03 smsl-n100 ntpd[2576]: Soliciting pool server 117.80.112.205
Mar 18 10:47:03 smsl-n100 ntpd[2576]: Soliciting pool server 202.118.1.130
Mar 18 10:47:03 smsl-n100 ntpd[2576]: Soliciting pool server 119.28.183.184
Mar 18 10:47:03 smsl-n100 ntpd[2576]: Soliciting pool server 113.207.49.85
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Plugin folders cleanup
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Scanning into folder /volumio/app/plugins/
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Scanning category audio_interface
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Scanning category miscellanea
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Scanning category music_service
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Scanning category plugins.json
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Scanning category system_controller
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Scanning category user_interface
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Scanning into folder /data/plugins/
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Plugin folders cleanup completed
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: -------------------------------------------
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: -----      Core plugins startup        ----
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: -------------------------------------------
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Loading plugins from folder /volumio/app/plugins/
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Adding plugin bluetooth to MyMusic Plugins
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Adding plugin multiroom to MyMusic Plugins
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Adding plugin upnp to MyMusic Plugins
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Adding plugin metavolumio to MyMusic Plugins
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Adding plugin airplay_emulation to MyMusic Plugins
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Adding plugin cd_controller to MyMusic Plugins
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Adding plugin raat to MyMusic Plugins
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Adding plugin smart_inputs to MyMusic Plugins
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Adding plugin tidalconnect to MyMusic Plugins
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Adding plugin upnp_browser to MyMusic Plugins
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Loading plugins from folder /data/plugins/
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Loading plugin "system"...
Mar 18 10:47:03 smsl-n100 volumio[2582]: info: Loading plugin "appearance"...
Mar 18 10:47:03 smsl-n100 nmbd[748]: [2025/03/18 10:47:03.837798,  0] ../source3/libsmb/nmblib.c:917(send_udp)
Mar 18 10:47:03 smsl-n100 nmbd[748]:   Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable
Mar 18 10:47:04 smsl-n100 ntpd[2576]: Soliciting pool server 84.16.67.12
Mar 18 10:47:04 smsl-n100 ntpd[2576]: Soliciting pool server 111.230.189.174
Mar 18 10:47:05 smsl-n100 ntpd[2576]: Soliciting pool server 78.46.102.180
Mar 18 10:47:05 smsl-n100 ntpd[2576]: Soliciting pool server 2001:1af8:4700:b210::34
Mar 18 10:47:05 smsl-n100 volumio[2582]: info: Loading plugin "network"...
Mar 18 10:47:05 smsl-n100 volumio[2582]: info: Refreshing Cached IP Addresses
Mar 18 10:47:05 smsl-n100 sudo[2613]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 18 10:47:05 smsl-n100 sudo[2613]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:05 smsl-n100 sudo[2613]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:05 smsl-n100 sudo[2615]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 18 10:47:05 smsl-n100 sudo[2615]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:05 smsl-n100 sudo[2615]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:05 smsl-n100 volumio[2582]: info: Loading plugin "services"...
Mar 18 10:47:05 smsl-n100 volumio[2582]: info: Loading plugin "alsa_controller"...
Mar 18 10:47:05 smsl-n100 sudo[2625]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Mar 18 10:47:05 smsl-n100 sudo[2625]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:05 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 18 10:47:05 smsl-n100 volumio[2582]: info: Loading plugin "wizard"...
Mar 18 10:47:05 smsl-n100 volumio[2582]: info: Loading plugin "networkfs"...
Mar 18 10:47:06 smsl-n100 volumio[2582]: info: Starting Udev Watcher for removable devices
Mar 18 10:47:06 smsl-n100 volumio[2582]: info: Ignoring mount for partition: boot
Mar 18 10:47:06 smsl-n100 volumio[2582]: info: Ignoring mount for partition: volumio
Mar 18 10:47:06 smsl-n100 volumio[2582]: info: Ignoring mount for partition: volumio_data
Mar 18 10:47:06 smsl-n100 volumio[2582]: info: Mounting Device U____U__
Mar 18 10:47:06 smsl-n100 sudo[2642]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/U____U__ -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Mar 18 10:47:06 smsl-n100 sudo[2642]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:06 smsl-n100 sudo[2642]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:06 smsl-n100 volumio[2582]: mount: /media/U____U__: /dev/sdb1 already mounted on /media/U____U__.
Mar 18 10:47:06 smsl-n100 volumio[2582]: error: Failed to mount U____U__: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/U____U__" -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Mar 18 10:47:06 smsl-n100 volumio[2582]: mount: /media/U____U__: /dev/sdb1 already mounted on /media/U____U__.
Mar 18 10:47:06 smsl-n100 volumio[2582]: info: Mounting Device PKBACK#_001
Mar 18 10:47:06 smsl-n100 sudo[2648]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/PKBACK#_001 -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Mar 18 10:47:06 smsl-n100 sudo[2648]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:06 smsl-n100 sudo[2648]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:06 smsl-n100 volumio[2582]: mount: /media/PKBACK#_001: /dev/sda1 already mounted on /media/PKBACK#_001.
Mar 18 10:47:06 smsl-n100 volumio[2582]: error: Failed to mount PKBACK#_001: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/PKBACK#_001" -o noatime,dmask=0000,fmask=0000,iocharset=utf8
Mar 18 10:47:06 smsl-n100 volumio[2582]: mount: /media/PKBACK#_001: /dev/sda1 already mounted on /media/PKBACK#_001.
Mar 18 10:47:06 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Mar 18 10:47:06 smsl-n100 volumio[2582]: info: Loading plugin "volumio_command_line_client"...
Mar 18 10:47:06 smsl-n100 volumio[2582]: info: Loading plugin "upnp"...
Mar 18 10:47:06 smsl-n100 volumio[2582]: info: [1742266026466] Starting Upmpd Daemon
Mar 18 10:47:06 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Mar 18 10:47:06 smsl-n100 volumio[2582]: info: Loading plugin "my_music"...
Mar 18 10:47:06 smsl-n100 volumio[2582]: info: Loading plugin "mpd"...
Mar 18 10:47:07 smsl-n100 volumio-remote-updater[574]: [2025-03-18 02:47:07] [connect] Successful connection
Mar 18 10:47:07 smsl-n100 volumio[2582]: info: Loading plugin "upnp_browser"...
Mar 18 10:47:07 smsl-n100 volumio[2582]: info: Loading plugin "bluetooth"...
Mar 18 10:47:08 smsl-n100 volumio[2582]: info: [1742266028007] Starting BluetoothController
Mar 18 10:47:08 smsl-n100 volumio[2582]: info: Loading plugin "alarm-clock"...
Mar 18 10:47:08 smsl-n100 volumio[2582]: info: Loading plugin "manifestui"...
Mar 18 10:47:08 smsl-n100 volumio[2582]: info: Loading plugin "metavolumio"...
Mar 18 10:47:10 smsl-n100 sudo[2625]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:10 smsl-n100 volumio[2582]: info: Loading plugin "airplay_emulation"...
Mar 18 10:47:10 smsl-n100 volumio[2582]: info: Starting Shairport Sync
Mar 18 10:47:10 smsl-n100 volumio[2582]: info: Loading plugin "cd_controller"...
Mar 18 10:47:11 smsl-n100 volumio[2582]: info: Loading plugin "last_100"...
Mar 18 10:47:11 smsl-n100 volumio[2582]: info: Loading plugin "raat"...
Mar 18 10:47:11 smsl-n100 volumio[2582]: info: RAAT Plugin loaded
Mar 18 10:47:11 smsl-n100 volumio[2582]: info: Adding restartRAATSocket REST API Endpoint
Mar 18 10:47:11 smsl-n100 volumio[2582]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat
Mar 18 10:47:11 smsl-n100 volumio[2582]: info: Loading plugin "streaming_services"...
Mar 18 10:47:13 smsl-n100 volumio[2582]: info: Starting Streaming Service Transparent Proxy
Mar 18 10:47:13 smsl-n100 volumio[2582]: info: Loading plugin "tidalconnect"...
Mar 18 10:47:13 smsl-n100 volumio[2582]: info: Loading plugin "webradio"...
Mar 18 10:47:14 smsl-n100 volumio[2582]: info: Loading plugin "i2s_dacs"...
Mar 18 10:47:14 smsl-n100 volumio[2582]: info: I2S DAC not set, start Auto-detection
Mar 18 10:47:14 smsl-n100 volumio[2582]: info: Loading plugin "volumiodiscovery"...
Mar 18 10:47:14 smsl-n100 volumio[2582]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Mar 18 10:47:14 smsl-n100 node[2582]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Mar 18 10:47:14 smsl-n100 node[2582]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 18 10:47:14 smsl-n100 volumio[2582]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 18 10:47:14 smsl-n100 volumio[2582]: *** WARNING *** For more information see 
Mar 18 10:47:14 smsl-n100 volumio[2582]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Mar 18 10:47:14 smsl-n100 volumio[2582]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 18 10:47:14 smsl-n100 volumio[2582]: *** WARNING *** For more information see 
Mar 18 10:47:14 smsl-n100 node[2582]: *** WARNING *** For more information see 
Mar 18 10:47:14 smsl-n100 node[2582]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Mar 18 10:47:14 smsl-n100 node[2582]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 18 10:47:14 smsl-n100 node[2582]: *** WARNING *** For more information see 
Mar 18 10:47:14 smsl-n100 volumio[2582]: info: Applying required configuration parameters for plugin volumiodiscovery
Mar 18 10:47:14 smsl-n100 volumio[2582]: info: Discovery: Started advertising with name: SMSL N100
Mar 18 10:47:14 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Mar 18 10:47:14 smsl-n100 volumio[2582]: info: Loading plugin "multiroom"...
Mar 18 10:47:15 smsl-n100 volumio[2582]: STREAMING PROXY: Starting server on port 3245
Mar 18 10:47:15 smsl-n100 volumio[2582]: Node JS runtime: 14
Mar 18 10:47:17 smsl-n100 volumio[2582]: info: Applying required configuration parameters for plugin multiroom
Mar 18 10:47:17 smsl-n100 sudo[2677]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Mar 18 10:47:17 smsl-n100 sudo[2677]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:17 smsl-n100 sudo[2677]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:17 smsl-n100 volumio[2582]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists
Mar 18 10:47:17 smsl-n100 volumio[2582]: info: MRS: MultiRoom plugin initialized
Mar 18 10:47:17 smsl-n100 volumio[2582]: info: MRS: STOPPING SNAPCLIENT
Mar 18 10:47:17 smsl-n100 volumio[2582]: info: MRS: Snap server stop
Mar 18 10:47:17 smsl-n100 sudo[2693]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Mar 18 10:47:17 smsl-n100 sudo[2693]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:17 smsl-n100 volumio[2582]: info: MRS: STOPPING volumioStreaming
Mar 18 10:47:17 smsl-n100 sudo[2695]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Mar 18 10:47:17 smsl-n100 sudo[2695]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:17 smsl-n100 sudo[2693]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:17 smsl-n100 volumio[2582]: info: Loading plugin "outputs"...
Mar 18 10:47:17 smsl-n100 sudo[2699]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Mar 18 10:47:17 smsl-n100 sudo[2699]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:17 smsl-n100 volumio[2582]: info: Loading plugin "albumart"...
Mar 18 10:47:17 smsl-n100 sudo[2695]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:17 smsl-n100 sudo[2701]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Mar 18 10:47:17 smsl-n100 sudo[2701]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:17 smsl-n100 sudo[2701]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:17 smsl-n100 volumio[2582]: info: Plugin example_plugin is not enabled
Mar 18 10:47:17 smsl-n100 volumio[2582]: info: Loading plugin "hi_res_audio"...
Mar 18 10:47:17 smsl-n100 sudo[2699]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:18 smsl-n100 volumio[2582]: Forking 3 albumart workers
Mar 18 10:47:20 smsl-n100 volumio[2582]: Starting albumart workers
Mar 18 10:47:20 smsl-n100 volumio[2582]: Starting albumart workers
Mar 18 10:47:20 smsl-n100 volumio[2582]: Starting albumart workers
Mar 18 10:47:21 smsl-n100 volumio[2582]: info: Applying required configuration parameters for plugin hi_res_audio
Mar 18 10:47:21 smsl-n100 volumio[2582]: info: Loading plugin "inputs"...
Mar 18 10:47:22 smsl-n100 volumio-remote-updater[574]: [2025-03-18 02:47:22] [connect] Successful connection
Mar 18 10:47:22 smsl-n100 volumio[2582]: info: Loading plugin "qobuz"...
Mar 18 10:47:24 smsl-n100 volumio[2582]: info: Loading plugin "smart_inputs"...
Mar 18 10:47:25 smsl-n100 volumio[2582]: info: Loading plugin "tidal"...
Mar 18 10:47:27 smsl-n100 volumio[2582]: info: Loading plugin "oem_helper"...
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: Applying required configuration parameters for plugin oem_helper
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: Loading plugin "smslcontrol"...
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: Adding this device properties
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: Setting Additional Device Volumio Properties: [object Object]
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: Loading plugin "updater_comm"...
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: Plugin mpdemulation is not enabled
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: Loading plugin "rest_api"...
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: Loading plugin "websocket"...
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: Starting Socket.io Server version 2.3.0
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: Loading i18n strings for locale zh
Mar 18 10:47:29 smsl-n100 volumio[2582]: Updating browse sources language
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::initPlayerControls
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:47:29 smsl-n100 volumio[2582]: Express server listening on port 3000
Mar 18 10:47:29 smsl-n100 volumio[2582]:  [Metrics]  WebUI:  29s 109.14ms 
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreStateMachine::resetVolumioState
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreStateMachine::getcurrentVolume
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioRetrievevolume
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreStateMachine::pushState
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:47:29 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioPushState
Mar 18 10:47:30 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:47:30 smsl-n100 sudo[2785]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 18 10:47:30 smsl-n100 sudo[2785]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:30 smsl-n100 sudo[2785]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:30 smsl-n100 volumio[2582]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Mar 18 10:47:30 smsl-n100 sudo[2789]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 18 10:47:30 smsl-n100 sudo[2789]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:30 smsl-n100 volumio[2582]: info: Completed loading Core Plugins
Mar 18 10:47:30 smsl-n100 volumio[2582]: info: Preparing to generate the ALSA configuration file
Mar 18 10:47:30 smsl-n100 sudo[2789]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:30 smsl-n100 volumio[2582]: info: Volumio Network Manager: Network status updated: 3
Mar 18 10:47:30 smsl-n100 volumio[2582]: info: MRS: Removed streaming files
Mar 18 10:47:30 smsl-n100 volumio[2582]: info: MRS: volumioStreaming STOPPED
Mar 18 10:47:30 smsl-n100 volumio[2582]: info: MRS: SNAPSERVER STOPPED
Mar 18 10:47:30 smsl-n100 volumio[2582]: info: MRS: SNAPCLIENT STOPPED
Mar 18 10:47:30 smsl-n100 volumio[2582]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
Mar 18 10:47:30 smsl-n100 volumio[2582]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Mar 18 10:47:30 smsl-n100 volumio[2582]: info: Reading ALSA contributions from plugins.
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 1
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 2
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 3
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 4
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 5
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 7
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 8
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 9
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 10
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 11
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 12
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 13
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 14
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 15
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 16
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 17
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 18
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 19
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 20
Mar 18 10:47:30 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 21
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 22
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 23
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 24
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 25
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 26
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 27
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 28
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 29
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 30
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 31
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 32
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 33
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 34
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 35
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 36
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 37
Mar 18 10:47:31 smsl-n100 volumio[2582]: info: Reloading queue from file
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 38
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 39
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 40
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 41
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 42
Mar 18 10:47:31 smsl-n100 volumio[2582]: info: CoreStateMachine::setRepeat null single undefined
Mar 18 10:47:31 smsl-n100 volumio[2582]: info: CoreStateMachine::pushState
Mar 18 10:47:31 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:31 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:47:31 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioPushState
Mar 18 10:47:31 smsl-n100 volumio[2582]: info: CoreStateMachine::setRandom null
Mar 18 10:47:31 smsl-n100 volumio[2582]: info: CoreStateMachine::pushState
Mar 18 10:47:31 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:31 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioPushState
Mar 18 10:47:31 smsl-n100 volumio[2582]: info: Setting Device type: NanoPi NEO 3
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 43
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 44
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 45
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 46
Mar 18 10:47:31 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 47
Mar 18 10:47:32 smsl-n100 sudo[2800]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Mar 18 10:47:32 smsl-n100 sudo[2800]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:32 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 48
Mar 18 10:47:32 smsl-n100 sudo[2800]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: Discovery: adding f27ca38c-d778-4dd6-9a9e-5cb4a4817be3
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: Discovery: Found device SMSL N100
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioGetState
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output for this device
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: Adding audio output:
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: Adding audio output:
Mar 18 10:47:32 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to 192.168.0.23 from 192.168.0.3 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 49
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioGetState
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: Upmpdcli Daemon Started
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: Asound.conf file unchanged, so no further update is needed
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: Output device has changed, restarting MPD
Mar 18 10:47:32 smsl-n100 sudo[2803]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 18 10:47:32 smsl-n100 sudo[2803]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: Output device has changed, restarting Shairport Sync
Mar 18 10:47:32 smsl-n100 sudo[2803]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:47:32 smsl-n100 sudo[2806]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 18 10:47:32 smsl-n100 sudo[2806]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:32 smsl-n100 systemd[1]: Stopping Music Player Daemon...
Mar 18 10:47:32 smsl-n100 systemd[1]: mpd.service: Succeeded.
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 18 10:47:32 smsl-n100 systemd[1]: Stopped Music Player Daemon.
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:32 smsl-n100 systemd[1]: Starting Music Player Daemon...
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: ___________ START PLUGINS ___________
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: ControllerMpd::onStart: Initializing MPD
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: Creating MPD Configuration file
Mar 18 10:47:32 smsl-n100 sudo[2815]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 18 10:47:32 smsl-n100 sudo[2815]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Mar 18 10:47:32 smsl-n100 sudo[2815]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: [1742266052897] CoreMusicLibrary::Adding element 媒体服务器
Mar 18 10:47:32 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 10:47:32 smsl-n100 sudo[2817]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 18 10:47:32 smsl-n100 sudo[2817]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:32 smsl-n100 sudo[2811]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Mar 18 10:47:32 smsl-n100 sudo[2811]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:32 smsl-n100 sudo[2811]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:32 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Mar 18 10:47:32 smsl-n100 systemd[1]: mpd.service: Succeeded.
Mar 18 10:47:32 smsl-n100 systemd[1]: Stopped Music Player Daemon.
Mar 18 10:47:33 smsl-n100 systemd[1]: Starting Music Player Daemon...
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Adding METAVOLUMIO REST API Endpoints
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:47:33 smsl-n100 sudo[2824]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name SMSL N100
Mar 18 10:47:33 smsl-n100 sudo[2824]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:33 smsl-n100 sudo[2824]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:33 smsl-n100 sudo[2822]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Mar 18 10:47:33 smsl-n100 sudo[2822]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:33 smsl-n100 sudo[2822]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Preparing CD Folders
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Adding CD REST API Endpoints
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Starting UDEV Watcher for CD
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Detecting CD presence with UDEV
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: [1742266053426] CoreMusicLibrary::Adding element Last_100
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Starting RAAT Plugin
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Additional UI Settings Added for plugin music_service/raat
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Registering DSP Elements listener and retrieving current ones
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Additional DSP elements updated
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Updating  RAAT Signal Path
Mar 18 10:47:33 smsl-n100 volumio[2582]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Streaming services startup
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Starting Streaming Daemon
Mar 18 10:47:33 smsl-n100 sudo[2835]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Mar 18 10:47:33 smsl-n100 sudo[2835]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:33 smsl-n100 sudo[2835]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: [1742266053690] CoreMusicLibrary::Adding element Webradio
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: Initializing BBC Radios
Mar 18 10:47:33 smsl-n100 sudo[2842]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 18 10:47:33 smsl-n100 sudo[2842]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:33 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service...
Mar 18 10:47:33 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM
Mar 18 10:47:33 smsl-n100 systemd[1]: vtcs.service: Succeeded.
Mar 18 10:47:33 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service.
Mar 18 10:47:33 smsl-n100 sudo[2842]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Mar 18 10:47:33 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:34 smsl-n100 volumio[2582]: error: Hi Res Audio Failed Login: Missing Login Data
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Adding HIGHRESAUDIO REST API Endpoints
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Initializing Serial Communication on port /dev/ttyS1
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Touch Event Listener Process Starting
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Adding inputs REST Endpoints
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Scanning Audio Inputs
Mar 18 10:47:34 smsl-n100 sudo[2859]: pam_unix(sudo:auth): conversation failed
Mar 18 10:47:34 smsl-n100 sudo[2859]: pam_unix(sudo:auth): auth could not identify password for [volumio]
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Checking against Known Cards name
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Adding Server instance for streaming
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Initializing GPIOs
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Starting Button Listener
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Turning off Green LED
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Tuning on Blue LED
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Signaling System Ready
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Volumio Calling Home
Mar 18 10:47:34 smsl-n100 volumio[2582]: (node:2582) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead.
Mar 18 10:47:34 smsl-n100 volumio[2582]: (Use `node --trace-deprecation ...` to show where the warning was created)
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Stopping AccessToken refresher cron for QOBUZ
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: AccessToken refresher cron started for QOBUZ
Mar 18 10:47:34 smsl-n100 volumio[2582]: info: Stopping AccessToken refresher cron
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: AccessToken refresher cron started
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Adding TIDAL REST API Endpoints
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Adding QOBUZ REST API Endpoints
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Discovery: this is already registered,  f27ca38c-d778-4dd6-9a9e-5cb4a4817be3
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Discovery: Found device SMSL N100
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioGetState
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioGetVisibleSources
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioGetState
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioGetQueue
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreStateMachine::getQueue
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CorePlayQueue::getQueue
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Listing playlists
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Received Get System Info
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Discovery: Getting this device information
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioGetState
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioGetState
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Received Get System Info
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Discovery: Getting this device information
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioGetState
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioGetState
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Serial port opened successfully
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Sending serial start messages
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Reporting MCU Network Status: 3
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: Touch Event Listener Process Closed
Mar 18 10:47:35 smsl-n100 volumio[2582]: error: Cannot start Volumio Streaming Daemon
Mar 18 10:47:35 smsl-n100 volumio[2582]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Mar 18 10:47:35 smsl-n100 volumio[2582]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Mar 18 10:47:35 smsl-n100 volumio[2582]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'SMSL N100'
Mar 18 10:47:35 smsl-n100 volumio[2582]: Can't get device info: No such device
Mar 18 10:47:35 smsl-n100 volumio[2582]: info: MPD Permissions set
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: MPD Permissions set
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: Setting Device Volume Override
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: Updating Volume Controller Parameters: Device: 1 Name: SPDIF Mixer:  Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: Disabling external Volume Control
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreStateMachine::pushState
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioPushState
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output
Mar 18 10:47:36 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:47:36 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:47:36 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreStateMachine::pushState
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioPushState
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: Volumio called home
Mar 18 10:47:36 smsl-n100 sudo[2930]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreStateMachine::pushState
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:36 smsl-n100 sudo[2930]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioPushState
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:47:36 smsl-n100 sudo[2930]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output
Mar 18 10:47:36 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:47:36 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:47:36 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreStateMachine::pushState
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioPushState
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output
Mar 18 10:47:36 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:47:36 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:47:36 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:47:36 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: 3, message: undefined
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreStateMachine::pushState
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioPushState
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:47:36 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output
Mar 18 10:47:36 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:47:37 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 50
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio-remote-updater[574]: [2025-03-18 02:47:37] [connect] Successful connection
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreStateMachine::pushState
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioPushState
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output
Mar 18 10:47:37 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:47:37 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:47:37 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Mar 18 10:47:37 smsl-n100 volumio[2582]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 51
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: TidalConnect service stoped!
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: Volumio BT Module successfully started
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreStateMachine::pushState
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioPushState
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output
Mar 18 10:47:37 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:47:37 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:47:37 smsl-n100 volumio[2582]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: VolumeController::SetAlsaVolume0
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreStateMachine::pushState
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CorePlayQueue::getTrack 0
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioPushState
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:47:37 smsl-n100 volumio[2582]: info: MRS: Pushing multiroomSync output
Mar 18 10:47:38 smsl-n100 volumio[2582]: info: Preload queue cleared
Mar 18 10:47:38 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioReplaceandPlayItems
Mar 18 10:47:38 smsl-n100 volumio[2582]: info: CoreStateMachine::ClearQueue
Mar 18 10:47:38 smsl-n100 volumio[2582]: info: CoreStateMachine::stop
Mar 18 10:47:38 smsl-n100 volumio[2582]: info: CoreStateMachine::setConsumeUpdateService undefined
Mar 18 10:47:38 smsl-n100 volumio[2582]: info: CorePlayQueue::clearPlayQueue
Mar 18 10:47:38 smsl-n100 volumio[2582]: info: CorePlayQueue::saveQueue
Mar 18 10:47:38 smsl-n100 volumio[2582]: info: CoreCommandRouter::volumioPushQueue
Mar 18 10:47:38 smsl-n100 volumio[2582]: info: CoreStateMachine::addQueueItems
Mar 18 10:47:38 smsl-n100 volumio[2582]: info: CorePlayQueue::addQueueItems
Mar 18 10:47:38 smsl-n100 volumio[2582]: info: Preload queue cleared
Mar 18 10:47:38 smsl-n100 volumio[2582]: info: Adding Item to queue: music-library/USB/PKBACK#_001/1_正弦波.wav
Mar 18 10:47:38 smsl-n100 volumio[2582]: info: Exploding uri music-library/USB/PKBACK#_001/1_正弦波.wav in service mpd
Mar 18 10:47:38 smsl-n100 volumio[2582]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 18 10:47:38 smsl-n100 volumio[2582]: TypeError: Cannot read property 'then' of undefined
Mar 18 10:47:38 smsl-n100 volumio[2582]:     at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2528:19)
Mar 18 10:47:38 smsl-n100 volumio[2582]:     at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2326:21)
Mar 18 10:47:38 smsl-n100 volumio[2582]:     at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1378:16)
Mar 18 10:47:38 smsl-n100 volumio[2582]:     at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24)
Mar 18 10:47:38 smsl-n100 volumio[2582]:     at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19)
Mar 18 10:47:38 smsl-n100 volumio[2582]:     at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30)
Mar 18 10:47:38 smsl-n100 volumio[2582]:     at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:247:25)
Mar 18 10:47:38 smsl-n100 volumio[2582]:     at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:519:25)
Mar 18 10:47:38 smsl-n100 volumio[2582]:     at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17)
Mar 18 10:47:38 smsl-n100 volumio[2582]:     at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33)
Mar 18 10:47:38 smsl-n100 volumio[2582]:     at Socket.emit (events.js:400:28)
Mar 18 10:47:38 smsl-n100 volumio[2582]:     at /volumio/node_modules/socket.io/lib/socket.js:528:12
Mar 18 10:47:38 smsl-n100 volumio[2582]:     at processTicksAndRejections (internal/process/task_queues.js:77:11)
Mar 18 10:47:38 smsl-n100 volumio[2582]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 18 10:47:38 smsl-n100 mpd[2830]: Mar 18 10:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Mar 18 10:47:38 smsl-n100 systemd[1]: Started Music Player Daemon.
Mar 18 10:47:38 smsl-n100 sudo[2806]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:38 smsl-n100 sudo[2817]: pam_unix(sudo:session): session closed for user root
Mar 18 10:47:39 smsl-n100 sudo[2959]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-18 10:46
Mar 18 10:47:39 smsl-n100 sudo[2959]: 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="cc0042a1eab1cb7982711a1347e242f8947b54b6"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="8205c9d25502d90d2bb411bb156e1d5a8ef7404e"
VOLUMIO_ARCH="armv7"
VOLUMIO_VARIANT="smsl"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Mon 20 Jan 2025 07:23:13 PM CET"
VOLUMIO_VERSION="3.788"
VOLUMIO_HARDWARE="nanopineo3"
VOLUMIO_DEVICENAME="Nanopi Neo3"
VOLUMIO_VENDOR_MODEL="SMSL S100"
VOLUMIO_VENDOR="SMSL"
VOLUMIO_MODEL="S100"
VOLUMIO_HASH="b3195a7f5841a4bc08e3e196c1a91b34"