-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2025-04-25 11:55:31 CEST. -- Apr 25 11:54:04 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 11:54:04 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 11:54:04 volumio volumio[975]: info: Retrieving Cloud Streaming UI Apr 25 11:54:05 volumio volumio[975]: info: Getting Tidal Cloud Configuration Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 11:54:05 volumio volumio[975]: info: Getting Qobuz Cloud Configuration Apr 25 11:54:05 volumio volumio[975]: info: Asking plugin for UI Config Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 11:54:05 volumio volumio[975]: info: Getting Spotify Cloud Configuration Apr 25 11:54:05 volumio volumio[975]: info: Asking plugin for UI Config Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 11:54:05 volumio volumio[975]: info: Saving Spotify Acccount Apr 25 11:54:05 volumio volumio[975]: info: Got Tidal Cloud Configuration Apr 25 11:54:05 volumio volumio[975]: info: Got it Apr 25 11:54:05 volumio volumio[975]: info: Got it Apr 25 11:54:05 volumio volumio[975]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 11:54:05 volumio volumio[975]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::volumioGetBrowseSources Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 25 11:54:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 25 11:54:06 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Apr 25 11:54:06 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:06 volumio volumio[975]: info: Listing playlists Apr 25 11:54:08 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Apr 25 11:54:08 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:10 volumio volumio[975]: info: CoreCommandRouter::volumioGetQueue Apr 25 11:54:10 volumio volumio[975]: info: CoreStateMachine::getQueue Apr 25 11:54:10 volumio volumio[975]: info: CorePlayQueue::getQueue Apr 25 11:54:14 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Apr 25 11:54:14 volumio volumio[975]: info: Preload queue cleared Apr 25 11:54:16 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Apr 25 11:54:16 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:22 volumio volumio[975]: Searching plugin music_service/webradio Apr 25 11:54:22 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 25 11:54:22 volumio volumio[975]: info: All search sources collected, pushing search results Apr 25 11:54:24 volumio volumio[975]: Searching plugin music_service/webradio Apr 25 11:54:24 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: webradio , search Apr 25 11:54:25 volumio volumio[975]: info: All search sources collected, pushing search results Apr 25 11:54:26 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Apr 25 11:54:26 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:26 volumio volumio[975]: info: Listing playlists Apr 25 11:54:26 volumio volumio[975]: info: Listing playlists Apr 25 11:54:31 volumio volumio[975]: info: Preload queue cleared Apr 25 11:54:31 volumio volumio[975]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 25 11:54:31 volumio volumio[975]: info: CoreStateMachine::ClearQueue Apr 25 11:54:31 volumio volumio[975]: info: CoreStateMachine::stop Apr 25 11:54:31 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 11:54:31 volumio volumio[975]: info: CoreStateMachine::updateTrackBlock Apr 25 11:54:31 volumio volumio[975]: info: CorePlayQueue::getTrackBlock Apr 25 11:54:31 volumio volumio[975]: info: CoreStateMachine::stPlaybackTimer Apr 25 11:54:31 volumio volumio[975]: info: CoreStateMachine::pushState Apr 25 11:54:31 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:31 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 11:54:31 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 25 11:54:31 volumio volumio[975]: info: CoreStateMachine::serviceStop Apr 25 11:54:31 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:31 volumio volumio[975]: info: CoreCommandRouter::serviceStop Apr 25 11:54:31 volumio volumio[975]: info: ControllerMpd::stop Apr 25 11:54:31 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 11:54:31 volumio volumio[975]: info: CorePlayQueue::clearPlayQueue Apr 25 11:54:31 volumio volumio[975]: info: CorePlayQueue::saveQueue Apr 25 11:54:31 volumio volumio[975]: info: CoreCommandRouter::volumioPushQueue Apr 25 11:54:31 volumio volumio[975]: info: CoreStateMachine::addQueueItems Apr 25 11:54:31 volumio volumio[975]: info: CorePlayQueue::addQueueItems Apr 25 11:54:31 volumio volumio[975]: info: Preload queue cleared Apr 25 11:54:31 volumio volumio[975]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s25217 Apr 25 11:54:31 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Apr 25 11:54:31 volumio volumio[975]: info: [ASDebug] CurState: stop PrevState: pause Apr 25 11:54:31 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Apr 25 11:54:31 volumio volumio[975]: info: PeppySpectrum ---peppyspectrum status stop Apr 25 11:54:31 volumio sudo[26367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 25 11:54:31 volumio sudo[26367]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:31 volumio volumio[975]: info: Apr 25 11:54:31 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 25 11:54:31 volumio volumio[975]: info: sendMpdCommand stop took 109 milliseconds Apr 25 11:54:31 volumio volumio[975]: info: ControllerMpd::getState Apr 25 11:54:31 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 25 11:54:31 volumio volumio[975]: info: sendMpdCommand status took 5 milliseconds Apr 25 11:54:31 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 25 11:54:31 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 11:54:31 volumio sudo[26370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 25 11:54:31 volumio sudo[26370]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:31 volumio sudo[26367]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:31 volumio volumio[975]: info: sendMpdCommand playlistinfo took 29 milliseconds Apr 25 11:54:31 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 25 11:54:31 volumio volumio[975]: info: ControllerMpd::pushState Apr 25 11:54:31 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 25 11:54:31 volumio volumio[975]: info: CoreStateMachine::pushState Apr 25 11:54:31 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:31 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 11:54:31 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 25 11:54:31 volumio sudo[26370]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:31 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:31 volumio volumio[975]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Crossing the deadline","artist":"Guano Apes","album":"proud like a god","uri":"NAS/Musik@server/Guano Apes/Guano Apes - Crossing The Deadline.mp3","trackType":"mp3"} Apr 25 11:54:31 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 25 11:54:31 volumio volumio[975]: info: CoreStateMachine::syncState stateService stop Apr 25 11:54:31 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Apr 25 11:54:31 volumio volumio[975]: info: CoreStateMachine::pushState Apr 25 11:54:31 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:31 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 25 11:54:31 volumio volumio[975]: info: No code Apr 25 11:54:31 volumio volumio[975]: info: CoreStateMachine::pushState Apr 25 11:54:31 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:31 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 25 11:54:32 volumio volumio[975]: info: ------------------------------ 104ms Apr 25 11:54:32 volumio volumio[975]: info: peppymeterbasic Daemon Stop Apr 25 11:54:32 volumio volumio[975]: info: [ASDebug] CurState: stop PrevState: pause Apr 25 11:54:32 volumio volumio[975]: info: [ASDebug] CurState: stop PrevState: pause Apr 25 11:54:32 volumio volumio[975]: info: [ASDebug] CurState: stop PrevState: pause Apr 25 11:54:32 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Apr 25 11:54:32 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Apr 25 11:54:32 volumio sudo[26380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 25 11:54:32 volumio sudo[26380]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:32 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Apr 25 11:54:32 volumio volumio[975]: info: PeppySpectrum ---peppyspectrum status stop Apr 25 11:54:32 volumio sudo[26383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 25 11:54:32 volumio sudo[26380]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:32 volumio sudo[26383]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:32 volumio sudo[26385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 25 11:54:32 volumio sudo[26385]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:32 volumio volumio[975]: info: PeppySpectrum ---peppyspectrum status stop Apr 25 11:54:32 volumio sudo[26389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 25 11:54:32 volumio sudo[26389]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:32 volumio volumio[975]: info: PeppySpectrum ---peppyspectrum status stop Apr 25 11:54:32 volumio sudo[26392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 25 11:54:32 volumio sudo[26392]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:32 volumio sudo[26385]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:32 volumio volumio[975]: info: peppyspectrum Daemon Stop Apr 25 11:54:32 volumio sudo[26383]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:32 volumio sudo[26395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 25 11:54:32 volumio sudo[26395]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:32 volumio sudo[26389]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:32 volumio sudo[26392]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:32 volumio volumio[975]: info: peppyspectrum Daemon Stop Apr 25 11:54:32 volumio volumio[975]: info: peppyspectrum Daemon Stop Apr 25 11:54:32 volumio volumio[975]: info: peppymeterbasic Daemon Stop Apr 25 11:54:32 volumio volumio[975]: info: peppymeterbasic Daemon Stop Apr 25 11:54:32 volumio volumio[975]: info: peppymeterbasic Daemon Stop Apr 25 11:54:32 volumio sudo[26395]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:32 volumio volumio[975]: info: peppyspectrum Daemon Stop Apr 25 11:54:32 volumio volumio[975]: info: CoreCommandRouter::volumioPushQueue Apr 25 11:54:32 volumio volumio[975]: info: CorePlayQueue::saveQueue Apr 25 11:54:32 volumio volumio[975]: info: CoreStateMachine::updateTrackBlock Apr 25 11:54:32 volumio volumio[975]: info: CorePlayQueue::getTrackBlock Apr 25 11:54:32 volumio volumio[975]: info: CoreCommandRouter::volumioPlay Apr 25 11:54:32 volumio volumio[975]: info: CoreStateMachine::play index 0 Apr 25 11:54:32 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 11:54:32 volumio volumio[975]: info: CoreStateMachine::stop Apr 25 11:54:32 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 11:54:32 volumio volumio[975]: info: CoreStateMachine::play index undefined Apr 25 11:54:32 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 11:54:32 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:32 volumio volumio[975]: info: CoreStateMachine::startPlaybackTimer Apr 25 11:54:32 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:32 volumio volumio[975]: info: [1745574872525] ControllerWebradio::clearAddPlayTrack Apr 25 11:54:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 11:54:32 volumio volumio[975]: info: sendMpdCommand stop took 38 milliseconds Apr 25 11:54:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Apr 25 11:54:32 volumio volumio[975]: info: Apr 25 11:54:32 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 25 11:54:32 volumio volumio[975]: info: Ignoring MPD Status Update Apr 25 11:54:32 volumio volumio[975]: info: sendMpdCommand clear took 7 milliseconds Apr 25 11:54:32 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand load "http://stream.rockantenne.de/rockantenne/stream/mp3?aw_0_1st.playerid=tunein.com" Apr 25 11:54:32 volumio volumio[975]: error: updateQueue error: null Apr 25 11:54:32 volumio volumio[975]: info: ------------------------------ 9ms Apr 25 11:54:34 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rockantenne.de/rockantenne/stream/mp3?aw_0_1st.playerid=tunein.com" Apr 25 11:54:34 volumio volumio[975]: info: Apr 25 11:54:34 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 25 11:54:34 volumio volumio[975]: info: Ignoring MPD Status Update Apr 25 11:54:34 volumio volumio[975]: info: sendMpdCommand add "http://stream.rockantenne.de/rockantenne/stream/mp3?aw_0_1st.playerid=tunein.com" took 2 milliseconds Apr 25 11:54:34 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 11:54:34 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand play Apr 25 11:54:34 volumio volumio[975]: info: ------------------------------ 5ms Apr 25 11:54:34 volumio volumio[975]: info: sendMpdCommand play took 2 milliseconds Apr 25 11:54:35 volumio volumio[975]: info: Apr 25 11:54:35 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 25 11:54:35 volumio volumio[975]: info: ControllerMpd::getState Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 25 11:54:35 volumio volumio[975]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 25 11:54:35 volumio volumio[975]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 25 11:54:35 volumio volumio[975]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 25 11:54:35 volumio volumio[975]: info: Apr 25 11:54:35 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 25 11:54:35 volumio volumio[975]: info: sendMpdCommand status took 17 milliseconds Apr 25 11:54:35 volumio volumio[975]: info: ControllerMpd::getState Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 11:54:35 volumio volumio[975]: info: sendMpdCommand status took 3 milliseconds Apr 25 11:54:35 volumio volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 25 11:54:35 volumio volumio[975]: info: ControllerMpd::pushState Apr 25 11:54:35 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 25 11:54:35 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:35 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.rockantenne.de/rockantenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Apr 25 11:54:35 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 25 11:54:35 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 25 11:54:35 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Apr 25 11:54:35 volumio volumio[975]: info: ------------------------------ 32ms Apr 25 11:54:35 volumio volumio[975]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 25 11:54:35 volumio volumio[975]: info: ControllerMpd::pushState Apr 25 11:54:35 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 25 11:54:35 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:35 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.rockantenne.de/rockantenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Apr 25 11:54:35 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 25 11:54:35 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 25 11:54:35 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Apr 25 11:54:35 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Apr 25 11:54:35 volumio volumio[975]: info: CoreStateMachine::pushState Apr 25 11:54:35 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 11:54:35 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 25 11:54:35 volumio volumio[975]: info: CoreStateMachine::pushState Apr 25 11:54:35 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 25 11:54:35 volumio volumio[975]: info: ------------------------------ 73ms Apr 25 11:54:35 volumio volumio[975]: info: Apr 25 11:54:35 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 25 11:54:35 volumio volumio[975]: info: Ignoring MPD Status Update Apr 25 11:54:35 volumio volumio[975]: info: Apr 25 11:54:35 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 25 11:54:35 volumio volumio[975]: info: ControllerMpd::getState Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 25 11:54:35 volumio volumio[975]: info: [ASDebug] CurState: play PrevState: pause Apr 25 11:54:35 volumio volumio[975]: info: [ASDebug] Togle GPIO: ON Apr 25 11:54:35 volumio volumio[975]: info: [ASDebug] CurState: play PrevState: play Apr 25 11:54:35 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 25 11:54:35 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 25 11:54:35 volumio sudo[26753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 25 11:54:35 volumio sudo[26753]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:35 volumio volumio[975]: info: PeppySpectrum ---peppyspectrum status play Apr 25 11:54:35 volumio volumio[975]: info: PeppySpectrum ---peppyspectrum status play Apr 25 11:54:35 volumio sudo[26756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 25 11:54:35 volumio sudo[26756]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:35 volumio sudo[26758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 25 11:54:35 volumio sudo[26758]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:35 volumio systemd[1]: Started peppymeterbasic Daemon. Apr 25 11:54:35 volumio sudo[26762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 25 11:54:35 volumio sudo[26762]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:35 volumio sudo[26753]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:35 volumio volumio[975]: info: Apr 25 11:54:35 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 25 11:54:35 volumio volumio[975]: info: Ignoring MPD Status Update Apr 25 11:54:35 volumio volumio[975]: info: Apr 25 11:54:35 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 25 11:54:35 volumio volumio[975]: info: ControllerMpd::getState Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 25 11:54:35 volumio volumio[975]: info: ------------------------------ 190ms Apr 25 11:54:35 volumio volumio[975]: info: sendMpdCommand status took 188 milliseconds Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 11:54:35 volumio volumio[975]: info: ------------------------------ 18ms Apr 25 11:54:35 volumio volumio[975]: info: sendMpdCommand status took 16 milliseconds Apr 25 11:54:35 volumio volumio[975]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 25 11:54:35 volumio volumio[975]: info: ControllerMpd::pushState Apr 25 11:54:35 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 25 11:54:35 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:35 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Rock Nonstop!","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.rockantenne.de/rockantenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Apr 25 11:54:35 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 25 11:54:35 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 25 11:54:35 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Apr 25 11:54:35 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Apr 25 11:54:35 volumio volumio[975]: info: CoreStateMachine::pushState Apr 25 11:54:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 11:54:35 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 25 11:54:35 volumio systemd[1]: Started peppyspectrum Daemon. Apr 25 11:54:35 volumio sudo[26756]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:35 volumio sudo[26762]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:35 volumio sudo[26758]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:35 volumio volumio[975]: info: CoreStateMachine::pushState Apr 25 11:54:35 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 25 11:54:35 volumio volumio[975]: info: ------------------------------ 329ms Apr 25 11:54:35 volumio volumio[975]: info: peppymeterbasic Daemon Started Apr 25 11:54:35 volumio volumio[975]: info: sendMpdCommand playlistinfo took 120 milliseconds Apr 25 11:54:35 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 25 11:54:35 volumio volumio[975]: info: ControllerMpd::pushState Apr 25 11:54:35 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 25 11:54:35 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:54:35 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ROCK ANTENNE - Rock Nonstop!","artist":"ROCK ANTENNE","album":null,"uri":"http://stream.rockantenne.de/rockantenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Apr 25 11:54:35 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 25 11:54:35 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 25 11:54:35 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Apr 25 11:54:35 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Apr 25 11:54:35 volumio volumio[975]: info: CoreStateMachine::pushState Apr 25 11:54:35 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 25 11:54:35 volumio volumio[975]: info: CoreStateMachine::pushState Apr 25 11:54:35 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 25 11:54:35 volumio volumio[975]: info: ------------------------------ 210ms Apr 25 11:54:35 volumio volumio[975]: info: [ASDebug] CurState: play PrevState: play Apr 25 11:54:35 volumio volumio[975]: info: [ASDebug] CurState: play PrevState: play Apr 25 11:54:35 volumio volumio[975]: info: [ASDebug] CurState: play PrevState: play Apr 25 11:54:35 volumio volumio[975]: info: [ASDebug] CurState: play PrevState: play Apr 25 11:54:35 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 25 11:54:35 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 25 11:54:35 volumio sudo[26776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 25 11:54:35 volumio sudo[26776]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:35 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 25 11:54:35 volumio sudo[26779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 25 11:54:35 volumio sudo[26779]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:35 volumio sudo[26776]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:35 volumio sudo[26779]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:35 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 25 11:54:35 volumio sudo[26782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 25 11:54:35 volumio sudo[26782]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:36 volumio volumio[975]: info: PeppySpectrum ---peppyspectrum status play Apr 25 11:54:36 volumio sudo[26785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 25 11:54:36 volumio sudo[26785]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:36 volumio sudo[26782]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:36 volumio volumio[975]: info: PeppySpectrum ---peppyspectrum status play Apr 25 11:54:36 volumio sudo[26785]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:36 volumio sudo[26789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 25 11:54:36 volumio sudo[26789]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:36 volumio volumio[975]: info: PeppySpectrum ---peppyspectrum status play Apr 25 11:54:36 volumio sudo[26794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 25 11:54:36 volumio sudo[26794]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:36 volumio sudo[26789]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:36 volumio volumio[975]: info: PeppySpectrum ---peppyspectrum status play Apr 25 11:54:36 volumio sudo[26797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 25 11:54:36 volumio sudo[26797]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:36 volumio sudo[26794]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:36 volumio sudo[26797]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:36 volumio volumio[975]: info: peppymeterbasic Daemon Started Apr 25 11:54:36 volumio volumio[975]: info: peppyspectrum Daemon Started Apr 25 11:54:36 volumio volumio[975]: info: peppyspectrum Daemon Started Apr 25 11:54:36 volumio sudo[26800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Apr 25 11:54:36 volumio sudo[26800]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:54:36 volumio volumio[975]: info: peppyspectrum Daemon Started Apr 25 11:54:36 volumio volumio[975]: info: peppyspectrum Daemon Started Apr 25 11:54:36 volumio volumio[975]: info: peppyspectrum Daemon Started Apr 25 11:54:36 volumio volumio[975]: info: peppymeterbasic Daemon Started Apr 25 11:54:36 volumio volumio[975]: info: peppymeterbasic Daemon Started Apr 25 11:54:36 volumio volumio[975]: info: peppymeterbasic Daemon Started Apr 25 11:54:36 volumio sudo[26800]: pam_unix(sudo:session): session closed for user root Apr 25 11:54:36 volumio volumio[975]: info: peppyspectrum Daemon Started Apr 25 11:54:36 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Apr 25 11:54:36 volumio volumio[26773]: pygame 1.9.4.post1 Apr 25 11:54:36 volumio volumio[26773]: Hello from the pygame community. https://www.pygame.org/contribute.html Apr 25 11:54:36 volumio volumio[26773]: Traceback (most recent call last): Apr 25 11:54:36 volumio volumio[26773]: File "spectrum.py", line 715, in Apr 25 11:54:36 volumio volumio[26773]: pm = Spectrum(None, True) Apr 25 11:54:36 volumio volumio[26773]: File "spectrum.py", line 63, in __init__ Apr 25 11:54:36 volumio volumio[26773]: self.init_display() Apr 25 11:54:36 volumio volumio[26773]: File "spectrum.py", line 104, in init_display Apr 25 11:54:36 volumio volumio[26773]: pygame.display.init() Apr 25 11:54:36 volumio volumio[26773]: pygame.error: Unable to open /dev/fb1 Apr 25 11:54:37 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Apr 25 11:54:37 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Apr 25 11:54:37 volumio volumio[26760]: pygame 1.9.4.post1 Apr 25 11:54:37 volumio volumio[26760]: Hello from the pygame community. https://www.pygame.org/contribute.html Apr 25 11:54:43 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Apr 25 11:54:46 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Apr 25 11:54:46 volumio volumio[975]: info: Listing playlists Apr 25 11:54:46 volumio volumio[975]: info: Listing playlists Apr 25 11:54:47 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 11:54:47 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 25 11:54:48 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 25 11:54:48 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 25 11:54:48 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Apr 25 11:54:48 volumio volumio[975]: info: Received Get System Version Apr 25 11:54:48 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 25 11:54:52 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Apr 25 11:54:56 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Apr 25 11:54:57 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 25 11:54:57 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 11:54:57 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 25 11:54:57 volumio volumio[975]: info: FusionDsp - Rock-FusionDsp Apr 25 11:54:57 volumio volumio[975]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Apr 25 11:55:02 volumio volumio[975]: info: Stopping peppyspectrum service Apr 25 11:55:02 volumio volumio[975]: info: CoreStateMachine::stop Apr 25 11:55:02 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 11:55:02 volumio volumio[975]: info: CoreStateMachine::stPlaybackTimer Apr 25 11:55:02 volumio volumio[975]: info: CoreStateMachine::updateTrackBlock Apr 25 11:55:02 volumio volumio[975]: info: CorePlayQueue::getTrackBlock Apr 25 11:55:02 volumio volumio[975]: info: CoreStateMachine::pushState Apr 25 11:55:02 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 25 11:55:02 volumio volumio[975]: info: CoreStateMachine::serviceStop Apr 25 11:55:02 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::serviceStop Apr 25 11:55:02 volumio volumio[975]: info: [1745574902323] ControllerWebradio::stop Apr 25 11:55:02 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 11:55:02 volumio volumio[975]: info: Disabling plugin peppyspectrum Apr 25 11:55:02 volumio volumio[975]: info: Preparing to generate the ALSA configuration file Apr 25 11:55:02 volumio volumio[975]: info: [ASDebug] CurState: stop PrevState: play Apr 25 11:55:02 volumio volumio[975]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 25 11:55:02 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Apr 25 11:55:02 volumio volumio[975]: info: PeppySpectrum ---peppyspectrum status stop Apr 25 11:55:02 volumio sudo[29758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 25 11:55:02 volumio sudo[29758]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:02 volumio volumio[975]: info: Apr 25 11:55:02 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 25 11:55:02 volumio volumio[975]: info: sendMpdCommand stop took 107 milliseconds Apr 25 11:55:02 volumio volumio[975]: info: ControllerMpd::getState Apr 25 11:55:02 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 25 11:55:02 volumio sudo[29761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 25 11:55:02 volumio sudo[29761]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:02 volumio systemd[1]: Stopping peppymeterbasic Daemon... Apr 25 11:55:02 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Apr 25 11:55:02 volumio systemd[1]: peppymeterbasic.service: Succeeded. Apr 25 11:55:02 volumio systemd[1]: Stopped peppymeterbasic Daemon. Apr 25 11:55:02 volumio volumio[975]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 25 11:55:02 volumio volumio[975]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Apr 25 11:55:02 volumio volumio[975]: info: Reading ALSA contributions from plugins. Apr 25 11:55:02 volumio sudo[29758]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:02 volumio sudo[29764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Apr 25 11:55:02 volumio sudo[29761]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:02 volumio sudo[29764]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:02 volumio volumio[975]: info: sendMpdCommand status took 80 milliseconds Apr 25 11:55:02 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 25 11:55:02 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 11:55:02 volumio volumio[975]: info: peppyspectrum Daemon Stop Apr 25 11:55:02 volumio volumio[975]: info: peppymeterbasic Daemon Stop Apr 25 11:55:02 volumio volumio[975]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 25 11:55:02 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 25 11:55:02 volumio volumio[975]: info: ControllerMpd::pushState Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 25 11:55:02 volumio volumio[975]: info: CoreStateMachine::pushState Apr 25 11:55:02 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 25 11:55:02 volumio sudo[29764]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:02 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:55:02 volumio volumio[975]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 25 11:55:02 volumio volumio[975]: info: ------------------------------ 127ms Apr 25 11:55:02 volumio volumio[975]: info: [ASDebug] CurState: stop PrevState: play Apr 25 11:55:02 volumio volumio[975]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 25 11:55:02 volumio volumio[975]: info: PeppyMeterBasic ---peppymeterbasic status stop Apr 25 11:55:02 volumio sudo[29774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 25 11:55:02 volumio sudo[29774]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:02 volumio volumio[975]: info: Asound.conf file written Apr 25 11:55:02 volumio sudo[29774]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:02 volumio sudo[29784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 25 11:55:02 volumio sudo[29784]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:02 volumio sudo[29784]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:02 volumio volumio[975]: info: Output device has changed, restarting MPD Apr 25 11:55:02 volumio sudo[29789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 11:55:02 volumio sudo[29789]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:02 volumio volumio[975]: info: Output device has changed, restarting Shairport Sync Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 11:55:02 volumio sudo[29789]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:02 volumio sudo[29792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 11:55:02 volumio sudo[29792]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:02 volumio systemd[1]: Stopping Music Player Daemon... Apr 25 11:55:02 volumio volumio[975]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 11:55:02 volumio volumio[975]: info: Done. Apr 25 11:55:02 volumio volumio[975]: info: MPD Permissions set Apr 25 11:55:02 volumio volumio[975]: info: peppymeterbasic Daemon Stop Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 11:55:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:03 volumio volumio[975]: info: Stopping peppymeterbasic service Apr 25 11:55:03 volumio volumio[975]: info: CoreStateMachine::stop Apr 25 11:55:03 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 11:55:03 volumio volumio[975]: info: Disabling plugin peppymeterbasic Apr 25 11:55:03 volumio volumio[975]: info: Preparing to generate the ALSA configuration file Apr 25 11:55:03 volumio sudo[29809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 25 11:55:03 volumio sudo[29809]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:03 volumio volumio[975]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 25 11:55:03 volumio volumio[975]: info: Reading ALSA contributions from plugins. Apr 25 11:55:03 volumio volumio[975]: info: Starting Shairport Sync Apr 25 11:55:03 volumio sudo[29809]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:03 volumio volumio[975]: info: FusionDsp - Rock-FusionDsp Apr 25 11:55:03 volumio sudo[29813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 11:55:03 volumio sudo[29813]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 25 11:55:03 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 25 11:55:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 25 11:55:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 25 11:55:03 volumio sudo[29813]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:03 volumio volumio[975]: info: [ASDebug] Togle GPIO: OFF Apr 25 11:55:03 volumio volumio[975]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Apr 25 11:55:03 volumio volumio[975]: info: Shairport-Sync Started Apr 25 11:55:03 volumio volumio[975]: info: Asound.conf file written Apr 25 11:55:03 volumio sudo[29819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 25 11:55:03 volumio sudo[29819]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:03 volumio sudo[29819]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:03 volumio volumio[975]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Apr 25 11:55:03 volumio volumio[975]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Apr 25 11:55:03 volumio volumio[975]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Apr 25 11:55:03 volumio volumio[975]: info: Output device has changed, restarting MPD Apr 25 11:55:03 volumio sudo[29824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 11:55:03 volumio sudo[29824]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:03 volumio volumio[975]: info: Output device has changed, restarting Shairport Sync Apr 25 11:55:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 11:55:03 volumio sudo[29824]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:03 volumio sudo[29827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 11:55:03 volumio sudo[29827]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:03 volumio volumio[975]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 11:55:03 volumio volumio[975]: info: Done. Apr 25 11:55:03 volumio systemd[1]: mpd.service: Succeeded. Apr 25 11:55:03 volumio systemd[1]: Stopped Music Player Daemon. Apr 25 11:55:03 volumio volumio[975]: info: MPD Permissions set Apr 25 11:55:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 11:55:03 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:03 volumio volumio[975]: info: Starting Shairport Sync Apr 25 11:55:03 volumio systemd[1]: Starting Music Player Daemon... Apr 25 11:55:03 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 25 11:55:03 volumio sudo[29838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 11:55:03 volumio sudo[29838]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:03 volumio volumio[975]: info: FusionDsp - Rock-FusionDsp Apr 25 11:55:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 25 11:55:03 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 25 11:55:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 25 11:55:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 25 11:55:03 volumio sudo[29838]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:04 volumio sudo[29832]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 11:55:04 volumio sudo[29832]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:04 volumio sudo[29832]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:04 volumio volumio[975]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Apr 25 11:55:04 volumio volumio[975]: info: Shairport-Sync Started Apr 25 11:55:05 volumio mpd[29845]: Apr 25 11:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 11:55:06 volumio systemd[1]: Started Music Player Daemon. Apr 25 11:55:06 volumio sudo[29792]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:06 volumio sudo[29827]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:06 volumio volumio[975]: error: MPD error: The expression evaluated to a falsy value: Apr 25 11:55:06 volumio volumio[975]: assert.ok(self.idling) Apr 25 11:55:06 volumio volumio[975]: error: The expression evaluated to a falsy value: Apr 25 11:55:06 volumio volumio[975]: assert.ok(self.idling) Apr 25 11:55:06 volumio volumio[975]: error: updateQueue error: null Apr 25 11:55:06 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Apr 25 11:55:06 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:55:06 volumio volumio[975]: info: Listing playlists Apr 25 11:55:06 volumio volumio[975]: info: Listing playlists Apr 25 11:55:12 volumio volumio[975]: info: [ASDebug] Port: 24 Apr 25 11:55:12 volumio volumio[975]: info: [ASDebug] Inverted: false Apr 25 11:55:12 volumio volumio[975]: info: [ASDebug] Delay: 720 Apr 25 11:55:16 volumio volumio[975]: info: FusionDsp - Stopping FusionDsp service Apr 25 11:55:16 volumio volumio[975]: info: camilladsp stopping service pid 29848... Apr 25 11:55:16 volumio volumio[975]: info: camilladsp service terminated, instance 1 Apr 25 11:55:16 volumio volumio[975]: info: Disabling plugin fusiondsp Apr 25 11:55:16 volumio volumio[975]: info: Preparing to generate the ALSA configuration file Apr 25 11:55:16 volumio sudo[29876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Apr 25 11:55:16 volumio sudo[29876]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:16 volumio volumio[975]: info: Asound.conf file written Apr 25 11:55:16 volumio systemd[1]: Stopping FusionDsp Daemon... Apr 25 11:55:16 volumio volumio[1319]: Backend configuration: Apr 25 11:55:16 volumio volumio[1319]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Apr 25 11:55:16 volumio volumio[1319]: active_config_txt: null Apr 25 11:55:16 volumio volumio[1319]: camilla_host: 127.0.0.1 Apr 25 11:55:16 volumio volumio[1319]: camilla_port: 9876 Apr 25 11:55:16 volumio volumio[1319]: coeff_dir: /data/INTERNAL/FusionDsp/filters Apr 25 11:55:16 volumio volumio[1319]: config_dir: /data/configuration/audio_interface/fusiondsp Apr 25 11:55:16 volumio volumio[1319]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Apr 25 11:55:16 volumio volumio[1319]: log_file: /tmp/camilladsp.log Apr 25 11:55:16 volumio volumio[1319]: on_get_active_config: null Apr 25 11:55:16 volumio volumio[1319]: on_set_active_config: null Apr 25 11:55:16 volumio volumio[1319]: port: 5011 Apr 25 11:55:16 volumio volumio[1319]: supported_capture_types: null Apr 25 11:55:16 volumio volumio[1319]: supported_playback_types: null Apr 25 11:55:16 volumio volumio[1319]: update_config_symlink: false Apr 25 11:55:16 volumio volumio[1319]: update_config_txt: false Apr 25 11:55:16 volumio volumio[1319]: ======== Running on http://0.0.0.0:5011 ======== Apr 25 11:55:16 volumio volumio[1319]: (Press CTRL+C to quit) Apr 25 11:55:16 volumio sudo[29879]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 25 11:55:16 volumio sudo[29879]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:16 volumio sudo[29879]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:16 volumio volumio[975]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Apr 25 11:55:16 volumio volumio[975]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Apr 25 11:55:16 volumio volumio[975]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Apr 25 11:55:16 volumio volumio[975]: info: Output device has changed, restarting MPD Apr 25 11:55:16 volumio sudo[29884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 25 11:55:16 volumio sudo[29884]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:16 volumio volumio[975]: info: Output device has changed, restarting Shairport Sync Apr 25 11:55:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 11:55:16 volumio sudo[29884]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:16 volumio sudo[29887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 25 11:55:16 volumio sudo[29887]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:16 volumio systemd[1]: Stopping Music Player Daemon... Apr 25 11:55:16 volumio volumio[975]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 25 11:55:16 volumio volumio[975]: info: Done. Apr 25 11:55:16 volumio volumio[975]: info: MPD Permissions set Apr 25 11:55:16 volumio systemd[1]: mpd.service: Succeeded. Apr 25 11:55:16 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Apr 25 11:55:16 volumio systemd[1]: Stopped Music Player Daemon. Apr 25 11:55:16 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:55:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 25 11:55:16 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 25 11:55:16 volumio volumio[975]: info: Starting Shairport Sync Apr 25 11:55:16 volumio sudo[29898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 25 11:55:16 volumio sudo[29898]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:16 volumio systemd[1]: Starting Music Player Daemon... Apr 25 11:55:16 volumio systemd[1]: fusiondsp.service: Succeeded. Apr 25 11:55:16 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 25 11:55:16 volumio sudo[29876]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:16 volumio volumio[975]: info: FusionDsp - Reporting Fusion DSP Disabled Apr 25 11:55:16 volumio volumio[975]: info: Removing fusiondspeq DSP Signal Path Element Apr 25 11:55:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 25 11:55:17 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 25 11:55:17 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 25 11:55:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 25 11:55:17 volumio sudo[29898]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:17 volumio volumio[975]: info: Shairport-Sync Started Apr 25 11:55:17 volumio sudo[29892]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 25 11:55:17 volumio sudo[29892]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 25 11:55:17 volumio sudo[29892]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:18 volumio mpd[29904]: Apr 25 11:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 25 11:55:18 volumio systemd[1]: Started Music Player Daemon. Apr 25 11:55:18 volumio sudo[29887]: pam_unix(sudo:session): session closed for user root Apr 25 11:55:18 volumio volumio[975]: error: updateQueue error: null Apr 25 11:55:19 volumio volumio[975]: info: Enabling plugin ampswitch Apr 25 11:55:19 volumio volumio[975]: info: Loading plugin "ampswitch"... Apr 25 11:55:19 volumio volumio[975]: info: Applying required configuration parameters for plugin ampswitch Apr 25 11:55:19 volumio volumio[975]: info: PLUGIN START: ampswitch Apr 25 11:55:19 volumio volumio[975]: info: Done. Apr 25 11:55:19 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Apr 25 11:55:19 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:55:19 volumio volumio[975]: info: [ASDebug] CurState: stop PrevState: stop Apr 25 11:55:19 volumio volumio[975]: info: [ASDebug] CurState: stop PrevState: stop Apr 25 11:55:19 volumio volumio[975]: info: [ASDebug] CurState: stop PrevState: stop Apr 25 11:55:20 volumio volumio[975]: info: [ASDebug] Port: 24 Apr 25 11:55:20 volumio volumio[975]: info: [ASDebug] Inverted: false Apr 25 11:55:20 volumio volumio[975]: info: [ASDebug] Delay: 720 Apr 25 11:55:26 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Apr 25 11:55:26 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:55:26 volumio volumio[975]: info: Listing playlists Apr 25 11:55:26 volumio volumio[975]: info: Listing playlists Apr 25 11:55:28 volumio volumio[975]: info: CoreCommandRouter::volumioPlay Apr 25 11:55:28 volumio volumio[975]: info: CoreStateMachine::play index undefined Apr 25 11:55:28 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 25 11:55:28 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:55:28 volumio volumio[975]: info: CoreStateMachine::startPlaybackTimer Apr 25 11:55:28 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:55:28 volumio volumio[975]: info: [1745574928419] ControllerWebradio::clearAddPlayTrack Apr 25 11:55:28 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Apr 25 11:55:28 volumio volumio[975]: info: sendMpdCommand stop took 1 milliseconds Apr 25 11:55:28 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Apr 25 11:55:28 volumio volumio[975]: info: Apr 25 11:55:28 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 25 11:55:28 volumio volumio[975]: info: Ignoring MPD Status Update Apr 25 11:55:28 volumio volumio[975]: info: sendMpdCommand clear took 2 milliseconds Apr 25 11:55:28 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand load "http://stream.rockantenne.de/rockantenne/stream/mp3?aw_0_1st.playerid=tunein.com" Apr 25 11:55:28 volumio volumio[975]: error: updateQueue error: null Apr 25 11:55:28 volumio volumio[975]: info: ------------------------------ 4ms Apr 25 11:55:30 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand add "http://stream.rockantenne.de/rockantenne/stream/mp3?aw_0_1st.playerid=tunein.com" Apr 25 11:55:30 volumio volumio[975]: info: sendMpdCommand add "http://stream.rockantenne.de/rockantenne/stream/mp3?aw_0_1st.playerid=tunein.com" took 1 milliseconds Apr 25 11:55:30 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 25 11:55:30 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand play Apr 25 11:55:30 volumio volumio[975]: info: Apr 25 11:55:30 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 25 11:55:30 volumio volumio[975]: info: Ignoring MPD Status Update Apr 25 11:55:30 volumio volumio[975]: info: sendMpdCommand play took 3 milliseconds Apr 25 11:55:30 volumio volumio[975]: info: ------------------------------ 2ms Apr 25 11:55:30 volumio volumio[975]: info: Apr 25 11:55:30 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 25 11:55:30 volumio volumio[975]: info: ControllerMpd::getState Apr 25 11:55:30 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 25 11:55:30 volumio volumio[975]: info: Apr 25 11:55:30 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 25 11:55:30 volumio volumio[975]: info: sendMpdCommand status took 31 milliseconds Apr 25 11:55:30 volumio volumio[975]: info: ControllerMpd::getState Apr 25 11:55:30 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 25 11:55:30 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 25 11:55:30 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 11:55:30 volumio volumio[975]: info: sendMpdCommand status took 6 milliseconds Apr 25 11:55:30 volumio volumio[975]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 25 11:55:30 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 25 11:55:30 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 25 11:55:30 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 25 11:55:30 volumio volumio[975]: info: ControllerMpd::pushState Apr 25 11:55:30 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 25 11:55:30 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:55:30 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.rockantenne.de/rockantenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Apr 25 11:55:30 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 25 11:55:30 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 25 11:55:30 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Apr 25 11:55:30 volumio volumio[975]: info: ------------------------------ 44ms Apr 25 11:55:30 volumio volumio[975]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 25 11:55:30 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 25 11:55:30 volumio volumio[975]: info: ControllerMpd::pushState Apr 25 11:55:30 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 25 11:55:30 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:55:30 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3?aw_0_1st.playerid=tunein.com","artist":null,"album":null,"uri":"http://stream.rockantenne.de/rockantenne/stream/mp3?aw_0_1st.playerid=tunein.com","trackType":"com"} Apr 25 11:55:30 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 25 11:55:30 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 25 11:55:30 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Apr 25 11:55:30 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Apr 25 11:55:30 volumio volumio[975]: info: CoreStateMachine::pushState Apr 25 11:55:30 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 25 11:55:30 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 25 11:55:30 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 25 11:55:31 volumio volumio[975]: info: CoreStateMachine::pushState Apr 25 11:55:31 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 25 11:55:31 volumio volumio[975]: info: ------------------------------ 52ms Apr 25 11:55:31 volumio volumio[975]: info: Apr 25 11:55:31 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 25 11:55:31 volumio volumio[975]: info: Ignoring MPD Status Update Apr 25 11:55:31 volumio volumio[975]: info: Apr 25 11:55:31 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 25 11:55:31 volumio volumio[975]: info: ControllerMpd::getState Apr 25 11:55:31 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 25 11:55:31 volumio volumio[975]: info: [ASDebug] CurState: play PrevState: stop Apr 25 11:55:31 volumio volumio[975]: info: [ASDebug] Togle GPIO: ON Apr 25 11:55:31 volumio volumio[975]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 11:55:31 volumio volumio[975]: Error: EBADF: bad file descriptor, write Apr 25 11:55:31 volumio volumio[975]: at Object.writeSync (fs.js:711:3) Apr 25 11:55:31 volumio volumio[975]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 25 11:55:31 volumio volumio[975]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Apr 25 11:55:31 volumio volumio[975]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Apr 25 11:55:31 volumio volumio[975]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Apr 25 11:55:31 volumio volumio[975]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Apr 25 11:55:31 volumio volumio[975]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Apr 25 11:55:31 volumio volumio[975]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Apr 25 11:55:31 volumio volumio[975]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Apr 25 11:55:31 volumio volumio[975]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Apr 25 11:55:31 volumio volumio[975]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Apr 25 11:55:31 volumio volumio[975]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Apr 25 11:55:31 volumio volumio[975]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Apr 25 11:55:31 volumio volumio[975]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Apr 25 11:55:31 volumio volumio[975]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Apr 25 11:55:31 volumio volumio[975]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Apr 25 11:55:31 volumio volumio[975]: errno: -9, Apr 25 11:55:31 volumio volumio[975]: syscall: 'write', Apr 25 11:55:31 volumio volumio[975]: code: 'EBADF' Apr 25 11:55:31 volumio volumio[975]: } Apr 25 11:55:31 volumio volumio[975]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 25 11:55:31 volumio sudo[29962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-25 11:54 Apr 25 11:55:31 volumio sudo[29962]: 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="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"