-- Logs begin at Tue 2022-07-19 18:58:21 UTC, end at Wed 2025-05-07 15:59:14 UTC. --
May 07 15:58:51 shd systemd[1]: Time has been changed
May 07 15:58:51 shd volumio[944]: info: Loading plugin "alarm-clock"...
May 07 15:58:51 shd volumio[944]: info: Loading plugin "metavolumio"...
May 07 15:58:52 shd volumio[944]: info: Loading plugin "airplay_emulation"...
May 07 15:58:52 shd volumio[944]: info: Starting Shairport Sync
May 07 15:58:52 shd volumio[944]: info: Loading plugin "cd_controller"...
May 07 15:58:52 shd volumio[944]: info: Loading plugin "last_100"...
May 07 15:58:52 shd volumio[944]: info: Loading plugin "raat"...
May 07 15:58:52 shd volumio[944]: info: RAAT Plugin loaded
May 07 15:58:52 shd volumio[944]: info: Adding restartRAATSocket REST API Endpoint
May 07 15:58:52 shd volumio[944]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat
May 07 15:58:52 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume
May 07 15:58:52 shd volumio[944]: error: Could not retrieve plugin music_service inputs
May 07 15:58:52 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP
May 07 15:58:52 shd volumio[944]: error: Could not retrieve plugin music_service inputs
May 07 15:58:52 shd volumio[944]: info: Loading plugin "streaming_services"...
May 07 15:58:52 shd volumio[944]: info: Loading plugin "webradio"...
May 07 15:58:52 shd volumio[944]: info: Loading plugin "i2s_dacs"...
May 07 15:58:52 shd volumio[944]: info: I2S DAC not set, start Auto-detection
May 07 15:58:52 shd volumio[944]: info: Loading plugin "volumiodiscovery"...
May 07 15:58:52 shd volumio[944]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
May 07 15:58:52 shd node[944]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
May 07 15:58:52 shd node[944]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 07 15:58:52 shd node[944]: *** WARNING *** For more information see
May 07 15:58:52 shd node[944]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
May 07 15:58:52 shd node[944]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 07 15:58:52 shd node[944]: *** WARNING *** For more information see
May 07 15:58:52 shd volumio[944]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 07 15:58:52 shd volumio[944]: *** WARNING *** For more information see
May 07 15:58:52 shd volumio[944]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
May 07 15:58:52 shd volumio[944]: *** WARNING *** Please fix your application to use the native API of Avahi!
May 07 15:58:52 shd volumio[944]: *** WARNING *** For more information see
May 07 15:58:52 shd volumio[944]: info: Applying required configuration parameters for plugin volumiodiscovery
May 07 15:58:52 shd volumio[944]: Discovery: StartAdv! undefined
May 07 15:58:52 shd volumio[944]: Discovery: Started advertising... shd - undefined
May 07 15:58:52 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 07 15:58:52 shd volumio[944]: info: Loading plugin "outputs"...
May 07 15:58:52 shd volumio[944]: info: Loading plugin "albumart"...
May 07 15:58:52 shd volumio[944]: info: Loading plugin "ms_surface_dial"...
May 07 15:58:53 shd volumio[944]: Forking 3 albumart workers
May 07 15:58:53 shd volumio[944]: info: Applying required configuration parameters for plugin ms_surface_dial
May 07 15:58:53 shd volumio[944]: info: Plugin example_plugin is not enabled
May 07 15:58:53 shd volumio[944]: info: Loading plugin "hi_res_audio"...
May 07 15:58:53 shd volumio[944]: info: Applying required configuration parameters for plugin hi_res_audio
May 07 15:58:53 shd volumio[944]: info: Loading plugin "inputs"...
May 07 15:58:54 shd volumio[944]: info: Loading plugin "qobuz"...
May 07 15:58:54 shd sudo[980]: pam_unix(sudo:session): session closed for user root
May 07 15:58:54 shd volumio[944]: Starting albumart workers
May 07 15:58:54 shd volumio-remote-updater[642]: [2025-05-07 15:58:54] [connect] Successful connection
May 07 15:58:54 shd volumio[944]: Starting albumart workers
May 07 15:58:54 shd volumio[944]: Starting albumart workers
May 07 15:58:56 shd volumio[944]: info: Loading plugin "tidal"...
May 07 15:58:59 shd volumio[944]: info: Loading plugin "updater_comm"...
May 07 15:58:59 shd volumio[944]: info: Plugin mpdemulation is not enabled
May 07 15:58:59 shd volumio[944]: info: Loading plugin "rest_api"...
May 07 15:58:59 shd volumio[944]: info: Loading plugin "websocket"...
May 07 15:58:59 shd volumio[944]: info: Loading plugin "RoonBridge"...
May 07 15:58:59 shd ifup[515]: dhcpcd[549]: timed out
May 07 15:58:59 shd dhcpcd[549]: timed out
May 07 15:58:59 shd dhcpcd[549]: exited
May 07 15:58:59 shd ifup[515]: dhcpcd[549]: exited
May 07 15:58:59 shd ifup[515]: Failed to bring up eth0.
May 07 15:58:59 shd volumio[944]: info: Applying required configuration parameters for plugin RoonBridge
May 07 15:58:59 shd volumio[944]: info: Loading plugin "spop"...
May 07 15:59:01 shd volumio[944]: info: Loading plugin "volspotconnect2"...
May 07 15:59:02 shd volumio[944]: info: ___________ START PLUGINS ___________
May 07 15:59:02 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 07 15:59:02 shd volumio[944]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 07 15:59:02 shd volumio[944]: info: [1746633542499] CoreMusicLibrary::Adding element Media Servers
May 07 15:59:02 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 07 15:59:02 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 07 15:59:02 shd volumio[944]: info: Adding METAVOLUMIO REST API Endpoints
May 07 15:59:02 shd volumio[944]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio
May 07 15:59:02 shd sudo[1041]: pam_unix(sudo:auth): conversation failed
May 07 15:59:02 shd sudo[1041]: pam_unix(sudo:auth): auth could not identify password for [volumio]
May 07 15:59:02 shd volumio[944]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 07 15:59:02 shd volumio[944]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
May 07 15:59:02 shd volumio[944]: info: Preparing CD Folders
May 07 15:59:02 shd volumio[944]: info: Adding CD REST API Endpoints
May 07 15:59:02 shd volumio[944]: info: Adding detectCD REST Endpoint for plugin: music_service/cd_controller
May 07 15:59:02 shd volumio[944]: info: Adding cdRemove REST Endpoint for plugin: music_service/cd_controller
May 07 15:59:02 shd volumio[944]: info: Adding cdPostScan REST Endpoint for plugin: music_service/cd_controller
May 07 15:59:02 shd volumio[944]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller
May 07 15:59:02 shd volumio[944]: info: Detecting CD presence with Flag file
May 07 15:59:02 shd volumio[944]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 07 15:59:02 shd volumio[944]: info: [1746633542687] CoreMusicLibrary::Adding element Last_100
May 07 15:59:02 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 07 15:59:02 shd volumio[944]: info: Starting RAAT Plugin
May 07 15:59:02 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections
May 07 15:59:02 shd volumio[944]: info: Additional UI Settings Added for plugin music_service/raat
May 07 15:59:02 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:02 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:02 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:02 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 07 15:59:02 shd volumio[944]: info: Not Reporting Auto name since its the default one
May 07 15:59:02 shd volumio[944]: info: Streaming services startup
May 07 15:59:02 shd volumio[944]: info: Starting Streaming Daemon
May 07 15:59:02 shd sudo[1055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
May 07 15:59:02 shd sudo[1055]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:02 shd systemd[1]: Stopping Volumio Streaming Daemon...
May 07 15:59:02 shd systemd[1]: Starting Volumio Streaming Daemon...
May 07 15:59:02 shd systemd[1]: Started Volumio Streaming Daemon.
May 07 15:59:02 shd sudo[1055]: pam_unix(sudo:session): session closed for user root
May 07 15:59:02 shd volumio-streaming-daemon[1060]: ############################
May 07 15:59:02 shd volumio-streaming-daemon[1060]: # Volumio Streaming Daemon #
May 07 15:59:02 shd volumio-streaming-daemon[1060]: # Running on port 7777 #
May 07 15:59:02 shd volumio-streaming-daemon[1060]: ############################
May 07 15:59:02 shd volumio-streaming-daemon[1060]: Checking variant in static cache
May 07 15:59:02 shd volumio-streaming-daemon[1060]: Enabled services:
May 07 15:59:02 shd volumio-streaming-daemon[1060]: 1) tidal
May 07 15:59:02 shd volumio-streaming-daemon[1060]: 2) qobuz
May 07 15:59:02 shd volumio[944]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 07 15:59:02 shd volumio[944]: info: [1746633542858] CoreMusicLibrary::Adding element Webradio
May 07 15:59:02 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 07 15:59:02 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
May 07 15:59:02 shd volumio[944]: error: Hi Res Audio Failed Login: Missing Login Data
May 07 15:59:02 shd volumio[944]: info: Refreshing QOBUZ token
May 07 15:59:02 shd sudo[1070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service
May 07 15:59:02 shd sudo[1070]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:02 shd systemd[1]: Starting RoonBridge...
May 07 15:59:02 shd systemd[1]: Started RoonBridge.
May 07 15:59:02 shd sudo[1070]: pam_unix(sudo:session): session closed for user root
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
May 07 15:59:03 shd volumio[944]: [SpotifyConnect] Creating VLS config file
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:03 shd sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts
May 07 15:59:03 shd sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:03 shd volumio[944]: [SpotifyConnect] Starting metadata listener
May 07 15:59:03 shd sudo[1078]: pam_unix(sudo:session): session closed for user root
May 07 15:59:03 shd volumio[944]: [SpotifyConnect] Creating VLS config file
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:03 shd volumio[944]: [SpotifyConnect] <> or Mixer found, using softvol
May 07 15:59:03 shd sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service
May 07 15:59:03 shd volumio[944]: [SpotifyConnect] Starting metadata listener
May 07 15:59:03 shd sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:03 shd systemd[1]: Starting Volspotconnect2 Daemon...
May 07 15:59:03 shd systemd[1]: Started Volspotconnect2 Daemon.
May 07 15:59:03 shd sudo[1084]: pam_unix(sudo:session): session closed for user root
May 07 15:59:03 shd volumio[944]: info: Loading i18n strings for locale en
May 07 15:59:03 shd sudo[1088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service
May 07 15:59:03 shd sudo[1088]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:03 shd volumio[944]: Updating browse sources language
May 07 15:59:03 shd volumio[944]: Cannot find translation for sourceMedia Servers
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 07 15:59:03 shd systemd[1]: Starting Volspotconnect2 Daemon...
May 07 15:59:03 shd systemd[1]: Started Volspotconnect2 Daemon.
May 07 15:59:03 shd sudo[1088]: pam_unix(sudo:session): session closed for user root
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::initPlayerControls
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 07 15:59:03 shd volumio[944]: info: BOOT COMPLETED
May 07 15:59:03 shd volumio[944]: [Metrics] CommandRouter: 17s 665.31ms
May 07 15:59:03 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 07 15:59:03 shd volumio[1087]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11
May 07 15:59:03 shd volumio[1087]: Reading Config from "/tmp/volspotify.toml"
May 07 15:59:03 shd volumio[1087]: Malformed config key: missing field `Authentication`
May 07 15:59:03 shd volumio[1091]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07
May 07 15:59:03 shd volumio[1091]: Reading Config from "volspotify.toml"
May 07 15:59:03 shd volumio[1091]: [Vollibrespot] : Using Alsa backend with device: plughw:0
May 07 15:59:03 shd volumio[1091]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" }
May 07 15:59:03 shd volumio[1091]: [Vollibrespot] : couldn't parse packet from V4(10.1.100.1:5353): type 47 is invalid
May 07 15:59:04 shd volumio[1091]: [Vollibrespot] : couldn't parse packet from V4(10.1.100.1:5353): type 47 is invalid
May 07 15:59:05 shd volumio[1091]: [Vollibrespot] : couldn't parse packet from V4(10.1.100.1:5353): type 47 is invalid
May 07 15:59:05 shd RoonBridge[1072]: 00:00:00.018 Warn: get lock file path: /tmp/.rnbgem0-
May 07 15:59:05 shd volumio[1091]: [Vollibrespot] : couldn't parse packet from V4(10.1.100.1:5353): type 47 is invalid
May 07 15:59:05 shd systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
May 07 15:59:05 shd systemd[1]: Stopping Volspotconnect2 Daemon...
May 07 15:59:05 shd systemd[1]: Starting Volspotconnect2 Daemon...
May 07 15:59:05 shd systemd[1]: Started Volspotconnect2 Daemon.
May 07 15:59:05 shd volumio[1100]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11
May 07 15:59:05 shd volumio[1100]: Reading Config from "/tmp/volspotify.toml"
May 07 15:59:05 shd volumio[1100]: Malformed config key: missing field `Authentication`
May 07 15:59:05 shd RoonBridge[1072]: 00:00:00.810 Trace: [childprocess] using unix child process
May 07 15:59:05 shd RoonBridge[1072]: 00:00:00.918 Debug: PathForResource, filename: ../.update
May 07 15:59:06 shd RoonBridge[1072]: 00:00:01.027 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update
May 07 15:59:06 shd RoonBridge[1072]: 00:00:01.040 Debug: PathForResource, filename: RAATServer
May 07 15:59:06 shd RoonBridge[1072]: 00:00:01.040 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
May 07 15:59:06 shd RoonBridge[1072]: 00:00:01.041 Debug: PathForResource, filename: RoonBridgeHelper
May 07 15:59:06 shd RoonBridge[1072]: 00:00:01.041 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper
May 07 15:59:06 shd RoonBridge[1072]: Initializing
May 07 15:59:06 shd RoonBridge[1072]: 00:00:01.110 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper
May 07 15:59:06 shd volumio[1091]: [Vollibrespot] : couldn't parse packet from V4(10.1.100.1:5353): type 47 is invalid
May 07 15:59:06 shd RoonBridge[1072]: 00:00:01.174 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer
May 07 15:59:06 shd RoonBridge[1072]: Not Running (.o)
May 07 15:59:06 shd volumio[1091]: [Vollibrespot] : couldn't parse packet from V4(10.1.100.1:5353): type 47 is invalid
May 07 15:59:06 shd RoonBridge[1072]: Running
May 07 15:59:07 shd volumio[1091]: [Vollibrespot] : couldn't parse packet from V4(10.1.100.1:5353): type 47 is invalid
May 07 15:59:07 shd volumio[1091]: [Vollibrespot] : couldn't parse packet from V4(10.1.100.1:5353): type 47 is invalid
May 07 15:59:07 shd RoonBridge[1072]: 00:00:00.054 Warn: get lock file path: /tmp/.rnbhgem0-
May 07 15:59:07 shd systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
May 07 15:59:07 shd systemd[1]: Stopping Volspotconnect2 Daemon...
May 07 15:59:07 shd systemd[1]: Starting Volspotconnect2 Daemon...
May 07 15:59:08 shd systemd[1]: Started Volspotconnect2 Daemon.
May 07 15:59:08 shd volumio[1128]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11
May 07 15:59:08 shd volumio[1128]: Reading Config from "/tmp/volspotify.toml"
May 07 15:59:08 shd volumio[1128]: Malformed config key: missing field `Authentication`
May 07 15:59:08 shd volumio[1091]: [Vollibrespot] : couldn't parse packet from V4(10.1.100.1:5353): type 47 is invalid
May 07 15:59:08 shd volumio[944]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo
May 07 15:59:08 shd volumio[944]: info: CoreCommandRouter::Close All Modals sent
May 07 15:59:08 shd volumio[944]: info: CoreCommandRouter::Close All Modals sent
May 07 15:59:08 shd volumio[944]: Express server listening on port 3000
May 07 15:59:08 shd volumio[944]: [Metrics] WebUI: 24s 360.41ms
May 07 15:59:08 shd volumio[944]: Volumio Calling Home
May 07 15:59:08 shd volumio[944]: [SpotifyConnect] Error: bind EADDRINUSE 0.0.0.0:5030
May 07 15:59:08 shd volumio[944]: at _handle.lookup (dgram.js:266:18)
May 07 15:59:08 shd volumio[944]: at _combinedTickCallback (internal/process/next_tick.js:142:11)
May 07 15:59:08 shd volumio[944]: at process._tickCallback (internal/process/next_tick.js:181:9)
May 07 15:59:08 shd volumio[944]: at Function.Module.runMain (module.js:696:11)
May 07 15:59:08 shd volumio[944]: at startup (bootstrap_node.js:204:16)
May 07 15:59:08 shd volumio[944]: at bootstrap_node.js:625:3
May 07 15:59:08 shd volumio[944]: [SpotifyConnect] Error [ERR_SOCKET_CANNOT_SEND]: Unable to send data
May 07 15:59:08 shd volumio[944]: at Socket.onListenError (dgram.js:362:22)
May 07 15:59:08 shd volumio[944]: at Object.onceWrapper (events.js:315:30)
May 07 15:59:08 shd volumio[944]: at emitOne (events.js:121:20)
May 07 15:59:08 shd volumio[944]: at Socket.emit (events.js:211:7)
May 07 15:59:08 shd volumio[944]: at _handle.lookup (dgram.js:267:14)
May 07 15:59:08 shd volumio[944]: at _combinedTickCallback (internal/process/next_tick.js:142:11)
May 07 15:59:08 shd volumio[944]: at process._tickCallback (internal/process/next_tick.js:181:9)
May 07 15:59:08 shd volumio[944]: at Function.Module.runMain (module.js:696:11)
May 07 15:59:08 shd volumio[944]: at startup (bootstrap_node.js:204:16)
May 07 15:59:08 shd volumio[944]: at bootstrap_node.js:625:3
May 07 15:59:08 shd volumio[944]: info: CoreStateMachine::resetVolumioState
May 07 15:59:08 shd volumio[944]: info: CoreStateMachine::getcurrentVolume
May 07 15:59:08 shd volumio[944]: info: CoreCommandRouter::volumioRetrievevolume
May 07 15:59:08 shd volumio[944]: info: Stopping AccessToken refresher cron
May 07 15:59:09 shd volumio[944]: info: AccessToken refresher cron started
May 07 15:59:09 shd volumio[944]: info: msSurfaceDial volumioupdatevolume callback: {"vol":100,"mute":false,"disableVolumeControl":true}
May 07 15:59:09 shd volumio[944]: info: CoreStateMachine::pushState
May 07 15:59:09 shd volumio[944]: info: CorePlayQueue::getTrack 0
May 07 15:59:09 shd volumio[944]: info: CoreCommandRouter::volumioPushState
May 07 15:59:09 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 07 15:59:09 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
May 07 15:59:09 shd sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
May 07 15:59:09 shd sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:09 shd sudo[1143]: pam_unix(sudo:session): session closed for user root
May 07 15:59:09 shd sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
May 07 15:59:09 shd sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:09 shd volumio-remote-updater[642]: [2025-05-07 15:59:09] [connect] Successful connection
May 07 15:59:09 shd sudo[1145]: pam_unix(sudo:session): session closed for user root
May 07 15:59:09 shd sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service
May 07 15:59:09 shd sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:09 shd systemd[1]: Configuration file /lib/systemd/system/volumiobt.service is marked executable. Please remove executable permission bits. Proceeding anyway.
May 07 15:59:09 shd systemd[1]: Starting Volumio Bluetooth Module...
May 07 15:59:09 shd systemd[1]: Started Volumio Bluetooth Module.
May 07 15:59:09 shd sudo[1149]: pam_unix(sudo:session): session closed for user root
May 07 15:59:09 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
May 07 15:59:09 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:09 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:09 shd sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/*
May 07 15:59:09 shd sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:09 shd volumiobt[1151]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory
May 07 15:59:09 shd sudo[1152]: pam_unix(sudo:session): session closed for user root
May 07 15:59:09 shd volumio[944]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt'
May 07 15:59:09 shd volumio[944]: info: Serial port opened successfully
May 07 15:59:09 shd volumio[944]: info: Volumio BT Module successfully started
May 07 15:59:09 shd volumio[944]: info: Setting Device type: NanoPi NEO2
May 07 15:59:09 shd volumio[944]: [SpotifyConnect] Vollibrespot Daemon service started!
May 07 15:59:09 shd volumio[944]: [Metrics] SpotifyConnect: 6s 575.70ms
May 07 15:59:09 shd volumio[944]: [SpotifyConnect] Vollibrespot Daemon service started!
May 07 15:59:09 shd volumio[944]: [Metrics] SpotifyConnect: 6s 578.67ms
May 07 15:59:09 shd volumio[944]: info: RAAT Albumart path created successfully
May 07 15:59:09 shd volumio[944]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'shd'
May 07 15:59:09 shd volumio[944]: sudo: no tty present and no askpass program specified
May 07 15:59:09 shd volumio[944]: info: Cannot mount NAS Music at system boot, trial number 1 ,retrying in 5 seconds
May 07 15:59:09 shd volumio[944]: info: MPD running with PID674
May 07 15:59:09 shd volumio[944]: ,establishing connection
May 07 15:59:09 shd sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1
May 07 15:59:09 shd sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:09 shd sudo[1157]: pam_unix(sudo:session): session closed for user root
May 07 15:59:09 shd dbus[676]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service'
May 07 15:59:09 shd systemd[1]: Starting Bluetooth service...
May 07 15:59:09 shd bluetoothd[1159]: Bluetooth daemon 5.23
May 07 15:59:09 shd dbus[676]: [system] Successfully activated service 'org.bluez'
May 07 15:59:09 shd systemd[1]: Started Bluetooth service.
May 07 15:59:09 shd kernel: Bluetooth: Core ver 2.22
May 07 15:59:09 shd kernel: NET: Registered protocol family 31
May 07 15:59:09 shd kernel: Bluetooth: HCI device and connection manager initialized
May 07 15:59:09 shd kernel: Bluetooth: HCI socket layer initialized
May 07 15:59:09 shd kernel: Bluetooth: L2CAP socket layer initialized
May 07 15:59:09 shd kernel: Bluetooth: SCO socket layer initialized
May 07 15:59:09 shd volumio-remote-updater[642]: [2025-05-07 15:59:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1746633549 101
May 07 15:59:09 shd bluetoothd[1159]: Starting SDP server
May 07 15:59:09 shd bluetoothd[1159]: Excluding (cli) sap
May 07 15:59:09 shd volumio[944]: info: Volumio called home
May 07 15:59:09 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
May 07 15:59:09 shd kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3
May 07 15:59:09 shd kernel: Bluetooth: BNEP filters: protocol multicast
May 07 15:59:09 shd kernel: Bluetooth: BNEP socket layer initialized
May 07 15:59:09 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
May 07 15:59:09 shd bluetoothd[1159]: Bluetooth management interface 1.14 initialized
May 07 15:59:09 shd dbus[676]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service'
May 07 15:59:09 shd systemd[1]: Starting Hostname Service...
May 07 15:59:10 shd volumio[944]: xcb_connection_has_error() returned true
May 07 15:59:10 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
May 07 15:59:10 shd systemd-hostnamed[1166]: Warning: nss-myhostname is not installed. Changing the local hostname might make it unresolveable. Please install nss-myhostname!
May 07 15:59:10 shd dbus[676]: [system] Successfully activated service 'org.freedesktop.hostname1'
May 07 15:59:10 shd systemd[1]: volspotconnect.service holdoff time over, scheduling restart.
May 07 15:59:10 shd systemd[1]: Started Hostname Service.
May 07 15:59:10 shd systemd[1]: Stopping Volspotconnect2 Daemon...
May 07 15:59:10 shd systemd[1]: Starting Volspotconnect2 Daemon...
May 07 15:59:10 shd systemd[1]: Started Volspotconnect2 Daemon.
May 07 15:59:10 shd volumio[1170]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11
May 07 15:59:10 shd volumio[1170]: Reading Config from "/tmp/volspotify.toml"
May 07 15:59:10 shd volumio[1170]: [Vollibrespot] : Using Alsa backend with device: plughw:0
May 07 15:59:10 shd volumio[944]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1
May 07 15:59:10 shd volumio[944]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory
May 07 15:59:10 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable
May 07 15:59:10 shd volumio[944]: xcb_connection_has_error() returned true
May 07 15:59:10 shd volumio[1170]: [Vollibrespot] : Connecting to AP "ap-gew4.spotify.com:443"
May 07 15:59:10 shd sudo[1178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio
May 07 15:59:10 shd sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:10 shd volumio[944]: info: Reloading queue from file
May 07 15:59:10 shd volumio[944]: info: Serial Data:
May 07 15:59:10 shd volumiobt[1151]: pulseaudio: no process found
May 07 15:59:10 shd sudo[1178]: pam_unix(sudo:session): session closed for user root
May 07 15:59:10 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat
May 07 15:59:10 shd volumio[944]: info: Reconfiguring and Restarting RAAT Plugin
May 07 15:59:10 shd sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service
May 07 15:59:10 shd sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:10 shd systemd[1]: Starting RAAT DAEMON...
May 07 15:59:10 shd systemd[1]: Started RAAT DAEMON.
May 07 15:59:10 shd sudo[1182]: pam_unix(sudo:session): session closed for user root
May 07 15:59:10 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:10 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:10 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
May 07 15:59:10 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
May 07 15:59:10 shd volumio[944]: info: Not Reporting Auto name since its the default one
May 07 15:59:10 shd volumio[944]: info: RAAT Overriding default device vendor model
May 07 15:59:10 shd volumio[944]: info: Adding MINIDSP Inputs
May 07 15:59:10 shd volumio[944]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 07 15:59:10 shd volumio[944]: info: [1746633550698] CoreMusicLibrary::Adding element Inputs
May 07 15:59:10 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 07 15:59:10 shd volumio[944]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 07 15:59:10 shd volumio[944]: info: [1746633550733] CoreMusicLibrary::Adding element Presets
May 07 15:59:10 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 07 15:59:10 shd dbus[676]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service'
May 07 15:59:10 shd volumio[944]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{},"org.bluez.Alert1":{},"org.bluez.HealthManager1":{}}}
May 07 15:59:10 shd volumio[944]: info: [msSurfaceDial init()] check /org/bluez
May 07 15:59:10 shd volumio[944]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null
May 07 15:59:10 shd systemd[1]: Starting RealtimeKit Scheduling Policy Service...
May 07 15:59:10 shd volumio[944]: info: msSurfaceDial BluetoothSurfaceDial init() - ready!
May 07 15:59:10 shd volumio[944]: info: CoreCommandRouter::Reload Ui
May 07 15:59:10 shd volumio[944]: ------------------------------------ BT MESSAGE: BT STATUS: running
May 07 15:59:10 shd dbus[676]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1'
May 07 15:59:10 shd rtkit-daemon[1204]: Successfully called chroot.
May 07 15:59:10 shd rtkit-daemon[1204]: Successfully dropped privileges.
May 07 15:59:10 shd rtkit-daemon[1204]: Successfully limited resources.
May 07 15:59:10 shd rtkit-daemon[1204]: Running.
May 07 15:59:10 shd rtkit-daemon[1204]: Watchdog thread running.
May 07 15:59:10 shd rtkit-daemon[1204]: Canary thread running.
May 07 15:59:10 shd systemd[1]: Started RealtimeKit Scheduling Policy Service.
May 07 15:59:10 shd volumio[944]: error: updateQueue error: null
May 07 15:59:10 shd volumio[944]: error: Failed LSINFO: Error: [50@0] {} No such directory
May 07 15:59:11 shd pulseaudio[1201]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
May 07 15:59:11 shd pulseaudio[1201]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11
May 07 15:59:11 shd pulseaudio[1201]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist
May 07 15:59:11 shd volumio[944]: info: CoreStateMachine::setRepeat undefined single undefined
May 07 15:59:11 shd volumio[944]: info: CoreStateMachine::pushState
May 07 15:59:11 shd volumio[944]: info: CorePlayQueue::getTrack 0
May 07 15:59:11 shd volumio[944]: info: CoreCommandRouter::volumioPushState
May 07 15:59:11 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 07 15:59:11 shd volumiobt[1151]: Applying permissions
May 07 15:59:11 shd volumiobt[1151]: Setting BT discoverable and pairable
May 07 15:59:11 shd volumio[944]: info: CoreStateMachine::setRandom true
May 07 15:59:11 shd volumio[944]: info: CoreStateMachine::pushState
May 07 15:59:11 shd volumio[944]: info: CorePlayQueue::getTrack 0
May 07 15:59:11 shd volumio[944]: info: CoreCommandRouter::volumioPushState
May 07 15:59:11 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 07 15:59:11 shd volumio[944]: ------------------------------------ BT MESSAGE: BT STATUS: running
May 07 15:59:11 shd volumiobt[1151]: [38B blob data]
May 07 15:59:11 shd volumiobt[1151]: Invalid command
May 07 15:59:11 shd volumiobt[1151]: [bluetooth]# agent on
May 07 15:59:11 shd volumiobt[1151]: [bluetooth]# discoverable on
May 07 15:59:11 shd volumiobt[1151]: No default controller available
May 07 15:59:11 shd volumiobt[1151]: [bluetooth]# pairable on
May 07 15:59:11 shd volumiobt[1151]: No default controller available
May 07 15:59:11 shd volumiobt[1151]: [bluetooth]# agent NoInputNoOutput
May 07 15:59:11 shd volumiobt[1151]: Failed to register agent object
May 07 15:59:11 shd volumiobt[1151]: [bluetooth]# default-agent
May 07 15:59:11 shd volumiobt[1151]: No agent is registered
May 07 15:59:11 shd volumiobt[1151]: [bluetooth]# quit
May 07 15:59:11 shd volumiobt[1151]: [61B blob data]
May 07 15:59:11 shd volumio[944]: info: Raat Daemon started successfully
May 07 15:59:11 shd volumiobt[1151]: [62B blob data]
May 07 15:59:11 shd volumio[944]: info: Executing endpoint restartRAATSocket
May 07 15:59:11 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection
May 07 15:59:11 shd volumio[944]: info: Starting Shairport Sync
May 07 15:59:12 shd volumio[944]: info: Starting Shairport Sync
May 07 15:59:12 shd sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 07 15:59:12 shd sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:12 shd systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver...
May 07 15:59:12 shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 07 15:59:12 shd sudo[1222]: pam_unix(sudo:session): session closed for user root
May 07 15:59:12 shd volumio[944]: info: Serial Data:
May 07 15:59:12 shd volumio[944]: info: msSurfaceDial volumioupdatevolume callback: {"mute":false,"disableVolumeControl":false}
May 07 15:59:12 shd volumio[944]: info: CoreStateMachine::pushState
May 07 15:59:12 shd sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
May 07 15:59:12 shd sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:12 shd volumio[944]: info: CorePlayQueue::getTrack 0
May 07 15:59:12 shd volumio[944]: info: CoreCommandRouter::volumioPushState
May 07 15:59:12 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 07 15:59:12 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume
May 07 15:59:12 shd volumio[944]: info: Updating RAAT Signal Path
May 07 15:59:12 shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
May 07 15:59:12 shd volumio[944]: info: CorePlayQueue::getTrack 0
May 07 15:59:12 shd volumio[944]: info: CoreCommandRouter::volumiosetSourceActiveno-source
May 07 15:59:12 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 07 15:59:12 shd volumio[944]: info: CoreCommandRouter::volumioStop
May 07 15:59:12 shd volumio[944]: info: CoreStateMachine::stop
May 07 15:59:12 shd volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined
May 07 15:59:12 shd systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver...
May 07 15:59:12 shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
May 07 15:59:12 shd sudo[1225]: pam_unix(sudo:session): session closed for user root
May 07 15:59:12 shd volumio[944]: verbose: UNSET VOLATILE
May 07 15:59:12 shd volumio[944]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"TOSLINK","disableUiControls":true,"albumart":"/albumart"}
May 07 15:59:12 shd volumio[944]: info: msSurfaceDial volumioupdatevolume callback: {"vol":null,"dbVolume":null,"mute":false,"disableVolumeControl":false}
May 07 15:59:12 shd sudo[1231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service
May 07 15:59:12 shd sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:12 shd volumio[944]: info: CoreStateMachine::pushState
May 07 15:59:12 shd volumio[944]: info: CorePlayQueue::getTrack 0
May 07 15:59:12 shd volumio[944]: info: CoreCommandRouter::volumioPushState
May 07 15:59:12 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 07 15:59:12 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume
May 07 15:59:12 shd volumio[944]: info: Updating RAAT Signal Path
May 07 15:59:12 shd systemd[1]: Starting UPnP Renderer front-end to MPD...
May 07 15:59:12 shd systemd[1]: Started UPnP Renderer front-end to MPD.
May 07 15:59:12 shd volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined
May 07 15:59:12 shd sudo[1231]: pam_unix(sudo:session): session closed for user root
May 07 15:59:12 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable
May 07 15:59:12 shd volumio[944]: xcb_connection_has_error() returned true
May 07 15:59:12 shd sudo[1254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service
May 07 15:59:12 shd sudo[1254]: pam_unix(sudo:session): session opened for user root by (uid=0)
May 07 15:59:12 shd systemd[1]: Stopping RAAT DAEMON...
May 07 15:59:12 shd systemd[1]: Starting RAAT DAEMON...
May 07 15:59:12 shd systemd[1]: Started RAAT DAEMON.
May 07 15:59:12 shd sudo[1254]: pam_unix(sudo:session): session closed for user root
May 07 15:59:12 shd volumio[944]: info: Access Token successfully retrieved
May 07 15:59:12 shd volumio[944]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
May 07 15:59:12 shd volumio[944]: info: [1746633552840] CoreMusicLibrary::Adding element QOBUZ
May 07 15:59:12 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
May 07 15:59:12 shd volumio[944]: info: Stopping AccessToken refresher cron for QOBUZ
May 07 15:59:12 shd volumio[944]: info: AccessToken refresher cron started for QOBUZ
May 07 15:59:12 shd volumio[944]: ------------------------------------ BT MESSAGE: BT STATUS: running
May 07 15:59:12 shd volumio[944]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
May 07 15:59:13 shd volumio[944]: ------------------------------------ BT MESSAGE: BT STATUS: running
May 07 15:59:13 shd volumio[944]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
May 07 15:59:13 shd volumio[944]: info: Upmpdcli Daemon Started
May 07 15:59:13 shd volumio[944]: info: Shairport-Sync Started
May 07 15:59:13 shd volumio[944]: Error adding Membership: Error: addMembership EINVAL
May 07 15:59:13 shd volumio[944]: info: Shairport-Sync Started
May 07 15:59:13 shd volumio[944]: info: CoreCommandRouter::servicePushState
May 07 15:59:13 shd volumio[944]: info: CoreStateMachine::pushState
May 07 15:59:13 shd volumio[944]: info: CoreCommandRouter::volumioPushState
May 07 15:59:13 shd volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 07 15:59:13 shd volumio[944]: Discovery: adding c96094bd-a322-465c-98e6-a51c19ab2527
May 07 15:59:13 shd volumio[944]: info: mDNS: Found device shd
May 07 15:59:13 shd volumio[944]: info: CoreCommandRouter::volumioGetState
May 07 15:59:13 shd volumio[944]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 07 15:59:13 shd volumio[944]: Error: The value NaN is not a number
May 07 15:59:13 shd volumio[944]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20)
May 07 15:59:13 shd volumio[944]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25)
May 07 15:59:13 shd volumio[944]: at ControllerVolumioDiscovery.pushMultiRoomStatusUpdate (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:287:25)
May 07 15:59:13 shd volumio[944]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:276:10)
May 07 15:59:13 shd volumio[944]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:202:12)
May 07 15:59:13 shd volumio[944]: at emitTwo (events.js:126:13)
May 07 15:59:13 shd volumio[944]: at Browser.emit (events.js:214:7)
May 07 15:59:13 shd volumio[944]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14)
May 07 15:59:13 shd volumio[944]: at next (/volumio/node_modules/mdns/lib/browser.js:104:7)
May 07 15:59:13 shd volumio[944]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11
May 07 15:59:13 shd volumio[944]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7)
May 07 15:59:13 shd volumio[944]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9)
May 07 15:59:13 shd volumio[944]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
May 07 15:59:14 shd sudo[1275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-07 15:58
May 07 15:59:14 shd sudo[1275]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 8 (jessie)"
NAME="Debian GNU/Linux"
VERSION_ID="8"
VERSION="8 (jessie)"
ID=debian
HOME_URL="http://www.debian.org/"
SUPPORT_URL="http://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03"
VOLUMIO_FE_VERSION="e5ce74e254a237ac98580c4437c4ed223c7d5cd9"
VOLUMIO_BE_VERSION="0cf482c24e7a7ab87af1081ea7d6d953621249a4"
VOLUMIO_ARCH="armv7"
VOLUMIO_VARIANT="minidspshd"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed Mar 23 10:08:04 CET 2022"
VOLUMIO_VERSION="1.121"
VOLUMIO_HARDWARE="nanopineo2"
VOLUMIO_VENDOR="miniDSP"
VOLUMIO_MODEL="SHD"
VOLUMIO_VENDOR_MODEL="miniDSP SHD"
VOLUMIO_HASH="c49a059c2bcfb892c670d4f5a84421ae"