-- Logs begin at Tue 2025-01-21 02:23:13 CST, end at Tue 2025-03-18 10:46:47 CST. --
Mar 18 10:45:00 smsl-n100 volumio[942]: info: Loading plugin "metavolumio"...
Mar 18 10:45:01 smsl-n100 sudo[1182]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:02 smsl-n100 volumio[942]: info: Loading plugin "airplay_emulation"...
Mar 18 10:45:02 smsl-n100 volumio[942]: info: Starting Shairport Sync
Mar 18 10:45:02 smsl-n100 volumio[942]: info: Loading plugin "cd_controller"...
Mar 18 10:45:03 smsl-n100 volumio[942]: info: Loading plugin "last_100"...
Mar 18 10:45:03 smsl-n100 volumio[942]: info: Loading plugin "raat"...
Mar 18 10:45:03 smsl-n100 systemd[1]: systemd-fsckd.service: Succeeded.
Mar 18 10:45:03 smsl-n100 volumio[942]: info: RAAT Plugin loaded
Mar 18 10:45:03 smsl-n100 volumio[942]: info: Adding restartRAATSocket REST API Endpoint
Mar 18 10:45:03 smsl-n100 volumio[942]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat
Mar 18 10:45:03 smsl-n100 volumio[942]: info: Loading plugin "streaming_services"...
Mar 18 10:45:06 smsl-n100 volumio[942]: info: Starting Streaming Service Transparent Proxy
Mar 18 10:45:06 smsl-n100 volumio[942]: info: Loading plugin "tidalconnect"...
Mar 18 10:45:06 smsl-n100 volumio[942]: info: Loading plugin "webradio"...
Mar 18 10:45:06 smsl-n100 volumio[942]: info: Loading plugin "i2s_dacs"...
Mar 18 10:45:06 smsl-n100 volumio[942]: info: I2S DAC not set, start Auto-detection
Mar 18 10:45:06 smsl-n100 volumio[942]: info: Loading plugin "volumiodiscovery"...
Mar 18 10:45:07 smsl-n100 volumio[942]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Mar 18 10:45:07 smsl-n100 node[942]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Mar 18 10:45:07 smsl-n100 node[942]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 18 10:45:07 smsl-n100 volumio[942]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 18 10:45:07 smsl-n100 volumio[942]: *** WARNING *** For more information see 
Mar 18 10:45:07 smsl-n100 volumio[942]: *** 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:45:07 smsl-n100 volumio[942]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 18 10:45:07 smsl-n100 volumio[942]: *** WARNING *** For more information see 
Mar 18 10:45:07 smsl-n100 node[942]: *** WARNING *** For more information see 
Mar 18 10:45:07 smsl-n100 node[942]: *** 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:45:07 smsl-n100 node[942]: *** WARNING *** Please fix your application to use the native API of Avahi!
Mar 18 10:45:07 smsl-n100 node[942]: *** WARNING *** For more information see 
Mar 18 10:45:07 smsl-n100 volumio[942]: info: Applying required configuration parameters for plugin volumiodiscovery
Mar 18 10:45:07 smsl-n100 volumio[942]: info: Discovery: Started advertising with name: SMSL N100
Mar 18 10:45:07 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Mar 18 10:45:07 smsl-n100 volumio[942]: info: Loading plugin "multiroom"...
Mar 18 10:45:08 smsl-n100 volumio[942]: STREAMING PROXY: Starting server on port 3245
Mar 18 10:45:08 smsl-n100 volumio[942]: Node JS runtime: 14
Mar 18 10:45:10 smsl-n100 volumio[942]: info: Applying required configuration parameters for plugin multiroom
Mar 18 10:45:10 smsl-n100 sudo[1241]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom
Mar 18 10:45:10 smsl-n100 sudo[1241]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:10 smsl-n100 sudo[1241]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:10 smsl-n100 volumio[942]: info: MRS: MultiRoom plugin initialized
Mar 18 10:45:10 smsl-n100 volumio[942]: info: MRS: STOPPING SNAPCLIENT
Mar 18 10:45:10 smsl-n100 volumio[942]: info: MRS: Snap server stop
Mar 18 10:45:10 smsl-n100 volumio[942]: info: MRS: STOPPING volumioStreaming
Mar 18 10:45:10 smsl-n100 sudo[1257]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient
Mar 18 10:45:10 smsl-n100 sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:10 smsl-n100 sudo[1259]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver
Mar 18 10:45:10 smsl-n100 sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:10 smsl-n100 sudo[1257]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:10 smsl-n100 sudo[1262]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming
Mar 18 10:45:10 smsl-n100 volumio[942]: info: Loading plugin "outputs"...
Mar 18 10:45:10 smsl-n100 sudo[1262]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:10 smsl-n100 volumio[942]: info: Loading plugin "albumart"...
Mar 18 10:45:10 smsl-n100 sudo[1265]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/*
Mar 18 10:45:10 smsl-n100 sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:10 smsl-n100 sudo[1259]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:10 smsl-n100 sudo[1265]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:10 smsl-n100 volumio[942]: info: Plugin example_plugin is not enabled
Mar 18 10:45:10 smsl-n100 volumio[942]: info: Loading plugin "hi_res_audio"...
Mar 18 10:45:10 smsl-n100 sudo[1262]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:11 smsl-n100 volumio[942]: Forking 3 albumart workers
Mar 18 10:45:12 smsl-n100 volumio-remote-updater[574]: [2025-03-18 02:45:12] [connect] Successful connection
Mar 18 10:45:13 smsl-n100 volumio[942]: Starting albumart workers
Mar 18 10:45:13 smsl-n100 volumio[942]: Starting albumart workers
Mar 18 10:45:13 smsl-n100 volumio[942]: Starting albumart workers
Mar 18 10:45:14 smsl-n100 volumio[942]: info: Applying required configuration parameters for plugin hi_res_audio
Mar 18 10:45:14 smsl-n100 volumio[942]: info: Loading plugin "inputs"...
Mar 18 10:45:16 smsl-n100 volumio[942]: info: Loading plugin "qobuz"...
Mar 18 10:45:18 smsl-n100 volumio[942]: info: Loading plugin "smart_inputs"...
Mar 18 10:45:19 smsl-n100 volumio[942]: info: Loading plugin "tidal"...
Mar 18 10:45:21 smsl-n100 systemd[1]: systemd-timedated.service: Succeeded.
Mar 18 10:45:21 smsl-n100 volumio[942]: info: Loading plugin "oem_helper"...
Mar 18 10:45:24 smsl-n100 volumio[942]: info: Applying required configuration parameters for plugin oem_helper
Mar 18 10:45:24 smsl-n100 volumio[942]: info: Loading plugin "smslcontrol"...
Mar 18 10:45:24 smsl-n100 kernel: gpio gpiochip0: Persistence not supported for GPIO 5
Mar 18 10:45:24 smsl-n100 volumio[942]: info: Adding this device properties
Mar 18 10:45:24 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties
Mar 18 10:45:24 smsl-n100 volumio[942]: info: Setting Additional Device Volumio Properties: [object Object]
Mar 18 10:45:24 smsl-n100 volumio[942]: info: Loading plugin "updater_comm"...
Mar 18 10:45:24 smsl-n100 volumio[942]: info: Plugin mpdemulation is not enabled
Mar 18 10:45:24 smsl-n100 volumio[942]: info: Loading plugin "rest_api"...
Mar 18 10:45:24 smsl-n100 volumio[942]: info: Loading plugin "websocket"...
Mar 18 10:45:24 smsl-n100 volumio[942]: info: Starting Socket.io Server version 2.3.0
Mar 18 10:45:24 smsl-n100 volumio[942]: info: Loading i18n strings for locale en
Mar 18 10:45:24 smsl-n100 volumio[942]: Updating browse sources language
Mar 18 10:45:24 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::initPlayerControls
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:45:25 smsl-n100 volumio[942]: Express server listening on port 3000
Mar 18 10:45:25 smsl-n100 volumio[942]:  [Metrics]  WebUI:  35s 650.74ms 
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreStateMachine::resetVolumioState
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreStateMachine::getcurrentVolume
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioRetrievevolume
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:25 smsl-n100 sudo[1357]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 18 10:45:25 smsl-n100 sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:25 smsl-n100 sudo[1357]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:25 smsl-n100 sudo[1359]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 18 10:45:25 smsl-n100 sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:25 smsl-n100 sudo[1359]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:25 smsl-n100 volumio[942]: info: Scanning new location : "USB/U____U__"
Mar 18 10:45:25 smsl-n100 volumio[942]: info: Scanning new location : "USB/PKBACK#_001"
Mar 18 10:45:25 smsl-n100 volumio[942]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
Mar 18 10:45:25 smsl-n100 volumio[942]: info: Completed loading Core Plugins
Mar 18 10:45:25 smsl-n100 volumio[942]: info: Preparing to generate the ALSA configuration file
Mar 18 10:45:25 smsl-n100 volumio[942]: info: Cannot read play queue from file
Mar 18 10:45:25 smsl-n100 volumio[942]: info: Volumio Network Manager: Network status updated: 1
Mar 18 10:45:25 smsl-n100 volumio[942]: info: MRS: Removed streaming files
Mar 18 10:45:25 smsl-n100 volumio[942]: info: MRS: volumioStreaming STOPPED
Mar 18 10:45:25 smsl-n100 volumio[942]: info: MRS: SNAPSERVER STOPPED
Mar 18 10:45:25 smsl-n100 volumio[942]: info: MRS: SNAPCLIENT STOPPED
Mar 18 10:45:25 smsl-n100 volumio[942]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision
Mar 18 10:45:25 smsl-n100 volumio[942]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Mar 18 10:45:25 smsl-n100 volumio[942]: info: Reading ALSA contributions from plugins.
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 18 10:45:25 smsl-n100 volumio[942]: info: Discovery: Getting this device information
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:25 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreStateMachine::setRepeat null single undefined
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreStateMachine::setRandom null
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:26 smsl-n100 volumio[942]: info: Setting Device type: NanoPi NEO 3
Mar 18 10:45:26 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: websocket Total Clients: 1
Mar 18 10:45:26 smsl-n100 sudo[1375]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
Mar 18 10:45:26 smsl-n100 sudo[1375]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Mar 18 10:45:26 smsl-n100 systemd[1]: Started UPnP Renderer front-end to MPD.
Mar 18 10:45:26 smsl-n100 volumio[942]: info: Discovery: adding f27ca38c-d778-4dd6-9a9e-5cb4a4817be3
Mar 18 10:45:26 smsl-n100 volumio[942]: info: Discovery: Found device SMSL N100
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:26 smsl-n100 sudo[1375]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:26 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output for this device
Mar 18 10:45:26 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:26 smsl-n100 volumio[942]: info: Adding audio output:
Mar 18 10:45:26 smsl-n100 volumio[942]: info: Adding audio output:
Mar 18 10:45:26 smsl-n100 volumio[942]: info: Upmpdcli Daemon Started
Mar 18 10:45:26 smsl-n100 volumio[942]: info: Asound.conf file unchanged, so no further update is needed
Mar 18 10:45:26 smsl-n100 volumio[942]: info: Output device has changed, restarting MPD
Mar 18 10:45:26 smsl-n100 sudo[1379]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 18 10:45:26 smsl-n100 sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:26 smsl-n100 volumio[942]: info: Output device has changed, restarting Shairport Sync
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:26 smsl-n100 sudo[1379]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:26 smsl-n100 sudo[1381]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 18 10:45:26 smsl-n100 sudo[1381]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:26 smsl-n100 systemd[1]: Stopping Music Player Daemon...
Mar 18 10:45:26 smsl-n100 volumio[942]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 18 10:45:26 smsl-n100 volumio[942]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:26 smsl-n100 volumio[942]: info: ___________ START PLUGINS ___________
Mar 18 10:45:26 smsl-n100 volumio[942]: info: ControllerMpd::onStart: Initializing MPD
Mar 18 10:45:26 smsl-n100 volumio[942]: info: Creating MPD Configuration file
Mar 18 10:45:26 smsl-n100 sudo[1404]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 18 10:45:26 smsl-n100 sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Mar 18 10:45:26 smsl-n100 volumio[942]: info: [1742265926922] CoreMusicLibrary::Adding element Media Servers
Mar 18 10:45:26 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 10:45:26 smsl-n100 sudo[1404]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:26 smsl-n100 sudo[1406]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 18 10:45:26 smsl-n100 systemd[1]: mpd.service: Succeeded.
Mar 18 10:45:26 smsl-n100 sudo[1406]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:26 smsl-n100 systemd[1]: Stopped Music Player Daemon.
Mar 18 10:45:26 smsl-n100 systemd[1]: Starting Music Player Daemon...
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Mar 18 10:45:27 smsl-n100 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM
Mar 18 10:45:27 smsl-n100 systemd[1]: mpd.service: Succeeded.
Mar 18 10:45:27 smsl-n100 systemd[1]: Stopped Music Player Daemon.
Mar 18 10:45:27 smsl-n100 systemd[1]: Starting Music Player Daemon...
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Adding METAVOLUMIO REST API Endpoints
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:27 smsl-n100 sudo[1412]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name SMSL N100
Mar 18 10:45:27 smsl-n100 sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:27 smsl-n100 sudo[1412]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:27 smsl-n100 sudo[1411]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Mar 18 10:45:27 smsl-n100 sudo[1411]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:27 smsl-n100 sudo[1411]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Preparing CD Folders
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Adding CD REST API Endpoints
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Starting UDEV Watcher for CD
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Detecting CD presence with UDEV
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Mar 18 10:45:27 smsl-n100 volumio[942]: info: [1742265927344] CoreMusicLibrary::Adding element Last_100
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Starting RAAT Plugin
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Additional UI Settings Added for plugin music_service/raat
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Registering DSP Elements listener and retrieving current ones
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Additional DSP elements updated
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Updating  RAAT Signal Path
Mar 18 10:45:27 smsl-n100 volumio[942]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Streaming services startup
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Starting Streaming Daemon
Mar 18 10:45:27 smsl-n100 sudo[1425]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Mar 18 10:45:27 smsl-n100 sudo[1425]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:27 smsl-n100 sudo[1425]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Mar 18 10:45:27 smsl-n100 volumio[942]: info: [1742265927610] CoreMusicLibrary::Adding element Webradio
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Initializing BBC Radios
Mar 18 10:45:27 smsl-n100 sudo[1432]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 18 10:45:27 smsl-n100 sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:27 smsl-n100 sudo[1432]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus
Mar 18 10:45:27 smsl-n100 volumio[942]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Mar 18 10:45:27 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:27 smsl-n100 volumio[942]: error: Hi Res Audio Failed Login: Missing Login Data
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Adding HIGHRESAUDIO REST API Endpoints
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Initializing Serial Communication on port /dev/ttyS1
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Touch Event Listener Process Starting
Mar 18 10:45:27 smsl-n100 volumio-remote-updater[574]: [2025-03-18 02:45:27] [connect] Successful connection
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Adding inputs REST Endpoints
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs
Mar 18 10:45:27 smsl-n100 volumio[942]: info: Scanning Audio Inputs
Mar 18 10:45:28 smsl-n100 sudo[1449]: pam_unix(sudo:auth): conversation failed
Mar 18 10:45:28 smsl-n100 sudo[1449]: pam_unix(sudo:auth): auth could not identify password for [volumio]
Mar 18 10:45:28 smsl-n100 volumio[942]: info: Checking against Known Cards name
Mar 18 10:45:28 smsl-n100 volumio[942]: info: Adding Server instance for streaming
Mar 18 10:45:28 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 18 10:45:28 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:28 smsl-n100 volumio[942]: info: Initializing GPIOs
Mar 18 10:45:28 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 1
Mar 18 10:45:28 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 15
Mar 18 10:45:28 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 0
Mar 18 10:45:28 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 2
Mar 18 10:45:28 smsl-n100 volumio[942]: info: Starting Button Listener
Mar 18 10:45:28 smsl-n100 volumio[942]: info: Turning off Green LED
Mar 18 10:45:28 smsl-n100 volumio[942]: info: Tuning on Blue LED
Mar 18 10:45:28 smsl-n100 volumio[942]: info: Signaling System Ready
Mar 18 10:45:28 smsl-n100 volumio[942]: info: Volumio Calling Home
Mar 18 10:45:28 smsl-n100 volumio[942]: (node:942) [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:45:28 smsl-n100 volumio[942]: (Use `node --trace-deprecation ...` to show where the warning was created)
Mar 18 10:45:28 smsl-n100 volumio[942]: info: Stopping AccessToken refresher cron for QOBUZ
Mar 18 10:45:28 smsl-n100 volumio[942]: info: AccessToken refresher cron started for QOBUZ
Mar 18 10:45:28 smsl-n100 volumio[942]: info: Stopping AccessToken refresher cron
Mar 18 10:45:28 smsl-n100 volumio[942]: info: AccessToken refresher cron started
Mar 18 10:45:28 smsl-n100 volumio[942]: info: Adding TIDAL REST API Endpoints
Mar 18 10:45:28 smsl-n100 volumio[942]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal
Mar 18 10:45:28 smsl-n100 volumio[942]: info: Adding QOBUZ REST API Endpoints
Mar 18 10:45:28 smsl-n100 volumio[942]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz
Mar 18 10:45:29 smsl-n100 volumio[942]: info: Discovery: this is already registered,  f27ca38c-d778-4dd6-9a9e-5cb4a4817be3
Mar 18 10:45:29 smsl-n100 volumio[942]: info: Discovery: Found device SMSL N100
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:29 smsl-n100 volumio[942]: info: Serial port opened successfully
Mar 18 10:45:29 smsl-n100 volumio[942]: info: Sending serial start messages
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: Reporting MCU Network Status: 1
Mar 18 10:45:29 smsl-n100 volumio[942]: info: Touch Event Listener Process Closed
Mar 18 10:45:29 smsl-n100 volumio[942]: error: Cannot start Volumio Streaming Daemon
Mar 18 10:45:29 smsl-n100 volumio[942]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Mar 18 10:45:29 smsl-n100 volumio[942]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Mar 18 10:45:29 smsl-n100 volumio[942]: info: RAAT Albumart path created successfully
Mar 18 10:45:29 smsl-n100 volumio[942]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'SMSL N100'
Mar 18 10:45:29 smsl-n100 volumio[942]: Can't get device info: No such device
Mar 18 10:45:29 smsl-n100 volumio[942]: info: MPD Permissions set
Mar 18 10:45:29 smsl-n100 volumio[942]: info: MPD Permissions set
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride
Mar 18 10:45:29 smsl-n100 volumio[942]: info: Setting Device Volume Override
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Mar 18 10:45:29 smsl-n100 volumio[942]: info: Updating Volume Controller Parameters: Device: 1 Name: SPDIF Mixer:  Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
Mar 18 10:45:29 smsl-n100 volumio[942]: info: Disabling external Volume Control
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:29 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:29 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:29 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:29 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:45:29 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:29 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:29 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:29 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:29 smsl-n100 volumio-remote-updater[574]: [2025-03-18 02:45:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1742265927 101
Mar 18 10:45:29 smsl-n100 volumio[942]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:29 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:29 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:29 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:29 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:45:29 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:29 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:29 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:29 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:29 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:29 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:45:29 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:29 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:29 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:29 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:29 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:29 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: 3
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:29 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:30 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:30 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:30 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:30 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:45:30 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:30 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:45:30 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: 4
Mar 18 10:45:30 smsl-n100 volumio[942]: info: Volumio called home
Mar 18 10:45:30 smsl-n100 sudo[1494]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
Mar 18 10:45:30 smsl-n100 sudo[1494]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:30 smsl-n100 systemd[1]: Started Volumio Bluetooth Module.
Mar 18 10:45:30 smsl-n100 sudo[1494]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:30 smsl-n100 sudo[1497]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/*
Mar 18 10:45:30 smsl-n100 sudo[1497]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:30 smsl-n100 volumiobt[1496]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory
Mar 18 10:45:30 smsl-n100 sudo[1497]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:30 smsl-n100 volumio[942]: info: Volumio BT Module successfully started
Mar 18 10:45:30 smsl-n100 volumio[942]: info: Starting Shairport Sync
Mar 18 10:45:30 smsl-n100 volumio[942]: info: Starting Shairport Sync
Mar 18 10:45:30 smsl-n100 sudo[1501]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 18 10:45:30 smsl-n100 volumio[942]: info: Starting Shairport Sync
Mar 18 10:45:30 smsl-n100 sudo[1501]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:30 smsl-n100 sudo[1503]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 18 10:45:30 smsl-n100 sudo[1503]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:30 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Mar 18 10:45:30 smsl-n100 systemd[1]: shairport-sync.service: Succeeded.
Mar 18 10:45:30 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Mar 18 10:45:30 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:30 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:30 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:30 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:30 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:30 smsl-n100 sudo[1506]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 18 10:45:30 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:30 smsl-n100 sudo[1506]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:30 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:45:30 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:30 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:45:30 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:30 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:30 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:30 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:30 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:30 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:30 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:45:30 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:30 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:45:30 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Mar 18 10:45:30 smsl-n100 sudo[1501]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:30 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Mar 18 10:45:30 smsl-n100 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Mar 18 10:45:30 smsl-n100 systemd[1]: shairport-sync.service: Succeeded.
Mar 18 10:45:30 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:45:30 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Mar 18 10:45:30 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:45:31 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Mar 18 10:45:31 smsl-n100 sudo[1506]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:31 smsl-n100 sudo[1503]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:31 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:45:31 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:31 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:45:31 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:45:31 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:45:31 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:45:31 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:31 smsl-n100 volumio[942]: info: Shairport-Sync Started
Mar 18 10:45:31 smsl-n100 volumio[942]: Error adding Membership: Error: addMembership EINVAL
Mar 18 10:45:31 smsl-n100 volumio[942]: info: Shairport-Sync Started
Mar 18 10:45:31 smsl-n100 volumio[942]: info: Shairport-Sync Started
Mar 18 10:45:31 smsl-n100 volumio[942]: info: TidalConnect service stoped!
Mar 18 10:45:31 smsl-n100 sudo[1518]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio
Mar 18 10:45:31 smsl-n100 sudo[1518]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:31 smsl-n100 volumiobt[1496]: pulseaudio: no process found
Mar 18 10:45:31 smsl-n100 sudo[1518]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:31 smsl-n100 dbus-daemon[563]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.16' (uid=1000 pid=1523 comm="/usr/bin/pulseaudio --high-priority=true ")
Mar 18 10:45:31 smsl-n100 systemd[1]: Starting RealtimeKit Scheduling Policy Service...
Mar 18 10:45:31 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:45:31 smsl-n100 dbus-daemon[563]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
Mar 18 10:45:31 smsl-n100 systemd[1]: Started RealtimeKit Scheduling Policy Service.
Mar 18 10:45:31 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:31 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:31 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:31 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:31 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:31 smsl-n100 rtkit-daemon[1527]: Successfully called chroot.
Mar 18 10:45:31 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:31 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:45:31 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:31 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:45:31 smsl-n100 rtkit-daemon[1527]: Successfully dropped privileges.
Mar 18 10:45:31 smsl-n100 rtkit-daemon[1527]: Successfully limited resources.
Mar 18 10:45:31 smsl-n100 rtkit-daemon[1527]: Running.
Mar 18 10:45:31 smsl-n100 rtkit-daemon[1527]: Canary thread running.
Mar 18 10:45:31 smsl-n100 rtkit-daemon[1527]: Watchdog thread running.
Mar 18 10:45:31 smsl-n100 dbus-daemon[563]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.17' (uid=0 pid=1527 comm="/usr/lib/rtkit/rtkit-daemon ")
Mar 18 10:45:31 smsl-n100 volumio[942]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Mar 18 10:45:31 smsl-n100 volumio[942]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect
Mar 18 10:45:31 smsl-n100 systemd[1]: Starting Authorization Manager...
Mar 18 10:45:32 smsl-n100 sudo[1532]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Mar 18 10:45:32 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:45:32 smsl-n100 sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:32 smsl-n100 polkitd[1530]: started daemon version 0.105 using authority implementation `local' version `0.105'
Mar 18 10:45:32 smsl-n100 dbus-daemon[563]: [system] Successfully activated service 'org.freedesktop.PolicyKit1'
Mar 18 10:45:32 smsl-n100 systemd[1]: Started Authorization Manager.
Mar 18 10:45:32 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service.
Mar 18 10:45:32 smsl-n100 sudo[1532]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:32 smsl-n100 volumio[942]: info: MRS: Getting audio outputs on start
Mar 18 10:45:32 smsl-n100 volumio[942]: info: MRS: Requesting all other devices output
Mar 18 10:45:32 smsl-n100 mpd[1421]: Mar 18 02:45 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Mar 18 10:45:32 smsl-n100 systemd[1]: Started Music Player Daemon.
Mar 18 10:45:32 smsl-n100 sudo[1406]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:32 smsl-n100 volumio[942]: Upnp client error: Error: This socket has been ended by the other party
Mar 18 10:45:32 smsl-n100 volumio[942]: Upnp client error: Error: This socket has been ended by the other party
Mar 18 10:45:32 smsl-n100 sudo[1381]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:33 smsl-n100 dbus-daemon[563]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.37' (uid=1000 pid=1523 comm="/usr/bin/pulseaudio --high-priority=true ")
Mar 18 10:45:33 smsl-n100 systemd[1]: Starting Bluetooth service...
Mar 18 10:45:33 smsl-n100 volumio[942]: error: MPD error: The expression evaluated to a falsy value:
Mar 18 10:45:33 smsl-n100 volumio[942]:   assert.ok(self.idling)
Mar 18 10:45:33 smsl-n100 volumio[942]: error: The expression evaluated to a falsy value:
Mar 18 10:45:33 smsl-n100 volumio[942]:   assert.ok(self.idling)
Mar 18 10:45:33 smsl-n100 volumio[942]: info: MPD running with PID1421
Mar 18 10:45:33 smsl-n100 volumio[942]:  ,establishing connection
Mar 18 10:45:33 smsl-n100 pulseaudio[1523]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
Mar 18 10:45:33 smsl-n100 pulseaudio[1523]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: Applying permissions
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: Setting BT discoverable and pairable
Mar 18 10:45:33 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:45:33 smsl-n100 volumio[942]: error: MPD error: The expression evaluated to a falsy value:
Mar 18 10:45:33 smsl-n100 volumio[942]:   assert.ok(self.idling)
Mar 18 10:45:33 smsl-n100 volumio[942]: error: The expression evaluated to a falsy value:
Mar 18 10:45:33 smsl-n100 volumio[942]:   assert.ok(self.idling)
Mar 18 10:45:33 smsl-n100 bluetoothd[1549]: Bluetooth daemon 5.50
Mar 18 10:45:33 smsl-n100 bluetoothd[1549]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf
Mar 18 10:45:33 smsl-n100 dbus-daemon[563]: [system] Successfully activated service 'org.bluez'
Mar 18 10:45:33 smsl-n100 systemd[1]: Started Bluetooth service.
Mar 18 10:45:33 smsl-n100 bluetoothd[1549]: Starting SDP server
Mar 18 10:45:33 smsl-n100 bluetoothd[1549]: Excluding (cli) sap
Mar 18 10:45:33 smsl-n100 bluetoothd[1549]: kernel lacks bnep-protocol support
Mar 18 10:45:33 smsl-n100 bluetoothd[1549]: System does not support network plugin
Mar 18 10:45:33 smsl-n100 bluetoothd[1549]: Bluetooth management interface 1.14 initialized
Mar 18 10:45:33 smsl-n100 dbus-daemon[563]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.38' (uid=0 pid=1549 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ")
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: [bluetooth]# -e power on
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: Invalid command in menu main: -e
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: 
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: Use "help" for a list of available commands in a menu.
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: Use "menu " if you want to enter any submenu.
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: Use "back" if you want to return to menu main.
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: [bluetooth]# agent on
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: Failed to register agent object
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: [bluetooth]# discoverable on
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: No default controller available
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: [bluetooth]# pairable on
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: No default controller available
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: [bluetooth]# agent NoInputNoOutput
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: Failed to register agent object
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: [bluetooth]# default-agent
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: No agent is registered
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: [bluetooth]# quit
Mar 18 10:45:33 smsl-n100 pulseaudio[1523]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files
Mar 18 10:45:33 smsl-n100 volumiobt[1496]: [59B blob data]
Mar 18 10:45:33 smsl-n100 systemd[1]: Starting Hostname Service...
Mar 18 10:45:33 smsl-n100 volumio[942]: info: Executing endpoint tc_getconfig
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig
Mar 18 10:45:33 smsl-n100 vtcs[1537]: STARTING TidalConnect services, version: 1.4.0.34
Mar 18 10:45:33 smsl-n100 volumio[942]: error: updateQueue error: null
Mar 18 10:45:33 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:45:33 smsl-n100 vtcs[1537]: STARTED TidalConnect services.
Mar 18 10:45:33 smsl-n100 volumio[942]: info: Executing endpoint tc_connect
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect
Mar 18 10:45:33 smsl-n100 volumio[942]: info: Connecting to TidalConnect
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreCommandRouter::servicePushState
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:33 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:33 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:33 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:45:33 smsl-n100 volumio[942]: verbose: CURRENT POSITION 0
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   stateService stop
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   currentStatus stop
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:33 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:33 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:33 smsl-n100 volumio[942]: info: No code
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:33 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:33 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreCommandRouter::servicePushState
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:33 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:33 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:33 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:45:33 smsl-n100 volumio[942]: verbose: CURRENT POSITION 0
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   stateService stop
Mar 18 10:45:33 smsl-n100 volumio[942]: info: CoreStateMachine::syncState   currentStatus stop
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:34 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:34 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:34 smsl-n100 volumio[942]: info: No code
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:34 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:34 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:34 smsl-n100 sudo[1571]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Mar 18 10:45:34 smsl-n100 sudo[1571]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:34 smsl-n100 sudo[1571]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:34 smsl-n100 sudo[1574]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Mar 18 10:45:34 smsl-n100 sudo[1574]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:34 smsl-n100 sudo[1574]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:34 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: 5
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetVisibleSources
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps
Mar 18 10:45:34 smsl-n100 dbus-daemon[563]: [system] Successfully activated service 'org.freedesktop.hostname1'
Mar 18 10:45:34 smsl-n100 systemd[1]: Started Hostname Service.
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones
Mar 18 10:45:34 smsl-n100 volumio[942]: info: Received Get System Info
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 18 10:45:34 smsl-n100 volumio[942]: info: Discovery: Getting this device information
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 18 10:45:34 smsl-n100 volumio-remote-updater[574]: No test mode
Mar 18 10:45:34 smsl-n100 volumio-remote-updater[574]: No alpha test mode
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Mar 18 10:45:34 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Mar 18 10:45:35 smsl-n100 volumio[942]: info: TidalConnect service started!
Mar 18 10:45:35 smsl-n100 volumio[942]: info: Completed starting Core Plugins
Mar 18 10:45:35 smsl-n100 volumio[942]: info: -------------------------------------------
Mar 18 10:45:35 smsl-n100 volumio[942]: info: -----    MyVolumio plugins startup     ----
Mar 18 10:45:35 smsl-n100 volumio[942]: info: -------------------------------------------
Mar 18 10:45:35 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Fetching plans data....
Mar 18 10:45:35 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:45:35 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Mar 18 10:45:36 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Mar 18 10:45:36 smsl-n100 volumio[942]: info: Loading i18n strings for locale zh
Mar 18 10:45:36 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Mar 18 10:45:36 smsl-n100 volumio[942]: info: Setting timezone to Asia/Shanghai
Mar 18 10:45:36 smsl-n100 sudo[1616]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Mar 18 10:45:36 smsl-n100 sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:36 smsl-n100 sudo[1616]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:36 smsl-n100 sudo[1619]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime
Mar 18 10:45:36 smsl-n100 sudo[1619]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:36 smsl-n100 sudo[1619]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:36 smsl-n100 sudo[1622]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Mar 18 10:45:36 smsl-n100 sudo[1622]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:36 smsl-n100 sudo[1622]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:36 smsl-n100 sudo[1625]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai
Mar 18 10:45:36 smsl-n100 sudo[1625]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:36 smsl-n100 dbus-daemon[563]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.43' (uid=0 pid=1626 comm="/usr/bin/timedatectl set-timezone Asia/Shanghai ")
Mar 18 10:45:36 smsl-n100 systemd[1]: Starting Time & Date Service...
Mar 18 10:45:36 smsl-n100 dbus-daemon[563]: [system] Successfully activated service 'org.freedesktop.timedate1'
Mar 18 10:45:36 smsl-n100 systemd[1]: Started Time & Date Service.
Mar 18 10:45:36 smsl-n100 sudo[1625]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:36 smsl-n100 volumio[942]: info: Fetching Streaming Services browse cache
Mar 18 10:45:36 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Mar 18 10:45:36 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:45:36 smsl-n100 volumio[942]: Updating browse sources language
Mar 18 10:45:36 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 10:45:37 smsl-n100 volumio[1377]: Generating RSA private key, 4096 bit long modulus (2 primes)
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Mar 18 10:45:44 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Mar 18 10:45:46 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Mar 18 10:45:46 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Mar 18 10:45:46 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:46 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:46 smsl-n100 volumio[942]: info: Starting MyVolumio Remote Streaming Endpoints
Mar 18 10:45:46 smsl-n100 volumio[942]: info: MyVolumio not started
Mar 18 10:45:46 smsl-n100 volumio[942]: info: Initializing device activation check
Mar 18 10:45:46 smsl-n100 volumio[942]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Mar 18 10:45:46 smsl-n100 volumio[942]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json'
Mar 18 10:45:47 smsl-n100 volumio[1377]: ...............................................................................................................................................++++
Mar 18 10:45:51 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Mar 18 10:45:51 smsl-n100 volumio[942]: info: Loading i18n strings for locale zh
Mar 18 10:45:51 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , setTimezone
Mar 18 10:45:51 smsl-n100 volumio[942]: info: Setting timezone to Asia/Shanghai
Mar 18 10:45:51 smsl-n100 sudo[1676]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Mar 18 10:45:51 smsl-n100 sudo[1676]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:51 smsl-n100 sudo[1676]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:51 smsl-n100 sudo[1679]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Asia/Shanghai /etc/localtime
Mar 18 10:45:51 smsl-n100 sudo[1679]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:51 smsl-n100 sudo[1679]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:51 smsl-n100 sudo[1682]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Mar 18 10:45:51 smsl-n100 sudo[1682]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:51 smsl-n100 sudo[1682]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:51 smsl-n100 sudo[1685]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Asia/Shanghai
Mar 18 10:45:51 smsl-n100 sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:51 smsl-n100 sudo[1685]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:51 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Mar 18 10:45:51 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Mar 18 10:45:51 smsl-n100 sudo[1688]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Mar 18 10:45:51 smsl-n100 sudo[1688]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:51 smsl-n100 volumio[942]: Updating browse sources language
Mar 18 10:45:51 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 10:45:51 smsl-n100 volumio[942]: info: Fetching Streaming Services browse cache
Mar 18 10:45:53 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , saveGeneralSettings
Mar 18 10:45:53 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAudioDevices
Mar 18 10:45:53 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:45:53 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Mar 18 10:45:53 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:53 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 18 10:45:53 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions
Mar 18 10:45:53 smsl-n100 volumio[942]: info: Preparing to save Alsa Options, stopping services first
Mar 18 10:45:53 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioGetState
Mar 18 10:45:53 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:53 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPause
Mar 18 10:45:53 smsl-n100 volumio[942]: info: CoreStateMachine::pause
Mar 18 10:45:53 smsl-n100 volumio[942]: info: Saving Audio Output to: {"i2s":false,"output_device":{"value":"1","label":"SPDIF"},"disallowPush":true}
Mar 18 10:45:53 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 18 10:45:53 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:45:54 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 18 10:45:54 smsl-n100 volumio[942]: info: Found match in Cards Database: setting mixer  for card SPDIF
Mar 18 10:45:54 smsl-n100 volumio[942]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes
Mar 18 10:45:54 smsl-n100 vtcs[1537]: [2025-03-18 10:45:54.056] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE
Mar 18 10:45:54 smsl-n100 vtcs[1537]: [2025-03-18 10:45:54.059] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer
Mar 18 10:45:54 smsl-n100 vtcs[1537]: [2025-03-18 10:45:54.059] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected
Mar 18 10:45:54 smsl-n100 sudo[1700]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 18 10:45:54 smsl-n100 sudo[1700]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:54 smsl-n100 systemd[1]: Stopping Volumio Tidal Connect Service...
Mar 18 10:45:54 smsl-n100 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM
Mar 18 10:45:54 smsl-n100 systemd[1]: vtcs.service: Succeeded.
Mar 18 10:45:54 smsl-n100 systemd[1]: Stopped Volumio Tidal Connect Service.
Mar 18 10:45:54 smsl-n100 sudo[1700]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:54 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:45:54 smsl-n100 volumio[942]: info: Device 1 does not have any Mixer Control Available
Mar 18 10:45:54 smsl-n100 volumio[942]: info: Reconfiguring and Restarting RAAT Plugin due to audio path changes
Mar 18 10:45:54 smsl-n100 sudo[1706]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 18 10:45:54 smsl-n100 sudo[1706]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:54 smsl-n100 sudo[1706]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:54 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Mar 18 10:45:54 smsl-n100 volumio[942]: info: Updating Volume Controller Parameters: Device: 1 Name: SPDIF Mixer:  Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
Mar 18 10:45:54 smsl-n100 volumio[942]: info: Disabling external Volume Control
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:54 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:54 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:54 smsl-n100 volumio[942]: info: Preparing to generate the ALSA configuration file
Mar 18 10:45:54 smsl-n100 sudo[1712]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 18 10:45:54 smsl-n100 sudo[1712]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:54 smsl-n100 sudo[1715]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 18 10:45:54 smsl-n100 sudo[1715]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:54 smsl-n100 sudo[1712]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: Not Reporting Auto name since its the default one
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Mar 18 10:45:54 smsl-n100 sudo[1715]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Mar 18 10:45:54 smsl-n100 sudo[1724]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Mar 18 10:45:54 smsl-n100 sudo[1724]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:54 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:45:54 smsl-n100 sudo[1727]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Mar 18 10:45:54 smsl-n100 sudo[1727]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:54 smsl-n100 volumio[942]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Mar 18 10:45:54 smsl-n100 volumio[942]: info: Reading ALSA contributions from plugins.
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:45:54 smsl-n100 volumio[942]: info: Not Reporting Auto name since its the default one
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable
Mar 18 10:45:55 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:45:55 smsl-n100 sudo[1736]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service
Mar 18 10:45:55 smsl-n100 sudo[1736]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:55 smsl-n100 sudo[1736]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:55 smsl-n100 sudo[1740]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service
Mar 18 10:45:55 smsl-n100 sudo[1740]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:55 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 18 10:45:55 smsl-n100 systemd[1]: Started RAAT DAEMON.
Mar 18 10:45:55 smsl-n100 sudo[1740]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:55 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection
Mar 18 10:45:55 smsl-n100 volumio[942]: error: MyVolumio Plugin failed to start in a timely fashion
Mar 18 10:45:55 smsl-n100 volumio[942]:  [Metrics]  CommandRouter:  64s 558.60ms 
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::volumiosetStartupVolume
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::Close All Modals sent
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::Close All Modals sent
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable
Mar 18 10:45:55 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:55 smsl-n100 volumio[942]: info: Retrieving Cloud Streaming UI
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:55 smsl-n100 volumio[942]: info: Retrieving Cloud Streaming UI
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:55 smsl-n100 volumio[942]: info: Retrieving Cloud Streaming UI
Mar 18 10:45:55 smsl-n100 sudo[1759]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service
Mar 18 10:45:55 smsl-n100 sudo[1759]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:55 smsl-n100 sudo[1759]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:55 smsl-n100 volumio[942]: info: Raat Daemon started successfully
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation
Mar 18 10:45:55 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware
Mar 18 10:45:55 smsl-n100 sudo[1761]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service
Mar 18 10:45:55 smsl-n100 sudo[1761]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:55 smsl-n100 systemd[1]: Stopping RAAT DAEMON...
Mar 18 10:45:55 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM
Mar 18 10:45:55 smsl-n100 systemd[1]: raat-daemon.service: Succeeded.
Mar 18 10:45:55 smsl-n100 systemd[1]: Stopped RAAT DAEMON.
Mar 18 10:45:55 smsl-n100 systemd[1]: Started RAAT DAEMON.
Mar 18 10:45:55 smsl-n100 sudo[1761]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:56 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware
Mar 18 10:45:56 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Executing endpoint restartRAATSocket
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection
Mar 18 10:45:56 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:45:56 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:45:56 smsl-n100 sudo[1727]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Raat Daemon started successfully
Mar 18 10:45:56 smsl-n100 sudo[1688]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:56 smsl-n100 sudo[1724]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Executing endpoint restartRAATSocket
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Asound.conf file unchanged, so no further update is needed
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Output device has changed, restarting MPD
Mar 18 10:45:56 smsl-n100 sudo[1781]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Output device has changed, restarting Shairport Sync
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:56 smsl-n100 sudo[1781]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:56 smsl-n100 sudo[1781]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:56 smsl-n100 sudo[1783]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Mar 18 10:45:56 smsl-n100 sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:56 smsl-n100 systemd[1]: Stopping Music Player Daemon...
Mar 18 10:45:56 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Mar 18 10:45:56 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:45:56 smsl-n100 volumio[942]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:56 smsl-n100 systemd[1]: mpd.service: Succeeded.
Mar 18 10:45:56 smsl-n100 systemd[1]: Stopped Music Player Daemon.
Mar 18 10:45:56 smsl-n100 systemd[1]: Starting Music Player Daemon...
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:56 smsl-n100 sudo[1793]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 18 10:45:56 smsl-n100 sudo[1793]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Getting Tidal Cloud Configuration
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Getting Qobuz Cloud Configuration
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Asking plugin for UI Config
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:56 smsl-n100 sudo[1795]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service
Mar 18 10:45:56 smsl-n100 sudo[1790]:     root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Mar 18 10:45:56 smsl-n100 sudo[1795]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:56 smsl-n100 sudo[1790]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Getting Spotify Cloud Configuration
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Asking plugin for UI Config
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Saving Spotify Acccount
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Got it
Mar 18 10:45:56 smsl-n100 volumio[942]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Getting Tidal Cloud Configuration
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:56 smsl-n100 sudo[1790]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:56 smsl-n100 sudo[1793]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Getting Qobuz Cloud Configuration
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Asking plugin for UI Config
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Getting Spotify Cloud Configuration
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Asking plugin for UI Config
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Saving Spotify Acccount
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Got it
Mar 18 10:45:56 smsl-n100 volumio[942]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Getting Tidal Cloud Configuration
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Getting Qobuz Cloud Configuration
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Asking plugin for UI Config
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:56 smsl-n100 sudo[1795]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Getting Spotify Cloud Configuration
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Asking plugin for UI Config
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Saving Spotify Acccount
Mar 18 10:45:56 smsl-n100 volumio[942]: info: Got it
Mar 18 10:45:56 smsl-n100 volumio[942]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Mar 18 10:45:56 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Mar 18 10:45:57 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Mar 18 10:45:57 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:45:57 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:45:57 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Retrieving Cloud Streaming UI
Mar 18 10:45:57 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: 6
Mar 18 10:45:57 smsl-n100 volumio[942]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined
Mar 18 10:45:57 smsl-n100 volumio[942]: info: MPD Permissions set
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Not Reporting Auto name since its the default one
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:57 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:57 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:57 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:57 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:45:57 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:57 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:57 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:57 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:57 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:57 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:45:57 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:57 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:45:57 smsl-n100 volumio[942]: info: TidalConnect service stoped!
Mar 18 10:45:57 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: 7
Mar 18 10:45:57 smsl-n100 volumio[942]: info: TidalConnect service stoped!
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::Reload Ui
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Wizard terminated Successfully
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::volumiosetStartupVolume
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Got Tidal Cloud Configuration
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Got it
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Got Tidal Cloud Configuration
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Got it
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Got Tidal Cloud Configuration
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Got it
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Getting Tidal Cloud Configuration
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Getting Qobuz Cloud Configuration
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Asking plugin for UI Config
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Getting Spotify Cloud Configuration
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Asking plugin for UI Config
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Saving Spotify Acccount
Mar 18 10:45:57 smsl-n100 volumio[942]: info: Got it
Mar 18 10:45:57 smsl-n100 volumio[942]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Mar 18 10:45:57 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable
Mar 18 10:45:58 smsl-n100 volumio[942]: xcb_connection_has_error() returned true
Mar 18 10:45:58 smsl-n100 volumio[942]: info: TidalConnect service stoped!
Mar 18 10:45:58 smsl-n100 sudo[1831]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service
Mar 18 10:45:58 smsl-n100 sudo[1831]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:58 smsl-n100 volumio[942]: info: TidalConnect service stoped!
Mar 18 10:45:58 smsl-n100 sudo[1831]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:58 smsl-n100 volumio[942]: info: RAAT: Requesting Headphone Status
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus
Mar 18 10:45:58 smsl-n100 sudo[1838]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service
Mar 18 10:45:58 smsl-n100 sudo[1838]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:58 smsl-n100 systemd[1]: Stopping RAAT DAEMON...
Mar 18 10:45:58 smsl-n100 systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM
Mar 18 10:45:58 smsl-n100 systemd[1]: raat-daemon.service: Succeeded.
Mar 18 10:45:58 smsl-n100 systemd[1]: Stopped RAAT DAEMON.
Mar 18 10:45:58 smsl-n100 volumio[942]: info: Starting Shairport Sync
Mar 18 10:45:58 smsl-n100 systemd[1]: Started RAAT DAEMON.
Mar 18 10:45:58 smsl-n100 sudo[1838]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:58 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:45:58 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:45:58 smsl-n100 sudo[1853]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Mar 18 10:45:58 smsl-n100 sudo[1853]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreCommandRouter::Reload Ui
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard
Mar 18 10:45:58 smsl-n100 volumio[942]: info: Wizard terminated Successfully
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreCommandRouter::volumiosetStartupVolume
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Mar 18 10:45:58 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Mar 18 10:45:58 smsl-n100 systemd[1]: shairport-sync.service: Succeeded.
Mar 18 10:45:58 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Mar 18 10:45:58 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Mar 18 10:45:58 smsl-n100 sudo[1853]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreStateMachine::getcurrentVolume
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioRetrievevolume
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:58 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:58 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 18 10:45:58 smsl-n100 volumio[942]: info: Preparing to generate the ALSA configuration file
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 10:45:58 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:58 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:58 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:58 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:58 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:45:58 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:58 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:45:58 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: running
Mar 18 10:45:58 smsl-n100 volumio[942]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Shairport-Sync Started
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect
Mar 18 10:45:59 smsl-n100 sudo[1870]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Mar 18 10:45:59 smsl-n100 sudo[1870]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Raat Daemon started successfully
Mar 18 10:45:59 smsl-n100 volumio[942]: info: RAAT: Requesting Headphone Status
Mar 18 10:45:59 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: inputs , getHeadphoneStatus
Mar 18 10:45:59 smsl-n100 volumio[942]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Reading ALSA contributions from plugins.
Mar 18 10:45:59 smsl-n100 sudo[1873]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service
Mar 18 10:45:59 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service.
Mar 18 10:45:59 smsl-n100 sudo[1873]: pam_unix(sudo:session): session opened for user root by (uid=0)
Mar 18 10:45:59 smsl-n100 sudo[1870]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:59 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Retrieving Cloud Streaming UI
Mar 18 10:45:59 smsl-n100 sudo[1873]: pam_unix(sudo:session): session closed for user root
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Executing endpoint restartRAATSocket
Mar 18 10:45:59 smsl-n100 volumio[942]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Got Tidal Cloud Configuration
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Got it
Mar 18 10:45:59 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:59 smsl-n100 volumio[942]: info: CoreStateMachine::pushState
Mar 18 10:45:59 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:59 smsl-n100 volumio[942]: info: CoreCommandRouter::volumioPushState
Mar 18 10:45:59 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output update for this device
Mar 18 10:45:59 smsl-n100 volumio[942]: info: MRS: Pushing multiroomSync output
Mar 18 10:45:59 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:59 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: MAXVOL, message:
Mar 18 10:45:59 smsl-n100 volumio[942]: error: Serial API: Failed to decode command: 100, message: undefined
Mar 18 10:45:59 smsl-n100 volumio[942]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff
Mar 18 10:45:59 smsl-n100 volumio[942]: info: CorePlayQueue::getTrack 0
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Getting Tidal Cloud Configuration
Mar 18 10:45:59 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Getting Qobuz Cloud Configuration
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Asking plugin for UI Config
Mar 18 10:45:59 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Getting Spotify Cloud Configuration
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Asking plugin for UI Config
Mar 18 10:45:59 smsl-n100 volumio[942]: info: CoreCommandRouter::getUIConfigOnPlugin
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Saving Spotify Acccount
Mar 18 10:45:59 smsl-n100 volumio[942]: info: Got it
Mar 18 10:45:59 smsl-n100 volumio[942]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Mar 18 10:45:59 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: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
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"