-- Logs begin at Wed 2025-09-17 17:22:15 CST, end at Wed 2025-09-17 17:26:31 CST. -- Sep 17 17:25:03 volumio volumio[1146]: info: Clearing queue after UPNP request Sep 17 17:25:03 volumio volumio[1146]: info: CoreStateMachine::ClearQueue Sep 17 17:25:03 volumio volumio[1146]: info: CoreStateMachine::stop Sep 17 17:25:03 volumio volumio[1146]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 17:25:03 volumio volumio[1146]: info: CorePlayQueue::clearPlayQueue Sep 17 17:25:03 volumio volumio[1146]: info: CorePlayQueue::saveQueue Sep 17 17:25:03 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:03 volumio volumio[1146]: info: CoreCommandRouter::volumioPushQueue Sep 17 17:25:03 volumio volumio[1146]: error: Upnp client error: Error: This socket has been ended by the other party Sep 17 17:25:04 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:25:04 volumio volumio[1146]: info: CorePlayQueue::getTrack 0 Sep 17 17:25:05 volumio volumio[1146]: info: Starting UPNP Playback Sep 17 17:25:05 volumio volumio[1146]: info: Preparing playback through UPNP Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:25:05 volumio volumio[1146]: info: CorePlayQueue::getTrack 0 Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 10ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 7ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 6ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 5ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 4ms Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 3ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 3ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 2ms Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 11ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 10ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 10ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 3ms Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 2ms Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 5ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 4ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 3ms Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 3ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 1ms Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 3ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 2ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 2ms Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces system playlist update Sep 17 17:25:05 volumio volumio[1146]: info: Ignoring MPD Status Update Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 3ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 3ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 2ms Sep 17 17:25:05 volumio volumio[1703]: 49 Sep 17 17:25:05 volumio volumio[1703]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 17 17:25:05 volumio volumio[1703]: Dload Upload Total Spent Left Speed Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- Client requests Volume 49 Sep 17 17:25:05 volumio volumio[1146]: info: VolumeController::SetAlsaVolume49 Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:05 volumio volumio[1703]: [158B blob data] Sep 17 17:25:05 volumio volumio[1703]: {"time":1758101105738,"response":"volume Success"}49 Sep 17 17:25:05 volumio volumio[1146]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces state update: player Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::getState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand status Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces state update: player Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::getState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand status Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces state update: player Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::getState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand status Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces state update: player Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::getState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand status Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand status took 47 milliseconds Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::parseState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces state update: player Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::getState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand status Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand status took 47 milliseconds Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand status took 44 milliseconds Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand status took 37 milliseconds Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::parseState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::parseState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::parseState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces state update: player Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::getState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand status Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces state update: player Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::getState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand status Sep 17 17:25:05 volumio volumio[1146]: info: Sep 17 17:25:05 volumio volumio[1146]: ---------------------------- MPD announces state update: player Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::getState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand status Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand playlistinfo took 10 milliseconds Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand status took 10 milliseconds Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand playlistinfo took 8 milliseconds Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand status took 6 milliseconds Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand status took 4 milliseconds Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand status took 4 milliseconds Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::parseTrackInfo Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::parseState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::parseTrackInfo Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::parseTrackInfo Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::parseTrackInfo Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::parseState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::parseState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::parseState Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::servicePushState Sep 17 17:25:05 volumio volumio[1146]: verbose: In UPNP mode Sep 17 17:25:05 volumio volumio[1146]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"倩女幽魂","artist":"音展","album":"2024广州国际音响唱片展","uri":"http://192.168.31.114:34209/bridge-1.flac","trackType":"flac"} Sep 17 17:25:05 volumio volumio[1146]: verbose: CURRENT POSITION 0 Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState stateService play Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState currentStatus stop Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::servicePushState Sep 17 17:25:05 volumio volumio[1146]: verbose: In UPNP mode Sep 17 17:25:05 volumio volumio[1146]: verbose: STATE SERVICE {"status":"play","position":0,"seek":136,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"598 Kbps","isStreaming":false,"title":"倩女幽魂","artist":"音展","album":"2024广州国际音响唱片展","uri":"http://192.168.31.114:34209/bridge-1.flac","trackType":"flac"} Sep 17 17:25:05 volumio volumio[1146]: verbose: CURRENT POSITION 0 Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState stateService play Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState currentStatus play Sep 17 17:25:05 volumio volumio[1146]: info: Received an update from plugin. extracting info from payload Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::servicePushState Sep 17 17:25:05 volumio volumio[1146]: verbose: In UPNP mode Sep 17 17:25:05 volumio volumio[1146]: verbose: STATE SERVICE {"status":"play","position":0,"seek":432,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"587 Kbps","isStreaming":false,"title":"倩女幽魂","artist":"音展","album":"2024广州国际音响唱片展","uri":"http://192.168.31.114:34209/bridge-1.flac","trackType":"flac"} Sep 17 17:25:05 volumio volumio[1146]: verbose: CURRENT POSITION 0 Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState stateService play Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState currentStatus play Sep 17 17:25:05 volumio volumio[1146]: info: Received an update from plugin. extracting info from payload Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::servicePushState Sep 17 17:25:05 volumio volumio[1146]: verbose: In UPNP mode Sep 17 17:25:05 volumio volumio[1146]: verbose: STATE SERVICE {"status":"play","position":0,"seek":432,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"587 Kbps","isStreaming":false,"title":"倩女幽魂","artist":"音展","album":"2024广州国际音响唱片展","uri":"http://192.168.31.114:34209/bridge-1.flac","trackType":"flac"} Sep 17 17:25:05 volumio volumio[1146]: verbose: CURRENT POSITION 0 Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState stateService play Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState currentStatus play Sep 17 17:25:05 volumio volumio[1146]: info: Received an update from plugin. extracting info from payload Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 101ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 121ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 121ms Sep 17 17:25:05 volumio volumio[1146]: info: ------------------------------ 118ms Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand playlistinfo took 70 milliseconds Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand playlistinfo took 69 milliseconds Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand playlistinfo took 69 milliseconds Sep 17 17:25:05 volumio volumio[1146]: info: sendMpdCommand playlistinfo took 78 milliseconds Sep 17 17:25:05 volumio volumio[1146]: verbose: ControllerMpd::parseTrackInfo Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::servicePushState Sep 17 17:25:05 volumio volumio[1146]: verbose: In UPNP mode Sep 17 17:25:05 volumio volumio[1146]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"745 Kbps","isStreaming":false,"title":"倩女幽魂","artist":"音展","album":"2024广州国际音响唱片展","uri":"http://192.168.31.114:34209/bridge-1.flac","trackType":"flac"} Sep 17 17:25:05 volumio volumio[1146]: verbose: CURRENT POSITION 0 Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState stateService play Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState currentStatus play Sep 17 17:25:05 volumio volumio[1146]: info: Received an update from plugin. extracting info from payload Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::servicePushState Sep 17 17:25:05 volumio volumio[1146]: verbose: In UPNP mode Sep 17 17:25:05 volumio volumio[1146]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"745 Kbps","isStreaming":false,"title":"倩女幽魂","artist":"音展","album":"2024广州国际音响唱片展","uri":"http://192.168.31.114:34209/bridge-1.flac","trackType":"flac"} Sep 17 17:25:05 volumio volumio[1146]: verbose: CURRENT POSITION 0 Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState stateService play Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState currentStatus play Sep 17 17:25:05 volumio volumio[1146]: info: Received an update from plugin. extracting info from payload Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::servicePushState Sep 17 17:25:05 volumio volumio[1146]: verbose: In UPNP mode Sep 17 17:25:05 volumio volumio[1146]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"745 Kbps","isStreaming":false,"title":"倩女幽魂","artist":"音展","album":"2024广州国际音响唱片展","uri":"http://192.168.31.114:34209/bridge-1.flac","trackType":"flac"} Sep 17 17:25:05 volumio volumio[1146]: verbose: CURRENT POSITION 0 Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState stateService play Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState currentStatus play Sep 17 17:25:05 volumio volumio[1146]: info: Received an update from plugin. extracting info from payload Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:05 volumio volumio[1146]: info: ControllerMpd::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::servicePushState Sep 17 17:25:05 volumio volumio[1146]: verbose: In UPNP mode Sep 17 17:25:05 volumio volumio[1146]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"745 Kbps","isStreaming":false,"title":"倩女幽魂","artist":"音展","album":"2024广州国际音响唱片展","uri":"http://192.168.31.114:34209/bridge-1.flac","trackType":"flac"} Sep 17 17:25:05 volumio volumio[1146]: verbose: CURRENT POSITION 0 Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState stateService play Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::syncState currentStatus play Sep 17 17:25:05 volumio volumio[1146]: info: Received an update from plugin. extracting info from payload Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 17 17:25:05 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:05 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:06 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:06 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:06 volumio volumio[1146]: info: ------------------------------ 168ms Sep 17 17:25:06 volumio volumio[1146]: info: ------------------------------ 166ms Sep 17 17:25:06 volumio volumio[1146]: info: ------------------------------ 165ms Sep 17 17:25:06 volumio volumio[1146]: info: ------------------------------ 164ms Sep 17 17:25:06 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:06 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:06 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:15 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:25:15 volumio sudo[2284]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Sep 17 17:25:15 volumio sudo[2284]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:25:15 volumio volumio[1703]: 73 Sep 17 17:25:15 volumio volumio[1703]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 17 17:25:15 volumio volumio[1703]: Dload Upload Total Spent Left Speed Sep 17 17:25:15 volumio volumio[1146]: info: Sep 17 17:25:15 volumio volumio[1146]: ---------------------------- Client requests Volume 73 Sep 17 17:25:15 volumio volumio[1146]: info: VolumeController::SetAlsaVolume73 Sep 17 17:25:15 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:15 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:25:15 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:15 volumio volumio[1703]: [158B blob data] Sep 17 17:25:15 volumio volumio[1703]: {"time":1758101115609,"response":"volume Success"}73 Sep 17 17:25:15 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:15 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:15 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:15 volumio volumio[1146]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Sep 17 17:25:17 volumio volumio[1146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 Sep 17 17:25:17 volumio volumio[1703]: 91 Sep 17 17:25:17 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:25:17 volumio volumio[1703]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 17 17:25:17 volumio volumio[1703]: Dload Upload Total Spent Left Speed Sep 17 17:25:17 volumio volumio[1146]: info: Sep 17 17:25:17 volumio volumio[1146]: ---------------------------- Client requests Volume 91 Sep 17 17:25:17 volumio volumio[1146]: info: VolumeController::SetAlsaVolume91 Sep 17 17:25:17 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:17 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:25:17 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:17 volumio volumio[1703]: [158B blob data] Sep 17 17:25:17 volumio volumio[1703]: {"time":1758101117649,"response":"volume Success"}91 Sep 17 17:25:17 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:17 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:17 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:17 volumio volumio[1146]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Sep 17 17:25:18 volumio volumio[1146]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Sep 17 17:25:18 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:25:19 volumio volumio[1703]: 92 Sep 17 17:25:19 volumio volumio[1703]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 17 17:25:19 volumio volumio[1703]: Dload Upload Total Spent Left Speed Sep 17 17:25:19 volumio volumio[1146]: info: Sep 17 17:25:19 volumio volumio[1146]: ---------------------------- Client requests Volume 92 Sep 17 17:25:19 volumio volumio[1146]: info: VolumeController::SetAlsaVolume92 Sep 17 17:25:19 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:19 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:25:19 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:19 volumio volumio[1703]: [158B blob data] Sep 17 17:25:19 volumio volumio[1703]: {"time":1758101119220,"response":"volume Success"}92 Sep 17 17:25:19 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:19 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:19 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:19 volumio volumio[1146]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Sep 17 17:25:23 volumio volumio[1703]: 93 Sep 17 17:25:23 volumio volumio[1703]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 17 17:25:23 volumio volumio[1703]: Dload Upload Total Spent Left Speed Sep 17 17:25:23 volumio volumio[1146]: info: Sep 17 17:25:23 volumio volumio[1146]: ---------------------------- Client requests Volume 93 Sep 17 17:25:23 volumio volumio[1146]: info: VolumeController::SetAlsaVolume93 Sep 17 17:25:23 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:23 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:25:23 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:23 volumio volumio[1703]: [158B blob data] Sep 17 17:25:23 volumio volumio[1703]: {"time":1758101123492,"response":"volume Success"}93 Sep 17 17:25:23 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:23 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:23 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:23 volumio volumio[1146]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Sep 17 17:25:24 volumio volumio[1703]: 94 Sep 17 17:25:24 volumio volumio[1703]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 17 17:25:24 volumio volumio[1703]: Dload Upload Total Spent Left Speed Sep 17 17:25:24 volumio volumio[1146]: info: Sep 17 17:25:24 volumio volumio[1146]: ---------------------------- Client requests Volume 94 Sep 17 17:25:24 volumio volumio[1146]: info: VolumeController::SetAlsaVolume94 Sep 17 17:25:24 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:24 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:25:24 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:24 volumio volumio[1703]: [158B blob data] Sep 17 17:25:24 volumio volumio[1703]: {"time":1758101124270,"response":"volume Success"}94 Sep 17 17:25:24 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:24 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:24 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:24 volumio volumio[1146]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Sep 17 17:25:24 volumio volumio[1703]: 95 Sep 17 17:25:24 volumio volumio[1703]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 17 17:25:24 volumio volumio[1703]: Dload Upload Total Spent Left Speed Sep 17 17:25:24 volumio volumio[1146]: info: Sep 17 17:25:24 volumio volumio[1146]: ---------------------------- Client requests Volume 95 Sep 17 17:25:24 volumio volumio[1146]: info: VolumeController::SetAlsaVolume95 Sep 17 17:25:24 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:24 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:25:24 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:24 volumio volumio[1703]: [158B blob data] Sep 17 17:25:24 volumio volumio[1703]: {"time":1758101124898,"response":"volume Success"}95 Sep 17 17:25:24 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:24 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:24 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:24 volumio volumio[1146]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Sep 17 17:25:25 volumio volumio[1703]: 96 Sep 17 17:25:25 volumio volumio[1703]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 17 17:25:25 volumio volumio[1703]: Dload Upload Total Spent Left Speed Sep 17 17:25:25 volumio volumio[1146]: info: Sep 17 17:25:25 volumio volumio[1146]: ---------------------------- Client requests Volume 96 Sep 17 17:25:25 volumio volumio[1146]: info: VolumeController::SetAlsaVolume96 Sep 17 17:25:25 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:25 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:25:25 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:25 volumio volumio[1703]: [158B blob data] Sep 17 17:25:25 volumio volumio[1703]: {"time":1758101125796,"response":"volume Success"}96 Sep 17 17:25:25 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:25 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:25 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:25 volumio volumio[1146]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Sep 17 17:25:26 volumio volumio[1703]: 97 Sep 17 17:25:26 volumio volumio[1703]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 17 17:25:26 volumio volumio[1703]: Dload Upload Total Spent Left Speed Sep 17 17:25:26 volumio volumio[1146]: info: Sep 17 17:25:26 volumio volumio[1146]: ---------------------------- Client requests Volume 97 Sep 17 17:25:26 volumio volumio[1146]: info: VolumeController::SetAlsaVolume97 Sep 17 17:25:26 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:26 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:25:26 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:26 volumio volumio[1703]: [158B blob data] Sep 17 17:25:26 volumio volumio[1703]: {"time":1758101126363,"response":"volume Success"}97 Sep 17 17:25:26 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:26 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:26 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:26 volumio volumio[1146]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Sep 17 17:25:26 volumio volumio[1703]: 99 Sep 17 17:25:26 volumio volumio[1703]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 17 17:25:26 volumio volumio[1703]: Dload Upload Total Spent Left Speed Sep 17 17:25:26 volumio volumio[1146]: info: Sep 17 17:25:26 volumio volumio[1146]: ---------------------------- Client requests Volume 99 Sep 17 17:25:26 volumio volumio[1146]: info: VolumeController::SetAlsaVolume99 Sep 17 17:25:26 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:26 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:25:26 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:26 volumio volumio[1703]: [158B blob data] Sep 17 17:25:26 volumio volumio[1703]: {"time":1758101126864,"response":"volume Success"}99 Sep 17 17:25:26 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:26 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:26 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:26 volumio volumio[1146]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Sep 17 17:25:27 volumio volumio[1703]: 100 Sep 17 17:25:27 volumio volumio[1703]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 17 17:25:27 volumio volumio[1703]: Dload Upload Total Spent Left Speed Sep 17 17:25:27 volumio volumio[1146]: info: Sep 17 17:25:27 volumio volumio[1146]: ---------------------------- Client requests Volume 100 Sep 17 17:25:27 volumio volumio[1146]: info: VolumeController::SetAlsaVolume100 Sep 17 17:25:27 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:25:27 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:25:27 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:25:27 volumio volumio[1703]: [158B blob data] Sep 17 17:25:27 volumio volumio[1703]: {"time":1758101127436,"response":"volume Success"}100 Sep 17 17:25:27 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:25:27 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:25:27 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:25:27 volumio volumio[1146]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control 'Master',0 Sep 17 17:25:51 volumio ntpd[1134]: Soliciting pool server 2a03:8600::cc Sep 17 17:25:51 volumio ntpd[1134]: Soliciting pool server 162.159.200.1 Sep 17 17:25:51 volumio ntpd[1134]: Soliciting pool server 162.159.200.1 Sep 17 17:25:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 17:25:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 17:25:56 volumio volumio[1146]: info: Discovery: Getting this device information Sep 17 17:25:56 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:25:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 17:25:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 17:25:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 17:25:56 volumio volumio[1146]: info: Discovery: Getting this device information Sep 17 17:25:56 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:25:56 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 17:25:57 volumio volumio[1146]: verbose: New Socket.io Connection to 192.168.31.109 from 192.168.31.136 UA: Mozilla/5.0 (Linux; Android 14; 22122RK93C Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.158 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Sep 17 17:25:57 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 17 17:25:57 volumio volumio[1146]: info: CoreCommandRouter::volumioGetVisibleSources Sep 17 17:25:57 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 17:25:57 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:25:57 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 17 17:25:57 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 17 17:25:57 volumio volumio[1146]: info: Received Get System Info Sep 17 17:25:57 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 17:25:57 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 17:25:57 volumio volumio[1146]: info: Discovery: Getting this device information Sep 17 17:25:57 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:25:57 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 17:25:57 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:25:57 volumio volumio[1146]: info: Listing playlists Sep 17 17:25:57 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 17 17:25:58 volumio volumio[1146]: verbose: New Socket.io Connection to 192.168.31.109:3000 from 192.168.31.136 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Sep 17 17:25:58 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 17:25:58 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 17:25:58 volumio volumio[1146]: info: Discovery: Getting this device information Sep 17 17:25:58 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:25:58 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 17:26:00 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:26:00 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:26:00 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:26:01 volumio ntpd[1134]: Soliciting pool server 193.182.111.141 Sep 17 17:26:04 volumio volumio[1146]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 17:26:04 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 17 17:26:04 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:26:04 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 17 17:26:04 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:04 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:04 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:04 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:04 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:04 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:04 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:04 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 17 17:26:04 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 17 17:26:07 volumio volumio[1146]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 17 17:26:07 volumio volumio[1146]: info: Preparing to save Alsa Options, stopping services first Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::volumioPause Sep 17 17:26:07 volumio volumio[1146]: info: CoreStateMachine::pause Sep 17 17:26:07 volumio volumio[1146]: info: CoreStateMachine::stPlaybackTimer Sep 17 17:26:07 volumio volumio[1146]: info: CoreStateMachine::servicePause Sep 17 17:26:07 volumio volumio[1146]: info: CorePlayQueue::getTrack 0 Sep 17 17:26:07 volumio volumio[1146]: info: Error: no service or no trackblock to pause Sep 17 17:26:07 volumio volumio[1146]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB Audio Device (UAC2.0)"}} Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 17 17:26:07 volumio volumio[1146]: info: Setting mixer USB Audio Device (UAC2.0) for card USB Audio Device (UAC2.0) Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 17 17:26:07 volumio volumio[1146]: info: Updating Volume Controller Parameters: Device: 5 Name: USB Audio Device (UAC2.0) Mixer: USB Audio Device (UAC2.0) Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 17 17:26:07 volumio volumio[1146]: info: Disabling external Volume Control Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 17:26:07 volumio volumio[1146]: info: Preparing to generate the ALSA configuration file Sep 17 17:26:07 volumio volumio[1146]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Sep 17 17:26:07 volumio volumio[1146]: info: Reading ALSA contributions from plugins. Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 17 17:26:07 volumio volumio[1146]: info: VolumeController:: Volume=20 Mute =false Sep 17 17:26:07 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:26:07 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:26:07 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:26:07 volumio volumio[1146]: info: Asound.conf file written Sep 17 17:26:07 volumio sudo[2739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Sep 17 17:26:07 volumio sudo[2739]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:07 volumio sudo[2739]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:07 volumio volumio[1146]: info: Output device has changed, restarting MPD Sep 17 17:26:07 volumio sudo[2745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 17 17:26:07 volumio sudo[2745]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:07 volumio volumio[1146]: info: Output device has changed, restarting Shairport Sync Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:26:07 volumio sudo[2745]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:07 volumio sudo[2748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 17 17:26:07 volumio sudo[2748]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:07 volumio systemd[1]: Stopping Music Player Daemon... Sep 17 17:26:07 volumio volumio[1146]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 17:26:07 volumio volumio[1146]: info: Output device has changed, restarting MPD Sep 17 17:26:07 volumio systemd[1]: mpd.service: Succeeded. Sep 17 17:26:07 volumio systemd[1]: Stopped Music Player Daemon. Sep 17 17:26:07 volumio sudo[2754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 17 17:26:07 volumio sudo[2754]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:07 volumio volumio[1146]: info: Output device has changed, restarting Shairport Sync Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio sudo[2754]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:26:07 volumio sudo[2759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 17 17:26:07 volumio sudo[2759]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:07 volumio systemd[1]: Starting Music Player Daemon... Sep 17 17:26:07 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Sep 17 17:26:07 volumio systemd[1]: mpd.service: Succeeded. Sep 17 17:26:07 volumio systemd[1]: Stopped Music Player Daemon. Sep 17 17:26:07 volumio systemd[1]: Starting Music Player Daemon... Sep 17 17:26:07 volumio volumio[1146]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 17:26:07 volumio volumio[1146]: info: MPD Permissions set Sep 17 17:26:07 volumio volumio[1146]: info: MPD Permissions set Sep 17 17:26:07 volumio volumio[1146]: verbose: New Socket.io Connection to 192.168.31.109:3000 from 192.168.31.136 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 17 17:26:07 volumio sudo[2764]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 17 17:26:07 volumio sudo[2764]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:07 volumio sudo[2764]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 17 17:26:07 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:26:07 volumio volumio[1146]: error: Upnp client error: Error: This socket has been ended by the other party Sep 17 17:26:07 volumio volumio[1146]: info: Starting Shairport Sync Sep 17 17:26:07 volumio volumio[1146]: info: Starting Shairport Sync Sep 17 17:26:07 volumio sudo[2780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 17:26:07 volumio sudo[2780]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 17 17:26:07 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 17 17:26:07 volumio sudo[2783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 17:26:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 17 17:26:07 volumio sudo[2783]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 17 17:26:07 volumio sudo[2780]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:07 volumio volumio[1146]: info: Shairport-Sync Started Sep 17 17:26:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 17 17:26:07 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Sep 17 17:26:07 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 17 17:26:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 17 17:26:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 17 17:26:07 volumio sudo[2783]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:07 volumio volumio[1146]: info: Shairport-Sync Started Sep 17 17:26:08 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:08 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:08 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:08 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:08 volumio mpd[2772]: Sep 17 17:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 17 17:26:08 volumio systemd[1]: Started Music Player Daemon. Sep 17 17:26:08 volumio sudo[2748]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:08 volumio sudo[2759]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:08 volumio volumio[1146]: error: MPD error: The expression evaluated to a falsy value: Sep 17 17:26:08 volumio volumio[1146]: assert.ok(self.idling) Sep 17 17:26:08 volumio volumio[1146]: error: The expression evaluated to a falsy value: Sep 17 17:26:08 volumio volumio[1146]: assert.ok(self.idling) Sep 17 17:26:08 volumio volumio[1146]: error: updateQueue error: null Sep 17 17:26:08 volumio volumio[1146]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Sep 17 17:26:08 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 17 17:26:08 volumio volumio[1146]: info: Preparing to save Alsa Options, stopping services first Sep 17 17:26:08 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:26:08 volumio volumio[1146]: info: CoreCommandRouter::volumioPause Sep 17 17:26:08 volumio volumio[1146]: info: CoreStateMachine::pause Sep 17 17:26:08 volumio volumio[1146]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB Audio Device (UAC2.0)"}} Sep 17 17:26:08 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 17 17:26:08 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 17 17:26:08 volumio volumio[1146]: info: Setting mixer USB Audio Device (UAC2.0) for card USB Audio Device (UAC2.0) Sep 17 17:26:08 volumio volumio[1146]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 17 17:26:08 volumio volumio[1146]: info: Updating Volume Controller Parameters: Device: 5 Name: USB Audio Device (UAC2.0) Mixer: USB Audio Device (UAC2.0) Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 17 17:26:08 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 17 17:26:08 volumio volumio[1146]: info: Disabling external Volume Control Sep 17 17:26:08 volumio volumio[1146]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 17:26:08 volumio volumio[1146]: info: Preparing to generate the ALSA configuration file Sep 17 17:26:08 volumio volumio[1146]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Sep 17 17:26:08 volumio volumio[1146]: info: Reading ALSA contributions from plugins. Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 17 17:26:09 volumio volumio[1146]: info: Asound.conf file unchanged, so no further update is needed Sep 17 17:26:09 volumio volumio[1146]: info: Output device has changed, restarting MPD Sep 17 17:26:09 volumio sudo[2814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 17 17:26:09 volumio sudo[2814]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:09 volumio volumio[1146]: info: Output device has changed, restarting Shairport Sync Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:26:09 volumio sudo[2814]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:09 volumio sudo[2819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 17 17:26:09 volumio sudo[2819]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:09 volumio systemd[1]: Stopping Music Player Daemon... Sep 17 17:26:09 volumio volumio[1146]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 17:26:09 volumio systemd[1]: mpd.service: Succeeded. Sep 17 17:26:09 volumio systemd[1]: Stopped Music Player Daemon. Sep 17 17:26:09 volumio volumio[1146]: info: MPD Permissions set Sep 17 17:26:09 volumio volumio[1146]: info: VolumeController:: Volume=20 Mute =false Sep 17 17:26:09 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:26:09 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:26:09 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:09 volumio systemd[1]: Starting Music Player Daemon... Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: Starting Shairport Sync Sep 17 17:26:09 volumio sudo[2824]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 17 17:26:09 volumio sudo[2824]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:09 volumio sudo[2836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 17:26:09 volumio sudo[2836]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:09 volumio sudo[2824]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:09 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 17 17:26:09 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 17 17:26:09 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 17 17:26:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 17 17:26:09 volumio sudo[2836]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:09 volumio volumio[1146]: info: Shairport-Sync Started Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:09 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:09 volumio mpd[2839]: Sep 17 17:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 17 17:26:09 volumio systemd[1]: Started Music Player Daemon. Sep 17 17:26:09 volumio sudo[2819]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:09 volumio volumio[1146]: error: updateQueue error: null Sep 17 17:26:11 volumio volumio[1146]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 17 17:26:11 volumio volumio[1146]: info: CoreStateMachine::getcurrentVolume Sep 17 17:26:11 volumio volumio[1146]: info: CoreCommandRouter::volumioRetrievevolume Sep 17 17:26:11 volumio volumio[1146]: info: VolumeController:: Volume=20 Mute =false Sep 17 17:26:11 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:26:11 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:26:11 volumio volumio[1146]: info: CoreStateMachine::updateTrackBlock Sep 17 17:26:11 volumio volumio[1146]: info: CorePlayQueue::getTrackBlock Sep 17 17:26:11 volumio volumio[1146]: info: CoreCommandRouter::volumioRetrievevolume Sep 17 17:26:11 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:26:11 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:26:11 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:26:11 volumio volumio[1146]: info: VolumeController:: Volume=20 Mute =false Sep 17 17:26:11 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:26:11 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:26:11 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:26:11 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:26:11 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:26:12 volumio volumio[1146]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 17 17:26:12 volumio volumio[1146]: info: CoreStateMachine::getcurrentVolume Sep 17 17:26:12 volumio volumio[1146]: info: CoreCommandRouter::volumioRetrievevolume Sep 17 17:26:13 volumio volumio[1146]: info: VolumeController:: Volume=20 Mute =false Sep 17 17:26:13 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:26:13 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:26:13 volumio volumio[1146]: info: CoreStateMachine::updateTrackBlock Sep 17 17:26:13 volumio volumio[1146]: info: CorePlayQueue::getTrackBlock Sep 17 17:26:13 volumio volumio[1146]: info: CoreCommandRouter::volumioRetrievevolume Sep 17 17:26:13 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:26:13 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:26:13 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:26:13 volumio volumio[1146]: info: VolumeController:: Volume=20 Mute =false Sep 17 17:26:13 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:26:13 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:26:13 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:26:13 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:26:13 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:26:14 volumio volumio[1146]: info: VolumeController::SetAlsaVolume23 Sep 17 17:26:14 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:26:14 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:26:14 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:26:14 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:26:14 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:26:14 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:26:14 volumio volumio[1146]: info: VolumeController::SetAlsaVolume64 Sep 17 17:26:14 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:26:14 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:26:14 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:26:14 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:26:14 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:26:14 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:26:15 volumio volumio[1146]: info: VolumeController::SetAlsaVolume65 Sep 17 17:26:15 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:26:15 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:26:15 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:26:15 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:26:15 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:26:15 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:26:15 volumio volumio[1146]: info: VolumeController::SetAlsaVolume76 Sep 17 17:26:15 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:26:15 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:26:15 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:26:15 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:26:15 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:26:15 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:26:22 volumio volumio[1146]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Sep 17 17:26:22 volumio volumio[1146]: info: Preparing to save Alsa Options, stopping services first Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::volumioPause Sep 17 17:26:22 volumio volumio[1146]: info: CoreStateMachine::pause Sep 17 17:26:22 volumio volumio[1146]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"USB Audio Device (UAC2.0)"}} Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 17 17:26:22 volumio volumio[1146]: info: Setting mixer USB Audio Device (UAC2.0) for card USB Audio Device (UAC2.0) Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::volumioUpdateVolumeSettings Sep 17 17:26:22 volumio volumio[1146]: info: Updating Volume Controller Parameters: Device: 5 Name: USB Audio Device (UAC2.0) Mixer: USB Audio Device (UAC2.0) Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Sep 17 17:26:22 volumio volumio[1146]: info: Disabling external Volume Control Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 17:26:22 volumio volumio[1146]: info: Preparing to generate the ALSA configuration file Sep 17 17:26:22 volumio volumio[1146]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Sep 17 17:26:22 volumio volumio[1146]: info: Reading ALSA contributions from plugins. Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 17 17:26:22 volumio volumio[1146]: info: VolumeController:: Volume=76 Mute =false Sep 17 17:26:22 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:26:22 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:26:22 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:26:22 volumio volumio[1146]: info: Asound.conf file unchanged, so no further update is needed Sep 17 17:26:22 volumio volumio[1146]: info: Output device has changed, restarting MPD Sep 17 17:26:22 volumio sudo[3029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 17 17:26:22 volumio sudo[3029]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:22 volumio volumio[1146]: info: Output device has changed, restarting Shairport Sync Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:26:22 volumio sudo[3029]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:22 volumio sudo[3032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 17 17:26:22 volumio sudo[3032]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:22 volumio systemd[1]: Stopping Music Player Daemon... Sep 17 17:26:22 volumio volumio[1146]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 17 17:26:22 volumio systemd[1]: mpd.service: Succeeded. Sep 17 17:26:22 volumio systemd[1]: Stopped Music Player Daemon. Sep 17 17:26:22 volumio volumio[1146]: info: MPD Permissions set Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 17 17:26:22 volumio systemd[1]: Starting Music Player Daemon... Sep 17 17:26:22 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:22 volumio volumio[1146]: info: Starting Shairport Sync Sep 17 17:26:23 volumio sudo[3045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 17 17:26:23 volumio sudo[3045]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:23 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 17 17:26:23 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 17 17:26:23 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 17 17:26:23 volumio sudo[3037]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 17 17:26:23 volumio sudo[3037]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 17:26:23 volumio sudo[3037]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:23 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 17 17:26:23 volumio sudo[3045]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:23 volumio volumio[1146]: info: Shairport-Sync Started Sep 17 17:26:23 volumio volumio[1146]: error: Upnp client error: Error: This socket has been ended by the other party Sep 17 17:26:23 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:23 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 17:26:23 volumio mpd[3050]: Sep 17 17:26 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 17 17:26:23 volumio systemd[1]: Started Music Player Daemon. Sep 17 17:26:23 volumio sudo[3032]: pam_unix(sudo:session): session closed for user root Sep 17 17:26:23 volumio volumio[1146]: error: updateQueue error: null Sep 17 17:26:26 volumio volumio[1146]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Sep 17 17:26:26 volumio volumio[1146]: info: CoreStateMachine::getcurrentVolume Sep 17 17:26:26 volumio volumio[1146]: info: CoreCommandRouter::volumioRetrievevolume Sep 17 17:26:26 volumio volumio[1146]: info: VolumeController:: Volume=76 Mute =false Sep 17 17:26:26 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:26:26 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:26:26 volumio volumio[1146]: info: CoreStateMachine::updateTrackBlock Sep 17 17:26:26 volumio volumio[1146]: info: CorePlayQueue::getTrackBlock Sep 17 17:26:26 volumio volumio[1146]: info: CoreCommandRouter::volumioRetrievevolume Sep 17 17:26:26 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:26:26 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:26:26 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:26:26 volumio volumio[1146]: info: VolumeController:: Volume=76 Mute =false Sep 17 17:26:26 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:26:26 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:26:26 volumio volumio[1146]: info: CoreCommandRouter::volumioGetQueue Sep 17 17:26:26 volumio volumio[1146]: info: CoreStateMachine::getQueue Sep 17 17:26:26 volumio volumio[1146]: info: CorePlayQueue::getQueue Sep 17 17:26:28 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 17:26:28 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 17:26:28 volumio volumio[1146]: info: Discovery: Getting this device information Sep 17 17:26:28 volumio volumio[1146]: info: CoreCommandRouter::volumioGetState Sep 17 17:26:28 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 17:26:30 volumio volumio[1146]: info: Clearing queue after UPNP request Sep 17 17:26:31 volumio volumio[1146]: info: CoreStateMachine::ClearQueue Sep 17 17:26:31 volumio volumio[1146]: info: CoreStateMachine::stop Sep 17 17:26:31 volumio volumio[1146]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 17:26:31 volumio volumio[1146]: info: CoreStateMachine::updateTrackBlock Sep 17 17:26:31 volumio volumio[1146]: info: CorePlayQueue::getTrackBlock Sep 17 17:26:31 volumio volumio[1146]: info: CoreStateMachine::stPlaybackTimer Sep 17 17:26:31 volumio volumio[1146]: info: CoreStateMachine::pushState Sep 17 17:26:31 volumio volumio[1146]: info: CorePlayQueue::getTrack 0 Sep 17 17:26:31 volumio volumio[1146]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 17:26:31 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:26:31 volumio volumio[1146]: info: CoreStateMachine::serviceStop Sep 17 17:26:31 volumio volumio[1146]: info: CorePlayQueue::getTrack 0 Sep 17 17:26:31 volumio volumio[1146]: info: ControllerMpd::stop Sep 17 17:26:31 volumio volumio[1146]: verbose: ControllerMpd::sendMpdCommand stop Sep 17 17:26:31 volumio volumio[1146]: info: CorePlayQueue::clearPlayQueue Sep 17 17:26:31 volumio volumio[1146]: info: CorePlayQueue::saveQueue Sep 17 17:26:31 volumio volumio[1146]: info: CoreCommandRouter::volumioPushState Sep 17 17:26:31 volumio volumio[1146]: info: CoreCommandRouter::volumioPushQueue Sep 17 17:26:31 volumio volumio[1146]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 17:26:31 volumio volumio[1146]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Sep 17 17:26:31 volumio volumio[1146]: at Object.readdirSync (fs.js:1048:3) Sep 17 17:26:31 volumio volumio[1146]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Sep 17 17:26:31 volumio volumio[1146]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Sep 17 17:26:31 volumio volumio[1146]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Sep 17 17:26:31 volumio volumio[1146]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Sep 17 17:26:31 volumio volumio[1146]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Sep 17 17:26:31 volumio volumio[1146]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Sep 17 17:26:31 volumio volumio[1146]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Sep 17 17:26:31 volumio volumio[1146]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Sep 17 17:26:31 volumio volumio[1146]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Sep 17 17:26:31 volumio volumio[1146]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Sep 17 17:26:31 volumio volumio[1146]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Sep 17 17:26:31 volumio volumio[1146]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Sep 17 17:26:31 volumio volumio[1146]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Sep 17 17:26:31 volumio volumio[1146]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Sep 17 17:26:31 volumio volumio[1146]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Sep 17 17:26:31 volumio volumio[1146]: errno: -20, Sep 17 17:26:31 volumio volumio[1146]: syscall: 'scandir', Sep 17 17:26:31 volumio volumio[1146]: code: 'ENOTDIR', Sep 17 17:26:31 volumio volumio[1146]: path: '/tmp/peppyrunning' Sep 17 17:26:31 volumio volumio[1146]: } Sep 17 17:26:31 volumio volumio[1146]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 17:26:31 volumio sudo[3128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-17 17:25 Sep 17 17:26:31 volumio sudo[3128]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"