-- Logs begin at Tue 2024-04-16 16:07:21 UTC, end at Tue 2024-04-16 16:10:32 UTC. -- Apr 16 16:09:06 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 16 16:09:06 volumio volumio[853]: info: CURURI: music-library/NAS/MZ/Gin Wigmore Apr 16 16:09:06 volumio volumio[853]: info: Preload queue cleared Apr 16 16:09:08 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 16 16:09:08 volumio volumio[853]: info: CURURI: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play Apr 16 16:09:08 volumio volumio[853]: info: Preload queue cleared Apr 16 16:09:08 volumio volumio[853]: info: Preloading song: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/01 - The Roses.mp3 Apr 16 16:09:08 volumio volumio[853]: info: Preloading song: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/02 - S.O.S.mp3 Apr 16 16:09:08 volumio volumio[853]: info: Preloading song: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/03 - Hallelujah.mp3 Apr 16 16:09:08 volumio volumio[853]: info: Preloading song: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/04 - Under My Skin.mp3 Apr 16 16:09:08 volumio volumio[853]: info: Preloading song: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/05 - Easy Come, Easy Go.mp3 Apr 16 16:09:08 volumio volumio[853]: info: Exploding uri music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/01 - The Roses.mp3 in service mpd Apr 16 16:09:08 volumio volumio[853]: info: ALBUMART /albumart?cacheid=601&web=Gin%20Wigmore/Extended%20Play/small&path=%2Fmnt%2FNAS%2FMZ%2FGin%20Wigmore%2FGin%20Wigmore%20-%202008%20-%20Extended%20Play%2F01%20-%20The%20Roses.mp3&metadata=false Apr 16 16:09:08 volumio volumio[853]: info: URI /mnt/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/01 - The Roses.mp3 Apr 16 16:09:08 volumio volumio[853]: info: Exploding uri music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/02 - S.O.S.mp3 in service mpd Apr 16 16:09:08 volumio volumio[853]: info: ALBUMART /albumart?cacheid=601&web=Gin%20Wigmore/Extended%20Play/small&path=%2Fmnt%2FNAS%2FMZ%2FGin%20Wigmore%2FGin%20Wigmore%20-%202008%20-%20Extended%20Play%2F02%20-%20S.O.S.mp3&metadata=false Apr 16 16:09:08 volumio volumio[853]: info: URI /mnt/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/02 - S.O.S.mp3 Apr 16 16:09:08 volumio volumio[853]: info: Exploding uri music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/03 - Hallelujah.mp3 in service mpd Apr 16 16:09:08 volumio volumio[853]: info: ALBUMART /albumart?cacheid=601&web=Gin%20Wigmore/Extended%20Play/small&path=%2Fmnt%2FNAS%2FMZ%2FGin%20Wigmore%2FGin%20Wigmore%20-%202008%20-%20Extended%20Play%2F03%20-%20Hallelujah.mp3&metadata=false Apr 16 16:09:08 volumio volumio[853]: info: URI /mnt/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/03 - Hallelujah.mp3 Apr 16 16:09:08 volumio volumio[853]: info: Exploding uri music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/04 - Under My Skin.mp3 in service mpd Apr 16 16:09:08 volumio volumio[853]: info: ALBUMART /albumart?cacheid=601&web=Gin%20Wigmore/Extended%20Play/small&path=%2Fmnt%2FNAS%2FMZ%2FGin%20Wigmore%2FGin%20Wigmore%20-%202008%20-%20Extended%20Play%2F04%20-%20Under%20My%20Skin.mp3&metadata=false Apr 16 16:09:08 volumio volumio[853]: info: URI /mnt/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/04 - Under My Skin.mp3 Apr 16 16:09:08 volumio volumio[853]: info: Exploding uri music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/05 - Easy Come, Easy Go.mp3 in service mpd Apr 16 16:09:08 volumio volumio[853]: info: ALBUMART /albumart?cacheid=601&web=Gin%20Wigmore/Extended%20Play/small&path=%2Fmnt%2FNAS%2FMZ%2FGin%20Wigmore%2FGin%20Wigmore%20-%202008%20-%20Extended%20Play%2F05%20-%20Easy%20Come%2C%20Easy%20Go.mp3&metadata=false Apr 16 16:09:08 volumio volumio[853]: info: URI /mnt/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/05 - Easy Come, Easy Go.mp3 Apr 16 16:09:09 volumio volumio[853]: info: Preload queue cleared Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::ClearQueue Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::stop Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::updateTrackBlock Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrackBlock Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 3 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::serviceStop Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 3 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::serviceStop Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::stop Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::clearPlayQueue Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::saveQueue Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushQueue Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::addQueueItems Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::addQueueItems Apr 16 16:09:09 volumio volumio[853]: info: Preload queue cleared Apr 16 16:09:09 volumio volumio[853]: info: Adding Item to queue: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/01 - The Roses.mp3 Apr 16 16:09:09 volumio volumio[853]: info: Using cached record of: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/01 - The Roses.mp3 Apr 16 16:09:09 volumio volumio[853]: info: Adding Item to queue: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/02 - S.O.S.mp3 Apr 16 16:09:09 volumio volumio[853]: info: Using cached record of: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/02 - S.O.S.mp3 Apr 16 16:09:09 volumio volumio[853]: info: Adding Item to queue: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/03 - Hallelujah.mp3 Apr 16 16:09:09 volumio volumio[853]: info: Using cached record of: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/03 - Hallelujah.mp3 Apr 16 16:09:09 volumio volumio[853]: info: Adding Item to queue: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/04 - Under My Skin.mp3 Apr 16 16:09:09 volumio volumio[853]: info: Using cached record of: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/04 - Under My Skin.mp3 Apr 16 16:09:09 volumio volumio[853]: info: Adding Item to queue: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/05 - Easy Come, Easy Go.mp3 Apr 16 16:09:09 volumio volumio[853]: info: Using cached record of: music-library/NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/05 - Easy Come, Easy Go.mp3 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushQueue Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::saveQueue Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::updateTrackBlock Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrackBlock Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPlay Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::play index 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::stop Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::play index undefined Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::startPlaybackTimer Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::clearAddPlayTracks NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/01 - The Roses.mp3 Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: stop PrevState: play Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] InitTimeout - Amp off in: 120 ms Apr 16 16:09:09 volumio volumio[853]: info: Apr 16 16:09:09 volumio volumio[853]: ---------------------------- MPD announces state update: player Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand stop took 71 milliseconds Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::getState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand stop took 19 milliseconds Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 16:09:09 volumio volumio[853]: info: Apr 16 16:09:09 volumio volumio[853]: ---------------------------- MPD announces state update: player Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::getState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Apr 16 16:09:09 volumio volumio[853]: info: Apr 16 16:09:09 volumio volumio[853]: ---------------------------- MPD announces state update: player Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::getState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Apr 16 16:09:09 volumio volumio[853]: info: Apr 16 16:09:09 volumio volumio[853]: ---------------------------- MPD announces system playlist update Apr 16 16:09:09 volumio volumio[853]: info: Ignoring MPD Status Update Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand status took 27 milliseconds Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand clear took 24 milliseconds Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand status took 22 milliseconds Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand status took 17 milliseconds Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand add "NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/01 - The Roses.mp3" Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseState Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::pushState Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::servicePushState Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 16 16:09:09 volumio volumio[853]: verbose: CURRENT POSITION 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState stateService stop Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus stop Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: No code Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::pushState Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::servicePushState Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 16 16:09:09 volumio volumio[853]: verbose: CURRENT POSITION 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState stateService stop Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus stop Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: No code Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: ------------------------------ 117ms Apr 16 16:09:09 volumio volumio[853]: info: ------------------------------ 116ms Apr 16 16:09:09 volumio volumio[853]: info: Apr 16 16:09:09 volumio volumio[853]: ---------------------------- MPD announces system playlist update Apr 16 16:09:09 volumio volumio[853]: info: Ignoring MPD Status Update Apr 16 16:09:09 volumio volumio[853]: info: Apr 16 16:09:09 volumio volumio[853]: ---------------------------- MPD announces system playlist update Apr 16 16:09:09 volumio volumio[853]: info: Ignoring MPD Status Update Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] Pulsing GPIO for 500ms Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] Togle GPIO: ON Apr 16 16:09:09 volumio volumio[853]: error: updateQueue error: null Apr 16 16:09:09 volumio volumio[853]: info: Apr 16 16:09:09 volumio volumio[853]: ---------------------------- MPD announces system playlist update Apr 16 16:09:09 volumio volumio[853]: info: Ignoring MPD Status Update Apr 16 16:09:09 volumio volumio[853]: info: ------------------------------ 117ms Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand playlistinfo took 112 milliseconds Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand add "NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/01 - The Roses.mp3" took 112 milliseconds Apr 16 16:09:09 volumio volumio[853]: info: ------------------------------ 20ms Apr 16 16:09:09 volumio volumio[853]: info: ------------------------------ 19ms Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand play Apr 16 16:09:09 volumio volumio[853]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Apr 16 16:09:09 volumio volumio[853]: info: ------------------------------ 157ms Apr 16 16:09:09 volumio volumio[853]: info: Apr 16 16:09:09 volumio volumio[853]: ---------------------------- MPD announces system playlist update Apr 16 16:09:09 volumio volumio[853]: info: Ignoring MPD Status Update Apr 16 16:09:09 volumio volumio[853]: info: Apr 16 16:09:09 volumio volumio[853]: ---------------------------- MPD announces system playlist update Apr 16 16:09:09 volumio volumio[853]: info: Ignoring MPD Status Update Apr 16 16:09:09 volumio volumio[853]: info: ------------------------------ 66ms Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand play took 56 milliseconds Apr 16 16:09:09 volumio volumio[853]: info: ------------------------------ 53ms Apr 16 16:09:09 volumio volumio[853]: info: ------------------------------ 52ms Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: stop PrevState: stop Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: stop PrevState: stop Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: stop PrevState: stop Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: stop PrevState: stop Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: stop PrevState: stop Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: stop PrevState: stop Apr 16 16:09:09 volumio volumio[853]: info: Apr 16 16:09:09 volumio volumio[853]: ---------------------------- MPD announces state update: player Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::getState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Apr 16 16:09:09 volumio volumio[853]: info: Apr 16 16:09:09 volumio volumio[853]: ---------------------------- MPD announces state update: player Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::getState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Apr 16 16:09:09 volumio volumio[853]: info: Apr 16 16:09:09 volumio volumio[853]: ---------------------------- MPD announces state update: player Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::getState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Apr 16 16:09:09 volumio volumio[853]: info: Apr 16 16:09:09 volumio volumio[853]: ---------------------------- MPD announces state update: player Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::getState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand status took 15 milliseconds Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand status took 12 milliseconds Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:09:09 volumio volumio[853]: info: Apr 16 16:09:09 volumio volumio[853]: ---------------------------- MPD announces state update: player Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::getState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Apr 16 16:09:09 volumio volumio[853]: info: Apr 16 16:09:09 volumio volumio[853]: ---------------------------- MPD announces state update: player Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::getState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand status took 34 milliseconds Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand status took 37 milliseconds Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand playlistinfo took 35 milliseconds Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand status took 30 milliseconds Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand status took 25 milliseconds Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseState Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::pushState Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::servicePushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Roses","artist":"Gin Wigmore","album":"Extended Play","uri":"NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/01 - The Roses.mp3","trackType":"mp3"} Apr 16 16:09:09 volumio volumio[853]: verbose: CURRENT POSITION 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState stateService play Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus stop Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::pushState Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::servicePushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: verbose: STATE SERVICE {"status":"play","position":0,"seek":982,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Roses","artist":"Gin Wigmore","album":"Extended Play","uri":"NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/01 - The Roses.mp3","trackType":"mp3"} Apr 16 16:09:09 volumio volumio[853]: verbose: CURRENT POSITION 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState stateService play Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus play Apr 16 16:09:09 volumio volumio[853]: info: Received an update from plugin. extracting info from payload Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: ------------------------------ 128ms Apr 16 16:09:09 volumio volumio[853]: info: ------------------------------ 145ms Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand playlistinfo took 87 milliseconds Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand playlistinfo took 87 milliseconds Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand playlistinfo took 83 milliseconds Apr 16 16:09:09 volumio volumio[853]: info: sendMpdCommand playlistinfo took 81 milliseconds Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Apr 16 16:09:09 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::pushState Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::servicePushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Roses","artist":"Gin Wigmore","album":"Extended Play","uri":"NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/01 - The Roses.mp3","trackType":"mp3"} Apr 16 16:09:09 volumio volumio[853]: verbose: CURRENT POSITION 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState stateService play Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus play Apr 16 16:09:09 volumio volumio[853]: info: Received an update from plugin. extracting info from payload Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::pushState Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::servicePushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Roses","artist":"Gin Wigmore","album":"Extended Play","uri":"NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/01 - The Roses.mp3","trackType":"mp3"} Apr 16 16:09:09 volumio volumio[853]: verbose: CURRENT POSITION 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState stateService play Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus play Apr 16 16:09:09 volumio volumio[853]: info: Received an update from plugin. extracting info from payload Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::pushState Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::servicePushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Roses","artist":"Gin Wigmore","album":"Extended Play","uri":"NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/01 - The Roses.mp3","trackType":"mp3"} Apr 16 16:09:09 volumio volumio[853]: verbose: CURRENT POSITION 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState stateService play Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus play Apr 16 16:09:09 volumio volumio[853]: info: Received an update from plugin. extracting info from payload Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: ControllerMpd::pushState Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::servicePushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":195,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"The Roses","artist":"Gin Wigmore","album":"Extended Play","uri":"NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/01 - The Roses.mp3","trackType":"mp3"} Apr 16 16:09:09 volumio volumio[853]: verbose: CURRENT POSITION 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState stateService play Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus play Apr 16 16:09:09 volumio volumio[853]: info: Received an update from plugin. extracting info from payload Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:09 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:09 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:09 volumio volumio[853]: info: ------------------------------ 297ms Apr 16 16:09:09 volumio volumio[853]: info: ------------------------------ 294ms Apr 16 16:09:09 volumio volumio[853]: info: ------------------------------ 284ms Apr 16 16:09:09 volumio volumio[853]: info: ------------------------------ 279ms Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: play PrevState: stop Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] Pulsing GPIO for 500ms Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] Togle GPIO: ON Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: play PrevState: play Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: play PrevState: play Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: play PrevState: play Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: play PrevState: play Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: play PrevState: play Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: play PrevState: play Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: play PrevState: play Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: play PrevState: play Apr 16 16:09:09 volumio volumio[853]: info: [ASDebug] CurState: play PrevState: play Apr 16 16:09:10 volumio volumio[853]: info: [ASDebug] Togle GPIO: OFF Apr 16 16:09:13 volumio volumio[853]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 16 16:09:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 16 16:09:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 16:09:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 16:09:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 16:09:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 16:09:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 16:09:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 16:09:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 16:09:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 16:09:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 16:09:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 16 16:09:14 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 16 16:09:21 volumio volumio[853]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Apr 16 16:09:21 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Apr 16 16:09:21 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Apr 16 16:09:21 volumio sudo[1605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 16 16:09:21 volumio sudo[1605]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:09:21 volumio sudo[1605]: pam_unix(sudo:session): session closed for user root Apr 16 16:09:21 volumio sudo[1607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 16 16:09:21 volumio sudo[1607]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:09:21 volumio volumio[853]: info: MPD Permissions set Apr 16 16:09:21 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:09:21 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:09:21 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:09:21 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:09:21 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:09:21 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:09:21 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:09:21 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:09:21 volumio systemd[1]: Stopping Music Player Daemon... Apr 16 16:09:21 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 16:09:21 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 16 16:09:21 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 16:09:21 volumio volumio[853]: error: Upnp client error: Error: This socket has been ended by the other party Apr 16 16:09:21 volumio systemd[1]: mpd.service: Succeeded. Apr 16 16:09:21 volumio systemd[1]: Stopped Music Player Daemon. Apr 16 16:09:21 volumio systemd[1]: Starting Music Player Daemon... Apr 16 16:09:22 volumio systemd[1]: camilladsp.service: Succeeded. Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 163, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.volume.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 538, in main Apr 16 16:09:23 volumio volumio[591]: vol = self.client.query("GetVolume") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 165, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.mute.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 621, in main Apr 16 16:09:23 volumio volumio[591]: mute = self.client.query("GetMute") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 163, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.volume.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 538, in main Apr 16 16:09:23 volumio volumio[591]: vol = self.client.query("GetVolume") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 163, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.volume.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 538, in main Apr 16 16:09:23 volumio volumio[591]: vol = self.client.query("GetVolume") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 165, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.mute.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 621, in main Apr 16 16:09:23 volumio volumio[591]: mute = self.client.query("GetMute") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 163, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.volume.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 538, in main Apr 16 16:09:23 volumio volumio[591]: vol = self.client.query("GetVolume") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 165, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.mute.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 621, in main Apr 16 16:09:23 volumio volumio[591]: mute = self.client.query("GetMute") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 163, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.volume.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 538, in main Apr 16 16:09:23 volumio volumio[591]: vol = self.client.query("GetVolume") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 165, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.mute.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 621, in main Apr 16 16:09:23 volumio volumio[591]: mute = self.client.query("GetMute") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 163, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.volume.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 538, in main Apr 16 16:09:23 volumio volumio[591]: vol = self.client.query("GetVolume") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 165, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.mute.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 621, in main Apr 16 16:09:23 volumio volumio[591]: mute = self.client.query("GetMute") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 163, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.volume.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 538, in main Apr 16 16:09:23 volumio volumio[591]: vol = self.client.query("GetVolume") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 165, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.mute.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 621, in main Apr 16 16:09:23 volumio volumio[591]: mute = self.client.query("GetMute") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 163, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.volume.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 538, in main Apr 16 16:09:23 volumio volumio[591]: vol = self.client.query("GetVolume") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 165, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.mute.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 621, in main Apr 16 16:09:23 volumio volumio[591]: mute = self.client.query("GetMute") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 163, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.volume.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 538, in main Apr 16 16:09:23 volumio volumio[591]: vol = self.client.query("GetVolume") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 165, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.mute.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 621, in main Apr 16 16:09:23 volumio volumio[591]: mute = self.client.query("GetMute") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 163, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.volume.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 538, in main Apr 16 16:09:23 volumio volumio[591]: vol = self.client.query("GetVolume") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 165, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.mute.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 621, in main Apr 16 16:09:23 volumio volumio[591]: mute = self.client.query("GetMute") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 163, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.volume.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 538, in main Apr 16 16:09:23 volumio volumio[591]: vol = self.client.query("GetVolume") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 165, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.mute.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 621, in main Apr 16 16:09:23 volumio volumio[591]: mute = self.client.query("GetMute") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 163, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.volume.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 538, in main Apr 16 16:09:23 volumio volumio[591]: vol = self.client.query("GetVolume") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 165, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.mute.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 621, in main Apr 16 16:09:23 volumio volumio[591]: mute = self.client.query("GetMute") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 163, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.volume.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 538, in main Apr 16 16:09:23 volumio volumio[591]: vol = self.client.query("GetVolume") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 165, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.mute.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 621, in main Apr 16 16:09:23 volumio volumio[591]: mute = self.client.query("GetMute") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:23 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:23 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:23 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:23 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:23 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 165, in get_param Apr 16 16:09:23 volumio volumio[591]: result = cdsp.mute.main() Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 621, in main Apr 16 16:09:23 volumio volumio[591]: mute = self.client.query("GetMute") Apr 16 16:09:23 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:23 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:23 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:24 volumio mpd[1616]: Apr 16 16:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 16 16:09:24 volumio systemd[1]: Started Music Player Daemon. Apr 16 16:09:24 volumio sudo[1607]: pam_unix(sudo:session): session closed for user root Apr 16 16:09:24 volumio volumio[853]: error: updateQueue error: null Apr 16 16:09:24 volumio systemd[1]: camilladsp.service: Service RestartSec=2s expired, scheduling restart. Apr 16 16:09:24 volumio systemd[1]: camilladsp.service: Scheduled restart job, restart counter is at 12. Apr 16 16:09:24 volumio systemd[1]: Stopped CamillaDsp Daemon. Apr 16 16:09:24 volumio systemd[1]: Started CamillaDsp Daemon. Apr 16 16:09:25 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:25 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:25 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:25 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:25 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:25 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:25 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 163, in get_param Apr 16 16:09:25 volumio volumio[591]: result = cdsp.volume.main() Apr 16 16:09:25 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 538, in main Apr 16 16:09:25 volumio volumio[591]: vol = self.client.query("GetVolume") Apr 16 16:09:25 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:25 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:25 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:25 volumio volumio[591]: ERROR:aiohttp.server:Error handling request Apr 16 16:09:25 volumio volumio[591]: Traceback (most recent call last): Apr 16 16:09:25 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_protocol.py", line 452, in _handle_request Apr 16 16:09:25 volumio volumio[591]: resp = await request_handler(request) Apr 16 16:09:25 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/aiohttp/web_app.py", line 543, in _handle Apr 16 16:09:25 volumio volumio[591]: resp = await handler(request) Apr 16 16:09:25 volumio volumio[591]: File "/opt/pp/pyenv/ngui/backend/views.py", line 165, in get_param Apr 16 16:09:25 volumio volumio[591]: result = cdsp.mute.main() Apr 16 16:09:25 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 621, in main Apr 16 16:09:25 volumio volumio[591]: mute = self.client.query("GetMute") Apr 16 16:09:25 volumio volumio[591]: File "/home/volumio/.local/lib/python3.8/site-packages/camilladsp/camilladsp.py", line 66, in query Apr 16 16:09:25 volumio volumio[591]: raise IOError("Not connected to CamillaDSP") Apr 16 16:09:25 volumio volumio[591]: OSError: Not connected to CamillaDSP Apr 16 16:09:31 volumio volumio[853]: info: CoreCommandRouter::volumioNext Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::next Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::stop Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::updateTrackBlock Apr 16 16:09:31 volumio volumio[853]: info: CorePlayQueue::getTrackBlock Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:31 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:31 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:09:31 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::serviceStop Apr 16 16:09:31 volumio volumio[853]: info: CorePlayQueue::getTrack 0 Apr 16 16:09:31 volumio volumio[853]: info: CoreCommandRouter::serviceStop Apr 16 16:09:31 volumio volumio[853]: info: ControllerMpd::stop Apr 16 16:09:31 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 16:09:31 volumio volumio[853]: info: [ASDebug] CurState: stop PrevState: play Apr 16 16:09:31 volumio volumio[853]: info: [ASDebug] InitTimeout - Amp off in: 120 ms Apr 16 16:09:31 volumio volumio[853]: info: sendMpdCommand stop took 18 milliseconds Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::play index undefined Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 16:09:31 volumio volumio[853]: info: CorePlayQueue::getTrack 3 Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::startPlaybackTimer Apr 16 16:09:31 volumio volumio[853]: info: CorePlayQueue::getTrack 3 Apr 16 16:09:31 volumio volumio[853]: verbose: ControllerMpd::clearAddPlayTracks NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/04 - Under My Skin.mp3 Apr 16 16:09:31 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand stop Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::updateTrackBlock Apr 16 16:09:31 volumio volumio[853]: info: CorePlayQueue::getTrackBlock Apr 16 16:09:31 volumio volumio[853]: info: sendMpdCommand stop took 9 milliseconds Apr 16 16:09:31 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand clear Apr 16 16:09:31 volumio volumio[853]: info: Apr 16 16:09:31 volumio volumio[853]: ---------------------------- MPD announces system playlist update Apr 16 16:09:31 volumio volumio[853]: info: Ignoring MPD Status Update Apr 16 16:09:31 volumio volumio[853]: info: sendMpdCommand clear took 4 milliseconds Apr 16 16:09:31 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand add "NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/04 - Under My Skin.mp3" Apr 16 16:09:31 volumio volumio[853]: error: updateQueue error: null Apr 16 16:09:31 volumio volumio[853]: info: Apr 16 16:09:31 volumio volumio[853]: ---------------------------- MPD announces system playlist update Apr 16 16:09:31 volumio volumio[853]: info: Ignoring MPD Status Update Apr 16 16:09:31 volumio volumio[853]: info: ------------------------------ 9ms Apr 16 16:09:31 volumio volumio[853]: info: sendMpdCommand add "NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/04 - Under My Skin.mp3" took 6 milliseconds Apr 16 16:09:31 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand play Apr 16 16:09:31 volumio volumio[853]: info: ------------------------------ 7ms Apr 16 16:09:31 volumio volumio[853]: info: sendMpdCommand play took 6 milliseconds Apr 16 16:09:31 volumio volumio[853]: info: [ASDebug] Pulsing GPIO for 500ms Apr 16 16:09:31 volumio volumio[853]: info: [ASDebug] Togle GPIO: ON Apr 16 16:09:31 volumio volumio[853]: info: Apr 16 16:09:31 volumio volumio[853]: ---------------------------- MPD announces state update: player Apr 16 16:09:31 volumio volumio[853]: info: ControllerMpd::getState Apr 16 16:09:31 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Apr 16 16:09:31 volumio volumio[853]: info: Apr 16 16:09:31 volumio volumio[853]: ---------------------------- MPD announces state update: player Apr 16 16:09:31 volumio volumio[853]: info: sendMpdCommand status took 14 milliseconds Apr 16 16:09:31 volumio volumio[853]: info: ControllerMpd::getState Apr 16 16:09:31 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Apr 16 16:09:31 volumio volumio[853]: verbose: ControllerMpd::parseState Apr 16 16:09:31 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:09:31 volumio volumio[853]: info: sendMpdCommand status took 4 milliseconds Apr 16 16:09:31 volumio volumio[853]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 16 16:09:31 volumio volumio[853]: verbose: ControllerMpd::parseState Apr 16 16:09:31 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:09:31 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Apr 16 16:09:31 volumio volumio[853]: info: ControllerMpd::pushState Apr 16 16:09:31 volumio volumio[853]: info: CoreCommandRouter::servicePushState Apr 16 16:09:31 volumio volumio[853]: info: CorePlayQueue::getTrack 3 Apr 16 16:09:31 volumio volumio[853]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":171,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Under My Skin","artist":"Gin Wigmore","album":"Extended Play","uri":"NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/04 - Under My Skin.mp3","trackType":"mp3"} Apr 16 16:09:31 volumio volumio[853]: verbose: CURRENT POSITION 3 Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::syncState stateService play Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus stop Apr 16 16:09:31 volumio volumio[853]: info: ------------------------------ 32ms Apr 16 16:09:31 volumio volumio[853]: info: sendMpdCommand playlistinfo took 8 milliseconds Apr 16 16:09:31 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Apr 16 16:09:31 volumio volumio[853]: info: ControllerMpd::pushState Apr 16 16:09:31 volumio volumio[853]: info: CoreCommandRouter::servicePushState Apr 16 16:09:31 volumio volumio[853]: info: CorePlayQueue::getTrack 3 Apr 16 16:09:31 volumio volumio[853]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":171,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Under My Skin","artist":"Gin Wigmore","album":"Extended Play","uri":"NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/04 - Under My Skin.mp3","trackType":"mp3"} Apr 16 16:09:31 volumio volumio[853]: verbose: CURRENT POSITION 3 Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::syncState stateService play Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus play Apr 16 16:09:31 volumio volumio[853]: info: Received an update from plugin. extracting info from payload Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:31 volumio volumio[853]: info: CorePlayQueue::getTrack 3 Apr 16 16:09:31 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:09:31 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:31 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:09:31 volumio volumio[853]: info: CorePlayQueue::getTrack 3 Apr 16 16:09:31 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:09:31 volumio volumio[853]: info: ------------------------------ 69ms Apr 16 16:09:31 volumio volumio[853]: info: [ASDebug] CurState: play PrevState: stop Apr 16 16:09:31 volumio volumio[853]: info: [ASDebug] Pulsing GPIO for 500ms Apr 16 16:09:31 volumio volumio[853]: info: [ASDebug] Togle GPIO: ON Apr 16 16:09:31 volumio volumio[853]: info: [ASDebug] CurState: play PrevState: play Apr 16 16:09:32 volumio volumio[853]: info: [ASDebug] Togle GPIO: OFF Apr 16 16:10:01 volumio sshd[1746]: Accepted password for volumio from 192.168.3.208 port 53702 ssh2 Apr 16 16:10:01 volumio sshd[1746]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Apr 16 16:10:01 volumio systemd-logind[594]: New session 3 of user volumio. Apr 16 16:10:01 volumio systemd[1]: Started Session 3 of user volumio. Apr 16 16:10:18 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 16 16:10:18 volumio volumio[853]: error: Could not retrieve plugin system_controller my_volumio Apr 16 16:10:19 volumio volumio[853]: info: CoreCommandRouter::Close All Modals sent Apr 16 16:10:21 volumio volumio[853]: info: [ASDebug] Port: 110 Apr 16 16:10:21 volumio volumio[853]: info: [ASDebug] Inverted: false Apr 16 16:10:21 volumio volumio[853]: info: [ASDebug] Delay: 120 Apr 16 16:10:24 volumio sudo[1846]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 16 16:10:24 volumio sudo[1846]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:10:24 volumio sudo[1846]: pam_unix(sudo:session): session closed for user root Apr 16 16:10:24 volumio sudo[1848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 16:10:24 volumio sudo[1848]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 16 16:10:24 volumio sudo[1848]: pam_unix(sudo:session): session closed for user root Apr 16 16:10:24 volumio volumio[853]: verbose: New Socket.io Connection to 192.168.3.201 from 192.168.3.208 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/123.0.0.0 Safari/537.36 Total Clients: 4 Apr 16 16:10:24 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 16 16:10:24 volumio volumio[853]: error: Could not retrieve plugin system_controller my_volumio Apr 16 16:10:24 volumio volumio[853]: info: CoreCommandRouter::volumioGetState Apr 16 16:10:24 volumio volumio[853]: info: CorePlayQueue::getTrack 3 Apr 16 16:10:24 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 16 16:10:24 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 16 16:10:24 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 16 16:10:24 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 16 16:10:25 volumio volumio[853]: info: CoreCommandRouter::Close All Modals sent Apr 16 16:10:27 volumio volumio[853]: info: Enabling plugin ampswitch Apr 16 16:10:27 volumio volumio[853]: info: Loading plugin "ampswitch"... Apr 16 16:10:27 volumio volumio[853]: info: Applying required configuration parameters for plugin ampswitch Apr 16 16:10:27 volumio volumio[853]: info: PLUGIN START: ampswitch Apr 16 16:10:27 volumio kernel: sun8i-h3-pinctrl 1c20800.pinctrl: supply vcc-pd not found, using dummy regulator Apr 16 16:10:27 volumio volumio[853]: info: Done. Apr 16 16:10:27 volumio volumio[853]: info: CoreCommandRouter::volumioGetState Apr 16 16:10:27 volumio volumio[853]: info: CorePlayQueue::getTrack 3 Apr 16 16:10:27 volumio volumio[853]: info: [ASDebug] CurState: play PrevState: play Apr 16 16:10:27 volumio volumio[853]: info: [ASDebug] CurState: play PrevState: play Apr 16 16:10:27 volumio volumio[853]: info: [ASDebug] CurState: play PrevState: play Apr 16 16:10:30 volumio volumio[853]: info: CoreCommandRouter::volumioPause Apr 16 16:10:30 volumio volumio[853]: info: CoreStateMachine::pause Apr 16 16:10:30 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Apr 16 16:10:30 volumio volumio[853]: info: CoreStateMachine::servicePause Apr 16 16:10:30 volumio volumio[853]: info: CorePlayQueue::getTrack 3 Apr 16 16:10:30 volumio volumio[853]: info: CoreCommandRouter::servicePause Apr 16 16:10:30 volumio volumio[853]: info: ControllerMpd::pause Apr 16 16:10:30 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand pause Apr 16 16:10:30 volumio volumio[853]: info: Apr 16 16:10:30 volumio volumio[853]: ---------------------------- MPD announces state update: player Apr 16 16:10:30 volumio volumio[853]: info: sendMpdCommand pause took 3 milliseconds Apr 16 16:10:30 volumio volumio[853]: info: ControllerMpd::getState Apr 16 16:10:30 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand status Apr 16 16:10:30 volumio volumio[853]: info: sendMpdCommand status took 2 milliseconds Apr 16 16:10:30 volumio volumio[853]: verbose: ControllerMpd::parseState Apr 16 16:10:30 volumio volumio[853]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 16 16:10:30 volumio volumio[853]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 16 16:10:30 volumio volumio[853]: verbose: ControllerMpd::parseTrackInfo Apr 16 16:10:30 volumio volumio[853]: info: ControllerMpd::pushState Apr 16 16:10:30 volumio volumio[853]: info: CoreCommandRouter::servicePushState Apr 16 16:10:30 volumio volumio[853]: info: CorePlayQueue::getTrack 3 Apr 16 16:10:31 volumio volumio[853]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61097,"duration":171,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Under My Skin","artist":"Gin Wigmore","album":"Extended Play","uri":"NAS/MZ/Gin Wigmore/Gin Wigmore - 2008 - Extended Play/04 - Under My Skin.mp3","trackType":"mp3"} Apr 16 16:10:31 volumio volumio[853]: verbose: CURRENT POSITION 3 Apr 16 16:10:31 volumio volumio[853]: info: CoreStateMachine::syncState stateService pause Apr 16 16:10:31 volumio volumio[853]: info: CoreStateMachine::syncState currentStatus pause Apr 16 16:10:31 volumio volumio[853]: info: CoreStateMachine::pushState Apr 16 16:10:31 volumio volumio[853]: info: CorePlayQueue::getTrack 3 Apr 16 16:10:31 volumio volumio[853]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 16:10:31 volumio volumio[853]: info: CoreCommandRouter::volumioPushState Apr 16 16:10:31 volumio volumio[853]: info: CoreStateMachine::stPlaybackTimer Apr 16 16:10:31 volumio volumio[853]: info: ------------------------------ 29ms Apr 16 16:10:31 volumio volumio[853]: info: [ASDebug] CurState: pause PrevState: play Apr 16 16:10:31 volumio volumio[853]: info: [ASDebug] InitTimeout - Amp off in: 120 ms Apr 16 16:10:31 volumio volumio[853]: info: [ASDebug] CurState: pause PrevState: play Apr 16 16:10:31 volumio volumio[853]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 16 16:10:31 volumio volumio[853]: info: [ASDebug] Pulsing GPIO for 500ms Apr 16 16:10:31 volumio volumio[853]: info: [ASDebug] Togle GPIO: ON Apr 16 16:10:31 volumio volumio[853]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 16:10:31 volumio volumio[853]: Error: EBADF: bad file descriptor, write Apr 16 16:10:31 volumio volumio[853]: at Object.writeSync (fs.js:737:3) Apr 16 16:10:31 volumio volumio[853]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 16 16:10:31 volumio volumio[853]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:199:23) Apr 16 16:10:31 volumio volumio[853]: at AmpSwitchController.pulse (/data/plugins/system_controller/ampswitch/index.js:223:8) Apr 16 16:10:31 volumio volumio[853]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:187:39) Apr 16 16:10:31 volumio volumio[853]: at listOnTimeout (internal/timers.js:557:17) Apr 16 16:10:31 volumio volumio[853]: at processTimers (internal/timers.js:500:7) { Apr 16 16:10:31 volumio volumio[853]: errno: -9, Apr 16 16:10:31 volumio volumio[853]: syscall: 'write', Apr 16 16:10:31 volumio volumio[853]: code: 'EBADF' Apr 16 16:10:31 volumio volumio[853]: } Apr 16 16:10:31 volumio volumio[853]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 16:10:31 volumio systemd[1]: camilladsp.service: Succeeded. Apr 16 16:10:32 volumio sudo[1968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-16 16:09 Apr 16 16:10:32 volumio sudo[1968]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="56d8f4e08ca3cb143324aa0740c362c469043a9b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="a77c7968e70cebb21b39a3791120006fed600769" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun Mar 31 07:04:08 UTC 2024" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="orangepipc" VOLUMIO_DEVICENAME="Orange Pi PC" VOLUMIO_HASH="096b35d8f64e65426ff888d164244ddd"