-- Logs begin at Thu 2019-02-14 06:11:59 AST, end at Fri 2025-05-09 22:53:05 AST. -- May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:00 volumio volumio[877]: info: FusionDsp - May 09 22:52:00 volumio volumio[877]: info: FusionDsp - May 09 22:52:00 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:00 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 22:52:00 volumio volumio[877]: info: Received Get System Info May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:52:00 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:00 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:52:00 volumio sudo[1374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 22:52:00 volumio sudo[1372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 22:52:00 volumio sudo[1374]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:00 volumio sudo[1372]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:00 volumio sudo[1374]: pam_unix(sudo:session): session closed for user root May 09 22:52:00 volumio sudo[1372]: pam_unix(sudo:session): session closed for user root May 09 22:52:00 volumio volumio[877]: verbose: New Socket.io Connection to 10.0.3.5 from 10.0.2.61 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 7 May 09 22:52:00 volumio volumio[877]: info: Received Get System Info May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:52:00 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:00 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:00 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::volumioGetVisibleSources May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:00 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 09 22:52:00 volumio volumio[877]: info: Received Get System Info May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:52:00 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:00 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:52:00 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:00 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:01 volumio volumio[877]: info: Listing playlists May 09 22:52:01 volumio volumio[877]: info: Received Get System Info May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:52:01 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:01 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:01 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:01 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 09 22:52:01 volumio volumio[877]: info: FusionDsp - May 09 22:52:01 volumio volumio[877]: info: FusionDsp - May 09 22:52:01 volumio volumio[877]: info: FusionDsp - May 09 22:52:01 volumio volumio[877]: info: FusionDsp - May 09 22:52:01 volumio volumio[877]: info: FusionDsp - May 09 22:52:02 volumio volumio[877]: info: FusionDsp - May 09 22:52:02 volumio volumio[877]: info: FusionDsp - May 09 22:52:02 volumio volumio[877]: info: FusionDsp - May 09 22:52:02 volumio volumio[877]: info: FusionDsp - May 09 22:52:02 volumio volumio[877]: info: AutoStart - Plugin is starting May 09 22:52:02 volumio volumio[877]: info: CoreCommandRouter::volumioGetQueue May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::getQueue May 09 22:52:02 volumio volumio[877]: info: CorePlayQueue::getQueue May 09 22:52:02 volumio volumio[877]: info: AutoStart - start playing May 09 22:52:02 volumio volumio[877]: info: AutoStart - start playing with no specific position May 09 22:52:02 volumio volumio[877]: info: CoreCommandRouter::volumioPlay May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::play index 0 May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::stop May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::play index undefined May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::setConsumeUpdateService undefined May 09 22:52:02 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:02 volumio volumio[877]: info: CoreStateMachine::startPlaybackTimer May 09 22:52:02 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:02 volumio volumio[877]: info: [1746845522928] ControllerSpotify::clearAddPlayTrack May 09 22:52:02 volumio volumio[877]: info: Sending Spotify command with payload to local API: /player/play May 09 22:52:02 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:02 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:03 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 09 22:52:03 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 09 22:52:03 volumio volumio[877]: info: MyVolumio token set successfully May 09 22:52:03 volumio volumio[877]: info: MYVOLUMIO: Adding device May 09 22:52:03 volumio volumio[877]: info: MYVOLUMIO: Evaluating Server May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="resolved context of track" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=trace msg="emitting websocket event: will_play" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1114" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="selected format OGG_VORBIS_320 (53a551ea83c7919a86d1616c0c36ea3cb185d62e)" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:03 volumio go-librespot[1182]: time="2025-05-09T22:52:03-04:00" level=debug msg="requested aes key for file 53a551ea83c7919a86d1616c0c36ea3cb185d62e, gid: 7DSAEUvxU8FajXtRloy8M0" May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=debug msg="fetched first chunk of 15, total size is 7862240 bytes" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=trace msg="seek to 0ms (diff: 0ms, samples: 0, bytes: 0)" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:04 volumio volumio[877]: info: MyVolumio status changed May 09 22:52:04 volumio volumio[877]: info: Streaming services startup May 09 22:52:04 volumio volumio[877]: info: Starting Streaming Daemon May 09 22:52:04 volumio volumio[877]: info: Removing browser output: myVolumio user plan is not superstar May 09 22:52:04 volumio volumio[877]: info: Removing audio output: May 09 22:52:04 volumio volumio[877]: info: Stoppping Tunnel 1 May 09 22:52:04 volumio sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 09 22:52:04 volumio sudo[1433]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:04 volumio volumio[877]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7DSAEUvxU8FajXtRloy8M0","play_origin":"go-librespot"}} May 09 22:52:04 volumio sudo[1433]: pam_unix(sudo:session): session closed for user root May 09 22:52:04 volumio sudo[1436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 09 22:52:04 volumio sudo[1436]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=debug msg="created new output device" May 09 22:52:04 volumio volumio[877]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 09 22:52:04 volumio volumio[877]: info: Received Get System Version May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 22:52:04 volumio volumio[877]: info: Received Get System Info May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 09 22:52:04 volumio volumio[877]: info: Discovery: Getting this device information May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=info msg="loaded track \"Flowers\" (paused: false, position: 0ms, duration: 200600ms, prefetched: false)" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:04 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=debug msg="fetched chunk 1/14, size: 524288" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:04 volumio sudo[1436]: pam_unix(sudo:session): session closed for user root May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:52:04 volumio volumio[877]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 09 22:52:04 volumio volumio[877]: info: FusionDsp - ---- read samplerate from file: 44100 May 09 22:52:04 volumio volumio[877]: info: camilladsp stopping service pid 1235... May 09 22:52:04 volumio volumio[877]: info: camilladsp service terminated, instance 1 May 09 22:52:04 volumio volumio[877]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 22:52:04 volumio volumio[877]: info: FusionDsp - crossfeed jameier May 09 22:52:04 volumio volumio[877]: info: FusionDsp - Loudness is ON true May 09 22:52:04 volumio volumio[877]: info: camilladsp service started and running in background, instance 1 May 09 22:52:04 volumio volumio[877]: info: Remote SSH Stopped May 09 22:52:04 volumio volumio[877]: error: Cannot start Volumio Streaming Daemon May 09 22:52:04 volumio volumio[877]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 09 22:52:04 volumio volumio[877]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:04 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:04 volumio volumio[877]: info: Setting Geolocation for MyVolumio to us1 May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:04 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=debug msg="fetched chunk 3/14, size: 524288" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:04 volumio volumio[877]: info: Listing playlists May 09 22:52:04 volumio volumio[877]: info: Listing playlists May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=trace msg="scheduling prefetch in 170s" May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=trace msg="emitting websocket event: metadata" May 09 22:52:04 volumio volumio[877]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7DSAEUvxU8FajXtRloy8M0","name":"Flowers","artist_names":["Miley Cyrus"],"album_name":"Endless Summer Vacation","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02cd222052a2594be29a6616b5","position":0,"duration":200600,"release_date":"year:2023 month:8 day:18","track_number":1,"disc_number":1}} May 09 22:52:04 volumio go-librespot[1182]: time="2025-05-09T22:52:04-04:00" level=debug msg="fetched chunk 2/14, size: 524288" uri="spotify:track:7DSAEUvxU8FajXtRloy8M0" May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:05 volumio go-librespot[1182]: time="2025-05-09T22:52:05-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 09 22:52:05 volumio go-librespot[1182]: time="2025-05-09T22:52:05-04:00" level=trace msg="emitting websocket event: playing" May 09 22:52:05 volumio volumio[877]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7DSAEUvxU8FajXtRloy8M0","play_origin":"go-librespot"}} May 09 22:52:05 volumio volumio[877]: SPOTIFY: PUSH STATE SPOTIFY May 09 22:52:05 volumio volumio[877]: SPOTIFY: {"status":"play","service":"spop","title":"Flowers","artist":"Miley Cyrus","album":"Endless Summer Vacation","albumart":"https://i.scdn.co/image/ab67616d00001e02cd222052a2594be29a6616b5","uri":"spotify:track:7DSAEUvxU8FajXtRloy8M0","trackType":"spotify","seek":0,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::servicePushState May 09 22:52:05 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:05 volumio volumio[877]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Flowers","artist":"Miley Cyrus","album":"Endless Summer Vacation","albumart":"https://i.scdn.co/image/ab67616d00001e02cd222052a2594be29a6616b5","uri":"spotify:track:7DSAEUvxU8FajXtRloy8M0","trackType":"spotify","seek":0,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 09 22:52:05 volumio volumio[877]: verbose: CURRENT POSITION 0 May 09 22:52:05 volumio volumio[877]: info: CoreStateMachine::syncState stateService play May 09 22:52:05 volumio volumio[877]: info: CoreStateMachine::syncState currentStatus stop May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::getUIConfigOnPlugin May 09 22:52:05 volumio volumio[877]: info: FusionDsp - May 09 22:52:05 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:05 volumio volumio[877]: info: Successfully Added MyVolumio device May 09 22:52:05 volumio volumio[877]: info: FusionDsp - May 09 22:52:05 volumio volumio[877]: info: FusionDsp - May 09 22:52:05 volumio volumio[877]: SPOTIFY: PUSH STATE SPOTIFY May 09 22:52:05 volumio volumio[877]: SPOTIFY: {"status":"play","service":"spop","title":"Flowers","artist":"Miley Cyrus","album":"Endless Summer Vacation","albumart":"https://i.scdn.co/image/ab67616d00001e02cd222052a2594be29a6616b5","uri":"spotify:track:7DSAEUvxU8FajXtRloy8M0","trackType":"spotify","seek":0,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::servicePushState May 09 22:52:05 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:05 volumio volumio[877]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Flowers","artist":"Miley Cyrus","album":"Endless Summer Vacation","albumart":"https://i.scdn.co/image/ab67616d00001e02cd222052a2594be29a6616b5","uri":"spotify:track:7DSAEUvxU8FajXtRloy8M0","trackType":"spotify","seek":0,"duration":200,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} May 09 22:52:05 volumio volumio[877]: verbose: CURRENT POSITION 0 May 09 22:52:05 volumio volumio[877]: info: CoreStateMachine::syncState stateService play May 09 22:52:05 volumio volumio[877]: info: CoreStateMachine::syncState currentStatus play May 09 22:52:05 volumio volumio[877]: info: Received an update from plugin. extracting info from payload May 09 22:52:05 volumio volumio[877]: info: CoreStateMachine::pushState May 09 22:52:05 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::volumioPushState May 09 22:52:05 volumio volumio[877]: info: CoreStateMachine::pushState May 09 22:52:05 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::volumioPushState May 09 22:52:05 volumio volumio[877]: info: Updating MyVolumio device info May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:05 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:05 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:05 volumio volumio[877]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json May 09 22:52:06 volumio volumio[877]: info: PeppyMeterBasic ---peppymeterbasic status play May 09 22:52:06 volumio volumio[877]: info: PeppyMeterBasic ---peppymeterbasic status play May 09 22:52:06 volumio sudo[1465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 09 22:52:06 volumio sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:06 volumio sudo[1468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service May 09 22:52:06 volumio sudo[1468]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:06 volumio volumio[877]: info: FusionDsp - volume level for loudness 91 gain applied 0.00 May 09 22:52:06 volumio volumio[877]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 22:52:06 volumio volumio[877]: info: FusionDsp - crossfeed jameier May 09 22:52:06 volumio volumio[877]: info: FusionDsp - Loudness is ON true May 09 22:52:06 volumio volumio[877]: info: FusionDsp - volume level for loudness 91 gain applied 0.00 May 09 22:52:06 volumio volumio[877]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 22:52:06 volumio volumio[877]: info: FusionDsp - crossfeed jameier May 09 22:52:06 volumio volumio[877]: info: FusionDsp - Loudness is ON true May 09 22:52:06 volumio volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 09 22:52:06 volumio volumio[877]: SPOTIFY: RECEIVED VOLUMIO VOLUME 91 May 09 22:52:06 volumio systemd[1]: Started peppymeterbasic Daemon. May 09 22:52:06 volumio sudo[1465]: pam_unix(sudo:session): session closed for user root May 09 22:52:06 volumio sudo[1468]: pam_unix(sudo:session): session closed for user root May 09 22:52:06 volumio volumio[877]: info: peppymeterbasic Daemon Started May 09 22:52:06 volumio volumio[877]: info: peppymeterbasic Daemon Started May 09 22:52:06 volumio volumio[877]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 09 22:52:06 volumio volumio[877]: info: FusionDsp - {"Reload":{"result":"Ok"}} May 09 22:52:07 volumio volumio[877]: info: Successfully Updated MyVolumio device May 09 22:52:08 volumio volumio[1470]: pygame 1.9.4.post1 May 09 22:52:08 volumio volumio[1470]: Hello from the pygame community. https://www.pygame.org/contribute.html May 09 22:52:08 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 09 22:52:08 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 09 22:52:08 volumio volumio[877]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 09 22:52:10 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 09 22:52:11 volumio volumio[877]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 09 22:52:11 volumio volumio[877]: info: FusionDsp - Stopping FusionDsp service May 09 22:52:11 volumio volumio[877]: info: camilladsp stopping service pid 1442... May 09 22:52:11 volumio volumio[877]: info: camilladsp service terminated, instance 1 May 09 22:52:11 volumio sudo[1485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service May 09 22:52:11 volumio sudo[1485]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:11 volumio systemd[1]: Stopping FusionDsp Daemon... May 09 22:52:11 volumio volumio[1243]: Backend configuration: May 09 22:52:11 volumio volumio[1243]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 09 22:52:11 volumio volumio[1243]: active_config_txt: null May 09 22:52:11 volumio volumio[1243]: camilla_host: 127.0.0.1 May 09 22:52:11 volumio volumio[1243]: camilla_port: 9876 May 09 22:52:11 volumio volumio[1243]: coeff_dir: /data/INTERNAL/FusionDsp/filters May 09 22:52:11 volumio volumio[1243]: config_dir: /data/configuration/audio_interface/fusiondsp May 09 22:52:11 volumio volumio[1243]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml May 09 22:52:11 volumio volumio[1243]: log_file: /tmp/camilladsp.log May 09 22:52:11 volumio volumio[1243]: on_get_active_config: null May 09 22:52:11 volumio volumio[1243]: on_set_active_config: null May 09 22:52:11 volumio volumio[1243]: port: 5011 May 09 22:52:11 volumio volumio[1243]: supported_capture_types: null May 09 22:52:11 volumio volumio[1243]: supported_playback_types: null May 09 22:52:11 volumio volumio[1243]: update_config_symlink: false May 09 22:52:11 volumio volumio[1243]: update_config_txt: false May 09 22:52:11 volumio volumio[1243]: ======== Running on http://0.0.0.0:5011 ======== May 09 22:52:11 volumio volumio[1243]: (Press CTRL+C to quit) May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 09 22:52:11 volumio volumio-remote-updater[587]: No test mode May 09 22:52:11 volumio volumio-remote-updater[587]: No alpha test mode May 09 22:52:11 volumio volumio[877]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:11 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:11 volumio systemd[1]: fusiondsp.service: Succeeded. May 09 22:52:11 volumio systemd[1]: Stopped FusionDsp Daemon. May 09 22:52:11 volumio sudo[1485]: pam_unix(sudo:session): session closed for user root May 09 22:52:11 volumio volumio[877]: info: FusionDsp - Reporting Fusion DSP Disabled May 09 22:52:11 volumio volumio[877]: info: Removing fusiondspeq DSP Signal Path Element May 09 22:52:11 volumio volumio[877]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 09 22:52:11 volumio volumio[877]: info: Check plugin dependencies May 09 22:52:11 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 22:52:11 volumio volumio[877]: info: Rename folder May 09 22:52:11 volumio volumio[877]: info: Folder /tmp/downloaded_plugin.zip removed May 09 22:52:11 volumio volumio[877]: info: Move to category May 09 22:52:12 volumio ntpd[849]: Soliciting pool server 23.186.168.127 May 09 22:52:13 volumio volumio[877]: info: Checking if install.sh is present May 09 22:52:13 volumio volumio[877]: info: Executing install.sh May 09 22:52:13 volumio sudo[1502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh May 09 22:52:13 volumio sudo[1502]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:13 volumio volumio[877]: info: creating filters folder and copying demo filters May 09 22:52:13 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:13 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:13 volumio volumio[877]: info: copying demo flters May 09 22:52:14 volumio volumio[877]: info: ./presets/ May 09 22:52:14 volumio volumio[877]: info: ./presets/2XEQ15/ May 09 22:52:14 volumio volumio[877]: info: ./presets/2XEQ15/.Bass-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: ./presets/2XEQ15/.Soundtrack-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: ./presets/2XEQ15/.Classic-FusionDsp.json May 09 22:52:14 volumio sudo[1527]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt update May 09 22:52:14 volumio volumio[877]: info: ./presets/2XEQ15/.Voice-FusionDsp.json May 09 22:52:14 volumio sudo[1527]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:14 volumio volumio[877]: info: ./presets/2XEQ15/.Rock-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: ./presets/PEQ/ May 09 22:52:14 volumio volumio[877]: info: ./presets/convfir/ May 09 22:52:14 volumio volumio[877]: info: ./presets/convfir/kef.json May 09 22:52:14 volumio volumio[877]: info: ./presets/convfir/test.json May 09 22:52:14 volumio volumio[877]: info: ./presets/EQ15/ May 09 22:52:14 volumio volumio[877]: info: ./presets/EQ15/.Bass-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: ./presets/EQ15/.Soundtrack-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: ./presets/EQ15/.Classic-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: ./presets/EQ15/.Voice-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: ./presets/EQ15/.Rock-FusionDsp.json May 09 22:52:14 volumio volumio[877]: info: Installing/fusiondsp dependencies May 09 22:52:15 volumio volumio[877]: info: Hit:1 http://archive.raspberrypi.org/debian buster InRelease May 09 22:52:15 volumio volumio[877]: info: Hit:2 https://deb.nodesource.com/node_14.x buster InRelease May 09 22:52:15 volumio ntpd[849]: Soliciting pool server 24.249.17.250 May 09 22:52:15 volumio ntpd[849]: Soliciting pool server 108.61.56.35 May 09 22:52:15 volumio volumio[877]: info: Hit:3 http://raspbian.raspberrypi.org/raspbian buster InRelease May 09 22:52:16 volumio ntpd[849]: Soliciting pool server 23.142.248.9 May 09 22:52:16 volumio ntpd[849]: Soliciting pool server 204.2.134.172 May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::volumioGetBrowseSources May 09 22:52:16 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 22:52:17 volumio ntpd[849]: Soliciting pool server 45.83.234.123 May 09 22:52:17 volumio ntpd[849]: Soliciting pool server 66.118.228.14 May 09 22:52:18 volumio volumio[877]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 09 22:52:18 volumio volumio[877]: info: Completed starting MyVolumio Plugin May 09 22:52:18 volumio volumio[877]: [Metrics] CommandRouter: 66s 456.84ms May 09 22:52:18 volumio volumio[877]: info: CoreCommandRouter::volumiosetStartupVolume May 09 22:52:18 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:52:18 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:52:18 volumio volumio[877]: info: CoreCommandRouter::Close All Modals sent May 09 22:52:18 volumio volumio[877]: info: CoreCommandRouter::Close All Modals sent May 09 22:52:18 volumio volumio[877]: info: FusionDsp - ---- read samplerate, raw: May 09 22:52:18 volumio volumio[877]: error: FusionDsp - invalid sample rate May 09 22:52:18 volumio volumio[877]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 09 22:52:18 volumio volumio[877]: info: FusionDsp - ---- read samplerate from file: 44100 May 09 22:52:18 volumio volumio[877]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 09 22:52:18 volumio volumio[877]: info: FusionDsp - crossfeed jameier May 09 22:52:18 volumio volumio[877]: info: FusionDsp - Loudness is ON true May 09 22:52:18 volumio volumio[877]: error: FusionDsp - WebSocket error: [object Object] May 09 22:52:18 volumio ntpd[849]: Soliciting pool server 73.65.80.137 May 09 22:52:18 volumio ntpd[849]: Soliciting pool server 206.226.67.233 May 09 22:52:19 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 09 22:52:19 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 09 22:52:19 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 09 22:52:19 volumio ntpd[849]: Soliciting pool server 207.244.103.95 May 09 22:52:19 volumio ntpd[849]: Soliciting pool server 2606:82c0:21::e May 09 22:52:20 volumio ntpd[849]: Soliciting pool server 23.186.168.125 May 09 22:52:21 volumio volumio[877]: info: Reading package lists... May 09 22:52:22 volumio volumio[877]: info: Building dependency tree... May 09 22:52:22 volumio volumio[877]: info: Reading state information... May 09 22:52:22 volumio volumio[877]: info: All packages are up to date. May 09 22:52:22 volumio sudo[1527]: pam_unix(sudo:session): session closed for user root May 09 22:52:23 volumio sudo[1745]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip May 09 22:52:23 volumio sudo[1745]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:23 volumio volumio[877]: info: Reading package lists... May 09 22:52:23 volumio volumio[877]: info: CoreCommandRouter::volumioGetState May 09 22:52:23 volumio volumio[877]: info: CorePlayQueue::getTrack 0 May 09 22:52:23 volumio volumio[877]: info: Listing playlists May 09 22:52:23 volumio volumio[877]: info: Listing playlists May 09 22:52:25 volumio ntpd[849]: receive: Unexpected origin timestamp 0xebc93de8.2cd28b9f does not match aorg 0000000000.00000000 from server@206.226.67.233 xmt 0xebc93de9.0b015000 May 09 22:52:25 volumio volumio[877]: info: Building dependency tree... May 09 22:52:25 volumio volumio[877]: info: Reading state information... May 09 22:52:25 volumio ntpd[849]: receive: Unexpected origin timestamp 0xebc93de8.2cd665cf does not match aorg 0000000000.00000000 from server@45.83.234.123 xmt 0xebc93de9.13350e94 May 09 22:52:25 volumio ntpd[849]: receive: Unexpected origin timestamp 0xebc93de8.2cd4bdb0 does not match aorg 0000000000.00000000 from server@66.118.228.14 xmt 0xebc93de9.2107f392 May 09 22:52:25 volumio volumio[877]: info: BOOT COMPLETED May 09 22:52:26 volumio volumio[877]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 09 22:52:26 volumio volumio[877]: info: python3-aiohttp is already the newest version (3.5.1-1+deb10u1). May 09 22:52:26 volumio sudo[1745]: pam_unix(sudo:session): session closed for user root May 09 22:52:26 volumio volumio[877]: info: python3-pip is already the newest version (18.1-5+rpt1). May 09 22:52:26 volumio volumio[877]: info: 0 upgraded, 0 newly installed, 0 to remove and 2 not upgraded. May 09 22:52:26 volumio sudo[1763]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / May 09 22:52:26 volumio sudo[1763]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:26 volumio volumio[877]: info: lib/systemd/system/fusiondsp.service May 09 22:52:26 volumio sudo[1763]: pam_unix(sudo:session): session closed for user root May 09 22:52:29 volumio volumio[877]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant May 09 22:52:29 volumio volumio[877]: info: more info at http://www.winimage.com/zLibDll/unzip.html May 09 22:52:29 volumio volumio[877]: info: May 09 22:52:29 volumio volumio[877]: info: cgui-1.0.0.zip opened May 09 22:52:29 volumio volumio[877]: info: creating directory: cgui/ May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/LICENSE.txt May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/README.md May 09 22:52:29 volumio sudo[1767]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui May 09 22:52:29 volumio volumio[877]: info: creating directory: cgui/build/ May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/robots.txt May 09 22:52:29 volumio sudo[1767]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/logo512.png May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/asset-manifest.json May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/index.html May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/.put_statics_here May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/favicon.ico May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/logo192.png May 09 22:52:29 volumio sudo[1767]: pam_unix(sudo:session): session closed for user root May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/.xdp-css-variables-1.css-PvrWoX May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/manifest.json May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/css-variables.css May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/precache-manifest.76db5aad1647230454f47b1e22783b54.js May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/service-worker.js May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/precache-manifest.5e6c675b70ef6a3fe73d0209febfe347.js May 09 22:52:29 volumio volumio[877]: info: creating directory: cgui/build/static/ May 09 22:52:29 volumio volumio[877]: info: creating directory: cgui/build/static/css/ May 09 22:52:29 volumio sudo[1769]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css May 09 22:52:29 volumio sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/main.d51d005e.chunk.css.map May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css.map May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/main.56bdf14e.chunk.css.map May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/main.1503f6cd.css.map May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css.map May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/main.1503f6cd.css May 09 22:52:29 volumio sudo[1769]: pam_unix(sudo:session): session closed for user root May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/2.975d6916.chunk.css May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/css/2.a4c7e7cf.chunk.css May 09 22:52:29 volumio volumio[877]: info: creating directory: cgui/build/static/media/ May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/media/camilladsp.95719842.svg May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/media/camilladsp.4767ea4ad3510467418b818dc677a87e.svg May 09 22:52:29 volumio volumio[877]: info: creating directory: cgui/build/static/js/ May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.map May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/js/main.71e11955.js May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.LICENSE.txt May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js May 09 22:52:29 volumio volumio[877]: info: extracting: cgui/build/static/js/main.61bb6809.chunk.js.map May 09 22:52:30 volumio sudo[1771]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip May 09 22:52:30 volumio sudo[1771]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js.map May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/main.71e11955.js.LICENSE.txt May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/main.71e11955.js.map May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/runtime-main.422581ec.js.map May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/main.1b3ac690.chunk.js May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/2.c1b9df8b.chunk.js.map May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/build/static/js/2.fd49ece5.chunk.js.LICENSE.txt May 09 22:52:30 volumio volumio[877]: info: creating directory: cgui/config/ May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/config/camillagui.yml May 09 22:52:30 volumio sudo[1771]: pam_unix(sudo:session): session closed for user root May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/config/.xdp-css-variables-1.css-ppBDuX May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/config/gui-config.yml May 09 22:52:30 volumio volumio[877]: info: creating directory: cgui/backend/ May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/filters_test.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/version.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/filters.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/settings.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/views.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/filterdefaults.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__init__.py May 09 22:52:30 volumio volumio[877]: info: creating directory: cgui/backend/__pycache__/ May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/filterdefaults.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/views.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/settings.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/__init__.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/routes.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/filemanagement.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/filters.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/__pycache__/version.cpython-37.pyc May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/routes.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/backend/filemanagement.py May 09 22:52:30 volumio volumio[877]: info: extracting: cgui/main.py May 09 22:52:30 volumio volumio[877]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 09 22:52:32 volumio volumio[877]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf May 09 22:52:32 volumio volumio[877]: info: FusionDsp - Stopping FusionDsp service May 09 22:52:32 volumio volumio[877]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 22:52:32 volumio volumio[877]: TypeError: Cannot read property 'stop' of null May 09 22:52:32 volumio volumio[877]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:109:23) May 09 22:52:32 volumio volumio[877]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) May 09 22:52:32 volumio volumio[877]: at /volumio/app/pluginmanager.js:956:12 May 09 22:52:32 volumio volumio[877]: at ChildProcess.exithandler (child_process.js:299:7) May 09 22:52:32 volumio volumio[877]: at ChildProcess.emit (events.js:315:20) May 09 22:52:32 volumio volumio[877]: at maybeClose (internal/child_process.js:1048:16) May 09 22:52:32 volumio volumio[877]: at Process.ChildProcess._handle.onexit (internal/child_process.js:288:5) May 09 22:52:32 volumio volumio[877]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 22:52:33 volumio volumio[1134]: ...............................................................................................................................................................................................................................................................................................................................................++++ May 09 22:52:33 volumio volumio[1134]: e is 65537 (0x010001) May 09 22:52:33 volumio volumio[1134]: writing RSA key May 09 22:52:33 volumio sudo[1803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-09 22:51 May 09 22:52:33 volumio sudo[1803]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:33 volumio sudo[1803]: pam_unix(sudo:session): session closed for user root May 09 22:52:34 volumio volumio-remote-updater[587]: [2025-05-09 22:52:34] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 09 22:52:34 volumio volumio-remote-updater[587]: [2025-05-09 22:52:34] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 09 22:52:34 volumio go-librespot[1182]: time="2025-05-09T22:52:34-04:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" May 09 22:52:35 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 09 22:52:35 volumio sudo[1502]: pam_unix(sudo:session): session closed for user root May 09 22:52:35 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 09 22:52:35 volumio systemd[1]: Started dynamicswap service. May 09 22:52:35 volumio systemd[1]: dynamicswap.service: Succeeded. May 09 22:52:35 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 09 22:52:35 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. May 09 22:52:35 volumio systemd[1]: Started dynamicswap service. May 09 22:52:35 volumio systemd[1]: Stopped Volumio Backend Module. May 09 22:52:35 volumio systemd[1]: Started Volumio Backend Module. May 09 22:52:35 volumio systemd[1]: dynamicswap.service: Succeeded. May 09 22:52:37 volumio volumio[1818]: info: ------------------------------------------- May 09 22:52:37 volumio volumio[1818]: info: ----- Volumio3 ---- May 09 22:52:37 volumio volumio[1818]: info: ------------------------------------------- May 09 22:52:37 volumio volumio[1818]: info: ----- System startup ---- May 09 22:52:37 volumio volumio[1818]: info: ------------------------------------------- May 09 22:52:38 volumio volumio[1818]: info: MYVOLUMIO Environment detected May 09 22:52:39 volumio volumio[1818]: info: Plugin folders cleanup May 09 22:52:39 volumio volumio[1818]: info: Scanning into folder /volumio/app/plugins/ May 09 22:52:39 volumio volumio[1818]: info: Scanning category audio_interface May 09 22:52:39 volumio volumio[1818]: info: Scanning category miscellanea May 09 22:52:39 volumio volumio[1818]: info: Scanning category music_service May 09 22:52:39 volumio volumio[1818]: info: Scanning category plugins.json May 09 22:52:39 volumio volumio[1818]: info: Scanning category system_controller May 09 22:52:39 volumio volumio[1818]: info: Scanning category user_interface May 09 22:52:39 volumio volumio[1818]: info: Scanning into folder /data/plugins/ May 09 22:52:39 volumio volumio[1818]: info: Scanning category audio_interface May 09 22:52:39 volumio volumio[1818]: info: Scanning category music_service May 09 22:52:39 volumio volumio[1818]: info: Scanning category system_controller May 09 22:52:39 volumio volumio[1818]: info: Scanning category user_interface May 09 22:52:39 volumio volumio[1818]: info: Plugin folders cleanup completed May 09 22:52:39 volumio volumio[1818]: info: ------------------------------------------- May 09 22:52:39 volumio volumio[1818]: info: ----- Core plugins startup ---- May 09 22:52:39 volumio volumio[1818]: info: ------------------------------------------- May 09 22:52:39 volumio volumio[1818]: info: Loading plugins from folder /volumio/app/plugins/ May 09 22:52:39 volumio volumio[1818]: info: Adding plugin upnp to MyMusic Plugins May 09 22:52:39 volumio volumio[1818]: info: Adding plugin airplay_emulation to MyMusic Plugins May 09 22:52:39 volumio volumio[1818]: info: Adding plugin upnp_browser to MyMusic Plugins May 09 22:52:39 volumio volumio[1818]: info: Loading plugins from folder /data/plugins/ May 09 22:52:39 volumio volumio[1818]: info: Loading plugin "system"... May 09 22:52:39 volumio volumio[1818]: info: Loading plugin "appearance"... May 09 22:52:39 volumio volumio-remote-updater[587]: [2025-05-09 22:52:39] [connect] Successful connection May 09 22:52:40 volumio volumio[1818]: info: Loading plugin "network"... May 09 22:52:40 volumio volumio[1818]: info: Refreshing Cached IP Addresses May 09 22:52:40 volumio sudo[1844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 22:52:40 volumio sudo[1844]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:40 volumio sudo[1844]: pam_unix(sudo:session): session closed for user root May 09 22:52:40 volumio sudo[1846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 22:52:40 volumio sudo[1846]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:52:40 volumio volumio[1818]: info: Loading plugin "services"... May 09 22:52:40 volumio sudo[1846]: pam_unix(sudo:session): session closed for user root May 09 22:52:40 volumio volumio[1818]: info: Loading plugin "alsa_controller"... May 09 22:52:40 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 22:52:40 volumio volumio[1818]: info: Loading plugin "wizard"... May 09 22:52:40 volumio volumio[1818]: info: Loading plugin "networkfs"... May 09 22:52:40 volumio volumio[1818]: info: Cannot mount NAS gntruenas-01 at system boot, trial number 1 ,retrying in 5 seconds May 09 22:52:40 volumio volumio[1818]: info: Starting Udev Watcher for removable devices May 09 22:52:40 volumio volumio[1818]: info: Ignoring mount for partition: boot May 09 22:52:40 volumio volumio[1818]: info: Ignoring mount for partition: volumio May 09 22:52:40 volumio volumio[1818]: info: Ignoring mount for partition: volumio_data May 09 22:52:40 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 22:52:40 volumio volumio[1818]: info: Loading plugin "volumio_command_line_client"... May 09 22:52:40 volumio volumio[1818]: info: Loading plugin "upnp"... May 09 22:52:40 volumio volumio[1818]: info: [1746845560779] Starting Upmpd Daemon May 09 22:52:40 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 22:52:40 volumio volumio[1818]: info: Loading plugin "my_music"... May 09 22:52:40 volumio volumio[1818]: info: Loading plugin "mpd"... May 09 22:52:41 volumio volumio[1818]: info: Loading plugin "upnp_browser"... May 09 22:52:41 volumio volumio[1818]: info: Loading plugin "alarm-clock"... May 09 22:52:41 volumio volumio[1818]: info: Loading plugin "airplay_emulation"... May 09 22:52:41 volumio volumio[1818]: info: Starting Shairport Sync May 09 22:52:41 volumio volumio[1818]: info: Loading plugin "last_100"... May 09 22:52:41 volumio volumio[1818]: info: Loading plugin "webradio"... May 09 22:52:42 volumio volumio[1818]: info: Loading plugin "i2s_dacs"... May 09 22:52:42 volumio volumio[1818]: info: Loading plugin "volumiodiscovery"... May 09 22:52:42 volumio volumio[1818]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 22:52:42 volumio volumio[1818]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 22:52:42 volumio volumio[1818]: *** WARNING *** For more information see May 09 22:52:42 volumio volumio[1818]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 22:52:42 volumio volumio[1818]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 22:52:42 volumio node[1818]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 09 22:52:42 volumio volumio[1818]: *** WARNING *** For more information see May 09 22:52:42 volumio node[1818]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 22:52:42 volumio node[1818]: *** WARNING *** For more information see May 09 22:52:42 volumio node[1818]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 09 22:52:42 volumio node[1818]: *** WARNING *** Please fix your application to use the native API of Avahi! May 09 22:52:42 volumio node[1818]: *** WARNING *** For more information see May 09 22:52:42 volumio volumio[1818]: info: Applying required configuration parameters for plugin volumiodiscovery May 09 22:52:42 volumio volumio[1818]: info: Discovery: Started advertising with name: Volumio May 09 22:52:42 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 09 22:52:42 volumio volumio[1818]: info: Loading plugin "mixcloud"... May 09 22:52:43 volumio volumio[1818]: info: Loading plugin "spop"... May 09 22:52:44 volumio volumio[1818]: info: Loading plugin "youtube2"... May 09 22:52:48 volumio volumio[1818]: info: Loading plugin "ytcr"... May 09 22:52:52 volumio volumio-remote-updater[587]: [2025-05-09 22:52:52] [connect] Successful connection May 09 22:52:54 volumio volumio[1818]: info: Loading plugin "ytmusic"... May 09 22:52:57 volumio volumio[1818]: info: Loading plugin "autostart"... May 09 22:52:57 volumio volumio[1818]: info: Applying required configuration parameters for plugin autostart May 09 22:52:57 volumio volumio[1818]: info: AutoStart - onVolumioStart - read config.json May 09 22:52:57 volumio volumio[1818]: info: Loading plugin "outputs"... May 09 22:52:57 volumio volumio[1818]: info: Loading plugin "albumart"... May 09 22:52:57 volumio volumio[1818]: info: Plugin example_plugin is not enabled May 09 22:52:57 volumio volumio[1818]: info: Loading plugin "inputs"... May 09 22:52:57 volumio volumio[1818]: info: Loading plugin "updater_comm"... May 09 22:52:57 volumio volumio[1818]: info: Plugin mpdemulation is not enabled May 09 22:52:57 volumio volumio[1818]: info: Loading plugin "rest_api"... May 09 22:52:57 volumio volumio[1818]: info: Loading plugin "websocket"... May 09 22:52:57 volumio volumio[1818]: info: Starting Socket.io Server version 2.3.0 May 09 22:52:57 volumio volumio[1818]: info: Loading plugin "fusiondsp"... May 09 22:52:57 volumio volumio[1818]: Forking 3 albumart workers May 09 22:52:58 volumio volumio[1818]: info: Applying required configuration parameters for plugin fusiondsp May 09 22:52:58 volumio volumio[1818]: info: Loading plugin "80s80s"... May 09 22:52:58 volumio volumio[1818]: info: Applying required configuration parameters for plugin 80s80s May 09 22:52:58 volumio volumio[1818]: info: [1746845578557] [80s80s] API delay: 30 May 09 22:52:58 volumio volumio[1818]: info: Loading plugin "motherearthradio"... May 09 22:52:58 volumio volumio[1818]: info: Applying required configuration parameters for plugin motherearthradio May 09 22:52:58 volumio volumio[1818]: info: [1746845578878] [MotherEarth] API delay: 5 May 09 22:52:58 volumio volumio[1818]: info: Loading plugin "personal_radio"... May 09 22:52:59 volumio volumio[1818]: Starting albumart workers May 09 22:52:59 volumio volumio[1818]: Starting albumart workers May 09 22:52:59 volumio volumio[1818]: Starting albumart workers May 09 22:52:59 volumio volumio[1818]: info: ControllerPersonalRadio::constructor May 09 22:52:59 volumio volumio[1818]: info: Loading plugin "podcast"... May 09 22:52:59 volumio volumio[1818]: info: ControllerPodcast::constructor May 09 22:52:59 volumio volumio[1818]: info: Loading plugin "rad357pl"... May 09 22:53:00 volumio volumio[1818]: info: Applying required configuration parameters for plugin rad357pl May 09 22:53:00 volumio volumio[1818]: info: [1746845580132] [rad357pl] API delay: 5 May 09 22:53:00 volumio volumio[1818]: info: Loading plugin "radio_paradise"... May 09 22:53:00 volumio volumio[1818]: info: Applying required configuration parameters for plugin radio_paradise May 09 22:53:00 volumio volumio[1818]: info: [1746845580338] [RadioParadise] API delay: 5 May 09 22:53:00 volumio volumio[1818]: info: Loading plugin "backup_restore"... May 09 22:53:00 volumio volumio[1818]: info: Applying required configuration parameters for plugin backup_restore May 09 22:53:00 volumio volumio[1818]: info: Loading plugin "peppymeterbasic"... May 09 22:53:00 volumio volumio[1818]: info: Loading i18n strings for locale en May 09 22:53:00 volumio volumio[1818]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. May 09 22:53:00 volumio volumio[1818]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. May 09 22:53:00 volumio volumio[1818]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. May 09 22:53:00 volumio volumio[1818]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. May 09 22:53:00 volumio volumio[1818]: Updating browse sources language May 09 22:53:00 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::initPlayerControls May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 22:53:01 volumio volumio[1818]: Express server listening on port 3000 May 09 22:53:01 volumio volumio[1818]: [Metrics] WebUI: 24s 220.00ms May 09 22:53:01 volumio volumio[1818]: info: CoreStateMachine::resetVolumioState May 09 22:53:01 volumio volumio[1818]: info: CoreStateMachine::getcurrentVolume May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::volumioRetrievevolume May 09 22:53:01 volumio volumio[1818]: info: Cannot mount NAS gntruenas-01 at system boot, trial number 2 ,retrying in 5 seconds May 09 22:53:01 volumio sudo[1958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 09 22:53:01 volumio sudo[1958]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:01 volumio sudo[1958]: pam_unix(sudo:session): session closed for user root May 09 22:53:01 volumio sudo[1961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 09 22:53:01 volumio sudo[1961]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:01 volumio sudo[1961]: pam_unix(sudo:session): session closed for user root May 09 22:53:01 volumio volumio[1818]: info: Volumio Network Manager: Network status updated: 1 May 09 22:53:01 volumio volumio[1818]: verbose: New Socket.io Connection to 10.0.3.5 from 10.0.2.61 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 1 May 09 22:53:01 volumio volumio[1818]: verbose: New Socket.io Connection to 10.0.3.5 from 10.0.2.61 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 2 May 09 22:53:01 volumio volumio[1818]: verbose: New Socket.io Connection to 10.0.3.5 from 10.0.2.61 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 3 May 09 22:53:01 volumio volumio[1818]: verbose: New Socket.io Connection to 10.0.3.5 from 10.0.2.61 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 4 May 09 22:53:01 volumio volumio[1818]: verbose: New Socket.io Connection to 10.0.3.5 from 10.0.2.61 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.5 Mobile/15E148 Safari/604.1 Engine version: 3 Transport: polling Total Clients: 5 May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::volumioGetState May 09 22:53:01 volumio volumio[1818]: info: CorePlayQueue::getTrack 0 May 09 22:53:01 volumio volumio[1818]: info: VolumeController:: Volume=91 Mute =false May 09 22:53:01 volumio volumio[1818]: info: CoreStateMachine::pushState May 09 22:53:01 volumio volumio[1818]: info: CorePlayQueue::getTrack 0 May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::volumioPushState May 09 22:53:01 volumio volumio[1818]: info: CoreStateMachine::updateTrackBlock May 09 22:53:01 volumio volumio[1818]: info: CorePlayQueue::getTrackBlock May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::volumioRetrievevolume May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:53:01 volumio volumio[1818]: info: Reloading queue from file May 09 22:53:01 volumio volumio[1818]: info: CoreStateMachine::setRepeat null single undefined May 09 22:53:01 volumio volumio[1818]: info: CoreStateMachine::pushState May 09 22:53:01 volumio volumio[1818]: info: CorePlayQueue::getTrack 0 May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::volumioPushState May 09 22:53:01 volumio volumio[1818]: info: CoreStateMachine::setRandom true May 09 22:53:01 volumio volumio[1818]: info: CoreStateMachine::pushState May 09 22:53:01 volumio volumio[1818]: info: CorePlayQueue::getTrack 0 May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::volumioPushState May 09 22:53:01 volumio volumio[1818]: info: Setting Device type: Raspberry PI May 09 22:53:01 volumio volumio[1818]: info: Listing playlists May 09 22:53:01 volumio volumio[1818]: info: Listing playlists May 09 22:53:01 volumio volumio[1818]: info: Completed loading Core Plugins May 09 22:53:01 volumio volumio[1818]: info: Preparing to generate the ALSA configuration file May 09 22:53:01 volumio volumio[1818]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 09 22:53:01 volumio volumio[1818]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 09 22:53:01 volumio volumio[1818]: info: Reading ALSA contributions from plugins. May 09 22:53:01 volumio volumio[1818]: info: VolumeController:: Volume=91 Mute =false May 09 22:53:01 volumio volumio[1818]: info: CoreStateMachine::pushState May 09 22:53:01 volumio volumio[1818]: info: CorePlayQueue::getTrack 0 May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::volumioPushState May 09 22:53:01 volumio sudo[1972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 09 22:53:01 volumio volumio[1818]: info: Asound.conf file unchanged, so no further update is needed May 09 22:53:01 volumio sudo[1972]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:01 volumio volumio[1818]: info: Output device has changed, restarting MPD May 09 22:53:01 volumio sudo[1972]: pam_unix(sudo:session): session closed for user root May 09 22:53:01 volumio sudo[1975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 22:53:01 volumio sudo[1975]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:01 volumio sudo[1975]: pam_unix(sudo:session): session closed for user root May 09 22:53:01 volumio volumio[1818]: info: Output device has changed, restarting Shairport Sync May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:01 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:53:01 volumio sudo[1978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 22:53:01 volumio sudo[1978]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:01 volumio systemd[1]: Stopping Music Player Daemon... May 09 22:53:01 volumio volumio[1818]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 22:53:01 volumio volumio[1818]: info: ___________ START PLUGINS ___________ May 09 22:53:01 volumio volumio[1818]: info: ControllerMpd::onStart: Initializing MPD May 09 22:53:01 volumio volumio[1818]: info: Creating MPD Configuration file May 09 22:53:02 volumio sudo[1984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 22:53:02 volumio sudo[1984]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:02 volumio sudo[1984]: pam_unix(sudo:session): session closed for user root May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:53:02 volumio volumio[1818]: info: [1746845582060] CoreMusicLibrary::Adding element Media Servers May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:53:02 volumio sudo[1987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 22:53:02 volumio sudo[1987]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:53:02 volumio volumio[1818]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:53:02 volumio systemd[1]: mpd.service: Succeeded. May 09 22:53:02 volumio systemd[1]: Stopped Music Player Daemon. May 09 22:53:02 volumio volumio[1818]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:53:02 volumio volumio[1818]: info: [1746845582231] CoreMusicLibrary::Adding element Last_100 May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:53:02 volumio volumio[1818]: info: [1746845582235] CoreMusicLibrary::Adding element Webradio May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 22:53:02 volumio volumio[1818]: info: Initializing BBC Radios May 09 22:53:02 volumio systemd[1]: Starting Music Player Daemon... May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:53:02 volumio volumio[1818]: info: [1746845582369] CoreMusicLibrary::Adding element Mixcloud May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Mixcloud May 09 22:53:02 volumio volumio[1818]: info: Creating Spotify config file May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:02 volumio sudo[1993]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 09 22:53:02 volumio sudo[1993]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:02 volumio sudo[1993]: pam_unix(sudo:session): session closed for user root May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:53:02 volumio volumio[1818]: info: [1746845582491] CoreMusicLibrary::Adding element YouTube2 May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Mixcloud May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source YouTube2 May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:53:02 volumio volumio[1818]: info: [1746845582554] CoreMusicLibrary::Adding element YouTube Music May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Mixcloud May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source YouTube2 May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source YouTube Music May 09 22:53:02 volumio volumio[1818]: info: AutoStart - onStart May 09 22:53:02 volumio volumio[1818]: info: Loading i18n strings for locale en May 09 22:53:02 volumio volumio[1818]: info: Plugin peppymeterbasic has duplicated i18n key APPLY. It is ignored. May 09 22:53:02 volumio volumio[1818]: info: Plugin peppymeterbasic has duplicated i18n key SAVE. It is ignored. May 09 22:53:02 volumio volumio[1818]: info: Plugin peppymeterbasic has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. May 09 22:53:02 volumio volumio[1818]: info: Plugin peppymeterbasic has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:02 volumio volumio[1818]: info: FusionDsp - mixtype--------------------- Hardware May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:53:02 volumio volumio[1818]: info: [1746845582587] CoreMusicLibrary::Adding element 80s80s Radio May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Mixcloud May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source YouTube2 May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source YouTube Music May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source 80s80s Radio May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:53:02 volumio volumio[1818]: info: [1746845582594] CoreMusicLibrary::Adding element Mother Earth Radio May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Mixcloud May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source YouTube2 May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source YouTube Music May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source 80s80s Radio May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Mother Earth Radio May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:53:02 volumio volumio[1818]: info: [1746845582617] CoreMusicLibrary::Adding element Personal Radio May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Mixcloud May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source YouTube2 May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source YouTube Music May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source 80s80s Radio May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Mother Earth Radio May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Personal Radio May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:53:02 volumio volumio[1818]: info: [1746845582627] CoreMusicLibrary::Adding element Podcast May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Mixcloud May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source YouTube2 May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source YouTube Music May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source 80s80s Radio May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Mother Earth Radio May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Personal Radio May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Podcast May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:53:02 volumio volumio[1818]: info: [1746845582634] CoreMusicLibrary::Adding element RADIO 357 May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Mixcloud May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source YouTube2 May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source YouTube Music May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source 80s80s Radio May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Mother Earth Radio May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Personal Radio May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Podcast May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source RADIO 357 May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 09 22:53:02 volumio volumio[1818]: info: [1746845582641] CoreMusicLibrary::Adding element Radio Paradise May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Mixcloud May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source YouTube2 May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source YouTube Music May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source 80s80s Radio May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Mother Earth Radio May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Personal Radio May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Podcast May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source RADIO 357 May 09 22:53:02 volumio volumio[1818]: Cannot find translation for source Radio Paradise May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 09 22:53:02 volumio volumio[1818]: info: Volumio Calling Home May 09 22:53:02 volumio volumio[1818]: info: Preparing to generate the ALSA configuration file May 09 22:53:02 volumio volumio[1818]: info: Discovery: adding 7b26da82-260d-49d7-98f7-2b0f4d86ca3e May 09 22:53:02 volumio volumio[1818]: info: Discovery: Found device Volumio May 09 22:53:02 volumio volumio[1818]: info: CoreCommandRouter::volumioGetState May 09 22:53:02 volumio volumio[1818]: info: CorePlayQueue::getTrack 0 May 09 22:53:02 volumio volumio[1818]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 09 22:53:02 volumio volumio[1818]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 09 22:53:02 volumio volumio[1818]: info: Reading ALSA contributions from plugins. May 09 22:53:02 volumio volumio[1818]: info: MPD Permissions set May 09 22:53:02 volumio volumio[1818]: info: MPD Permissions set May 09 22:53:02 volumio volumio[1818]: info: Upmpdcli Daemon Started May 09 22:53:02 volumio volumio[1818]: info: Spotify config file written May 09 22:53:03 volumio volumio[1818]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 May 09 22:53:03 volumio volumio[1818]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio sudo[2012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 09 22:53:03 volumio sudo[2012]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:03 volumio systemd[1]: Stopping go-librespot Daemon... May 09 22:53:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM May 09 22:53:03 volumio systemd[1]: go-librespot-daemon.service: Succeeded. May 09 22:53:03 volumio systemd[1]: Stopped go-librespot Daemon. May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio systemd[1]: Started go-librespot Daemon. May 09 22:53:03 volumio go-librespot[2018]: Librespot-go daemon starting... May 09 22:53:03 volumio sudo[2012]: pam_unix(sudo:session): session closed for user root May 09 22:53:03 volumio go-librespot[2018]: time="2025-05-09T22:53:03-04:00" level=info msg="generated new device id: fbe31c2392c690d3b4e4135229a47e4dbc66650d" May 09 22:53:03 volumio go-librespot[2018]: time="2025-05-09T22:53:03-04:00" level=debug msg="stored credentials found for higiniog13" May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:03 volumio volumio[1818]: info: No need to fix Spotify hosts May 09 22:53:03 volumio go-librespot[2018]: time="2025-05-09T22:53:03-04:00" level=debug msg="fetched new accesspoints: [ap-guc3.spotify.com:4070 ap-guc3.spotify.com:443 ap-guc3.spotify.com:80 ap-gae2.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" May 09 22:53:03 volumio go-librespot[2018]: time="2025-05-09T22:53:03-04:00" level=debug msg="fetched new dealers: [guc3-dealer.spotify.com:443 gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" May 09 22:53:03 volumio go-librespot[2018]: time="2025-05-09T22:53:03-04:00" level=debug msg="fetched new spclients: [guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" May 09 22:53:03 volumio go-librespot[2018]: time="2025-05-09T22:53:03-04:00" level=debug msg="zeroconf server listening on port 34659" May 09 22:53:03 volumio volumio[1818]: info: Volumio called home May 09 22:53:03 volumio go-librespot[2018]: time="2025-05-09T22:53:03-04:00" level=debug msg="obtained new client token: AADIOW0MFQsv6NAhcMNyDIZEpB+fi9F/utDIRCbkY6R8JVOgBEYcxtFC4Ez/Nm6xL+Cjx06QKVKbmWDstR63JQNn0jelJZjxfMkQhQBQR+mLgUk6DrbM325tTsKrhXGzU6/w9hNMcFk+uQo8FH1clNyqCQ1XptMYIifpSqifOS9mcOyULMZJIUbDY7R9Kxw2ZsGT3DJtGsEQP3bZNWHd8J46arghRaYWmCmmk1+M1H3fetHNpDpA0CJXjnPi8A==" May 09 22:53:03 volumio volumio[1818]: info: Starting Shairport Sync May 09 22:53:03 volumio volumio[1818]: info: Starting Shairport Sync May 09 22:53:03 volumio go-librespot[2018]: time="2025-05-09T22:53:03-04:00" level=info msg="connected to ap-guc3.spotify.com:4070" May 09 22:53:03 volumio sudo[2031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 22:53:03 volumio sudo[2031]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:03 volumio volumio[1818]: info: Starting Shairport Sync May 09 22:53:03 volumio sudo[2034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 22:53:03 volumio sudo[2034]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 22:53:03 volumio systemd[1]: shairport-sync.service: Succeeded. May 09 22:53:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 22:53:03 volumio go-librespot[2018]: time="2025-05-09T22:53:03-04:00" level=debug msg="completed keyexchange" May 09 22:53:04 volumio sudo[2038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 22:53:04 volumio sudo[2038]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::volumioGetState May 09 22:53:04 volumio volumio[1818]: info: CorePlayQueue::getTrack 0 May 09 22:53:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 22:53:04 volumio sudo[2031]: pam_unix(sudo:session): session closed for user root May 09 22:53:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 22:53:04 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 09 22:53:04 volumio systemd[1]: shairport-sync.service: Succeeded. May 09 22:53:04 volumio volumio[1818]: (node:1818) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. May 09 22:53:04 volumio volumio[1818]: (Use `node --trace-deprecation ...` to show where the warning was created) May 09 22:53:04 volumio volumio[1818]: (node:1818) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. May 09 22:53:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 22:53:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 22:53:04 volumio sudo[2034]: pam_unix(sudo:session): session closed for user root May 09 22:53:04 volumio sudo[2038]: pam_unix(sudo:session): session closed for user root May 09 22:53:04 volumio volumio[1818]: info: Shairport-Sync Started May 09 22:53:04 volumio volumio[1818]: Error adding Membership: Error: addMembership EINVAL May 09 22:53:04 volumio volumio[1818]: info: Asound.conf file unchanged, so no further update is needed May 09 22:53:04 volumio volumio[1818]: info: Output device has changed, restarting MPD May 09 22:53:04 volumio sudo[2044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 22:53:04 volumio sudo[2044]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:04 volumio sudo[2044]: pam_unix(sudo:session): session closed for user root May 09 22:53:04 volumio volumio[1818]: info: Output device has changed, restarting Shairport Sync May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:53:04 volumio sudo[2047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 22:53:04 volumio sudo[2047]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 09 22:53:04 volumio systemd[1]: mpd.service: Succeeded. May 09 22:53:04 volumio systemd[1]: Stopped Music Player Daemon. May 09 22:53:04 volumio volumio[1818]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 22:53:04 volumio volumio[1818]: info: Preparing to generate the ALSA configuration file May 09 22:53:04 volumio volumio[1818]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 09 22:53:04 volumio volumio[1818]: SPOTIFY: BQBdLFDsYUYBVwUxmmvI9N3AsnijZkz1oCUksK_bw8SllmhW7IwXq_lAWlIDV2PP1fEXtKMa_TymFLADj_s0zixkisZYj4wTYcuv3TkOI0UQOKymdvwYdGoujW-ZthMsXgXrcIz2rhvg7Vz8jZEjlYb23fKw50yNu1COX65B2WaOHUy3Ocl8biDq2424A9vayjld-ti3o85V_ftlMUxNU2SDTBfiuEX06zDaf3Nx65wCOHfru6cjgWnkM_emvuOErD3xJ4qyMZS07C3ONbxjlhC99Ag May 09 22:53:04 volumio volumio[1818]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 09 22:53:04 volumio volumio[1818]: info: New Spotify access token = BQBdLFDsYUYBVwUxmmvI9N3AsnijZkz1oCUksK_bw8SllmhW7IwXq_lAWlIDV2PP1fEXtKMa_TymFLADj_s0zixkisZYj4wTYcuv3TkOI0UQOKymdvwYdGoujW-ZthMsXgXrcIz2rhvg7Vz8jZEjlYb23fKw50yNu1COX65B2WaOHUy3Ocl8biDq2424A9vayjld-ti3o85V_ftlMUxNU2SDTBfiuEX06zDaf3Nx65wCOHfru6cjgWnkM_emvuOErD3xJ4qyMZS07C3ONbxjlhC99Ag May 09 22:53:04 volumio volumio[1818]: info: Spotify credentials grant success - running version from March 24, 2019 May 09 22:53:04 volumio systemd[1]: Starting Music Player Daemon... May 09 22:53:04 volumio volumio[1818]: info: Shairport-Sync Started May 09 22:53:04 volumio volumio[1818]: info: Shairport-Sync Started May 09 22:53:04 volumio volumio[1818]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 09 22:53:04 volumio volumio[1818]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf May 09 22:53:04 volumio volumio[1818]: info: Reading ALSA contributions from plugins. May 09 22:53:04 volumio volumio[1818]: info: MPD Permissions set May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:04 volumio go-librespot[2018]: time="2025-05-09T22:53:04-04:00" level=debug msg="completed challenge" May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:04 volumio sudo[2052]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 09 22:53:04 volumio sudo[2052]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:04 volumio sudo[2052]: pam_unix(sudo:session): session closed for user root May 09 22:53:04 volumio volumio[1818]: info: Starting Shairport Sync May 09 22:53:04 volumio go-librespot[2018]: time="2025-05-09T22:53:04-04:00" level=debug msg="authenticated as higiniog13" May 09 22:53:04 volumio volumio[1818]: info: Asound.conf file unchanged, so no further update is needed May 09 22:53:04 volumio volumio[1818]: info: Output device has changed, restarting MPD May 09 22:53:04 volumio sudo[2061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 09 22:53:04 volumio sudo[2061]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:04 volumio sudo[2064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 09 22:53:04 volumio sudo[2064]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 09 22:53:04 volumio sudo[2064]: pam_unix(sudo:session): session closed for user root May 09 22:53:04 volumio systemd[1]: shairport-sync.service: Succeeded. May 09 22:53:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 09 22:53:04 volumio volumio[1818]: info: Output device has changed, restarting Shairport Sync May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 09 22:53:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 09 22:53:04 volumio sudo[2061]: pam_unix(sudo:session): session closed for user root May 09 22:53:04 volumio sudo[2068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 09 22:53:04 volumio sudo[2068]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:04 volumio volumio[1818]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 09 22:53:04 volumio go-librespot[2018]: time="2025-05-09T22:53:04-04:00" level=debug msg="authenticated as higiniog13" May 09 22:53:04 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM May 09 22:53:04 volumio systemd[1]: mpd.service: Succeeded. May 09 22:53:04 volumio systemd[1]: Stopped Music Player Daemon. May 09 22:53:04 volumio volumio[1818]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 09 22:53:04 volumio volumio[1818]: 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 09 22:53:04 volumio volumio[1818]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists May 09 22:53:04 volumio volumio[1818]: info: camilladsp service started and running in background, instance 1 May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 09 22:53:04 volumio systemd[1]: Starting Music Player Daemon... May 09 22:53:04 volumio volumio[1818]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 09 22:53:04 volumio volumio[1818]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json May 09 22:53:04 volumio volumio[1818]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found May 09 22:53:04 volumio volumio[1818]: info: FusionDsp loaded May 09 22:53:04 volumio volumio[1818]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 09 22:53:04 volumio sudo[2089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 09 22:53:04 volumio sudo[2089]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:04 volumio volumio[1818]: info: FusionDsp - Reporting Fusion DSP Enabled May 09 22:53:04 volumio volumio[1818]: info: Adding Signal Path Element [object Object] May 09 22:53:04 volumio volumio[1818]: info: Adding fusiondspeq DSP Signal Path Element May 09 22:53:04 volumio go-librespot[2018]: time="2025-05-09T22:53:04-04:00" level=debug msg="dealer connection opened" May 09 22:53:04 volumio go-librespot[2018]: time="2025-05-09T22:53:04-04:00" level=debug msg="initializing zeroconf session, username: higiniog13" May 09 22:53:04 volumio go-librespot[2018]: time="2025-05-09T22:53:04-04:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 09 22:53:04 volumio go-librespot[2018]: time="2025-05-09T22:53:04-04:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 09 22:53:04 volumio volumio[1818]: info: FusionDsp - ---- installed callbackRead May 09 22:53:04 volumio go-librespot[2018]: time="2025-05-09T22:53:04-04:00" level=debug msg="autoplay enabled: false" May 09 22:53:04 volumio go-librespot[2018]: time="2025-05-09T22:53:04-04:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 09 22:53:04 volumio go-librespot[2018]: time="2025-05-09T22:53:04-04:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 09 22:53:04 volumio go-librespot[2018]: time="2025-05-09T22:53:04-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 09 22:53:04 volumio go-librespot[2018]: time="2025-05-09T22:53:04-04:00" level=debug msg="received connection id: M2E1M2YwZDEtNTExMC00ZTI0LWFhNjUtZTE2YThiZTg1NDFkK2RlYWxlcit0Y3A6Ly8wYWNiZDlkZS5pcC5ndWMzLnNwb3RpZnkubmV0OjU3MDArNzI4NjFFQ0QzOEFGRUFGMjQzRDAyMzE4RDJFRTA5NjVGMDk3Rjk2NUE1NjA0OUUyODUwNzY5NzU5RThERjU0MQ==" May 09 22:53:04 volumio volumio[1818]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 22:53:04 volumio systemd[1]: Started FusionDsp Daemon. May 09 22:53:04 volumio volumio[1818]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT May 09 22:53:04 volumio volumio[1818]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) May 09 22:53:04 volumio volumio[1818]: at onErrorNT (internal/child_process.js:465:16) May 09 22:53:04 volumio volumio[1818]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 09 22:53:04 volumio volumio[1818]: at runNextTicks (internal/process/task_queues.js:62:3) May 09 22:53:04 volumio volumio[1818]: at listOnTimeout (internal/timers.js:523:9) May 09 22:53:04 volumio volumio[1818]: at processTimers (internal/timers.js:497:7) { May 09 22:53:04 volumio volumio[1818]: errno: -2, May 09 22:53:04 volumio volumio[1818]: code: 'ENOENT', May 09 22:53:04 volumio volumio[1818]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', May 09 22:53:04 volumio volumio[1818]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', May 09 22:53:04 volumio volumio[1818]: spawnargs: [ May 09 22:53:04 volumio volumio[1818]: '-p', May 09 22:53:04 volumio volumio[1818]: 9876, May 09 22:53:04 volumio volumio[1818]: '-o', May 09 22:53:04 volumio volumio[1818]: '/tmp/camilladsp.log', May 09 22:53:04 volumio volumio[1818]: '-l', May 09 22:53:04 volumio volumio[1818]: 'warn', May 09 22:53:04 volumio volumio[1818]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' May 09 22:53:04 volumio volumio[1818]: ] May 09 22:53:04 volumio volumio[1818]: } May 09 22:53:04 volumio volumio[1818]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 09 22:53:05 volumio sudo[2089]: pam_unix(sudo:session): session closed for user root May 09 22:53:05 volumio sudo[2078]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 09 22:53:05 volumio sudo[2078]: pam_unix(sudo:session): session opened for user root by (uid=0) May 09 22:53:05 volumio sudo[2078]: pam_unix(sudo:session): session closed for user root May 09 22:53:05 volumio go-librespot[2018]: time="2025-05-09T22:53:05-04:00" level=debug msg="put connect state because NEW_DEVICE" May 09 22:53:05 volumio sudo[2141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-09 22:52 May 09 22:53:05 volumio sudo[2141]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"