-- Logs begin at Tue 2024-05-07 04:46:49 UTC, end at Sun 2024-05-12 08:59:16 UTC. -- May 12 08:58:00 volumiobad volumio[16161]: info: Loading plugin "websocket"... May 12 08:58:00 volumiobad volumio[16161]: info: Loading plugin "fusiondsp"... May 12 08:58:00 volumiobad volumio[16161]: Forking 3 albumart workers May 12 08:58:00 volumiobad volumio[16161]: info: Applying required configuration parameters for plugin fusiondsp May 12 08:58:00 volumiobad volumio[16161]: info: Loading plugin "spop"... May 12 08:58:01 volumiobad volumio[16161]: Starting albumart workers May 12 08:58:01 volumiobad volumio[16161]: Starting albumart workers May 12 08:58:01 volumiobad volumio[16161]: info: Loading plugin "volspotconnect2"... May 12 08:58:01 volumiobad volumio[16161]: Starting albumart workers May 12 08:58:01 volumiobad volumio[16161]: info: Loading i18n strings for locale de May 12 08:58:01 volumiobad volumio[16161]: Updating browse sources language May 12 08:58:01 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:01 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:01 volumiobad volumio[16161]: info: CoreCommandRouter::initPlayerControls May 12 08:58:01 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:01 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:01 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:01 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:01 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:01 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:01 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:01 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:01 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:01 volumiobad volumio[16161]: Express server listening on port 3000 May 12 08:58:01 volumiobad volumio[16161]: [Metrics] WebUI: 6s 622.25ms May 12 08:58:01 volumiobad volumio[16161]: info: CoreStateMachine::resetVolumioState May 12 08:58:01 volumiobad volumio[16161]: info: CoreStateMachine::getcurrentVolume May 12 08:58:01 volumiobad volumio[16161]: info: CoreCommandRouter::volumioRetrievevolume May 12 08:58:02 volumiobad volumio[16161]: info: MPD Permissions set May 12 08:58:02 volumiobad volumio[16161]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 May 12 08:58:02 volumiobad volumio[16161]: info: MPD running with PID16201 May 12 08:58:02 volumiobad volumio[16161]: ,establishing connection May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: Executing start script for DAC IQaudIO Pi-DigiAMP+ May 12 08:58:02 volumiobad volumio[16161]: info: Reloading queue from file May 12 08:58:02 volumiobad volumio[16161]: error: updateQueue error: null May 12 08:58:02 volumiobad volumio[16161]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 12 08:58:02 volumiobad volumio[16161]: info: CoreStateMachine::setRepeat null single undefined May 12 08:58:02 volumiobad volumio[16161]: info: CoreStateMachine::pushState May 12 08:58:02 volumiobad volumio[16161]: info: CorePlayQueue::getTrack 0 May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::volumioPushState May 12 08:58:02 volumiobad volumio[16161]: info: CoreStateMachine::setRandom false May 12 08:58:02 volumiobad volumio[16161]: info: CoreStateMachine::pushState May 12 08:58:02 volumiobad volumio[16161]: info: CorePlayQueue::getTrack 0 May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::volumioPushState May 12 08:58:02 volumiobad volumio[16161]: info: Setting Device type: Raspberry PI May 12 08:58:02 volumiobad volumio[16161]: info: DAC script executed May 12 08:58:02 volumiobad volumio[16161]: info: Completed loading Core Plugins May 12 08:58:02 volumiobad volumio[16161]: info: Preparing to generate the ALSA configuration file May 12 08:58:02 volumiobad volumio[16161]: info: VolumeController:: Volume=37 Mute =false May 12 08:58:02 volumiobad volumio[16161]: info: CoreStateMachine::pushState May 12 08:58:02 volumiobad volumio[16161]: info: CorePlayQueue::getTrack 0 May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::volumioPushState May 12 08:58:02 volumiobad volumio[16161]: info: CoreStateMachine::updateTrackBlock May 12 08:58:02 volumiobad volumio[16161]: info: CorePlayQueue::getTrackBlock May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::volumioRetrievevolume May 12 08:58:02 volumiobad volumio[16161]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 08:58:02 volumiobad volumio[16161]: info: Reading ALSA contributions from plugins. May 12 08:58:02 volumiobad volumio[16161]: error: updateQueue error: null May 12 08:58:02 volumiobad volumio[16161]: info: Discovery: adding 3986bfa1-ca3f-4ec8-a6f7-9ee4571b4957 May 12 08:58:02 volumiobad volumio[16161]: info: Discovery: Found device Volumiobad May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::volumioGetState May 12 08:58:02 volumiobad volumio[16161]: info: CorePlayQueue::getTrack 0 May 12 08:58:02 volumiobad volumio[16161]: info: Asound.conf file unchanged, so no further update is needed May 12 08:58:02 volumiobad volumio[16161]: info: Output device has changed, restarting MPD May 12 08:58:02 volumiobad sudo[16282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:02 volumiobad sudo[16282]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:02 volumiobad volumio[16161]: info: Output device has changed, restarting Shairport Sync May 12 08:58:02 volumiobad sudo[16282]: pam_unix(sudo:session): session closed for user root May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:02 volumiobad sudo[16284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:02 volumiobad sudo[16284]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:02 volumiobad systemd[1]: Stopping Music Player Daemon... May 12 08:58:02 volumiobad volumio[16161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:02 volumiobad volumio[16161]: info: ___________ START PLUGINS ___________ May 12 08:58:02 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:58:02 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:02 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:02 volumiobad volumio[16161]: info: [1715504282696] CoreMusicLibrary::Adding element Media Servers May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:02 volumiobad volumio[16161]: Cannot find translation for sourceMedia Servers May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:02 volumiobad volumio[16161]: info: [1715504282855] CoreMusicLibrary::Adding element Last_100 May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:02 volumiobad volumio[16161]: Cannot find translation for sourceMedia Servers May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:02 volumiobad volumio[16161]: info: [1715504282860] CoreMusicLibrary::Adding element Webradio May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:02 volumiobad volumio[16161]: Cannot find translation for sourceMedia Servers May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 08:58:02 volumiobad volumio[16161]: info: Loading i18n strings for locale de May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: FusionDsp - mixtype--------------------- Hardware May 12 08:58:02 volumiobad volumio[16161]: [SpotifyConnect] Creating VLS config file May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:02 volumiobad volumio[16161]: [SpotifyConnect] Starting metadata listener May 12 08:58:02 volumiobad sudo[16299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 12 08:58:02 volumiobad sudo[16299]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:02 volumiobad volumio[16161]: info: Volumio Calling Home May 12 08:58:02 volumiobad systemd[1]: Started Spotify Daemon. May 12 08:58:03 volumiobad sudo[16299]: pam_unix(sudo:session): session closed for user root May 12 08:58:03 volumiobad sudo[16303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 12 08:58:03 volumiobad volumio[16304]: 2024-05-12 08:58:03 [SPTF] 08:58:03.024 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 12 08:58:03 volumiobad sudo[16303]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:03 volumiobad volumio[16304]: 2024-05-12 08:58:03 [SPTF] 08:58:03.053 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 12 08:58:03 volumiobad sudo[16303]: pam_unix(sudo:session): session closed for user root May 12 08:58:03 volumiobad volumio[16304]: 2024-05-12 08:58:03 [SPTF] 08:58:03.083 I [ap:1226] Connected to AP: 34.158.0.131:4070 May 12 08:58:03 volumiobad volumio[16161]: info: Preparing to generate the ALSA configuration file May 12 08:58:03 volumiobad volumio[16161]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 08:58:03 volumiobad volumio[16161]: info: Reading ALSA contributions from plugins. May 12 08:58:03 volumiobad volumio[16161]: [SpotifyConnect] Vollibrespot Daemon service started! May 12 08:58:03 volumiobad volumio[16161]: [Metrics] SpotifyConnect: 0s 367.72ms May 12 08:58:03 volumiobad volumio[16161]: info: SpopD Daemon Started May 12 08:58:03 volumiobad volumio[16161]: info: MPD Permissions set May 12 08:58:03 volumiobad volumio[16161]: info: VolumeController:: Volume=37 Mute =false May 12 08:58:03 volumiobad volumio[16161]: info: CoreStateMachine::pushState May 12 08:58:03 volumiobad volumio[16161]: info: CorePlayQueue::getTrack 0 May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::volumioPushState May 12 08:58:03 volumiobad volumio[16304]: 2024-05-12 08:58:03 [SPTF] 08:58:03.314 E [ap:3953] Connection error: 409 May 12 08:58:03 volumiobad volumio[16304]: 2024-05-12 08:58:03 [WARN] Login failed: User needs a premium account May 12 08:58:03 volumiobad volumio[16304]: 2024-05-12 08:58:03 [ERR ] Could not get the playlist container. May 12 08:58:03 volumiobad systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 12 08:58:03 volumiobad systemd[1]: spop.service: Failed with result 'signal'. May 12 08:58:03 volumiobad volumio[16161]: info: Volumio called home May 12 08:58:03 volumiobad volumio[16161]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CALLMETHOD: user_interface websocket broadcastMessage [object Object] May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 12 08:58:03 volumiobad volumio[16161]: info: Starting Shairport Sync May 12 08:58:03 volumiobad volumio[16161]: info: Starting Shairport Sync May 12 08:58:03 volumiobad sudo[16316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:03 volumiobad sudo[16316]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:03 volumiobad volumio[16161]: info: Starting Shairport Sync May 12 08:58:03 volumiobad sudo[16318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:03 volumiobad sudo[16318]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:03 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:03 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:03 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:03 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:03 volumiobad sudo[16322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:03 volumiobad sudo[16322]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:03 volumiobad sudo[16316]: pam_unix(sudo:session): session closed for user root May 12 08:58:03 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:03 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:03 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:03 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:03 volumiobad sudo[16322]: pam_unix(sudo:session): session closed for user root May 12 08:58:03 volumiobad sudo[16318]: pam_unix(sudo:session): session closed for user root May 12 08:58:03 volumiobad volumio[16161]: info: Shairport-Sync Started May 12 08:58:03 volumiobad volumio[16161]: Error adding Membership: Error: addMembership EINVAL May 12 08:58:03 volumiobad volumio[16161]: info: Asound.conf file unchanged, so no further update is needed May 12 08:58:03 volumiobad volumio[16161]: info: Output device has changed, restarting MPD May 12 08:58:03 volumiobad sudo[16330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:03 volumiobad volumio[16161]: info: Output device has changed, restarting Shairport Sync May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:03 volumiobad sudo[16330]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:03 volumiobad sudo[16330]: pam_unix(sudo:session): session closed for user root May 12 08:58:03 volumiobad sudo[16332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:03 volumiobad sudo[16332]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:03 volumiobad systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 12 08:58:03 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:58:03 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:58:03 volumiobad volumio[16161]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:03 volumiobad volumio[16161]: [SpotifyConnect] Creating VLS config file May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:58:03 volumiobad volumio[16161]: info: Shairport-Sync Started May 12 08:58:03 volumiobad volumio[16161]: info: Shairport-Sync Started May 12 08:58:03 volumiobad volumio[16161]: info: MPD Permissions set May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::volumioGetState May 12 08:58:03 volumiobad volumio[16161]: info: CorePlayQueue::getTrack 0 May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:03 volumiobad volumio[16161]: info: Starting Shairport Sync May 12 08:58:04 volumiobad sudo[16346]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:04 volumiobad sudo[16346]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:04 volumiobad volumio[16161]: [SpotifyConnect] Restarting Vollibrespot Daemon May 12 08:58:04 volumiobad sudo[16351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service May 12 08:58:04 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:04 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:04 volumiobad sudo[16351]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:04 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:04 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:04 volumiobad sudo[16346]: pam_unix(sudo:session): session closed for user root May 12 08:58:04 volumiobad sudo[16355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 12 08:58:04 volumiobad sudo[16355]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:04 volumiobad volumio[16161]: info: Shairport-Sync Started May 12 08:58:04 volumiobad systemd[1]: Started Spotify Daemon. May 12 08:58:04 volumiobad systemd[1]: Stopping Volspotconnect2 Daemon... May 12 08:58:04 volumiobad systemd[1]: volspotconnect2.service: Main process exited, code=killed, status=15/TERM May 12 08:58:04 volumiobad systemd[1]: volspotconnect2.service: Succeeded. May 12 08:58:04 volumiobad volumio[16360]: 2024-05-12 08:58:04 [SPTF] 08:58:04.206 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 12 08:58:04 volumiobad systemd[1]: Stopped Volspotconnect2 Daemon. May 12 08:58:04 volumiobad volumio[16360]: 2024-05-12 08:58:04 [SPTF] 08:58:04.214 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 12 08:58:04 volumiobad systemd[1]: Started Volspotconnect2 Daemon. May 12 08:58:04 volumiobad sudo[16351]: pam_unix(sudo:session): session closed for user root May 12 08:58:04 volumiobad volumio[16161]: [SpotifyConnect] Vollibrespot Daemon service restarted! May 12 08:58:04 volumiobad volumio[16363]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 May 12 08:58:04 volumiobad volumio[16363]: Reading Config from "volspotify.toml" May 12 08:58:04 volumiobad volumio[16363]: [Vollibrespot] : Using Alsa backend with device: volumio May 12 08:58:04 volumiobad volumio[16360]: 2024-05-12 08:58:04 [SPTF] 08:58:04.243 I [ap:1226] Connected to AP: 34.158.0.131:4070 May 12 08:58:04 volumiobad sudo[16355]: pam_unix(sudo:session): session closed for user root May 12 08:58:04 volumiobad volumio[16161]: info: SpopD Daemon Started May 12 08:58:04 volumiobad volumio[16363]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } May 12 08:58:04 volumiobad volumio[16360]: 2024-05-12 08:58:04 [SPTF] 08:58:04.428 E [ap:3953] Connection error: 409 May 12 08:58:04 volumiobad volumio[16360]: 2024-05-12 08:58:04 [WARN] Login failed: User needs a premium account May 12 08:58:04 volumiobad volumio[16360]: 2024-05-12 08:58:04 [ERR ] Could not get the playlist container. May 12 08:58:04 volumiobad systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 12 08:58:04 volumiobad systemd[1]: spop.service: Failed with result 'signal'. May 12 08:58:04 volumiobad volumio[16161]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 08:58:04 volumiobad volumio[16161]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 12 08:58:04 volumiobad volumio[16161]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 08:58:04 volumiobad volumio[16161]: info: camilladsp spawned new process with pid undefined, instance 1, run: true May 12 08:58:04 volumiobad volumio[16161]: info: camilladsp service started and running in background, instance 1 May 12 08:58:04 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:04 volumiobad volumio[16161]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 08:58:04 volumiobad volumio[16161]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 12 08:58:04 volumiobad volumio[16161]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 08:58:05 volumiobad volumio[16161]: info: FusionDsp loaded May 12 08:58:05 volumiobad volumio[16161]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 08:58:05 volumiobad sudo[16375]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 12 08:58:05 volumiobad sudo[16375]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:05 volumiobad volumio[16161]: info: FusionDsp - Reporting Fusion DSP Enabled May 12 08:58:05 volumiobad volumio[16161]: info: FusionDsp - ---- installed callbackRead May 12 08:58:05 volumiobad sudo[16375]: pam_unix(sudo:session): session closed for user root May 12 08:58:05 volumiobad volumio[16161]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:58:05 volumiobad volumio[16161]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 12 08:58:05 volumiobad volumio[16161]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 12 08:58:05 volumiobad volumio[16161]: at onErrorNT (internal/child_process.js:465:16) May 12 08:58:05 volumiobad volumio[16161]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 12 08:58:05 volumiobad volumio[16161]: errno: -2, May 12 08:58:05 volumiobad volumio[16161]: code: 'ENOENT', May 12 08:58:05 volumiobad volumio[16161]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 12 08:58:05 volumiobad volumio[16161]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 12 08:58:05 volumiobad volumio[16161]: spawnargs: [ May 12 08:58:05 volumiobad volumio[16161]: '-p', May 12 08:58:05 volumiobad volumio[16161]: 9876, May 12 08:58:05 volumiobad volumio[16161]: '-o', May 12 08:58:05 volumiobad volumio[16161]: '/tmp/camilladsp.log', May 12 08:58:05 volumiobad volumio[16161]: '-l', May 12 08:58:05 volumiobad volumio[16161]: 'warn', May 12 08:58:05 volumiobad volumio[16161]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 12 08:58:05 volumiobad volumio[16161]: ] May 12 08:58:05 volumiobad volumio[16161]: } May 12 08:58:05 volumiobad volumio[16161]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:58:05 volumiobad mpd[16357]: May 12 08:58 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 08:58:05 volumiobad mpd[16357]: May 12 08:58 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 12 08:58:05 volumiobad mpd[16357]: May 12 08:58 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 12 08:58:05 volumiobad systemd[1]: Started Music Player Daemon. May 12 08:58:05 volumiobad sudo[16332]: pam_unix(sudo:session): session closed for user root May 12 08:58:05 volumiobad sudo[16284]: pam_unix(sudo:session): session closed for user root May 12 08:58:05 volumiobad sudo[16388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-12 08:57 May 12 08:58:05 volumiobad sudo[16388]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:05 volumiobad sudo[16388]: pam_unix(sudo:session): session closed for user root May 12 08:58:05 volumiobad systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 12 08:58:05 volumiobad systemd[1]: volumio.service: Failed with result 'exit-code'. May 12 08:58:05 volumiobad systemd[1]: Started dynamicswap service. May 12 08:58:06 volumiobad systemd[1]: dynamicswap.service: Succeeded. May 12 08:58:06 volumiobad systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 12 08:58:06 volumiobad systemd[1]: volumio.service: Scheduled restart job, restart counter is at 46. May 12 08:58:06 volumiobad systemd[1]: Started dynamicswap service. May 12 08:58:06 volumiobad systemd[1]: Stopped Volumio Backend Module. May 12 08:58:06 volumiobad systemd[1]: Started Volumio Backend Module. May 12 08:58:06 volumiobad systemd[1]: dynamicswap.service: Succeeded. May 12 08:58:08 volumiobad volumio[16399]: info: ------------------------------------------- May 12 08:58:08 volumiobad volumio[16399]: info: ----- Volumio3 ---- May 12 08:58:08 volumiobad volumio[16399]: info: ------------------------------------------- May 12 08:58:08 volumiobad volumio[16399]: info: ----- System startup ---- May 12 08:58:08 volumiobad volumio[16399]: info: ------------------------------------------- May 12 08:58:08 volumiobad volumio[16399]: info: MYVOLUMIO Environment detected May 12 08:58:08 volumiobad volumio[16399]: info: Plugin folders cleanup May 12 08:58:08 volumiobad volumio[16399]: info: Scanning into folder /volumio/app/plugins/ May 12 08:58:08 volumiobad volumio[16399]: info: Scanning category audio_interface May 12 08:58:08 volumiobad volumio[16399]: info: Scanning category miscellanea May 12 08:58:08 volumiobad volumio[16399]: info: Scanning category music_service May 12 08:58:08 volumiobad volumio[16399]: info: Scanning category plugins.json May 12 08:58:08 volumiobad volumio[16399]: info: Scanning category system_controller May 12 08:58:08 volumiobad volumio[16399]: info: Scanning category user_interface May 12 08:58:08 volumiobad volumio[16399]: info: Scanning into folder /data/plugins/ May 12 08:58:08 volumiobad volumio[16399]: info: Scanning category audio_interface May 12 08:58:08 volumiobad volumio[16399]: info: Scanning category music_service May 12 08:58:08 volumiobad volumio[16399]: info: Plugin folders cleanup completed May 12 08:58:08 volumiobad volumio[16399]: info: ------------------------------------------- May 12 08:58:08 volumiobad volumio[16399]: info: ----- Core plugins startup ---- May 12 08:58:08 volumiobad volumio[16399]: info: ------------------------------------------- May 12 08:58:08 volumiobad volumio[16399]: info: Loading plugins from folder /volumio/app/plugins/ May 12 08:58:08 volumiobad volumio[16399]: info: Adding plugin upnp to MyMusic Plugins May 12 08:58:08 volumiobad volumio[16399]: info: Adding plugin airplay_emulation to MyMusic Plugins May 12 08:58:08 volumiobad volumio[16399]: info: Adding plugin upnp_browser to MyMusic Plugins May 12 08:58:08 volumiobad volumio[16399]: info: Loading plugins from folder /data/plugins/ May 12 08:58:08 volumiobad volumio[16399]: info: Loading plugin "system"... May 12 08:58:08 volumiobad volumio[16399]: info: Loading plugin "appearance"... May 12 08:58:10 volumiobad volumio[16399]: info: Loading plugin "network"... May 12 08:58:10 volumiobad volumio[16399]: info: Refreshing Cached IP Addresses May 12 08:58:10 volumiobad sudo[16423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 08:58:10 volumiobad sudo[16423]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:10 volumiobad volumio[16399]: info: Loading plugin "services"... May 12 08:58:10 volumiobad volumio[16399]: info: Loading plugin "alsa_controller"... May 12 08:58:10 volumiobad sudo[16423]: pam_unix(sudo:session): session closed for user root May 12 08:58:10 volumiobad sudo[16425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 08:58:10 volumiobad sudo[16425]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:10 volumiobad sudo[16425]: pam_unix(sudo:session): session closed for user root May 12 08:58:10 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 08:58:10 volumiobad volumio[16399]: info: Loading plugin "wizard"... May 12 08:58:10 volumiobad volumio[16399]: info: Loading plugin "volumio_command_line_client"... May 12 08:58:10 volumiobad volumio[16399]: info: Loading plugin "upnp"... May 12 08:58:10 volumiobad volumio[16399]: info: [1715504290199] Starting Upmpd Daemon May 12 08:58:10 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:10 volumiobad volumio[16399]: info: Loading plugin "my_music"... May 12 08:58:10 volumiobad volumio[16399]: info: Loading plugin "mpd"... May 12 08:58:10 volumiobad volumio[16399]: info: Creating MPD Configuration file May 12 08:58:10 volumiobad volumio[16399]: info: Loading plugin "upnp_browser"... May 12 08:58:10 volumiobad sudo[16433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:10 volumiobad sudo[16433]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:10 volumiobad sudo[16433]: pam_unix(sudo:session): session closed for user root May 12 08:58:10 volumiobad sudo[16435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:10 volumiobad sudo[16435]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:10 volumiobad systemd[1]: Stopping Music Player Daemon... May 12 08:58:10 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:58:10 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:58:10 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:58:10 volumiobad volumio[16399]: info: Loading plugin "networkfs"... May 12 08:58:11 volumiobad volumio[16399]: info: Starting Udev Watcher for removable devices May 12 08:58:11 volumiobad volumio[16399]: info: Ignoring mount for partition: boot May 12 08:58:11 volumiobad volumio[16399]: info: Ignoring mount for partition: volumio May 12 08:58:11 volumiobad volumio[16399]: info: Ignoring mount for partition: volumio_data May 12 08:58:11 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:11 volumiobad volumio[16399]: info: Loading plugin "alarm-clock"... May 12 08:58:11 volumiobad volumio[16399]: info: Loading plugin "airplay_emulation"... May 12 08:58:11 volumiobad volumio[16399]: info: Starting Shairport Sync May 12 08:58:11 volumiobad volumio[16399]: info: Loading plugin "last_100"... May 12 08:58:11 volumiobad volumio[16399]: info: Loading plugin "webradio"... May 12 08:58:11 volumiobad volumio[16399]: info: Loading plugin "i2s_dacs"... May 12 08:58:11 volumiobad volumio[16399]: info: Loading plugin "volumiodiscovery"... May 12 08:58:11 volumiobad volumio[16399]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 08:58:11 volumiobad node[16399]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 08:58:11 volumiobad volumio[16399]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:11 volumiobad node[16399]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:11 volumiobad volumio[16399]: *** WARNING *** For more information see May 12 08:58:11 volumiobad node[16399]: *** WARNING *** For more information see May 12 08:58:11 volumiobad volumio[16399]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 08:58:11 volumiobad node[16399]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 08:58:11 volumiobad volumio[16399]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:11 volumiobad node[16399]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:11 volumiobad volumio[16399]: *** WARNING *** For more information see May 12 08:58:11 volumiobad node[16399]: *** WARNING *** For more information see May 12 08:58:11 volumiobad volumio[16399]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 08:58:11 volumiobad volumio[16399]: info: Discovery: Started advertising with name: Volumiobad May 12 08:58:11 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:11 volumiobad volumio[16399]: info: Loading plugin "outputs"... May 12 08:58:11 volumiobad volumio[16399]: info: Loading plugin "albumart"... May 12 08:58:12 volumiobad volumio[16399]: info: Plugin example_plugin is not enabled May 12 08:58:12 volumiobad volumio[16399]: info: Loading plugin "inputs"... May 12 08:58:12 volumiobad volumio[16399]: info: Loading plugin "updater_comm"... May 12 08:58:12 volumiobad mpd[16440]: May 12 08:58 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 08:58:12 volumiobad mpd[16440]: May 12 08:58 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 12 08:58:12 volumiobad mpd[16440]: May 12 08:58 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 12 08:58:12 volumiobad systemd[1]: Started Music Player Daemon. May 12 08:58:12 volumiobad sudo[16435]: pam_unix(sudo:session): session closed for user root May 12 08:58:12 volumiobad volumio[16399]: info: Plugin mpdemulation is not enabled May 12 08:58:12 volumiobad volumio[16399]: info: Loading plugin "rest_api"... May 12 08:58:12 volumiobad volumio[16399]: info: Loading plugin "websocket"... May 12 08:58:12 volumiobad volumio[16399]: info: Loading plugin "fusiondsp"... May 12 08:58:12 volumiobad volumio[16399]: Forking 3 albumart workers May 12 08:58:12 volumiobad volumio[16399]: info: Applying required configuration parameters for plugin fusiondsp May 12 08:58:12 volumiobad volumio[16399]: info: Loading plugin "spop"... May 12 08:58:13 volumiobad volumio[16399]: Starting albumart workers May 12 08:58:13 volumiobad volumio[16399]: Starting albumart workers May 12 08:58:13 volumiobad volumio[16399]: Starting albumart workers May 12 08:58:13 volumiobad volumio[16399]: info: Loading plugin "volspotconnect2"... May 12 08:58:14 volumiobad volumio[16399]: info: Loading i18n strings for locale de May 12 08:58:14 volumiobad volumio[16399]: Updating browse sources language May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::initPlayerControls May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: Express server listening on port 3000 May 12 08:58:14 volumiobad volumio[16399]: [Metrics] WebUI: 6s 718.14ms May 12 08:58:14 volumiobad volumio[16399]: info: CoreStateMachine::resetVolumioState May 12 08:58:14 volumiobad volumio[16399]: info: CoreStateMachine::getcurrentVolume May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::volumioRetrievevolume May 12 08:58:14 volumiobad volumio[16399]: info: MPD Permissions set May 12 08:58:14 volumiobad volumio[16399]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 May 12 08:58:14 volumiobad volumio[16399]: info: MPD running with PID16440 May 12 08:58:14 volumiobad volumio[16399]: ,establishing connection May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: Executing start script for DAC IQaudIO Pi-DigiAMP+ May 12 08:58:14 volumiobad volumio[16399]: info: Reloading queue from file May 12 08:58:14 volumiobad volumio[16399]: error: updateQueue error: null May 12 08:58:14 volumiobad volumio[16399]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 12 08:58:14 volumiobad volumio[16399]: info: CoreStateMachine::setRepeat null single undefined May 12 08:58:14 volumiobad volumio[16399]: info: CoreStateMachine::pushState May 12 08:58:14 volumiobad volumio[16399]: info: CorePlayQueue::getTrack 0 May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::volumioPushState May 12 08:58:14 volumiobad volumio[16399]: info: CoreStateMachine::setRandom false May 12 08:58:14 volumiobad volumio[16399]: info: CoreStateMachine::pushState May 12 08:58:14 volumiobad volumio[16399]: info: CorePlayQueue::getTrack 0 May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::volumioPushState May 12 08:58:14 volumiobad volumio[16399]: info: Setting Device type: Raspberry PI May 12 08:58:14 volumiobad volumio[16399]: info: DAC script executed May 12 08:58:14 volumiobad volumio[16399]: info: Completed loading Core Plugins May 12 08:58:14 volumiobad volumio[16399]: info: Preparing to generate the ALSA configuration file May 12 08:58:14 volumiobad volumio[16399]: info: VolumeController:: Volume=37 Mute =false May 12 08:58:14 volumiobad volumio[16399]: info: CoreStateMachine::pushState May 12 08:58:14 volumiobad volumio[16399]: info: CorePlayQueue::getTrack 0 May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::volumioPushState May 12 08:58:14 volumiobad volumio[16399]: info: CoreStateMachine::updateTrackBlock May 12 08:58:14 volumiobad volumio[16399]: info: CorePlayQueue::getTrackBlock May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::volumioRetrievevolume May 12 08:58:14 volumiobad volumio[16399]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 08:58:14 volumiobad volumio[16399]: info: Reading ALSA contributions from plugins. May 12 08:58:14 volumiobad volumio[16399]: error: updateQueue error: null May 12 08:58:14 volumiobad volumio[16399]: info: Discovery: adding 3986bfa1-ca3f-4ec8-a6f7-9ee4571b4957 May 12 08:58:14 volumiobad volumio[16399]: info: Discovery: Found device Volumiobad May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::volumioGetState May 12 08:58:14 volumiobad volumio[16399]: info: CorePlayQueue::getTrack 0 May 12 08:58:14 volumiobad volumio[16399]: info: Asound.conf file unchanged, so no further update is needed May 12 08:58:14 volumiobad volumio[16399]: info: Output device has changed, restarting MPD May 12 08:58:14 volumiobad sudo[16521]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:14 volumiobad volumio[16399]: info: Output device has changed, restarting Shairport Sync May 12 08:58:14 volumiobad sudo[16521]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:14 volumiobad sudo[16521]: pam_unix(sudo:session): session closed for user root May 12 08:58:14 volumiobad sudo[16523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:14 volumiobad sudo[16523]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:14 volumiobad systemd[1]: Stopping Music Player Daemon... May 12 08:58:14 volumiobad volumio[16399]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:14 volumiobad volumio[16399]: info: ___________ START PLUGINS ___________ May 12 08:58:14 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:58:14 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:14 volumiobad volumio[16399]: info: [1715504294991] CoreMusicLibrary::Adding element Media Servers May 12 08:58:14 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:14 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:58:14 volumiobad volumio[16399]: Cannot find translation for sourceMedia Servers May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:15 volumiobad volumio[16399]: info: [1715504295151] CoreMusicLibrary::Adding element Last_100 May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:15 volumiobad volumio[16399]: Cannot find translation for sourceMedia Servers May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:15 volumiobad volumio[16399]: info: [1715504295155] CoreMusicLibrary::Adding element Webradio May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:15 volumiobad volumio[16399]: Cannot find translation for sourceMedia Servers May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 08:58:15 volumiobad volumio[16399]: info: Loading i18n strings for locale de May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: FusionDsp - mixtype--------------------- Hardware May 12 08:58:15 volumiobad volumio[16399]: [SpotifyConnect] Creating VLS config file May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: [SpotifyConnect] Starting metadata listener May 12 08:58:15 volumiobad sudo[16538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 12 08:58:15 volumiobad sudo[16538]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:15 volumiobad volumio[16399]: info: Volumio Calling Home May 12 08:58:15 volumiobad systemd[1]: Started Spotify Daemon. May 12 08:58:15 volumiobad sudo[16538]: pam_unix(sudo:session): session closed for user root May 12 08:58:15 volumiobad sudo[16542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 12 08:58:15 volumiobad sudo[16542]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:15 volumiobad volumio[16544]: 2024-05-12 08:58:15 [SPTF] 08:58:15.331 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 12 08:58:15 volumiobad volumio[16544]: 2024-05-12 08:58:15 [SPTF] 08:58:15.344 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 12 08:58:15 volumiobad sudo[16542]: pam_unix(sudo:session): session closed for user root May 12 08:58:15 volumiobad volumio[16544]: 2024-05-12 08:58:15 [SPTF] 08:58:15.378 I [ap:1226] Connected to AP: 34.158.0.131:4070 May 12 08:58:15 volumiobad volumio[16399]: info: Preparing to generate the ALSA configuration file May 12 08:58:15 volumiobad volumio[16544]: 2024-05-12 08:58:15 [SPTF] 08:58:15.542 E [ap:3953] Connection error: 409 May 12 08:58:15 volumiobad volumio[16544]: 2024-05-12 08:58:15 [WARN] Login failed: User needs a premium account May 12 08:58:15 volumiobad volumio[16544]: 2024-05-12 08:58:15 [ERR ] Could not get the playlist container. May 12 08:58:15 volumiobad volumio[16399]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 08:58:15 volumiobad volumio[16399]: info: Reading ALSA contributions from plugins. May 12 08:58:15 volumiobad systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 12 08:58:15 volumiobad systemd[1]: spop.service: Failed with result 'signal'. May 12 08:58:15 volumiobad volumio[16399]: [SpotifyConnect] Vollibrespot Daemon service started! May 12 08:58:15 volumiobad volumio[16399]: [Metrics] SpotifyConnect: 0s 349.57ms May 12 08:58:15 volumiobad volumio[16399]: info: SpopD Daemon Started May 12 08:58:15 volumiobad volumio[16399]: info: MPD Permissions set May 12 08:58:15 volumiobad volumio[16399]: info: VolumeController:: Volume=37 Mute =false May 12 08:58:15 volumiobad volumio[16399]: info: CoreStateMachine::pushState May 12 08:58:15 volumiobad volumio[16399]: info: CorePlayQueue::getTrack 0 May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::volumioPushState May 12 08:58:15 volumiobad volumio[16399]: info: Volumio called home May 12 08:58:15 volumiobad volumio[16399]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:15 volumiobad volumio[16399]: info: CALLMETHOD: user_interface websocket broadcastMessage [object Object] May 12 08:58:15 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 12 08:58:15 volumiobad volumio[16399]: info: Starting Shairport Sync May 12 08:58:15 volumiobad volumio[16399]: info: Starting Shairport Sync May 12 08:58:15 volumiobad sudo[16555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:15 volumiobad volumio[16399]: info: Starting Shairport Sync May 12 08:58:15 volumiobad sudo[16555]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:15 volumiobad sudo[16557]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:15 volumiobad sudo[16557]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:15 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:15 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:15 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:15 volumiobad sudo[16560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:15 volumiobad sudo[16560]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:15 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:15 volumiobad sudo[16555]: pam_unix(sudo:session): session closed for user root May 12 08:58:15 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:15 volumiobad volumio[16399]: info: Asound.conf file unchanged, so no further update is needed May 12 08:58:15 volumiobad volumio[16399]: info: Output device has changed, restarting MPD May 12 08:58:15 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:16 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:16 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:16 volumiobad sudo[16557]: pam_unix(sudo:session): session closed for user root May 12 08:58:16 volumiobad sudo[16560]: pam_unix(sudo:session): session closed for user root May 12 08:58:16 volumiobad sudo[16567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:16 volumiobad sudo[16567]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:16 volumiobad sudo[16567]: pam_unix(sudo:session): session closed for user root May 12 08:58:16 volumiobad volumio[16399]: info: Output device has changed, restarting Shairport Sync May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:16 volumiobad sudo[16570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:16 volumiobad sudo[16570]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:16 volumiobad systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 12 08:58:16 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:58:16 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:58:16 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:58:16 volumiobad volumio[16399]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:16 volumiobad volumio[16399]: [SpotifyConnect] Creating VLS config file May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: Shairport-Sync Started May 12 08:58:16 volumiobad volumio[16399]: Error adding Membership: Error: addMembership EINVAL May 12 08:58:16 volumiobad volumio[16399]: info: MPD Permissions set May 12 08:58:16 volumiobad volumio[16399]: info: Shairport-Sync Started May 12 08:58:16 volumiobad volumio[16399]: info: Shairport-Sync Started May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::volumioGetState May 12 08:58:16 volumiobad volumio[16399]: info: CorePlayQueue::getTrack 0 May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:16 volumiobad volumio[16399]: info: Starting Shairport Sync May 12 08:58:16 volumiobad sudo[16585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:16 volumiobad sudo[16585]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:16 volumiobad volumio[16399]: [SpotifyConnect] Restarting Vollibrespot Daemon May 12 08:58:16 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:16 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:16 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:16 volumiobad sudo[16590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service May 12 08:58:16 volumiobad sudo[16590]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:16 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:16 volumiobad sudo[16594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 12 08:58:16 volumiobad sudo[16594]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:16 volumiobad sudo[16585]: pam_unix(sudo:session): session closed for user root May 12 08:58:16 volumiobad volumio[16399]: info: Shairport-Sync Started May 12 08:58:16 volumiobad systemd[1]: Stopping Volspotconnect2 Daemon... May 12 08:58:16 volumiobad systemd[1]: Started Spotify Daemon. May 12 08:58:16 volumiobad systemd[1]: volspotconnect2.service: Main process exited, code=killed, status=15/TERM May 12 08:58:16 volumiobad systemd[1]: volspotconnect2.service: Succeeded. May 12 08:58:16 volumiobad systemd[1]: Stopped Volspotconnect2 Daemon. May 12 08:58:16 volumiobad sudo[16594]: pam_unix(sudo:session): session closed for user root May 12 08:58:16 volumiobad systemd[1]: Started Volspotconnect2 Daemon. May 12 08:58:16 volumiobad volumio[16599]: 2024-05-12 08:58:16 [SPTF] 08:58:16.531 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 12 08:58:16 volumiobad volumio[16399]: info: SpopD Daemon Started May 12 08:58:16 volumiobad volumio[16599]: 2024-05-12 08:58:16 [SPTF] 08:58:16.539 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 12 08:58:16 volumiobad sudo[16590]: pam_unix(sudo:session): session closed for user root May 12 08:58:16 volumiobad volumio[16600]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 May 12 08:58:16 volumiobad volumio[16600]: Reading Config from "volspotify.toml" May 12 08:58:16 volumiobad volumio[16600]: [Vollibrespot] : Using Alsa backend with device: volumio May 12 08:58:16 volumiobad volumio[16399]: [SpotifyConnect] Vollibrespot Daemon service restarted! May 12 08:58:16 volumiobad volumio[16599]: 2024-05-12 08:58:16 [SPTF] 08:58:16.569 I [ap:1226] Connected to AP: 34.158.0.131:4070 May 12 08:58:16 volumiobad volumio[16600]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } May 12 08:58:16 volumiobad volumio[16599]: 2024-05-12 08:58:16 [SPTF] 08:58:16.759 E [ap:3953] Connection error: 409 May 12 08:58:16 volumiobad volumio[16599]: 2024-05-12 08:58:16 [WARN] Login failed: User needs a premium account May 12 08:58:16 volumiobad volumio[16599]: 2024-05-12 08:58:16 [ERR ] Could not get the playlist container. May 12 08:58:16 volumiobad systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 12 08:58:16 volumiobad systemd[1]: spop.service: Failed with result 'signal'. May 12 08:58:17 volumiobad volumio[16399]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 08:58:17 volumiobad volumio[16399]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 12 08:58:17 volumiobad volumio[16399]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 08:58:17 volumiobad volumio[16399]: info: camilladsp spawned new process with pid undefined, instance 1, run: true May 12 08:58:17 volumiobad volumio[16399]: info: camilladsp service started and running in background, instance 1 May 12 08:58:17 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:17 volumiobad volumio[16399]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 08:58:17 volumiobad volumio[16399]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 12 08:58:17 volumiobad volumio[16399]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 08:58:17 volumiobad volumio[16399]: info: FusionDsp loaded May 12 08:58:17 volumiobad volumio[16399]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 08:58:17 volumiobad sudo[16614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 12 08:58:17 volumiobad sudo[16614]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:17 volumiobad volumio[16399]: info: FusionDsp - Reporting Fusion DSP Enabled May 12 08:58:17 volumiobad sudo[16614]: pam_unix(sudo:session): session closed for user root May 12 08:58:17 volumiobad volumio[16399]: info: FusionDsp - ---- installed callbackRead May 12 08:58:17 volumiobad volumio[16399]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:58:17 volumiobad volumio[16399]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 12 08:58:17 volumiobad volumio[16399]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 12 08:58:17 volumiobad volumio[16399]: at onErrorNT (internal/child_process.js:465:16) May 12 08:58:17 volumiobad volumio[16399]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 12 08:58:17 volumiobad volumio[16399]: errno: -2, May 12 08:58:17 volumiobad volumio[16399]: code: 'ENOENT', May 12 08:58:17 volumiobad volumio[16399]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 12 08:58:17 volumiobad volumio[16399]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 12 08:58:17 volumiobad volumio[16399]: spawnargs: [ May 12 08:58:17 volumiobad volumio[16399]: '-p', May 12 08:58:17 volumiobad volumio[16399]: 9876, May 12 08:58:17 volumiobad volumio[16399]: '-o', May 12 08:58:17 volumiobad volumio[16399]: '/tmp/camilladsp.log', May 12 08:58:17 volumiobad volumio[16399]: '-l', May 12 08:58:17 volumiobad volumio[16399]: 'warn', May 12 08:58:17 volumiobad volumio[16399]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 12 08:58:17 volumiobad volumio[16399]: ] May 12 08:58:17 volumiobad volumio[16399]: } May 12 08:58:17 volumiobad volumio[16399]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:58:17 volumiobad mpd[16595]: May 12 08:58 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 08:58:17 volumiobad mpd[16595]: May 12 08:58 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 12 08:58:17 volumiobad mpd[16595]: May 12 08:58 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 12 08:58:17 volumiobad systemd[1]: Started Music Player Daemon. May 12 08:58:17 volumiobad sudo[16570]: pam_unix(sudo:session): session closed for user root May 12 08:58:17 volumiobad sudo[16523]: pam_unix(sudo:session): session closed for user root May 12 08:58:17 volumiobad sudo[16627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-12 08:57 May 12 08:58:17 volumiobad sudo[16627]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:18 volumiobad sudo[16627]: pam_unix(sudo:session): session closed for user root May 12 08:58:18 volumiobad systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 12 08:58:18 volumiobad systemd[1]: volumio.service: Failed with result 'exit-code'. May 12 08:58:18 volumiobad systemd[1]: Started dynamicswap service. May 12 08:58:18 volumiobad systemd[1]: dynamicswap.service: Succeeded. May 12 08:58:18 volumiobad systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 12 08:58:18 volumiobad systemd[1]: volumio.service: Scheduled restart job, restart counter is at 47. May 12 08:58:18 volumiobad systemd[1]: Started dynamicswap service. May 12 08:58:18 volumiobad systemd[1]: Stopped Volumio Backend Module. May 12 08:58:18 volumiobad systemd[1]: Started Volumio Backend Module. May 12 08:58:18 volumiobad systemd[1]: dynamicswap.service: Succeeded. May 12 08:58:20 volumiobad volumio[16638]: info: ------------------------------------------- May 12 08:58:20 volumiobad volumio[16638]: info: ----- Volumio3 ---- May 12 08:58:20 volumiobad volumio[16638]: info: ------------------------------------------- May 12 08:58:20 volumiobad volumio[16638]: info: ----- System startup ---- May 12 08:58:20 volumiobad volumio[16638]: info: ------------------------------------------- May 12 08:58:21 volumiobad volumio[16638]: info: MYVOLUMIO Environment detected May 12 08:58:21 volumiobad volumio[16638]: info: Plugin folders cleanup May 12 08:58:21 volumiobad volumio[16638]: info: Scanning into folder /volumio/app/plugins/ May 12 08:58:21 volumiobad volumio[16638]: info: Scanning category audio_interface May 12 08:58:21 volumiobad volumio[16638]: info: Scanning category miscellanea May 12 08:58:21 volumiobad volumio[16638]: info: Scanning category music_service May 12 08:58:21 volumiobad volumio[16638]: info: Scanning category plugins.json May 12 08:58:21 volumiobad volumio[16638]: info: Scanning category system_controller May 12 08:58:21 volumiobad volumio[16638]: info: Scanning category user_interface May 12 08:58:21 volumiobad volumio[16638]: info: Scanning into folder /data/plugins/ May 12 08:58:21 volumiobad volumio[16638]: info: Scanning category audio_interface May 12 08:58:21 volumiobad volumio[16638]: info: Scanning category music_service May 12 08:58:21 volumiobad volumio[16638]: info: Plugin folders cleanup completed May 12 08:58:21 volumiobad volumio[16638]: info: ------------------------------------------- May 12 08:58:21 volumiobad volumio[16638]: info: ----- Core plugins startup ---- May 12 08:58:21 volumiobad volumio[16638]: info: ------------------------------------------- May 12 08:58:21 volumiobad volumio[16638]: info: Loading plugins from folder /volumio/app/plugins/ May 12 08:58:21 volumiobad volumio[16638]: info: Adding plugin upnp to MyMusic Plugins May 12 08:58:21 volumiobad volumio[16638]: info: Adding plugin airplay_emulation to MyMusic Plugins May 12 08:58:21 volumiobad volumio[16638]: info: Adding plugin upnp_browser to MyMusic Plugins May 12 08:58:21 volumiobad volumio[16638]: info: Loading plugins from folder /data/plugins/ May 12 08:58:21 volumiobad volumio[16638]: info: Loading plugin "system"... May 12 08:58:21 volumiobad volumio[16638]: info: Loading plugin "appearance"... May 12 08:58:22 volumiobad volumio[16638]: info: Loading plugin "network"... May 12 08:58:22 volumiobad volumio[16638]: info: Refreshing Cached IP Addresses May 12 08:58:22 volumiobad sudo[16661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 08:58:22 volumiobad sudo[16661]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:22 volumiobad volumio[16638]: info: Loading plugin "services"... May 12 08:58:22 volumiobad sudo[16661]: pam_unix(sudo:session): session closed for user root May 12 08:58:22 volumiobad volumio[16638]: info: Loading plugin "alsa_controller"... May 12 08:58:22 volumiobad sudo[16664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 08:58:22 volumiobad sudo[16664]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:22 volumiobad sudo[16664]: pam_unix(sudo:session): session closed for user root May 12 08:58:22 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 08:58:22 volumiobad volumio[16638]: info: Loading plugin "wizard"... May 12 08:58:22 volumiobad volumio[16638]: info: Loading plugin "volumio_command_line_client"... May 12 08:58:22 volumiobad volumio[16638]: info: Loading plugin "upnp"... May 12 08:58:22 volumiobad volumio[16638]: info: [1715504302465] Starting Upmpd Daemon May 12 08:58:22 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:22 volumiobad volumio[16638]: info: Loading plugin "my_music"... May 12 08:58:22 volumiobad volumio[16638]: info: Loading plugin "mpd"... May 12 08:58:22 volumiobad volumio[16638]: info: Creating MPD Configuration file May 12 08:58:22 volumiobad volumio[16638]: info: Loading plugin "upnp_browser"... May 12 08:58:22 volumiobad sudo[16671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:22 volumiobad sudo[16671]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:22 volumiobad sudo[16671]: pam_unix(sudo:session): session closed for user root May 12 08:58:22 volumiobad sudo[16673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:22 volumiobad sudo[16673]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:22 volumiobad systemd[1]: Stopping Music Player Daemon... May 12 08:58:22 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:58:22 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:58:22 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:58:23 volumiobad volumio[16638]: info: Loading plugin "networkfs"... May 12 08:58:23 volumiobad volumio[16638]: info: Starting Udev Watcher for removable devices May 12 08:58:23 volumiobad volumio[16638]: info: Ignoring mount for partition: boot May 12 08:58:23 volumiobad volumio[16638]: info: Ignoring mount for partition: volumio May 12 08:58:23 volumiobad volumio[16638]: info: Ignoring mount for partition: volumio_data May 12 08:58:23 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:23 volumiobad volumio[16638]: info: Loading plugin "alarm-clock"... May 12 08:58:23 volumiobad volumio[16600]: [Vollibrespot] : couldn't parse packet from 192.168.2.164:5353: type 47 is invalid May 12 08:58:24 volumiobad volumio[16638]: info: Loading plugin "airplay_emulation"... May 12 08:58:24 volumiobad volumio[16638]: info: Starting Shairport Sync May 12 08:58:24 volumiobad volumio[16638]: info: Loading plugin "last_100"... May 12 08:58:24 volumiobad volumio[16638]: info: Loading plugin "webradio"... May 12 08:58:24 volumiobad volumio[16638]: info: Loading plugin "i2s_dacs"... May 12 08:58:24 volumiobad volumio[16638]: info: Loading plugin "volumiodiscovery"... May 12 08:58:24 volumiobad volumio[16638]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 08:58:24 volumiobad volumio[16638]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:24 volumiobad volumio[16638]: *** WARNING *** For more information see May 12 08:58:24 volumiobad node[16638]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 08:58:24 volumiobad volumio[16638]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 08:58:24 volumiobad volumio[16638]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:24 volumiobad volumio[16638]: *** WARNING *** For more information see May 12 08:58:24 volumiobad node[16638]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:24 volumiobad node[16638]: *** WARNING *** For more information see May 12 08:58:24 volumiobad node[16638]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 08:58:24 volumiobad node[16638]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:24 volumiobad node[16638]: *** WARNING *** For more information see May 12 08:58:24 volumiobad volumio[16638]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 08:58:24 volumiobad volumio[16638]: info: Discovery: Started advertising with name: Volumiobad May 12 08:58:24 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:24 volumiobad volumio[16638]: info: Loading plugin "outputs"... May 12 08:58:24 volumiobad volumio[16638]: info: Loading plugin "albumart"... May 12 08:58:24 volumiobad volumio[16638]: info: Plugin example_plugin is not enabled May 12 08:58:24 volumiobad volumio[16638]: info: Loading plugin "inputs"... May 12 08:58:24 volumiobad volumio[16638]: info: Loading plugin "updater_comm"... May 12 08:58:24 volumiobad mpd[16679]: May 12 08:58 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 08:58:24 volumiobad mpd[16679]: May 12 08:58 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 12 08:58:24 volumiobad mpd[16679]: May 12 08:58 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 12 08:58:24 volumiobad systemd[1]: Started Music Player Daemon. May 12 08:58:24 volumiobad sudo[16673]: pam_unix(sudo:session): session closed for user root May 12 08:58:24 volumiobad volumio[16638]: info: Plugin mpdemulation is not enabled May 12 08:58:24 volumiobad volumio[16638]: info: Loading plugin "rest_api"... May 12 08:58:24 volumiobad volumio[16638]: info: Loading plugin "websocket"... May 12 08:58:24 volumiobad volumio[16638]: info: Loading plugin "fusiondsp"... May 12 08:58:24 volumiobad volumio[16638]: Forking 3 albumart workers May 12 08:58:24 volumiobad volumio[16638]: info: Applying required configuration parameters for plugin fusiondsp May 12 08:58:25 volumiobad volumio[16638]: info: Loading plugin "spop"... May 12 08:58:25 volumiobad volumio[16638]: Starting albumart workers May 12 08:58:25 volumiobad volumio[16638]: Starting albumart workers May 12 08:58:25 volumiobad volumio[16638]: Starting albumart workers May 12 08:58:25 volumiobad volumio[16638]: info: Loading plugin "volspotconnect2"... May 12 08:58:26 volumiobad volumio[16638]: info: Loading i18n strings for locale de May 12 08:58:26 volumiobad volumio[16638]: Updating browse sources language May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::initPlayerControls May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: Express server listening on port 3000 May 12 08:58:26 volumiobad volumio[16638]: [Metrics] WebUI: 6s 795.42ms May 12 08:58:26 volumiobad volumio[16638]: info: CoreStateMachine::resetVolumioState May 12 08:58:26 volumiobad volumio[16638]: info: CoreStateMachine::getcurrentVolume May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::volumioRetrievevolume May 12 08:58:26 volumiobad volumio[16638]: info: MPD Permissions set May 12 08:58:26 volumiobad volumio[16638]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 May 12 08:58:26 volumiobad volumio[16638]: info: MPD running with PID16679 May 12 08:58:26 volumiobad volumio[16638]: ,establishing connection May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:26 volumiobad volumio[16638]: info: Executing start script for DAC IQaudIO Pi-DigiAMP+ May 12 08:58:26 volumiobad volumio[16638]: info: Reloading queue from file May 12 08:58:26 volumiobad volumio[16638]: error: updateQueue error: null May 12 08:58:26 volumiobad volumio[16638]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 12 08:58:26 volumiobad volumio[16638]: info: CoreStateMachine::setRepeat null single undefined May 12 08:58:26 volumiobad volumio[16638]: info: CoreStateMachine::pushState May 12 08:58:26 volumiobad volumio[16638]: info: CorePlayQueue::getTrack 0 May 12 08:58:26 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::volumioPushState May 12 08:58:27 volumiobad volumio[16638]: info: CoreStateMachine::setRandom false May 12 08:58:27 volumiobad volumio[16638]: info: CoreStateMachine::pushState May 12 08:58:27 volumiobad volumio[16638]: info: CorePlayQueue::getTrack 0 May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::volumioPushState May 12 08:58:27 volumiobad volumio[16638]: info: Setting Device type: Raspberry PI May 12 08:58:27 volumiobad volumio[16638]: info: DAC script executed May 12 08:58:27 volumiobad volumio[16638]: info: Completed loading Core Plugins May 12 08:58:27 volumiobad volumio[16638]: info: Preparing to generate the ALSA configuration file May 12 08:58:27 volumiobad volumio[16638]: info: VolumeController:: Volume=37 Mute =false May 12 08:58:27 volumiobad volumio[16638]: info: CoreStateMachine::pushState May 12 08:58:27 volumiobad volumio[16638]: info: CorePlayQueue::getTrack 0 May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::volumioPushState May 12 08:58:27 volumiobad volumio[16638]: info: CoreStateMachine::updateTrackBlock May 12 08:58:27 volumiobad volumio[16638]: info: CorePlayQueue::getTrackBlock May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::volumioRetrievevolume May 12 08:58:27 volumiobad volumio[16638]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 08:58:27 volumiobad volumio[16638]: info: Reading ALSA contributions from plugins. May 12 08:58:27 volumiobad volumio[16638]: error: updateQueue error: null May 12 08:58:27 volumiobad volumio[16638]: info: Discovery: adding 3986bfa1-ca3f-4ec8-a6f7-9ee4571b4957 May 12 08:58:27 volumiobad volumio[16638]: info: Discovery: Found device Volumiobad May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::volumioGetState May 12 08:58:27 volumiobad volumio[16638]: info: CorePlayQueue::getTrack 0 May 12 08:58:27 volumiobad volumio[16638]: info: VolumeController:: Volume=37 Mute =false May 12 08:58:27 volumiobad volumio[16638]: info: CoreStateMachine::pushState May 12 08:58:27 volumiobad volumio[16638]: info: CorePlayQueue::getTrack 0 May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::volumioPushState May 12 08:58:27 volumiobad volumio[16638]: info: Asound.conf file unchanged, so no further update is needed May 12 08:58:27 volumiobad volumio[16638]: info: Output device has changed, restarting MPD May 12 08:58:27 volumiobad volumio[16638]: info: Output device has changed, restarting Shairport Sync May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:27 volumiobad sudo[16761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:27 volumiobad sudo[16761]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:27 volumiobad sudo[16762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:27 volumiobad sudo[16762]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:27 volumiobad sudo[16761]: pam_unix(sudo:session): session closed for user root May 12 08:58:27 volumiobad systemd[1]: Stopping Music Player Daemon... May 12 08:58:27 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:58:27 volumiobad volumio[16638]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:27 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:58:27 volumiobad volumio[16638]: info: ___________ START PLUGINS ___________ May 12 08:58:27 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:27 volumiobad volumio[16638]: info: [1715504307396] CoreMusicLibrary::Adding element Media Servers May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:27 volumiobad volumio[16638]: Cannot find translation for sourceMedia Servers May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:27 volumiobad volumio[16638]: info: [1715504307554] CoreMusicLibrary::Adding element Last_100 May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:27 volumiobad volumio[16638]: Cannot find translation for sourceMedia Servers May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:27 volumiobad volumio[16638]: info: [1715504307558] CoreMusicLibrary::Adding element Webradio May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:27 volumiobad volumio[16638]: Cannot find translation for sourceMedia Servers May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 08:58:27 volumiobad volumio[16638]: info: Loading i18n strings for locale de May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: FusionDsp - mixtype--------------------- Hardware May 12 08:58:27 volumiobad volumio[16638]: [SpotifyConnect] Creating VLS config file May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: [SpotifyConnect] Starting metadata listener May 12 08:58:27 volumiobad sudo[16777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 12 08:58:27 volumiobad sudo[16777]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:27 volumiobad volumio[16638]: info: Volumio Calling Home May 12 08:58:27 volumiobad systemd[1]: Started Spotify Daemon. May 12 08:58:27 volumiobad sudo[16777]: pam_unix(sudo:session): session closed for user root May 12 08:58:27 volumiobad sudo[16781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 12 08:58:27 volumiobad sudo[16781]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:27 volumiobad volumio[16783]: 2024-05-12 08:58:27 [SPTF] 08:58:27.716 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 12 08:58:27 volumiobad volumio[16783]: 2024-05-12 08:58:27 [SPTF] 08:58:27.733 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 12 08:58:27 volumiobad sudo[16781]: pam_unix(sudo:session): session closed for user root May 12 08:58:27 volumiobad volumio[16783]: 2024-05-12 08:58:27 [SPTF] 08:58:27.776 I [ap:1226] Connected to AP: 34.158.0.131:4070 May 12 08:58:27 volumiobad volumio[16638]: info: Preparing to generate the ALSA configuration file May 12 08:58:27 volumiobad volumio[16638]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 08:58:27 volumiobad volumio[16638]: info: Reading ALSA contributions from plugins. May 12 08:58:27 volumiobad volumio[16638]: [SpotifyConnect] Vollibrespot Daemon service started! May 12 08:58:27 volumiobad volumio[16638]: [Metrics] SpotifyConnect: 0s 332.44ms May 12 08:58:27 volumiobad volumio[16638]: info: SpopD Daemon Started May 12 08:58:27 volumiobad volumio[16638]: info: MPD Permissions set May 12 08:58:27 volumiobad volumio[16783]: 2024-05-12 08:58:27 [SPTF] 08:58:27.949 E [ap:3953] Connection error: 409 May 12 08:58:27 volumiobad volumio[16783]: 2024-05-12 08:58:27 [WARN] Login failed: User needs a premium account May 12 08:58:27 volumiobad volumio[16783]: 2024-05-12 08:58:27 [ERR ] Could not get the playlist container. May 12 08:58:27 volumiobad volumio[16638]: info: Volumio called home May 12 08:58:27 volumiobad systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 12 08:58:27 volumiobad systemd[1]: spop.service: Failed with result 'signal'. May 12 08:58:27 volumiobad volumio[16638]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:27 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CALLMETHOD: user_interface websocket broadcastMessage [object Object] May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 12 08:58:28 volumiobad volumio[16638]: info: Starting Shairport Sync May 12 08:58:28 volumiobad volumio[16638]: info: Starting Shairport Sync May 12 08:58:28 volumiobad sudo[16794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:28 volumiobad sudo[16794]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:28 volumiobad volumio[16638]: info: Starting Shairport Sync May 12 08:58:28 volumiobad sudo[16796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:28 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:28 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:28 volumiobad sudo[16796]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:28 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:28 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:28 volumiobad sudo[16794]: pam_unix(sudo:session): session closed for user root May 12 08:58:28 volumiobad sudo[16799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:28 volumiobad sudo[16799]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:28 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:28 volumiobad systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 12 08:58:28 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:28 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:28 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:28 volumiobad volumio[16638]: info: Asound.conf file unchanged, so no further update is needed May 12 08:58:28 volumiobad volumio[16638]: info: Output device has changed, restarting MPD May 12 08:58:28 volumiobad sudo[16796]: pam_unix(sudo:session): session closed for user root May 12 08:58:28 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:28 volumiobad systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 12 08:58:28 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:28 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:28 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:28 volumiobad sudo[16799]: pam_unix(sudo:session): session closed for user root May 12 08:58:28 volumiobad sudo[16805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:28 volumiobad sudo[16805]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:28 volumiobad sudo[16805]: pam_unix(sudo:session): session closed for user root May 12 08:58:28 volumiobad sudo[16809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:28 volumiobad sudo[16809]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:28 volumiobad volumio[16638]: info: Output device has changed, restarting Shairport Sync May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:28 volumiobad systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 12 08:58:28 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:58:28 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:58:28 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:58:28 volumiobad volumio[16638]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:28 volumiobad volumio[16638]: [SpotifyConnect] Creating VLS config file May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: Shairport-Sync Started May 12 08:58:28 volumiobad volumio[16638]: Error adding Membership: Error: addMembership EINVAL May 12 08:58:28 volumiobad volumio[16638]: info: MPD Permissions set May 12 08:58:28 volumiobad volumio[16638]: info: Shairport-Sync Started May 12 08:58:28 volumiobad volumio[16638]: info: Shairport-Sync Started May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::volumioGetState May 12 08:58:28 volumiobad volumio[16638]: info: CorePlayQueue::getTrack 0 May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:28 volumiobad volumio[16638]: info: Starting Shairport Sync May 12 08:58:28 volumiobad sudo[16823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:28 volumiobad sudo[16823]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:28 volumiobad volumio[16638]: [SpotifyConnect] Restarting Vollibrespot Daemon May 12 08:58:28 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:28 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:28 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:28 volumiobad sudo[16829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service May 12 08:58:28 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:28 volumiobad sudo[16829]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:28 volumiobad sudo[16823]: pam_unix(sudo:session): session closed for user root May 12 08:58:28 volumiobad volumio[16638]: info: Shairport-Sync Started May 12 08:58:28 volumiobad systemd[1]: Stopping Volspotconnect2 Daemon... May 12 08:58:28 volumiobad systemd[1]: volspotconnect2.service: Main process exited, code=killed, status=15/TERM May 12 08:58:28 volumiobad systemd[1]: volspotconnect2.service: Succeeded. May 12 08:58:28 volumiobad systemd[1]: Stopped Volspotconnect2 Daemon. May 12 08:58:28 volumiobad sudo[16833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 12 08:58:28 volumiobad systemd[1]: Started Volspotconnect2 Daemon. May 12 08:58:28 volumiobad sudo[16829]: pam_unix(sudo:session): session closed for user root May 12 08:58:28 volumiobad sudo[16833]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:28 volumiobad volumio[16638]: [SpotifyConnect] Vollibrespot Daemon service restarted! May 12 08:58:28 volumiobad volumio[16836]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 May 12 08:58:28 volumiobad volumio[16836]: Reading Config from "volspotify.toml" May 12 08:58:28 volumiobad volumio[16836]: [Vollibrespot] : Using Alsa backend with device: volumio May 12 08:58:28 volumiobad systemd[1]: Started Spotify Daemon. May 12 08:58:28 volumiobad volumio[16839]: 2024-05-12 08:58:28 [SPTF] 08:58:28.883 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 12 08:58:28 volumiobad volumio[16836]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } May 12 08:58:28 volumiobad sudo[16833]: pam_unix(sudo:session): session closed for user root May 12 08:58:28 volumiobad volumio[16839]: 2024-05-12 08:58:28 [SPTF] 08:58:28.896 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 12 08:58:28 volumiobad volumio[16638]: info: SpopD Daemon Started May 12 08:58:28 volumiobad volumio[16839]: 2024-05-12 08:58:28 [SPTF] 08:58:28.925 I [ap:1226] Connected to AP: 34.158.0.131:4070 May 12 08:58:29 volumiobad volumio[16839]: 2024-05-12 08:58:29 [SPTF] 08:58:29.187 E [ap:3953] Connection error: 409 May 12 08:58:29 volumiobad volumio[16839]: 2024-05-12 08:58:29 [WARN] Login failed: User needs a premium account May 12 08:58:29 volumiobad volumio[16839]: 2024-05-12 08:58:29 [ERR ] Could not get the playlist container. May 12 08:58:29 volumiobad systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 12 08:58:29 volumiobad systemd[1]: spop.service: Failed with result 'signal'. May 12 08:58:29 volumiobad volumio[16638]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 08:58:29 volumiobad volumio[16638]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 12 08:58:29 volumiobad volumio[16638]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 08:58:29 volumiobad volumio[16638]: info: camilladsp spawned new process with pid undefined, instance 1, run: true May 12 08:58:29 volumiobad volumio[16638]: info: camilladsp service started and running in background, instance 1 May 12 08:58:29 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:29 volumiobad volumio[16638]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 08:58:29 volumiobad volumio[16638]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 12 08:58:29 volumiobad volumio[16638]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 08:58:29 volumiobad volumio[16638]: info: FusionDsp loaded May 12 08:58:29 volumiobad volumio[16638]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 08:58:29 volumiobad sudo[16852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 12 08:58:29 volumiobad sudo[16852]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:29 volumiobad volumio[16638]: info: FusionDsp - Reporting Fusion DSP Enabled May 12 08:58:29 volumiobad volumio[16638]: info: FusionDsp - ---- installed callbackRead May 12 08:58:29 volumiobad volumio[16638]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:58:29 volumiobad sudo[16852]: pam_unix(sudo:session): session closed for user root May 12 08:58:29 volumiobad volumio[16638]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 12 08:58:29 volumiobad volumio[16638]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 12 08:58:29 volumiobad volumio[16638]: at onErrorNT (internal/child_process.js:465:16) May 12 08:58:29 volumiobad volumio[16638]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 12 08:58:29 volumiobad volumio[16638]: errno: -2, May 12 08:58:29 volumiobad volumio[16638]: code: 'ENOENT', May 12 08:58:29 volumiobad volumio[16638]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 12 08:58:29 volumiobad volumio[16638]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 12 08:58:29 volumiobad volumio[16638]: spawnargs: [ May 12 08:58:29 volumiobad volumio[16638]: '-p', May 12 08:58:29 volumiobad volumio[16638]: 9876, May 12 08:58:29 volumiobad volumio[16638]: '-o', May 12 08:58:29 volumiobad volumio[16638]: '/tmp/camilladsp.log', May 12 08:58:29 volumiobad volumio[16638]: '-l', May 12 08:58:29 volumiobad volumio[16638]: 'warn', May 12 08:58:29 volumiobad volumio[16638]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 12 08:58:29 volumiobad volumio[16638]: ] May 12 08:58:29 volumiobad volumio[16638]: } May 12 08:58:29 volumiobad volumio[16638]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:58:29 volumiobad mpd[16828]: May 12 08:58 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 08:58:29 volumiobad mpd[16828]: May 12 08:58 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 12 08:58:29 volumiobad mpd[16828]: May 12 08:58 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 12 08:58:29 volumiobad systemd[1]: Started Music Player Daemon. May 12 08:58:29 volumiobad sudo[16809]: pam_unix(sudo:session): session closed for user root May 12 08:58:29 volumiobad sudo[16762]: pam_unix(sudo:session): session closed for user root May 12 08:58:30 volumiobad sudo[16866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-12 08:57 May 12 08:58:30 volumiobad sudo[16866]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:30 volumiobad sudo[16866]: pam_unix(sudo:session): session closed for user root May 12 08:58:30 volumiobad systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 12 08:58:30 volumiobad systemd[1]: volumio.service: Failed with result 'exit-code'. May 12 08:58:30 volumiobad systemd[1]: Started dynamicswap service. May 12 08:58:30 volumiobad systemd[1]: dynamicswap.service: Succeeded. May 12 08:58:31 volumiobad systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 12 08:58:31 volumiobad systemd[1]: volumio.service: Scheduled restart job, restart counter is at 48. May 12 08:58:31 volumiobad systemd[1]: Started dynamicswap service. May 12 08:58:31 volumiobad systemd[1]: Stopped Volumio Backend Module. May 12 08:58:31 volumiobad systemd[1]: Started Volumio Backend Module. May 12 08:58:31 volumiobad systemd[1]: dynamicswap.service: Succeeded. May 12 08:58:32 volumiobad volumio[16877]: info: ------------------------------------------- May 12 08:58:32 volumiobad volumio[16877]: info: ----- Volumio3 ---- May 12 08:58:32 volumiobad volumio[16877]: info: ------------------------------------------- May 12 08:58:32 volumiobad volumio[16877]: info: ----- System startup ---- May 12 08:58:32 volumiobad volumio[16877]: info: ------------------------------------------- May 12 08:58:33 volumiobad volumio[16877]: info: MYVOLUMIO Environment detected May 12 08:58:33 volumiobad volumio[16877]: info: Plugin folders cleanup May 12 08:58:33 volumiobad volumio[16877]: info: Scanning into folder /volumio/app/plugins/ May 12 08:58:33 volumiobad volumio[16877]: info: Scanning category audio_interface May 12 08:58:33 volumiobad volumio[16877]: info: Scanning category miscellanea May 12 08:58:33 volumiobad volumio[16877]: info: Scanning category music_service May 12 08:58:33 volumiobad volumio[16877]: info: Scanning category plugins.json May 12 08:58:33 volumiobad volumio[16877]: info: Scanning category system_controller May 12 08:58:33 volumiobad volumio[16877]: info: Scanning category user_interface May 12 08:58:33 volumiobad volumio[16877]: info: Scanning into folder /data/plugins/ May 12 08:58:33 volumiobad volumio[16877]: info: Scanning category audio_interface May 12 08:58:33 volumiobad volumio[16877]: info: Scanning category music_service May 12 08:58:33 volumiobad volumio[16877]: info: Plugin folders cleanup completed May 12 08:58:33 volumiobad volumio[16877]: info: ------------------------------------------- May 12 08:58:33 volumiobad volumio[16877]: info: ----- Core plugins startup ---- May 12 08:58:33 volumiobad volumio[16877]: info: ------------------------------------------- May 12 08:58:33 volumiobad volumio[16877]: info: Loading plugins from folder /volumio/app/plugins/ May 12 08:58:33 volumiobad volumio[16877]: info: Adding plugin upnp to MyMusic Plugins May 12 08:58:33 volumiobad volumio[16877]: info: Adding plugin airplay_emulation to MyMusic Plugins May 12 08:58:33 volumiobad volumio[16877]: info: Adding plugin upnp_browser to MyMusic Plugins May 12 08:58:33 volumiobad volumio[16877]: info: Loading plugins from folder /data/plugins/ May 12 08:58:33 volumiobad volumio[16877]: info: Loading plugin "system"... May 12 08:58:33 volumiobad volumio[16877]: info: Loading plugin "appearance"... May 12 08:58:34 volumiobad volumio[16877]: info: Loading plugin "network"... May 12 08:58:34 volumiobad volumio[16877]: info: Refreshing Cached IP Addresses May 12 08:58:34 volumiobad sudo[16900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 08:58:34 volumiobad sudo[16900]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:34 volumiobad volumio[16877]: info: Loading plugin "services"... May 12 08:58:34 volumiobad sudo[16900]: pam_unix(sudo:session): session closed for user root May 12 08:58:34 volumiobad volumio[16877]: info: Loading plugin "alsa_controller"... May 12 08:58:34 volumiobad sudo[16903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 08:58:34 volumiobad sudo[16903]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:34 volumiobad sudo[16903]: pam_unix(sudo:session): session closed for user root May 12 08:58:34 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 08:58:34 volumiobad volumio[16877]: info: Loading plugin "wizard"... May 12 08:58:34 volumiobad volumio[16877]: info: Loading plugin "volumio_command_line_client"... May 12 08:58:34 volumiobad volumio[16877]: info: Loading plugin "upnp"... May 12 08:58:34 volumiobad volumio[16877]: info: [1715504314941] Starting Upmpd Daemon May 12 08:58:34 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:34 volumiobad volumio[16877]: info: Loading plugin "my_music"... May 12 08:58:34 volumiobad volumio[16877]: info: Loading plugin "mpd"... May 12 08:58:35 volumiobad volumio[16877]: info: Creating MPD Configuration file May 12 08:58:35 volumiobad volumio[16877]: info: Loading plugin "upnp_browser"... May 12 08:58:35 volumiobad sudo[16910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:35 volumiobad sudo[16910]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:35 volumiobad sudo[16910]: pam_unix(sudo:session): session closed for user root May 12 08:58:35 volumiobad sudo[16912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:35 volumiobad sudo[16912]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:35 volumiobad systemd[1]: Stopping Music Player Daemon... May 12 08:58:35 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:58:35 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:58:35 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:58:35 volumiobad volumio[16877]: info: Loading plugin "networkfs"... May 12 08:58:35 volumiobad volumio[16877]: info: Starting Udev Watcher for removable devices May 12 08:58:36 volumiobad volumio[16877]: info: Ignoring mount for partition: boot May 12 08:58:36 volumiobad volumio[16877]: info: Ignoring mount for partition: volumio May 12 08:58:36 volumiobad volumio[16877]: info: Ignoring mount for partition: volumio_data May 12 08:58:36 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:36 volumiobad volumio[16877]: info: Loading plugin "alarm-clock"... May 12 08:58:36 volumiobad volumio[16877]: info: Loading plugin "airplay_emulation"... May 12 08:58:36 volumiobad volumio[16877]: info: Starting Shairport Sync May 12 08:58:36 volumiobad volumio[16877]: info: Loading plugin "last_100"... May 12 08:58:36 volumiobad volumio[16877]: info: Loading plugin "webradio"... May 12 08:58:36 volumiobad volumio[16877]: info: Loading plugin "i2s_dacs"... May 12 08:58:36 volumiobad volumio[16877]: info: Loading plugin "volumiodiscovery"... May 12 08:58:36 volumiobad volumio[16877]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 08:58:36 volumiobad node[16877]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 08:58:36 volumiobad volumio[16877]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:36 volumiobad node[16877]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:36 volumiobad volumio[16877]: *** WARNING *** For more information see May 12 08:58:36 volumiobad node[16877]: *** WARNING *** For more information see May 12 08:58:36 volumiobad volumio[16877]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 08:58:36 volumiobad node[16877]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 08:58:36 volumiobad volumio[16877]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:36 volumiobad node[16877]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:36 volumiobad volumio[16877]: *** WARNING *** For more information see May 12 08:58:36 volumiobad node[16877]: *** WARNING *** For more information see May 12 08:58:36 volumiobad volumio[16877]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 08:58:36 volumiobad volumio[16877]: info: Discovery: Started advertising with name: Volumiobad May 12 08:58:36 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:36 volumiobad volumio[16877]: info: Loading plugin "outputs"... May 12 08:58:36 volumiobad volumio[16877]: info: Loading plugin "albumart"... May 12 08:58:36 volumiobad volumio[16877]: info: Plugin example_plugin is not enabled May 12 08:58:36 volumiobad volumio[16877]: info: Loading plugin "inputs"... May 12 08:58:36 volumiobad volumio[16877]: info: Loading plugin "updater_comm"... May 12 08:58:36 volumiobad mpd[16917]: May 12 08:58 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 08:58:36 volumiobad mpd[16917]: May 12 08:58 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 12 08:58:36 volumiobad mpd[16917]: May 12 08:58 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 12 08:58:36 volumiobad systemd[1]: Started Music Player Daemon. May 12 08:58:36 volumiobad sudo[16912]: pam_unix(sudo:session): session closed for user root May 12 08:58:37 volumiobad volumio[16877]: info: Plugin mpdemulation is not enabled May 12 08:58:37 volumiobad volumio[16877]: info: Loading plugin "rest_api"... May 12 08:58:37 volumiobad volumio[16877]: info: Loading plugin "websocket"... May 12 08:58:37 volumiobad volumio[16877]: info: Loading plugin "fusiondsp"... May 12 08:58:37 volumiobad volumio[16877]: Forking 3 albumart workers May 12 08:58:37 volumiobad volumio[16877]: info: Applying required configuration parameters for plugin fusiondsp May 12 08:58:37 volumiobad volumio[16877]: info: Loading plugin "spop"... May 12 08:58:38 volumiobad volumio[16877]: Starting albumart workers May 12 08:58:38 volumiobad volumio[16877]: Starting albumart workers May 12 08:58:38 volumiobad volumio[16877]: Starting albumart workers May 12 08:58:38 volumiobad volumio[16877]: info: Loading plugin "volspotconnect2"... May 12 08:58:38 volumiobad volumio[16877]: info: Loading i18n strings for locale de May 12 08:58:38 volumiobad volumio[16877]: Updating browse sources language May 12 08:58:38 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:38 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:38 volumiobad volumio[16877]: info: CoreCommandRouter::initPlayerControls May 12 08:58:38 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:38 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:38 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:38 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:38 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:38 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:38 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:38 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:38 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:38 volumiobad volumio[16877]: Express server listening on port 3000 May 12 08:58:38 volumiobad volumio[16877]: [Metrics] WebUI: 6s 672.02ms May 12 08:58:39 volumiobad volumio[16877]: info: CoreStateMachine::resetVolumioState May 12 08:58:39 volumiobad volumio[16877]: info: CoreStateMachine::getcurrentVolume May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::volumioRetrievevolume May 12 08:58:39 volumiobad volumio[16877]: info: MPD Permissions set May 12 08:58:39 volumiobad volumio[16877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 May 12 08:58:39 volumiobad volumio[16877]: info: MPD running with PID16917 May 12 08:58:39 volumiobad volumio[16877]: ,establishing connection May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: Executing start script for DAC IQaudIO Pi-DigiAMP+ May 12 08:58:39 volumiobad volumio[16877]: info: Reloading queue from file May 12 08:58:39 volumiobad volumio[16877]: error: updateQueue error: null May 12 08:58:39 volumiobad volumio[16877]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 12 08:58:39 volumiobad volumio[16877]: info: CoreStateMachine::setRepeat null single undefined May 12 08:58:39 volumiobad volumio[16877]: info: CoreStateMachine::pushState May 12 08:58:39 volumiobad volumio[16877]: info: CorePlayQueue::getTrack 0 May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::volumioPushState May 12 08:58:39 volumiobad volumio[16877]: info: CoreStateMachine::setRandom false May 12 08:58:39 volumiobad volumio[16877]: info: CoreStateMachine::pushState May 12 08:58:39 volumiobad volumio[16877]: info: CorePlayQueue::getTrack 0 May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::volumioPushState May 12 08:58:39 volumiobad volumio[16877]: info: Setting Device type: Raspberry PI May 12 08:58:39 volumiobad volumio[16877]: info: DAC script executed May 12 08:58:39 volumiobad volumio[16877]: info: Completed loading Core Plugins May 12 08:58:39 volumiobad volumio[16877]: info: Preparing to generate the ALSA configuration file May 12 08:58:39 volumiobad volumio[16877]: info: VolumeController:: Volume=37 Mute =false May 12 08:58:39 volumiobad volumio[16877]: info: CoreStateMachine::pushState May 12 08:58:39 volumiobad volumio[16877]: info: CorePlayQueue::getTrack 0 May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::volumioPushState May 12 08:58:39 volumiobad volumio[16877]: info: CoreStateMachine::updateTrackBlock May 12 08:58:39 volumiobad volumio[16877]: info: CorePlayQueue::getTrackBlock May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::volumioRetrievevolume May 12 08:58:39 volumiobad volumio[16877]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 08:58:39 volumiobad volumio[16877]: info: Reading ALSA contributions from plugins. May 12 08:58:39 volumiobad volumio[16877]: error: updateQueue error: null May 12 08:58:39 volumiobad volumio[16877]: info: Discovery: adding 3986bfa1-ca3f-4ec8-a6f7-9ee4571b4957 May 12 08:58:39 volumiobad volumio[16877]: info: Discovery: Found device Volumiobad May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::volumioGetState May 12 08:58:39 volumiobad volumio[16877]: info: CorePlayQueue::getTrack 0 May 12 08:58:39 volumiobad volumio[16877]: info: Asound.conf file unchanged, so no further update is needed May 12 08:58:39 volumiobad volumio[16877]: info: Output device has changed, restarting MPD May 12 08:58:39 volumiobad sudo[16998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:39 volumiobad volumio[16877]: info: Output device has changed, restarting Shairport Sync May 12 08:58:39 volumiobad sudo[16998]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:39 volumiobad sudo[16998]: pam_unix(sudo:session): session closed for user root May 12 08:58:39 volumiobad sudo[17000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:39 volumiobad sudo[17000]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:39 volumiobad systemd[1]: Stopping Music Player Daemon... May 12 08:58:39 volumiobad volumio[16877]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:39 volumiobad volumio[16877]: info: ___________ START PLUGINS ___________ May 12 08:58:39 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:58:39 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:39 volumiobad volumio[16877]: info: [1715504319728] CoreMusicLibrary::Adding element Media Servers May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:39 volumiobad volumio[16877]: Cannot find translation for sourceMedia Servers May 12 08:58:39 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:39 volumiobad volumio[16877]: info: [1715504319906] CoreMusicLibrary::Adding element Last_100 May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:39 volumiobad volumio[16877]: Cannot find translation for sourceMedia Servers May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:39 volumiobad volumio[16877]: info: [1715504319910] CoreMusicLibrary::Adding element Webradio May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:39 volumiobad volumio[16877]: Cannot find translation for sourceMedia Servers May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 08:58:39 volumiobad volumio[16877]: info: Loading i18n strings for locale de May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: FusionDsp - mixtype--------------------- Hardware May 12 08:58:39 volumiobad volumio[16877]: [SpotifyConnect] Creating VLS config file May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:39 volumiobad volumio[16877]: [SpotifyConnect] Starting metadata listener May 12 08:58:39 volumiobad sudo[17015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 12 08:58:39 volumiobad sudo[17015]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:40 volumiobad volumio[16877]: info: Volumio Calling Home May 12 08:58:40 volumiobad systemd[1]: Started Spotify Daemon. May 12 08:58:40 volumiobad sudo[17019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 12 08:58:40 volumiobad sudo[17019]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:40 volumiobad sudo[17015]: pam_unix(sudo:session): session closed for user root May 12 08:58:40 volumiobad volumio[17020]: 2024-05-12 08:58:40 [SPTF] 08:58:40.090 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 12 08:58:40 volumiobad volumio[17020]: 2024-05-12 08:58:40 [SPTF] 08:58:40.101 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 12 08:58:40 volumiobad sudo[17019]: pam_unix(sudo:session): session closed for user root May 12 08:58:40 volumiobad volumio[17020]: 2024-05-12 08:58:40 [SPTF] 08:58:40.133 I [ap:1226] Connected to AP: 34.158.0.131:4070 May 12 08:58:40 volumiobad volumio[16877]: info: Preparing to generate the ALSA configuration file May 12 08:58:40 volumiobad volumio[16877]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 08:58:40 volumiobad volumio[16877]: info: Reading ALSA contributions from plugins. May 12 08:58:40 volumiobad volumio[17020]: 2024-05-12 08:58:40 [SPTF] 08:58:40.327 E [ap:3953] Connection error: 409 May 12 08:58:40 volumiobad volumio[17020]: 2024-05-12 08:58:40 [WARN] Login failed: User needs a premium account May 12 08:58:40 volumiobad volumio[17020]: 2024-05-12 08:58:40 [ERR ] Could not get the playlist container. May 12 08:58:40 volumiobad systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 12 08:58:40 volumiobad systemd[1]: spop.service: Failed with result 'signal'. May 12 08:58:40 volumiobad volumio[16877]: [SpotifyConnect] Vollibrespot Daemon service started! May 12 08:58:40 volumiobad volumio[16877]: [Metrics] SpotifyConnect: 0s 401.26ms May 12 08:58:40 volumiobad volumio[16877]: info: SpopD Daemon Started May 12 08:58:40 volumiobad volumio[16877]: info: MPD Permissions set May 12 08:58:40 volumiobad volumio[16877]: info: VolumeController:: Volume=37 Mute =false May 12 08:58:40 volumiobad volumio[16877]: info: CoreStateMachine::pushState May 12 08:58:40 volumiobad volumio[16877]: info: CorePlayQueue::getTrack 0 May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::volumioPushState May 12 08:58:40 volumiobad volumio[16877]: info: Volumio called home May 12 08:58:40 volumiobad volumio[16877]: info: CALLMETHOD: user_interface websocket broadcastMessage [object Object] May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 12 08:58:40 volumiobad volumio[16877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:40 volumiobad volumio[16877]: info: Starting Shairport Sync May 12 08:58:40 volumiobad volumio[16877]: info: Starting Shairport Sync May 12 08:58:40 volumiobad sudo[17032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:40 volumiobad volumio[16877]: info: Starting Shairport Sync May 12 08:58:40 volumiobad sudo[17032]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:40 volumiobad sudo[17034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:40 volumiobad sudo[17034]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:40 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:40 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:40 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:40 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:40 volumiobad sudo[17032]: pam_unix(sudo:session): session closed for user root May 12 08:58:40 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:40 volumiobad systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 12 08:58:40 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:40 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:40 volumiobad sudo[17038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:40 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:40 volumiobad sudo[17038]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:40 volumiobad sudo[17034]: pam_unix(sudo:session): session closed for user root May 12 08:58:40 volumiobad volumio[16877]: info: Asound.conf file unchanged, so no further update is needed May 12 08:58:40 volumiobad volumio[16877]: info: Output device has changed, restarting MPD May 12 08:58:40 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:40 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:40 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:40 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:40 volumiobad sudo[17038]: pam_unix(sudo:session): session closed for user root May 12 08:58:40 volumiobad volumio[16877]: info: Output device has changed, restarting Shairport Sync May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:40 volumiobad sudo[17047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:40 volumiobad sudo[17047]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:40 volumiobad sudo[17045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:40 volumiobad sudo[17045]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:40 volumiobad sudo[17045]: pam_unix(sudo:session): session closed for user root May 12 08:58:40 volumiobad systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 12 08:58:40 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:58:40 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:58:40 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:58:40 volumiobad volumio[16877]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:40 volumiobad volumio[16877]: [SpotifyConnect] Creating VLS config file May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:40 volumiobad volumio[16877]: info: Shairport-Sync Started May 12 08:58:40 volumiobad volumio[16877]: Error adding Membership: Error: addMembership EINVAL May 12 08:58:40 volumiobad volumio[16877]: info: Shairport-Sync Started May 12 08:58:40 volumiobad volumio[16877]: info: MPD Permissions set May 12 08:58:40 volumiobad volumio[16877]: info: Shairport-Sync Started May 12 08:58:40 volumiobad volumio[16877]: info: CoreCommandRouter::volumioGetState May 12 08:58:40 volumiobad volumio[16877]: info: CorePlayQueue::getTrack 0 May 12 08:58:41 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:41 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:41 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:41 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:41 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:41 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:41 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:41 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:41 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:41 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:41 volumiobad volumio[16877]: info: Starting Shairport Sync May 12 08:58:41 volumiobad sudo[17062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:41 volumiobad sudo[17062]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:41 volumiobad volumio[16877]: [SpotifyConnect] Restarting Vollibrespot Daemon May 12 08:58:41 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:41 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:41 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:41 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:41 volumiobad sudo[17067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service May 12 08:58:41 volumiobad sudo[17067]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:41 volumiobad sudo[17062]: pam_unix(sudo:session): session closed for user root May 12 08:58:41 volumiobad sudo[17071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 12 08:58:41 volumiobad sudo[17071]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:41 volumiobad volumio[16877]: info: Shairport-Sync Started May 12 08:58:41 volumiobad systemd[1]: Stopping Volspotconnect2 Daemon... May 12 08:58:41 volumiobad systemd[1]: volspotconnect2.service: Main process exited, code=killed, status=15/TERM May 12 08:58:41 volumiobad systemd[1]: volspotconnect2.service: Succeeded. May 12 08:58:41 volumiobad systemd[1]: Stopped Volspotconnect2 Daemon. May 12 08:58:41 volumiobad systemd[1]: Started Volspotconnect2 Daemon. May 12 08:58:41 volumiobad sudo[17067]: pam_unix(sudo:session): session closed for user root May 12 08:58:41 volumiobad volumio[16877]: [SpotifyConnect] Vollibrespot Daemon service restarted! May 12 08:58:41 volumiobad volumio[17076]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 May 12 08:58:41 volumiobad volumio[17076]: Reading Config from "volspotify.toml" May 12 08:58:41 volumiobad volumio[17076]: [Vollibrespot] : Using Alsa backend with device: volumio May 12 08:58:41 volumiobad systemd[1]: Started Spotify Daemon. May 12 08:58:41 volumiobad sudo[17071]: pam_unix(sudo:session): session closed for user root May 12 08:58:41 volumiobad volumio[16877]: info: SpopD Daemon Started May 12 08:58:41 volumiobad volumio[17078]: 2024-05-12 08:58:41 [SPTF] 08:58:41.328 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 12 08:58:41 volumiobad volumio[17078]: 2024-05-12 08:58:41 [SPTF] 08:58:41.339 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 12 08:58:41 volumiobad volumio[17078]: 2024-05-12 08:58:41 [SPTF] 08:58:41.368 I [ap:1226] Connected to AP: 34.158.0.131:4070 May 12 08:58:41 volumiobad volumio[17076]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } May 12 08:58:41 volumiobad volumio[17078]: 2024-05-12 08:58:41 [SPTF] 08:58:41.562 E [ap:3953] Connection error: 409 May 12 08:58:41 volumiobad volumio[17078]: 2024-05-12 08:58:41 [WARN] Login failed: User needs a premium account May 12 08:58:41 volumiobad volumio[17078]: 2024-05-12 08:58:41 [ERR ] Could not get the playlist container. May 12 08:58:41 volumiobad systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 12 08:58:41 volumiobad systemd[1]: spop.service: Failed with result 'signal'. May 12 08:58:41 volumiobad volumio[16877]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 08:58:41 volumiobad volumio[16877]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 12 08:58:41 volumiobad volumio[16877]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 08:58:41 volumiobad volumio[16877]: info: camilladsp spawned new process with pid undefined, instance 1, run: true May 12 08:58:41 volumiobad volumio[16877]: info: camilladsp service started and running in background, instance 1 May 12 08:58:41 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:42 volumiobad volumio[16877]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 08:58:42 volumiobad volumio[16877]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 12 08:58:42 volumiobad volumio[16877]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 08:58:42 volumiobad volumio[16877]: info: FusionDsp loaded May 12 08:58:42 volumiobad volumio[16877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 08:58:42 volumiobad sudo[17091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 12 08:58:42 volumiobad sudo[17091]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:42 volumiobad volumio[16877]: info: FusionDsp - Reporting Fusion DSP Enabled May 12 08:58:42 volumiobad volumio[16877]: info: FusionDsp - ---- installed callbackRead May 12 08:58:42 volumiobad volumio[16877]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:58:42 volumiobad sudo[17091]: pam_unix(sudo:session): session closed for user root May 12 08:58:42 volumiobad volumio[16877]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 12 08:58:42 volumiobad volumio[16877]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 12 08:58:42 volumiobad volumio[16877]: at onErrorNT (internal/child_process.js:465:16) May 12 08:58:42 volumiobad volumio[16877]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 12 08:58:42 volumiobad volumio[16877]: errno: -2, May 12 08:58:42 volumiobad volumio[16877]: code: 'ENOENT', May 12 08:58:42 volumiobad volumio[16877]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 12 08:58:42 volumiobad volumio[16877]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 12 08:58:42 volumiobad volumio[16877]: spawnargs: [ May 12 08:58:42 volumiobad volumio[16877]: '-p', May 12 08:58:42 volumiobad volumio[16877]: 9876, May 12 08:58:42 volumiobad volumio[16877]: '-o', May 12 08:58:42 volumiobad volumio[16877]: '/tmp/camilladsp.log', May 12 08:58:42 volumiobad volumio[16877]: '-l', May 12 08:58:42 volumiobad volumio[16877]: 'warn', May 12 08:58:42 volumiobad volumio[16877]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 12 08:58:42 volumiobad volumio[16877]: ] May 12 08:58:42 volumiobad volumio[16877]: } May 12 08:58:42 volumiobad volumio[16877]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:58:42 volumiobad mpd[17072]: May 12 08:58 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 08:58:42 volumiobad mpd[17072]: May 12 08:58 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 12 08:58:42 volumiobad mpd[17072]: May 12 08:58 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 12 08:58:42 volumiobad systemd[1]: Started Music Player Daemon. May 12 08:58:42 volumiobad sudo[17047]: pam_unix(sudo:session): session closed for user root May 12 08:58:42 volumiobad sudo[17000]: pam_unix(sudo:session): session closed for user root May 12 08:58:42 volumiobad sudo[17104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-12 08:57 May 12 08:58:42 volumiobad sudo[17104]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:42 volumiobad sudo[17104]: pam_unix(sudo:session): session closed for user root May 12 08:58:43 volumiobad systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 12 08:58:43 volumiobad systemd[1]: volumio.service: Failed with result 'exit-code'. May 12 08:58:43 volumiobad systemd[1]: Started dynamicswap service. May 12 08:58:43 volumiobad systemd[1]: dynamicswap.service: Succeeded. May 12 08:58:43 volumiobad systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 12 08:58:43 volumiobad systemd[1]: volumio.service: Scheduled restart job, restart counter is at 49. May 12 08:58:43 volumiobad systemd[1]: Started dynamicswap service. May 12 08:58:43 volumiobad systemd[1]: Stopped Volumio Backend Module. May 12 08:58:43 volumiobad systemd[1]: Started Volumio Backend Module. May 12 08:58:43 volumiobad systemd[1]: dynamicswap.service: Succeeded. May 12 08:58:45 volumiobad volumio[17115]: info: ------------------------------------------- May 12 08:58:45 volumiobad volumio[17115]: info: ----- Volumio3 ---- May 12 08:58:45 volumiobad volumio[17115]: info: ------------------------------------------- May 12 08:58:45 volumiobad volumio[17115]: info: ----- System startup ---- May 12 08:58:45 volumiobad volumio[17115]: info: ------------------------------------------- May 12 08:58:45 volumiobad volumio[17115]: info: MYVOLUMIO Environment detected May 12 08:58:45 volumiobad volumio[17115]: info: Plugin folders cleanup May 12 08:58:45 volumiobad volumio[17115]: info: Scanning into folder /volumio/app/plugins/ May 12 08:58:45 volumiobad volumio[17115]: info: Scanning category audio_interface May 12 08:58:45 volumiobad volumio[17115]: info: Scanning category miscellanea May 12 08:58:45 volumiobad volumio[17115]: info: Scanning category music_service May 12 08:58:45 volumiobad volumio[17115]: info: Scanning category plugins.json May 12 08:58:45 volumiobad volumio[17115]: info: Scanning category system_controller May 12 08:58:45 volumiobad volumio[17115]: info: Scanning category user_interface May 12 08:58:45 volumiobad volumio[17115]: info: Scanning into folder /data/plugins/ May 12 08:58:45 volumiobad volumio[17115]: info: Scanning category audio_interface May 12 08:58:45 volumiobad volumio[17115]: info: Scanning category music_service May 12 08:58:45 volumiobad volumio[17115]: info: Plugin folders cleanup completed May 12 08:58:45 volumiobad volumio[17115]: info: ------------------------------------------- May 12 08:58:45 volumiobad volumio[17115]: info: ----- Core plugins startup ---- May 12 08:58:45 volumiobad volumio[17115]: info: ------------------------------------------- May 12 08:58:45 volumiobad volumio[17115]: info: Loading plugins from folder /volumio/app/plugins/ May 12 08:58:45 volumiobad volumio[17115]: info: Adding plugin upnp to MyMusic Plugins May 12 08:58:45 volumiobad volumio[17115]: info: Adding plugin airplay_emulation to MyMusic Plugins May 12 08:58:45 volumiobad volumio[17115]: info: Adding plugin upnp_browser to MyMusic Plugins May 12 08:58:45 volumiobad volumio[17115]: info: Loading plugins from folder /data/plugins/ May 12 08:58:45 volumiobad volumio[17115]: info: Loading plugin "system"... May 12 08:58:46 volumiobad volumio[17115]: info: Loading plugin "appearance"... May 12 08:58:47 volumiobad volumio[17115]: info: Loading plugin "network"... May 12 08:58:47 volumiobad volumio[17115]: info: Refreshing Cached IP Addresses May 12 08:58:47 volumiobad sudo[17138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 08:58:47 volumiobad sudo[17138]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:47 volumiobad volumio[17115]: info: Loading plugin "services"... May 12 08:58:47 volumiobad sudo[17138]: pam_unix(sudo:session): session closed for user root May 12 08:58:47 volumiobad volumio[17115]: info: Loading plugin "alsa_controller"... May 12 08:58:47 volumiobad sudo[17141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 08:58:47 volumiobad sudo[17141]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:47 volumiobad sudo[17141]: pam_unix(sudo:session): session closed for user root May 12 08:58:47 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 08:58:47 volumiobad volumio[17115]: info: Loading plugin "wizard"... May 12 08:58:47 volumiobad volumio[17115]: info: Loading plugin "volumio_command_line_client"... May 12 08:58:47 volumiobad volumio[17115]: info: Loading plugin "upnp"... May 12 08:58:47 volumiobad volumio[17115]: info: [1715504327215] Starting Upmpd Daemon May 12 08:58:47 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:47 volumiobad volumio[17115]: info: Loading plugin "my_music"... May 12 08:58:47 volumiobad volumio[17115]: info: Loading plugin "mpd"... May 12 08:58:47 volumiobad volumio[17115]: info: Creating MPD Configuration file May 12 08:58:47 volumiobad volumio[17115]: info: Loading plugin "upnp_browser"... May 12 08:58:47 volumiobad sudo[17148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:47 volumiobad sudo[17148]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:47 volumiobad sudo[17148]: pam_unix(sudo:session): session closed for user root May 12 08:58:47 volumiobad sudo[17150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:47 volumiobad sudo[17150]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:47 volumiobad systemd[1]: Stopping Music Player Daemon... May 12 08:58:47 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:58:47 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:58:47 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:58:48 volumiobad volumio[17115]: info: Loading plugin "networkfs"... May 12 08:58:48 volumiobad volumio[17115]: info: Starting Udev Watcher for removable devices May 12 08:58:48 volumiobad volumio[17115]: info: Ignoring mount for partition: boot May 12 08:58:48 volumiobad volumio[17115]: info: Ignoring mount for partition: volumio May 12 08:58:48 volumiobad volumio[17115]: info: Ignoring mount for partition: volumio_data May 12 08:58:48 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:48 volumiobad volumio[17115]: info: Loading plugin "alarm-clock"... May 12 08:58:48 volumiobad volumio[17115]: info: Loading plugin "airplay_emulation"... May 12 08:58:48 volumiobad volumio[17115]: info: Starting Shairport Sync May 12 08:58:48 volumiobad volumio[17115]: info: Loading plugin "last_100"... May 12 08:58:48 volumiobad volumio[17115]: info: Loading plugin "webradio"... May 12 08:58:48 volumiobad volumio[17115]: info: Loading plugin "i2s_dacs"... May 12 08:58:48 volumiobad volumio[17115]: info: Loading plugin "volumiodiscovery"... May 12 08:58:48 volumiobad volumio[17115]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 08:58:48 volumiobad volumio[17115]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:48 volumiobad volumio[17115]: *** WARNING *** For more information see May 12 08:58:48 volumiobad node[17115]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 08:58:48 volumiobad volumio[17115]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 08:58:48 volumiobad volumio[17115]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:48 volumiobad volumio[17115]: *** WARNING *** For more information see May 12 08:58:48 volumiobad node[17115]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:48 volumiobad node[17115]: *** WARNING *** For more information see May 12 08:58:48 volumiobad node[17115]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 08:58:48 volumiobad node[17115]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:58:48 volumiobad node[17115]: *** WARNING *** For more information see May 12 08:58:48 volumiobad volumio[17115]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 08:58:48 volumiobad volumio[17115]: info: Discovery: Started advertising with name: Volumiobad May 12 08:58:48 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:48 volumiobad volumio[17115]: info: Loading plugin "outputs"... May 12 08:58:48 volumiobad volumio[17115]: info: Loading plugin "albumart"... May 12 08:58:49 volumiobad volumio[17115]: info: Plugin example_plugin is not enabled May 12 08:58:49 volumiobad volumio[17115]: info: Loading plugin "inputs"... May 12 08:58:49 volumiobad volumio[17115]: info: Loading plugin "updater_comm"... May 12 08:58:49 volumiobad mpd[17155]: May 12 08:58 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 08:58:49 volumiobad mpd[17155]: May 12 08:58 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 12 08:58:49 volumiobad mpd[17155]: May 12 08:58 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 12 08:58:49 volumiobad systemd[1]: Started Music Player Daemon. May 12 08:58:49 volumiobad sudo[17150]: pam_unix(sudo:session): session closed for user root May 12 08:58:49 volumiobad volumio[17115]: info: Plugin mpdemulation is not enabled May 12 08:58:49 volumiobad volumio[17115]: info: Loading plugin "rest_api"... May 12 08:58:49 volumiobad volumio[17115]: info: Loading plugin "websocket"... May 12 08:58:49 volumiobad volumio[17115]: info: Loading plugin "fusiondsp"... May 12 08:58:49 volumiobad volumio[17115]: Forking 3 albumart workers May 12 08:58:49 volumiobad volumio[17115]: info: Applying required configuration parameters for plugin fusiondsp May 12 08:58:49 volumiobad volumio[17115]: info: Loading plugin "spop"... May 12 08:58:50 volumiobad volumio[17115]: Starting albumart workers May 12 08:58:50 volumiobad volumio[17115]: Starting albumart workers May 12 08:58:50 volumiobad volumio[17115]: info: Loading plugin "volspotconnect2"... May 12 08:58:50 volumiobad volumio[17115]: Starting albumart workers May 12 08:58:51 volumiobad volumio[17115]: info: Loading i18n strings for locale de May 12 08:58:51 volumiobad volumio[17115]: Updating browse sources language May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::initPlayerControls May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: Express server listening on port 3000 May 12 08:58:51 volumiobad volumio[17115]: [Metrics] WebUI: 6s 798.29ms May 12 08:58:51 volumiobad volumio[17115]: info: CoreStateMachine::resetVolumioState May 12 08:58:51 volumiobad volumio[17115]: info: CoreStateMachine::getcurrentVolume May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::volumioRetrievevolume May 12 08:58:51 volumiobad volumio[17115]: info: MPD Permissions set May 12 08:58:51 volumiobad volumio[17115]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 May 12 08:58:51 volumiobad volumio[17076]: [Vollibrespot] : couldn't parse packet from 192.168.2.164:5353: type 47 is invalid May 12 08:58:51 volumiobad volumio[17115]: info: MPD running with PID17155 May 12 08:58:51 volumiobad volumio[17115]: ,establishing connection May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:51 volumiobad volumio[17115]: info: Executing start script for DAC IQaudIO Pi-DigiAMP+ May 12 08:58:51 volumiobad volumio[17115]: info: Reloading queue from file May 12 08:58:51 volumiobad volumio[17115]: error: updateQueue error: null May 12 08:58:51 volumiobad volumio[17115]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 12 08:58:51 volumiobad volumio[17115]: info: CoreStateMachine::setRepeat null single undefined May 12 08:58:51 volumiobad volumio[17115]: info: CoreStateMachine::pushState May 12 08:58:51 volumiobad volumio[17115]: info: CorePlayQueue::getTrack 0 May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::volumioPushState May 12 08:58:51 volumiobad volumio[17115]: info: CoreStateMachine::setRandom false May 12 08:58:51 volumiobad volumio[17115]: info: CoreStateMachine::pushState May 12 08:58:51 volumiobad volumio[17115]: info: CorePlayQueue::getTrack 0 May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::volumioPushState May 12 08:58:51 volumiobad volumio[17115]: info: Setting Device type: Raspberry PI May 12 08:58:51 volumiobad volumio[17115]: info: DAC script executed May 12 08:58:51 volumiobad volumio[17115]: info: Completed loading Core Plugins May 12 08:58:51 volumiobad volumio[17115]: info: Preparing to generate the ALSA configuration file May 12 08:58:51 volumiobad volumio[17115]: info: VolumeController:: Volume=37 Mute =false May 12 08:58:51 volumiobad volumio[17115]: info: CoreStateMachine::pushState May 12 08:58:51 volumiobad volumio[17115]: info: CorePlayQueue::getTrack 0 May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::volumioPushState May 12 08:58:51 volumiobad volumio[17115]: info: CoreStateMachine::updateTrackBlock May 12 08:58:51 volumiobad volumio[17115]: info: CorePlayQueue::getTrackBlock May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::volumioRetrievevolume May 12 08:58:51 volumiobad volumio[17115]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 08:58:51 volumiobad volumio[17115]: info: Reading ALSA contributions from plugins. May 12 08:58:51 volumiobad volumio[17115]: error: updateQueue error: null May 12 08:58:51 volumiobad volumio[17115]: info: Discovery: adding 3986bfa1-ca3f-4ec8-a6f7-9ee4571b4957 May 12 08:58:51 volumiobad volumio[17115]: info: Discovery: Found device Volumiobad May 12 08:58:51 volumiobad volumio[17115]: info: CoreCommandRouter::volumioGetState May 12 08:58:51 volumiobad volumio[17115]: info: CorePlayQueue::getTrack 0 May 12 08:58:52 volumiobad volumio[17115]: info: VolumeController:: Volume=37 Mute =false May 12 08:58:52 volumiobad volumio[17115]: info: CoreStateMachine::pushState May 12 08:58:52 volumiobad volumio[17115]: info: CorePlayQueue::getTrack 0 May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::volumioPushState May 12 08:58:52 volumiobad volumio[17115]: info: Asound.conf file unchanged, so no further update is needed May 12 08:58:52 volumiobad volumio[17115]: info: Output device has changed, restarting MPD May 12 08:58:52 volumiobad sudo[17237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:52 volumiobad sudo[17237]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:52 volumiobad volumio[17115]: info: Output device has changed, restarting Shairport Sync May 12 08:58:52 volumiobad sudo[17237]: pam_unix(sudo:session): session closed for user root May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:52 volumiobad sudo[17240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:52 volumiobad sudo[17240]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:52 volumiobad systemd[1]: Stopping Music Player Daemon... May 12 08:58:52 volumiobad volumio[17115]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:52 volumiobad volumio[17115]: info: ___________ START PLUGINS ___________ May 12 08:58:52 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:58:52 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:52 volumiobad volumio[17115]: info: [1715504332147] CoreMusicLibrary::Adding element Media Servers May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:52 volumiobad volumio[17115]: Cannot find translation for sourceMedia Servers May 12 08:58:52 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:52 volumiobad volumio[17115]: info: [1715504332324] CoreMusicLibrary::Adding element Last_100 May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:52 volumiobad volumio[17115]: Cannot find translation for sourceMedia Servers May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:58:52 volumiobad volumio[17115]: info: [1715504332328] CoreMusicLibrary::Adding element Webradio May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:58:52 volumiobad volumio[17115]: Cannot find translation for sourceMedia Servers May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 08:58:52 volumiobad volumio[17115]: info: Loading i18n strings for locale de May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: FusionDsp - mixtype--------------------- Hardware May 12 08:58:52 volumiobad volumio[17115]: [SpotifyConnect] Creating VLS config file May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: [SpotifyConnect] Starting metadata listener May 12 08:58:52 volumiobad sudo[17254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 12 08:58:52 volumiobad sudo[17254]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:52 volumiobad volumio[17115]: info: Volumio Calling Home May 12 08:58:52 volumiobad sudo[17258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 12 08:58:52 volumiobad sudo[17258]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:52 volumiobad systemd[1]: Started Spotify Daemon. May 12 08:58:52 volumiobad sudo[17254]: pam_unix(sudo:session): session closed for user root May 12 08:58:52 volumiobad volumio[17259]: 2024-05-12 08:58:52 [SPTF] 08:58:52.481 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 12 08:58:52 volumiobad volumio[17259]: 2024-05-12 08:58:52 [SPTF] 08:58:52.494 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 12 08:58:52 volumiobad sudo[17258]: pam_unix(sudo:session): session closed for user root May 12 08:58:52 volumiobad volumio[17259]: 2024-05-12 08:58:52 [SPTF] 08:58:52.524 I [ap:1226] Connected to AP: 34.158.0.131:4070 May 12 08:58:52 volumiobad volumio[17115]: info: Preparing to generate the ALSA configuration file May 12 08:58:52 volumiobad volumio[17115]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 08:58:52 volumiobad volumio[17115]: info: Reading ALSA contributions from plugins. May 12 08:58:52 volumiobad volumio[17115]: [SpotifyConnect] Vollibrespot Daemon service started! May 12 08:58:52 volumiobad volumio[17115]: [Metrics] SpotifyConnect: 0s 348.75ms May 12 08:58:52 volumiobad volumio[17115]: info: SpopD Daemon Started May 12 08:58:52 volumiobad volumio[17115]: info: MPD Permissions set May 12 08:58:52 volumiobad volumio[17259]: 2024-05-12 08:58:52 [SPTF] 08:58:52.732 E [ap:3953] Connection error: 409 May 12 08:58:52 volumiobad volumio[17259]: 2024-05-12 08:58:52 [WARN] Login failed: User needs a premium account May 12 08:58:52 volumiobad volumio[17259]: 2024-05-12 08:58:52 [ERR ] Could not get the playlist container. May 12 08:58:52 volumiobad systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 12 08:58:52 volumiobad systemd[1]: spop.service: Failed with result 'signal'. May 12 08:58:52 volumiobad volumio[17115]: info: Volumio called home May 12 08:58:52 volumiobad volumio[17115]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:52 volumiobad volumio[17115]: info: CALLMETHOD: user_interface websocket broadcastMessage [object Object] May 12 08:58:52 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 12 08:58:52 volumiobad volumio[17115]: info: Starting Shairport Sync May 12 08:58:52 volumiobad volumio[17115]: info: Starting Shairport Sync May 12 08:58:52 volumiobad sudo[17271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:52 volumiobad volumio[17115]: info: Starting Shairport Sync May 12 08:58:52 volumiobad sudo[17271]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:52 volumiobad sudo[17273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:52 volumiobad sudo[17273]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:52 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:52 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:52 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:53 volumiobad sudo[17277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:53 volumiobad sudo[17277]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:53 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:53 volumiobad sudo[17271]: pam_unix(sudo:session): session closed for user root May 12 08:58:53 volumiobad sudo[17273]: pam_unix(sudo:session): session closed for user root May 12 08:58:53 volumiobad volumio[17115]: info: Asound.conf file unchanged, so no further update is needed May 12 08:58:53 volumiobad volumio[17115]: info: Output device has changed, restarting MPD May 12 08:58:53 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:53 volumiobad systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 12 08:58:53 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:53 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:53 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:53 volumiobad sudo[17281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:58:53 volumiobad sudo[17281]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:53 volumiobad volumio[17115]: info: Output device has changed, restarting Shairport Sync May 12 08:58:53 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:53 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:53 volumiobad sudo[17281]: pam_unix(sudo:session): session closed for user root May 12 08:58:53 volumiobad sudo[17277]: pam_unix(sudo:session): session closed for user root May 12 08:58:53 volumiobad sudo[17286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:58:53 volumiobad sudo[17286]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:53 volumiobad volumio[17115]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:58:53 volumiobad volumio[17115]: [SpotifyConnect] Creating VLS config file May 12 08:58:53 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:53 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:53 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:53 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:53 volumiobad systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 12 08:58:53 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:58:53 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:58:53 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:53 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:58:53 volumiobad volumio[17115]: info: Shairport-Sync Started May 12 08:58:53 volumiobad volumio[17115]: Error adding Membership: Error: addMembership EINVAL May 12 08:58:53 volumiobad volumio[17115]: info: MPD Permissions set May 12 08:58:53 volumiobad volumio[17115]: info: Shairport-Sync Started May 12 08:58:53 volumiobad volumio[17115]: info: Shairport-Sync Started May 12 08:58:53 volumiobad volumio[17115]: info: CoreCommandRouter::volumioGetState May 12 08:58:53 volumiobad volumio[17115]: info: CorePlayQueue::getTrack 0 May 12 08:58:53 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:53 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:53 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:53 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:58:53 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:53 volumiobad volumio[17115]: info: Starting Shairport Sync May 12 08:58:53 volumiobad sudo[17300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:58:53 volumiobad sudo[17300]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:53 volumiobad volumio[17115]: [SpotifyConnect] Restarting Vollibrespot Daemon May 12 08:58:53 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:58:53 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:58:53 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:58:53 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:58:53 volumiobad sudo[17300]: pam_unix(sudo:session): session closed for user root May 12 08:58:53 volumiobad sudo[17305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service May 12 08:58:53 volumiobad sudo[17305]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:53 volumiobad volumio[17115]: info: Shairport-Sync Started May 12 08:58:53 volumiobad systemd[1]: Stopping Volspotconnect2 Daemon... May 12 08:58:53 volumiobad systemd[1]: volspotconnect2.service: Main process exited, code=killed, status=15/TERM May 12 08:58:53 volumiobad systemd[1]: volspotconnect2.service: Succeeded. May 12 08:58:53 volumiobad systemd[1]: Stopped Volspotconnect2 Daemon. May 12 08:58:53 volumiobad systemd[1]: Started Volspotconnect2 Daemon. May 12 08:58:53 volumiobad sudo[17305]: pam_unix(sudo:session): session closed for user root May 12 08:58:53 volumiobad sudo[17310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 12 08:58:53 volumiobad sudo[17310]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:53 volumiobad volumio[17115]: [SpotifyConnect] Vollibrespot Daemon service restarted! May 12 08:58:53 volumiobad volumio[17311]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 May 12 08:58:53 volumiobad volumio[17311]: Reading Config from "volspotify.toml" May 12 08:58:53 volumiobad volumio[17311]: [Vollibrespot] : Using Alsa backend with device: volumio May 12 08:58:53 volumiobad systemd[1]: Started Spotify Daemon. May 12 08:58:53 volumiobad sudo[17310]: pam_unix(sudo:session): session closed for user root May 12 08:58:53 volumiobad volumio[17316]: 2024-05-12 08:58:53 [SPTF] 08:58:53.599 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 12 08:58:53 volumiobad volumio[17115]: info: SpopD Daemon Started May 12 08:58:53 volumiobad volumio[17316]: 2024-05-12 08:58:53 [SPTF] 08:58:53.604 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 12 08:58:53 volumiobad volumio[17311]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } May 12 08:58:53 volumiobad volumio[17316]: 2024-05-12 08:58:53 [SPTF] 08:58:53.633 I [ap:1226] Connected to AP: 34.158.0.131:4070 May 12 08:58:53 volumiobad volumio[17316]: 2024-05-12 08:58:53 [SPTF] 08:58:53.792 E [ap:3953] Connection error: 409 May 12 08:58:53 volumiobad volumio[17316]: 2024-05-12 08:58:53 [WARN] Login failed: User needs a premium account May 12 08:58:53 volumiobad volumio[17316]: 2024-05-12 08:58:53 [ERR ] Could not get the playlist container. May 12 08:58:53 volumiobad systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 12 08:58:53 volumiobad systemd[1]: spop.service: Failed with result 'signal'. May 12 08:58:54 volumiobad volumio[17115]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 08:58:54 volumiobad volumio[17115]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 12 08:58:54 volumiobad volumio[17115]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 08:58:54 volumiobad volumio[17115]: info: camilladsp spawned new process with pid undefined, instance 1, run: true May 12 08:58:54 volumiobad volumio[17115]: info: camilladsp service started and running in background, instance 1 May 12 08:58:54 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:58:54 volumiobad volumio[17115]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 08:58:54 volumiobad volumio[17115]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 12 08:58:54 volumiobad volumio[17115]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 08:58:54 volumiobad volumio[17115]: info: FusionDsp loaded May 12 08:58:54 volumiobad volumio[17115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 08:58:54 volumiobad sudo[17329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 12 08:58:54 volumiobad sudo[17329]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:54 volumiobad volumio[17115]: info: FusionDsp - Reporting Fusion DSP Enabled May 12 08:58:54 volumiobad sudo[17329]: pam_unix(sudo:session): session closed for user root May 12 08:58:54 volumiobad volumio[17115]: info: FusionDsp - ---- installed callbackRead May 12 08:58:54 volumiobad volumio[17115]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:58:54 volumiobad volumio[17115]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 12 08:58:54 volumiobad volumio[17115]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 12 08:58:54 volumiobad volumio[17115]: at onErrorNT (internal/child_process.js:465:16) May 12 08:58:54 volumiobad volumio[17115]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 12 08:58:54 volumiobad volumio[17115]: errno: -2, May 12 08:58:54 volumiobad volumio[17115]: code: 'ENOENT', May 12 08:58:54 volumiobad volumio[17115]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 12 08:58:54 volumiobad volumio[17115]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 12 08:58:54 volumiobad volumio[17115]: spawnargs: [ May 12 08:58:54 volumiobad volumio[17115]: '-p', May 12 08:58:54 volumiobad volumio[17115]: 9876, May 12 08:58:54 volumiobad volumio[17115]: '-o', May 12 08:58:54 volumiobad volumio[17115]: '/tmp/camilladsp.log', May 12 08:58:54 volumiobad volumio[17115]: '-l', May 12 08:58:54 volumiobad volumio[17115]: 'warn', May 12 08:58:54 volumiobad volumio[17115]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 12 08:58:54 volumiobad volumio[17115]: ] May 12 08:58:54 volumiobad volumio[17115]: } May 12 08:58:54 volumiobad volumio[17115]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:58:54 volumiobad mpd[17308]: May 12 08:58 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 08:58:54 volumiobad mpd[17308]: May 12 08:58 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 12 08:58:54 volumiobad mpd[17308]: May 12 08:58 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 12 08:58:54 volumiobad systemd[1]: Started Music Player Daemon. May 12 08:58:54 volumiobad sudo[17286]: pam_unix(sudo:session): session closed for user root May 12 08:58:54 volumiobad sudo[17240]: pam_unix(sudo:session): session closed for user root May 12 08:58:55 volumiobad sudo[17342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-12 08:57 May 12 08:58:55 volumiobad sudo[17342]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:55 volumiobad sudo[17342]: pam_unix(sudo:session): session closed for user root May 12 08:58:55 volumiobad systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 12 08:58:55 volumiobad systemd[1]: volumio.service: Failed with result 'exit-code'. May 12 08:58:55 volumiobad systemd[1]: Started dynamicswap service. May 12 08:58:55 volumiobad systemd[1]: dynamicswap.service: Succeeded. May 12 08:58:55 volumiobad systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 12 08:58:55 volumiobad systemd[1]: volumio.service: Scheduled restart job, restart counter is at 50. May 12 08:58:55 volumiobad systemd[1]: Started dynamicswap service. May 12 08:58:55 volumiobad systemd[1]: Stopped Volumio Backend Module. May 12 08:58:55 volumiobad systemd[1]: Started Volumio Backend Module. May 12 08:58:55 volumiobad systemd[1]: dynamicswap.service: Succeeded. May 12 08:58:57 volumiobad volumio[17353]: info: ------------------------------------------- May 12 08:58:57 volumiobad volumio[17353]: info: ----- Volumio3 ---- May 12 08:58:57 volumiobad volumio[17353]: info: ------------------------------------------- May 12 08:58:57 volumiobad volumio[17353]: info: ----- System startup ---- May 12 08:58:57 volumiobad volumio[17353]: info: ------------------------------------------- May 12 08:58:58 volumiobad volumio[17353]: info: MYVOLUMIO Environment detected May 12 08:58:58 volumiobad volumio[17353]: info: Plugin folders cleanup May 12 08:58:58 volumiobad volumio[17353]: info: Scanning into folder /volumio/app/plugins/ May 12 08:58:58 volumiobad volumio[17353]: info: Scanning category audio_interface May 12 08:58:58 volumiobad volumio[17353]: info: Scanning category miscellanea May 12 08:58:58 volumiobad volumio[17353]: info: Scanning category music_service May 12 08:58:58 volumiobad volumio[17353]: info: Scanning category plugins.json May 12 08:58:58 volumiobad volumio[17353]: info: Scanning category system_controller May 12 08:58:58 volumiobad volumio[17353]: info: Scanning category user_interface May 12 08:58:58 volumiobad volumio[17353]: info: Scanning into folder /data/plugins/ May 12 08:58:58 volumiobad volumio[17353]: info: Scanning category audio_interface May 12 08:58:58 volumiobad volumio[17353]: info: Scanning category music_service May 12 08:58:58 volumiobad volumio[17353]: info: Plugin folders cleanup completed May 12 08:58:58 volumiobad volumio[17353]: info: ------------------------------------------- May 12 08:58:58 volumiobad volumio[17353]: info: ----- Core plugins startup ---- May 12 08:58:58 volumiobad volumio[17353]: info: ------------------------------------------- May 12 08:58:58 volumiobad volumio[17353]: info: Loading plugins from folder /volumio/app/plugins/ May 12 08:58:58 volumiobad volumio[17353]: info: Adding plugin upnp to MyMusic Plugins May 12 08:58:58 volumiobad volumio[17353]: info: Adding plugin airplay_emulation to MyMusic Plugins May 12 08:58:58 volumiobad volumio[17353]: info: Adding plugin upnp_browser to MyMusic Plugins May 12 08:58:58 volumiobad volumio[17353]: info: Loading plugins from folder /data/plugins/ May 12 08:58:58 volumiobad volumio[17353]: info: Loading plugin "system"... May 12 08:58:58 volumiobad volumio[17353]: info: Loading plugin "appearance"... May 12 08:58:59 volumiobad volumio[17353]: info: Loading plugin "network"... May 12 08:58:59 volumiobad volumio[17353]: info: Refreshing Cached IP Addresses May 12 08:58:59 volumiobad sudo[17376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 08:58:59 volumiobad sudo[17376]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:59 volumiobad volumio[17353]: info: Loading plugin "services"... May 12 08:58:59 volumiobad sudo[17376]: pam_unix(sudo:session): session closed for user root May 12 08:58:59 volumiobad volumio[17353]: info: Loading plugin "alsa_controller"... May 12 08:58:59 volumiobad sudo[17379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 08:58:59 volumiobad sudo[17379]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:58:59 volumiobad sudo[17379]: pam_unix(sudo:session): session closed for user root May 12 08:58:59 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 08:58:59 volumiobad volumio[17353]: info: Loading plugin "wizard"... May 12 08:58:59 volumiobad volumio[17353]: info: Loading plugin "volumio_command_line_client"... May 12 08:58:59 volumiobad volumio[17353]: info: Loading plugin "upnp"... May 12 08:58:59 volumiobad volumio[17353]: info: [1715504339672] Starting Upmpd Daemon May 12 08:58:59 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:58:59 volumiobad volumio[17353]: info: Loading plugin "my_music"... May 12 08:58:59 volumiobad volumio[17353]: info: Loading plugin "mpd"... May 12 08:59:00 volumiobad volumio[17353]: info: Creating MPD Configuration file May 12 08:59:00 volumiobad volumio[17353]: info: Loading plugin "upnp_browser"... May 12 08:59:00 volumiobad sudo[17386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:59:00 volumiobad sudo[17386]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:00 volumiobad sudo[17386]: pam_unix(sudo:session): session closed for user root May 12 08:59:00 volumiobad sudo[17388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:59:00 volumiobad sudo[17388]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:00 volumiobad systemd[1]: Stopping Music Player Daemon... May 12 08:59:00 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:59:00 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:59:00 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:59:00 volumiobad volumio[17353]: info: Loading plugin "networkfs"... May 12 08:59:00 volumiobad volumio[17353]: info: Starting Udev Watcher for removable devices May 12 08:59:01 volumiobad volumio[17353]: info: Ignoring mount for partition: boot May 12 08:59:01 volumiobad volumio[17353]: info: Ignoring mount for partition: volumio May 12 08:59:01 volumiobad volumio[17353]: info: Ignoring mount for partition: volumio_data May 12 08:59:01 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:59:01 volumiobad volumio[17353]: info: Loading plugin "alarm-clock"... May 12 08:59:01 volumiobad volumio[17353]: info: Loading plugin "airplay_emulation"... May 12 08:59:01 volumiobad volumio[17353]: info: Starting Shairport Sync May 12 08:59:01 volumiobad volumio[17353]: info: Loading plugin "last_100"... May 12 08:59:01 volumiobad volumio[17353]: info: Loading plugin "webradio"... May 12 08:59:01 volumiobad volumio[17353]: info: Loading plugin "i2s_dacs"... May 12 08:59:01 volumiobad volumio[17353]: info: Loading plugin "volumiodiscovery"... May 12 08:59:01 volumiobad volumio[17353]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 08:59:01 volumiobad volumio[17353]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:59:01 volumiobad volumio[17353]: *** WARNING *** For more information see May 12 08:59:01 volumiobad node[17353]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 08:59:01 volumiobad volumio[17353]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 08:59:01 volumiobad volumio[17353]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:59:01 volumiobad volumio[17353]: *** WARNING *** For more information see May 12 08:59:01 volumiobad node[17353]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:59:01 volumiobad node[17353]: *** WARNING *** For more information see May 12 08:59:01 volumiobad node[17353]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 08:59:01 volumiobad node[17353]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:59:01 volumiobad node[17353]: *** WARNING *** For more information see May 12 08:59:01 volumiobad volumio[17353]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 08:59:01 volumiobad volumio[17353]: info: Discovery: Started advertising with name: Volumiobad May 12 08:59:01 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:59:01 volumiobad volumio[17353]: info: Loading plugin "outputs"... May 12 08:59:01 volumiobad volumio[17353]: info: Loading plugin "albumart"... May 12 08:59:01 volumiobad volumio[17353]: info: Plugin example_plugin is not enabled May 12 08:59:01 volumiobad volumio[17353]: info: Loading plugin "inputs"... May 12 08:59:01 volumiobad volumio[17353]: info: Loading plugin "updater_comm"... May 12 08:59:01 volumiobad mpd[17393]: May 12 08:59 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 08:59:01 volumiobad mpd[17393]: May 12 08:59 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 12 08:59:01 volumiobad mpd[17393]: May 12 08:59 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 12 08:59:01 volumiobad systemd[1]: Started Music Player Daemon. May 12 08:59:01 volumiobad sudo[17388]: pam_unix(sudo:session): session closed for user root May 12 08:59:01 volumiobad volumio[17353]: info: Plugin mpdemulation is not enabled May 12 08:59:01 volumiobad volumio[17353]: info: Loading plugin "rest_api"... May 12 08:59:01 volumiobad volumio[17353]: info: Loading plugin "websocket"... May 12 08:59:01 volumiobad volumio[17353]: info: Loading plugin "fusiondsp"... May 12 08:59:01 volumiobad volumio[17353]: Forking 3 albumart workers May 12 08:59:02 volumiobad volumio[17353]: info: Applying required configuration parameters for plugin fusiondsp May 12 08:59:02 volumiobad volumio[17353]: info: Loading plugin "spop"... May 12 08:59:02 volumiobad volumio[17353]: Starting albumart workers May 12 08:59:02 volumiobad volumio[17353]: Starting albumart workers May 12 08:59:03 volumiobad volumio[17353]: Starting albumart workers May 12 08:59:03 volumiobad volumio[17353]: info: Loading plugin "volspotconnect2"... May 12 08:59:03 volumiobad volumio[17353]: info: Loading i18n strings for locale de May 12 08:59:03 volumiobad volumio[17353]: Updating browse sources language May 12 08:59:03 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:59:03 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:59:03 volumiobad volumio[17353]: info: CoreCommandRouter::initPlayerControls May 12 08:59:03 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:03 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:03 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:03 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:03 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:03 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:03 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:03 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:03 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:59:03 volumiobad volumio[17353]: Express server listening on port 3000 May 12 08:59:03 volumiobad volumio[17353]: [Metrics] WebUI: 6s 751.84ms May 12 08:59:03 volumiobad volumio[17353]: info: CoreStateMachine::resetVolumioState May 12 08:59:03 volumiobad volumio[17353]: info: CoreStateMachine::getcurrentVolume May 12 08:59:03 volumiobad volumio[17353]: info: CoreCommandRouter::volumioRetrievevolume May 12 08:59:03 volumiobad volumio[17353]: info: MPD Permissions set May 12 08:59:04 volumiobad volumio[17353]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 May 12 08:59:04 volumiobad volumio[17353]: info: MPD running with PID17393 May 12 08:59:04 volumiobad volumio[17353]: ,establishing connection May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: Executing start script for DAC IQaudIO Pi-DigiAMP+ May 12 08:59:04 volumiobad volumio[17353]: info: Reloading queue from file May 12 08:59:04 volumiobad volumio[17353]: error: updateQueue error: null May 12 08:59:04 volumiobad volumio[17353]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 12 08:59:04 volumiobad volumio[17353]: info: CoreStateMachine::setRepeat null single undefined May 12 08:59:04 volumiobad volumio[17353]: info: CoreStateMachine::pushState May 12 08:59:04 volumiobad volumio[17353]: info: CorePlayQueue::getTrack 0 May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::volumioPushState May 12 08:59:04 volumiobad volumio[17353]: info: CoreStateMachine::setRandom false May 12 08:59:04 volumiobad volumio[17353]: info: CoreStateMachine::pushState May 12 08:59:04 volumiobad volumio[17353]: info: CorePlayQueue::getTrack 0 May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::volumioPushState May 12 08:59:04 volumiobad volumio[17353]: info: Setting Device type: Raspberry PI May 12 08:59:04 volumiobad volumio[17353]: info: DAC script executed May 12 08:59:04 volumiobad volumio[17353]: info: Completed loading Core Plugins May 12 08:59:04 volumiobad volumio[17353]: info: Preparing to generate the ALSA configuration file May 12 08:59:04 volumiobad volumio[17353]: info: VolumeController:: Volume=37 Mute =false May 12 08:59:04 volumiobad volumio[17353]: info: CoreStateMachine::pushState May 12 08:59:04 volumiobad volumio[17353]: info: CorePlayQueue::getTrack 0 May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::volumioPushState May 12 08:59:04 volumiobad volumio[17353]: info: CoreStateMachine::updateTrackBlock May 12 08:59:04 volumiobad volumio[17353]: info: CorePlayQueue::getTrackBlock May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::volumioRetrievevolume May 12 08:59:04 volumiobad volumio[17353]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 08:59:04 volumiobad volumio[17353]: info: Reading ALSA contributions from plugins. May 12 08:59:04 volumiobad volumio[17353]: error: updateQueue error: null May 12 08:59:04 volumiobad volumio[17353]: info: Discovery: adding 3986bfa1-ca3f-4ec8-a6f7-9ee4571b4957 May 12 08:59:04 volumiobad volumio[17353]: info: Discovery: Found device Volumiobad May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::volumioGetState May 12 08:59:04 volumiobad volumio[17353]: info: CorePlayQueue::getTrack 0 May 12 08:59:04 volumiobad volumio[17353]: info: Asound.conf file unchanged, so no further update is needed May 12 08:59:04 volumiobad volumio[17353]: info: Output device has changed, restarting MPD May 12 08:59:04 volumiobad sudo[17474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:59:04 volumiobad sudo[17474]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:04 volumiobad volumio[17353]: info: Output device has changed, restarting Shairport Sync May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:59:04 volumiobad sudo[17474]: pam_unix(sudo:session): session closed for user root May 12 08:59:04 volumiobad sudo[17476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:59:04 volumiobad sudo[17476]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:04 volumiobad systemd[1]: Stopping Music Player Daemon... May 12 08:59:04 volumiobad volumio[17353]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:59:04 volumiobad volumio[17353]: info: ___________ START PLUGINS ___________ May 12 08:59:04 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:59:04 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:59:04 volumiobad volumio[17353]: info: [1715504344500] CoreMusicLibrary::Adding element Media Servers May 12 08:59:04 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:59:04 volumiobad volumio[17353]: Cannot find translation for sourceMedia Servers May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:59:04 volumiobad volumio[17353]: info: [1715504344669] CoreMusicLibrary::Adding element Last_100 May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:59:04 volumiobad volumio[17353]: Cannot find translation for sourceMedia Servers May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 12 08:59:04 volumiobad volumio[17353]: info: [1715504344673] CoreMusicLibrary::Adding element Webradio May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:59:04 volumiobad volumio[17353]: Cannot find translation for sourceMedia Servers May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 08:59:04 volumiobad volumio[17353]: info: Loading i18n strings for locale de May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: FusionDsp - mixtype--------------------- Hardware May 12 08:59:04 volumiobad volumio[17353]: [SpotifyConnect] Creating VLS config file May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:04 volumiobad volumio[17353]: [SpotifyConnect] Starting metadata listener May 12 08:59:04 volumiobad sudo[17491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 12 08:59:04 volumiobad sudo[17491]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:04 volumiobad volumio[17353]: info: Volumio Calling Home May 12 08:59:04 volumiobad systemd[1]: Started Spotify Daemon. May 12 08:59:04 volumiobad sudo[17491]: pam_unix(sudo:session): session closed for user root May 12 08:59:04 volumiobad sudo[17495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service May 12 08:59:04 volumiobad sudo[17495]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:04 volumiobad sudo[17495]: pam_unix(sudo:session): session closed for user root May 12 08:59:04 volumiobad volumio[17497]: 2024-05-12 08:59:04 [SPTF] 08:59:04.857 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 12 08:59:04 volumiobad volumio[17497]: 2024-05-12 08:59:04 [SPTF] 08:59:04.873 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 12 08:59:04 volumiobad volumio[17353]: info: Preparing to generate the ALSA configuration file May 12 08:59:04 volumiobad volumio[17497]: 2024-05-12 08:59:04 [SPTF] 08:59:04.909 I [ap:1226] Connected to AP: 34.158.0.131:4070 May 12 08:59:05 volumiobad volumio[17353]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 12 08:59:05 volumiobad volumio[17353]: info: Reading ALSA contributions from plugins. May 12 08:59:05 volumiobad volumio[17353]: [SpotifyConnect] Vollibrespot Daemon service started! May 12 08:59:05 volumiobad volumio[17353]: [Metrics] SpotifyConnect: 0s 352.65ms May 12 08:59:05 volumiobad volumio[17353]: info: SpopD Daemon Started May 12 08:59:05 volumiobad volumio[17353]: info: MPD Permissions set May 12 08:59:05 volumiobad volumio[17353]: info: VolumeController:: Volume=37 Mute =false May 12 08:59:05 volumiobad volumio[17497]: 2024-05-12 08:59:05 [SPTF] 08:59:05.095 E [ap:3953] Connection error: 409 May 12 08:59:05 volumiobad volumio[17497]: 2024-05-12 08:59:05 [WARN] Login failed: User needs a premium account May 12 08:59:05 volumiobad volumio[17497]: 2024-05-12 08:59:05 [ERR ] Could not get the playlist container. May 12 08:59:05 volumiobad systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 12 08:59:05 volumiobad volumio[17353]: info: CoreStateMachine::pushState May 12 08:59:05 volumiobad systemd[1]: spop.service: Failed with result 'signal'. May 12 08:59:05 volumiobad volumio[17353]: info: CorePlayQueue::getTrack 0 May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::volumioPushState May 12 08:59:05 volumiobad volumio[17353]: info: Volumio called home May 12 08:59:05 volumiobad volumio[17353]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CALLMETHOD: user_interface websocket broadcastMessage [object Object] May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: websocket , broadcastMessage May 12 08:59:05 volumiobad volumio[17353]: info: Starting Shairport Sync May 12 08:59:05 volumiobad volumio[17353]: info: Starting Shairport Sync May 12 08:59:05 volumiobad sudo[17509]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:59:05 volumiobad volumio[17353]: info: Starting Shairport Sync May 12 08:59:05 volumiobad sudo[17509]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:05 volumiobad sudo[17511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:59:05 volumiobad sudo[17511]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:05 volumiobad sudo[17515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:59:05 volumiobad sudo[17515]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:05 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:59:05 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:59:05 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:59:05 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:59:05 volumiobad volumio[17353]: info: Asound.conf file unchanged, so no further update is needed May 12 08:59:05 volumiobad volumio[17353]: info: Output device has changed, restarting MPD May 12 08:59:05 volumiobad sudo[17509]: pam_unix(sudo:session): session closed for user root May 12 08:59:05 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:59:05 volumiobad systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 12 08:59:05 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:59:05 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:59:05 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:59:05 volumiobad sudo[17515]: pam_unix(sudo:session): session closed for user root May 12 08:59:05 volumiobad sudo[17511]: pam_unix(sudo:session): session closed for user root May 12 08:59:05 volumiobad sudo[17519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:59:05 volumiobad volumio[17353]: info: Output device has changed, restarting Shairport Sync May 12 08:59:05 volumiobad sudo[17519]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:59:05 volumiobad sudo[17522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:59:05 volumiobad sudo[17522]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:05 volumiobad sudo[17519]: pam_unix(sudo:session): session closed for user root May 12 08:59:05 volumiobad systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 12 08:59:05 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:59:05 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:59:05 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:59:05 volumiobad volumio[17353]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 12 08:59:05 volumiobad volumio[17353]: [SpotifyConnect] Creating VLS config file May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: MPD Permissions set May 12 08:59:05 volumiobad volumio[17353]: info: Shairport-Sync Started May 12 08:59:05 volumiobad volumio[17353]: Error adding Membership: Error: addMembership EINVAL May 12 08:59:05 volumiobad volumio[17353]: info: Shairport-Sync Started May 12 08:59:05 volumiobad volumio[17353]: info: Shairport-Sync Started May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::volumioGetState May 12 08:59:05 volumiobad volumio[17353]: info: CorePlayQueue::getTrack 0 May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:05 volumiobad volumio[17353]: info: Starting Shairport Sync May 12 08:59:05 volumiobad volumio[17353]: [SpotifyConnect] Restarting Vollibrespot Daemon May 12 08:59:05 volumiobad sudo[17537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 12 08:59:05 volumiobad sudo[17537]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:05 volumiobad sudo[17540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service May 12 08:59:05 volumiobad sudo[17540]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:05 volumiobad systemd[1]: Stopping Volspotconnect2 Daemon... May 12 08:59:05 volumiobad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 12 08:59:05 volumiobad systemd[1]: volspotconnect2.service: Main process exited, code=killed, status=15/TERM May 12 08:59:05 volumiobad systemd[1]: volspotconnect2.service: Succeeded. May 12 08:59:05 volumiobad systemd[1]: Stopped Volspotconnect2 Daemon. May 12 08:59:05 volumiobad systemd[1]: shairport-sync.service: Succeeded. May 12 08:59:05 volumiobad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 12 08:59:05 volumiobad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 12 08:59:05 volumiobad sudo[17546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service May 12 08:59:05 volumiobad sudo[17546]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:05 volumiobad sudo[17537]: pam_unix(sudo:session): session closed for user root May 12 08:59:05 volumiobad volumio[17353]: info: Shairport-Sync Started May 12 08:59:05 volumiobad systemd[1]: Started Volspotconnect2 Daemon. May 12 08:59:05 volumiobad sudo[17540]: pam_unix(sudo:session): session closed for user root May 12 08:59:05 volumiobad volumio[17549]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 May 12 08:59:05 volumiobad volumio[17353]: [SpotifyConnect] Vollibrespot Daemon service restarted! May 12 08:59:05 volumiobad volumio[17549]: Reading Config from "volspotify.toml" May 12 08:59:05 volumiobad volumio[17549]: [Vollibrespot] : Using Alsa backend with device: volumio May 12 08:59:06 volumiobad systemd[1]: Started Spotify Daemon. May 12 08:59:06 volumiobad sudo[17546]: pam_unix(sudo:session): session closed for user root May 12 08:59:06 volumiobad volumio[17353]: info: SpopD Daemon Started May 12 08:59:06 volumiobad volumio[17552]: 2024-05-12 08:59:06 [SPTF] 08:59:06.043 I [offline_authorizer.cpp:297] Unable to login offline: no such user May 12 08:59:06 volumiobad volumio[17552]: 2024-05-12 08:59:06 [SPTF] 08:59:06.051 I [ap:1752] Connecting to AP ap.spotify.com:4070 May 12 08:59:06 volumiobad volumio[17552]: 2024-05-12 08:59:06 [SPTF] 08:59:06.080 I [ap:1226] Connected to AP: 34.158.0.131:4070 May 12 08:59:06 volumiobad volumio[17549]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } May 12 08:59:06 volumiobad volumio[17552]: 2024-05-12 08:59:06 [SPTF] 08:59:06.247 E [ap:3953] Connection error: 409 May 12 08:59:06 volumiobad volumio[17552]: 2024-05-12 08:59:06 [WARN] Login failed: User needs a premium account May 12 08:59:06 volumiobad volumio[17552]: 2024-05-12 08:59:06 [ERR ] Could not get the playlist container. May 12 08:59:06 volumiobad systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 12 08:59:06 volumiobad systemd[1]: spop.service: Failed with result 'signal'. May 12 08:59:06 volumiobad volumio[17353]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 08:59:06 volumiobad volumio[17353]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo May 12 08:59:06 volumiobad volumio[17353]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 12 08:59:06 volumiobad volumio[17353]: info: camilladsp spawned new process with pid undefined, instance 1, run: true May 12 08:59:06 volumiobad volumio[17353]: info: camilladsp service started and running in background, instance 1 May 12 08:59:06 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:06 volumiobad volumio[17353]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 08:59:06 volumiobad volumio[17353]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 12 08:59:06 volumiobad volumio[17353]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 12 08:59:06 volumiobad volumio[17353]: info: FusionDsp loaded May 12 08:59:06 volumiobad volumio[17353]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 12 08:59:06 volumiobad sudo[17566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 12 08:59:06 volumiobad sudo[17566]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:06 volumiobad volumio[17353]: info: FusionDsp - Reporting Fusion DSP Enabled May 12 08:59:06 volumiobad volumio[17353]: info: FusionDsp - ---- installed callbackRead May 12 08:59:06 volumiobad volumio[17353]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:59:06 volumiobad sudo[17566]: pam_unix(sudo:session): session closed for user root May 12 08:59:06 volumiobad volumio[17353]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 12 08:59:06 volumiobad volumio[17353]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 12 08:59:06 volumiobad volumio[17353]: at onErrorNT (internal/child_process.js:465:16) May 12 08:59:06 volumiobad volumio[17353]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { May 12 08:59:06 volumiobad volumio[17353]: errno: -2, May 12 08:59:06 volumiobad volumio[17353]: code: 'ENOENT', May 12 08:59:06 volumiobad volumio[17353]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 12 08:59:06 volumiobad volumio[17353]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 12 08:59:06 volumiobad volumio[17353]: spawnargs: [ May 12 08:59:06 volumiobad volumio[17353]: '-p', May 12 08:59:06 volumiobad volumio[17353]: 9876, May 12 08:59:06 volumiobad volumio[17353]: '-o', May 12 08:59:06 volumiobad volumio[17353]: '/tmp/camilladsp.log', May 12 08:59:06 volumiobad volumio[17353]: '-l', May 12 08:59:06 volumiobad volumio[17353]: 'warn', May 12 08:59:06 volumiobad volumio[17353]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 12 08:59:06 volumiobad volumio[17353]: ] May 12 08:59:06 volumiobad volumio[17353]: } May 12 08:59:06 volumiobad volumio[17353]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:59:06 volumiobad mpd[17544]: May 12 08:59 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 08:59:06 volumiobad mpd[17544]: May 12 08:59 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 12 08:59:06 volumiobad mpd[17544]: May 12 08:59 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 12 08:59:06 volumiobad systemd[1]: Started Music Player Daemon. May 12 08:59:06 volumiobad sudo[17476]: pam_unix(sudo:session): session closed for user root May 12 08:59:06 volumiobad sudo[17522]: pam_unix(sudo:session): session closed for user root May 12 08:59:07 volumiobad sudo[17579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-12 08:58 May 12 08:59:07 volumiobad sudo[17579]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:07 volumiobad sudo[17579]: pam_unix(sudo:session): session closed for user root May 12 08:59:07 volumiobad systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 12 08:59:07 volumiobad systemd[1]: volumio.service: Failed with result 'exit-code'. May 12 08:59:07 volumiobad systemd[1]: Started dynamicswap service. May 12 08:59:07 volumiobad systemd[1]: dynamicswap.service: Succeeded. May 12 08:59:08 volumiobad systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 12 08:59:08 volumiobad systemd[1]: volumio.service: Scheduled restart job, restart counter is at 51. May 12 08:59:08 volumiobad systemd[1]: Stopped Volumio Backend Module. May 12 08:59:08 volumiobad systemd[1]: Started Volumio Backend Module. May 12 08:59:08 volumiobad systemd[1]: Started dynamicswap service. May 12 08:59:08 volumiobad systemd[1]: dynamicswap.service: Succeeded. May 12 08:59:09 volumiobad volumio[17589]: info: ------------------------------------------- May 12 08:59:09 volumiobad volumio[17589]: info: ----- Volumio3 ---- May 12 08:59:09 volumiobad volumio[17589]: info: ------------------------------------------- May 12 08:59:09 volumiobad volumio[17589]: info: ----- System startup ---- May 12 08:59:09 volumiobad volumio[17589]: info: ------------------------------------------- May 12 08:59:10 volumiobad volumio[17589]: info: MYVOLUMIO Environment detected May 12 08:59:10 volumiobad volumio[17589]: info: Plugin folders cleanup May 12 08:59:10 volumiobad volumio[17589]: info: Scanning into folder /volumio/app/plugins/ May 12 08:59:10 volumiobad volumio[17589]: info: Scanning category audio_interface May 12 08:59:10 volumiobad volumio[17589]: info: Scanning category miscellanea May 12 08:59:10 volumiobad volumio[17589]: info: Scanning category music_service May 12 08:59:10 volumiobad volumio[17589]: info: Scanning category plugins.json May 12 08:59:10 volumiobad volumio[17589]: info: Scanning category system_controller May 12 08:59:10 volumiobad volumio[17589]: info: Scanning category user_interface May 12 08:59:10 volumiobad volumio[17589]: info: Scanning into folder /data/plugins/ May 12 08:59:10 volumiobad volumio[17589]: info: Scanning category audio_interface May 12 08:59:10 volumiobad volumio[17589]: info: Scanning category music_service May 12 08:59:10 volumiobad volumio[17589]: info: Plugin folders cleanup completed May 12 08:59:10 volumiobad volumio[17589]: info: ------------------------------------------- May 12 08:59:10 volumiobad volumio[17589]: info: ----- Core plugins startup ---- May 12 08:59:10 volumiobad volumio[17589]: info: ------------------------------------------- May 12 08:59:10 volumiobad volumio[17589]: info: Loading plugins from folder /volumio/app/plugins/ May 12 08:59:10 volumiobad volumio[17589]: info: Adding plugin upnp to MyMusic Plugins May 12 08:59:10 volumiobad volumio[17589]: info: Adding plugin airplay_emulation to MyMusic Plugins May 12 08:59:10 volumiobad volumio[17589]: info: Adding plugin upnp_browser to MyMusic Plugins May 12 08:59:10 volumiobad volumio[17589]: info: Loading plugins from folder /data/plugins/ May 12 08:59:10 volumiobad volumio[17589]: info: Loading plugin "system"... May 12 08:59:10 volumiobad volumio[17589]: info: Loading plugin "appearance"... May 12 08:59:11 volumiobad volumio[17589]: info: Loading plugin "network"... May 12 08:59:11 volumiobad volumio[17589]: info: Refreshing Cached IP Addresses May 12 08:59:11 volumiobad sudo[17614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 12 08:59:11 volumiobad sudo[17614]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:11 volumiobad volumio[17589]: info: Loading plugin "services"... May 12 08:59:11 volumiobad sudo[17614]: pam_unix(sudo:session): session closed for user root May 12 08:59:11 volumiobad volumio[17589]: info: Loading plugin "alsa_controller"... May 12 08:59:11 volumiobad sudo[17617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 12 08:59:11 volumiobad sudo[17617]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:11 volumiobad sudo[17617]: pam_unix(sudo:session): session closed for user root May 12 08:59:11 volumiobad volumio[17589]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 12 08:59:11 volumiobad volumio[17589]: info: Loading plugin "wizard"... May 12 08:59:11 volumiobad volumio[17589]: info: Loading plugin "volumio_command_line_client"... May 12 08:59:11 volumiobad volumio[17589]: info: Loading plugin "upnp"... May 12 08:59:11 volumiobad volumio[17589]: info: [1715504351960] Starting Upmpd Daemon May 12 08:59:11 volumiobad volumio[17589]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:59:11 volumiobad volumio[17589]: info: Loading plugin "my_music"... May 12 08:59:11 volumiobad volumio[17589]: info: Loading plugin "mpd"... May 12 08:59:12 volumiobad volumio[17589]: info: Creating MPD Configuration file May 12 08:59:12 volumiobad volumio[17589]: info: Loading plugin "upnp_browser"... May 12 08:59:12 volumiobad sudo[17624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 12 08:59:12 volumiobad sudo[17624]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:12 volumiobad sudo[17624]: pam_unix(sudo:session): session closed for user root May 12 08:59:12 volumiobad sudo[17626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 12 08:59:12 volumiobad sudo[17626]: pam_unix(sudo:session): session opened for user root by (uid=0) May 12 08:59:12 volumiobad systemd[1]: Stopping Music Player Daemon... May 12 08:59:12 volumiobad systemd[1]: mpd.service: Succeeded. May 12 08:59:12 volumiobad systemd[1]: Stopped Music Player Daemon. May 12 08:59:12 volumiobad systemd[1]: Starting Music Player Daemon... May 12 08:59:12 volumiobad volumio[17589]: info: Loading plugin "networkfs"... May 12 08:59:12 volumiobad volumio[17589]: info: Starting Udev Watcher for removable devices May 12 08:59:13 volumiobad volumio[17589]: info: Ignoring mount for partition: boot May 12 08:59:13 volumiobad volumio[17589]: info: Ignoring mount for partition: volumio May 12 08:59:13 volumiobad volumio[17589]: info: Ignoring mount for partition: volumio_data May 12 08:59:13 volumiobad volumio[17589]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:59:13 volumiobad volumio[17589]: info: Loading plugin "alarm-clock"... May 12 08:59:13 volumiobad volumio[17589]: info: Loading plugin "airplay_emulation"... May 12 08:59:13 volumiobad volumio[17589]: info: Starting Shairport Sync May 12 08:59:13 volumiobad volumio[17589]: info: Loading plugin "last_100"... May 12 08:59:13 volumiobad volumio[17589]: info: Loading plugin "webradio"... May 12 08:59:13 volumiobad volumio[17589]: info: Loading plugin "i2s_dacs"... May 12 08:59:13 volumiobad volumio[17589]: info: Loading plugin "volumiodiscovery"... May 12 08:59:13 volumiobad volumio[17589]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 08:59:13 volumiobad volumio[17589]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:59:13 volumiobad node[17589]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 12 08:59:13 volumiobad volumio[17589]: *** WARNING *** For more information see May 12 08:59:13 volumiobad volumio[17589]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 08:59:13 volumiobad volumio[17589]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:59:13 volumiobad volumio[17589]: *** WARNING *** For more information see May 12 08:59:13 volumiobad node[17589]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:59:13 volumiobad node[17589]: *** WARNING *** For more information see May 12 08:59:13 volumiobad node[17589]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 12 08:59:13 volumiobad node[17589]: *** WARNING *** Please fix your application to use the native API of Avahi! May 12 08:59:13 volumiobad node[17589]: *** WARNING *** For more information see May 12 08:59:13 volumiobad volumio[17589]: info: Applying required configuration parameters for plugin volumiodiscovery May 12 08:59:13 volumiobad volumio[17589]: info: Discovery: Started advertising with name: Volumiobad May 12 08:59:13 volumiobad volumio[17589]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 12 08:59:13 volumiobad volumio[17589]: info: Loading plugin "outputs"... May 12 08:59:13 volumiobad volumio[17589]: info: Loading plugin "albumart"... May 12 08:59:13 volumiobad volumio[17589]: info: Plugin example_plugin is not enabled May 12 08:59:13 volumiobad volumio[17589]: info: Loading plugin "inputs"... May 12 08:59:13 volumiobad volumio[17589]: info: Loading plugin "updater_comm"... May 12 08:59:13 volumiobad mpd[17631]: May 12 08:59 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 12 08:59:13 volumiobad mpd[17631]: May 12 08:59 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 12 08:59:13 volumiobad mpd[17631]: May 12 08:59 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 12 08:59:13 volumiobad systemd[1]: Started Music Player Daemon. May 12 08:59:13 volumiobad sudo[17626]: pam_unix(sudo:session): session closed for user root May 12 08:59:13 volumiobad volumio[17589]: info: Plugin mpdemulation is not enabled May 12 08:59:13 volumiobad volumio[17589]: info: Loading plugin "rest_api"... May 12 08:59:14 volumiobad volumio[17589]: info: Loading plugin "websocket"... May 12 08:59:14 volumiobad volumio[17589]: info: Loading plugin "fusiondsp"... May 12 08:59:14 volumiobad volumio[17589]: Forking 3 albumart workers May 12 08:59:14 volumiobad volumio[17589]: info: Applying required configuration parameters for plugin fusiondsp May 12 08:59:14 volumiobad volumio[17589]: info: Loading plugin "spop"... May 12 08:59:15 volumiobad volumio[17589]: Starting albumart workers May 12 08:59:15 volumiobad volumio[17589]: Starting albumart workers May 12 08:59:15 volumiobad volumio[17589]: Starting albumart workers May 12 08:59:15 volumiobad volumio[17589]: info: Loading plugin "volspotconnect2"... May 12 08:59:15 volumiobad volumio[17589]: info: Loading i18n strings for locale de May 12 08:59:16 volumiobad volumio[17589]: Updating browse sources language May 12 08:59:16 volumiobad volumio[17589]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 12 08:59:16 volumiobad volumio[17589]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:59:16 volumiobad volumio[17589]: info: CoreCommandRouter::initPlayerControls May 12 08:59:16 volumiobad volumio[17589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:16 volumiobad volumio[17589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:16 volumiobad volumio[17589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:16 volumiobad volumio[17589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:16 volumiobad volumio[17589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:16 volumiobad volumio[17589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:16 volumiobad volumio[17589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:16 volumiobad volumio[17589]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 12 08:59:16 volumiobad volumio[17589]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 12 08:59:16 volumiobad volumio[17589]: Express server listening on port 3000 May 12 08:59:16 volumiobad volumio[17589]: [Metrics] WebUI: 6s 746.92ms May 12 08:59:16 volumiobad volumio[17589]: info: CoreStateMachine::resetVolumioState May 12 08:59:16 volumiobad volumio[17589]: info: CoreStateMachine::getcurrentVolume May 12 08:59:16 volumiobad volumio[17589]: info: CoreCommandRouter::volumioRetrievevolume May 12 08:59:16 volumiobad volumio[17589]: info: MPD Permissions set May 12 08:59:16 volumiobad volumio[17589]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 1 May 12 08:59:16 volumiobad volumio[17589]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:59:16 volumiobad volumio[17589]: Error: write ECONNRESET May 12 08:59:16 volumiobad volumio[17589]: at afterWriteDispatched (internal/stream_base_commons.js:156:25) May 12 08:59:16 volumiobad volumio[17589]: at writeGeneric (internal/stream_base_commons.js:147:3) May 12 08:59:16 volumiobad volumio[17589]: at Socket._writeGeneric (net.js:785:11) May 12 08:59:16 volumiobad volumio[17589]: at Socket._write (net.js:797:8) May 12 08:59:16 volumiobad volumio[17589]: at writeOrBuffer (internal/streams/writable.js:358:12) May 12 08:59:16 volumiobad volumio[17589]: at Socket.Writable.write (internal/streams/writable.js:303:10) May 12 08:59:16 volumiobad volumio[17589]: at abortConnection (/volumio/node_modules/engine.io/lib/server.js:471:12) May 12 08:59:16 volumiobad volumio[17589]: at /volumio/node_modules/engine.io/lib/server.js:325:7 May 12 08:59:16 volumiobad volumio[17589]: at Server.verify (/volumio/node_modules/engine.io/lib/server.js:141:14) May 12 08:59:16 volumiobad volumio[17589]: at Server.handleUpgrade (/volumio/node_modules/engine.io/lib/server.js:323:8) May 12 08:59:16 volumiobad volumio[17589]: at Server. (/volumio/node_modules/engine.io/lib/server.js:443:14) May 12 08:59:16 volumiobad volumio[17589]: at Server.emit (events.js:315:20) May 12 08:59:16 volumiobad volumio[17589]: at onParserExecuteCommon (_http_server.js:695:14) May 12 08:59:16 volumiobad volumio[17589]: at onParserExecute (_http_server.js:608:3) { May 12 08:59:16 volumiobad volumio[17589]: errno: -104, May 12 08:59:16 volumiobad volumio[17589]: code: 'ECONNRESET', May 12 08:59:16 volumiobad volumio[17589]: syscall: 'write' May 12 08:59:16 volumiobad volumio[17589]: } May 12 08:59:16 volumiobad volumio[17589]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 12 08:59:16 volumiobad sudo[17704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-12 08:58 May 12 08:59:16 volumiobad sudo[17704]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"