-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Mon 2025-06-23 14:29:04 CST. -- Jun 23 14:28:11 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 23 14:28:11 volumio volumio[890]: info: CURURI: music-library/USB/微PE工具箱/0 试音 测试文件 Jun 23 14:28:11 volumio volumio[890]: info: Preload queue cleared Jun 23 14:28:17 volumio volumio[890]: info: Preload queue cleared Jun 23 14:28:17 volumio volumio[890]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::ClearQueue Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::stop Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::stPlaybackTimer Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::updateTrackBlock Jun 23 14:28:17 volumio volumio[890]: info: CorePlayQueue::getTrackBlock Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::pushState Jun 23 14:28:17 volumio volumio[890]: info: CorePlayQueue::getTrack 0 Jun 23 14:28:17 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 14:28:17 volumio volumio[890]: info: CoreCommandRouter::volumioPushState Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::serviceStop Jun 23 14:28:17 volumio volumio[890]: info: CorePlayQueue::getTrack 0 Jun 23 14:28:17 volumio volumio[890]: info: CoreCommandRouter::serviceStop Jun 23 14:28:17 volumio volumio[890]: info: ControllerMpd::stop Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 14:28:17 volumio volumio[890]: info: CorePlayQueue::clearPlayQueue Jun 23 14:28:17 volumio volumio[890]: info: CorePlayQueue::saveQueue Jun 23 14:28:17 volumio volumio[890]: info: CoreCommandRouter::volumioPushQueue Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::addQueueItems Jun 23 14:28:17 volumio volumio[890]: info: CorePlayQueue::addQueueItems Jun 23 14:28:17 volumio volumio[890]: info: Preload queue cleared Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/1-sony 1K 0db_DSD128.dff Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/1-sony 1K 0db_DSD128.dff Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/1-sony 1K 0db_DSD16.dff Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/1-sony 1K 0db_DSD16.dff Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/1-sony 1K 0db_DSD256.dff Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/1-sony 1K 0db_DSD256.dff Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/1-sony 1K 0db_DSD32.dff Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/1-sony 1K 0db_DSD32.dff Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/1-sony 1K 0db_DSD512.dff Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/1-sony 1K 0db_DSD512.dff Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/1-sony 1K 0db_DSD64.dff Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/1-sony 1K 0db_DSD64.dff Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/1K 0db sony.wav Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/1K 0db sony.wav Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/o5_DSD512.dff Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/o5_DSD512.dff Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/26.ape-C5000-24bit96K-Water.ape Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/26.ape-C5000-24bit96K-Water.ape Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/05.ape-c5000-24bit192K-Lacrymosa.ape Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/05.ape-c5000-24bit192K-Lacrymosa.ape Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/06.flac-level0-24bit192K-Boisterous.flac Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/06.flac-level0-24bit192K-Boisterous.flac Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/352.flac Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/352.flac Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/384.flac Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/384.flac Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/27.flac-level0-24bit96K-Water.flac Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/27.flac-level0-24bit96K-Water.flac Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/Canon-24bit 44.1K.ape Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/Canon-24bit 44.1K.ape Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/Canon-24bit 44.1K.flac Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/Canon-24bit 44.1K.flac Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/滴答- 16bit 44.1K.ape Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/滴答- 16bit 44.1K.ape Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/滴答- 16bit 44.1K.flac Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/滴答- 16bit 44.1K.flac Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/最终信仰-16bit 48K.ape Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/最终信仰-16bit 48K.ape Jun 23 14:28:17 volumio volumio[890]: info: Adding Item to queue: music-library/USB/微PE工具箱/0 试音 测试文件/最终信仰-16bit 48K.flac Jun 23 14:28:17 volumio volumio[890]: info: Using cached record of: music-library/USB/微PE工具箱/0 试音 测试文件/最终信仰-16bit 48K.flac Jun 23 14:28:17 volumio volumio[890]: info: CoreCommandRouter::volumioPushQueue Jun 23 14:28:17 volumio volumio[890]: info: CorePlayQueue::saveQueue Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::updateTrackBlock Jun 23 14:28:17 volumio volumio[890]: info: CorePlayQueue::getTrackBlock Jun 23 14:28:17 volumio volumio[890]: info: CoreCommandRouter::volumioPlay Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::play index 19 Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::stop Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::play index undefined Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 23 14:28:17 volumio volumio[890]: info: CorePlayQueue::getTrack 19 Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::startPlaybackTimer Jun 23 14:28:17 volumio volumio[890]: info: CorePlayQueue::getTrack 19 Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::clearAddPlayTracks USB/微PE工具箱/0 试音 测试文件/最终信仰-16bit 48K.flac Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand stop Jun 23 14:28:17 volumio volumio[890]: info: Jun 23 14:28:17 volumio volumio[890]: ---------------------------- MPD announces state update: player Jun 23 14:28:17 volumio volumio[890]: info: sendMpdCommand stop took 11 milliseconds Jun 23 14:28:17 volumio volumio[890]: info: ControllerMpd::getState Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand status Jun 23 14:28:17 volumio volumio[890]: info: sendMpdCommand stop took 3 milliseconds Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand clear Jun 23 14:28:17 volumio volumio[890]: info: Jun 23 14:28:17 volumio volumio[890]: ---------------------------- MPD announces system playlist update Jun 23 14:28:17 volumio volumio[890]: info: Ignoring MPD Status Update Jun 23 14:28:17 volumio volumio[890]: info: sendMpdCommand status took 2 milliseconds Jun 23 14:28:17 volumio volumio[890]: info: sendMpdCommand clear took 1 milliseconds Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::parseState Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand add "USB/微PE工具箱/0 试音 测试文件/最终信仰-16bit 48K.flac" Jun 23 14:28:17 volumio volumio[890]: error: updateQueue error: null Jun 23 14:28:17 volumio volumio[890]: info: Jun 23 14:28:17 volumio volumio[890]: ---------------------------- MPD announces system playlist update Jun 23 14:28:17 volumio volumio[890]: info: Ignoring MPD Status Update Jun 23 14:28:17 volumio volumio[890]: info: ------------------------------ 2ms Jun 23 14:28:17 volumio volumio[890]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 23 14:28:17 volumio volumio[890]: info: sendMpdCommand add "USB/微PE工具箱/0 试音 测试文件/最终信仰-16bit 48K.flac" took 1 milliseconds Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::parseTrackInfo Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand play Jun 23 14:28:17 volumio volumio[890]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 23 14:28:17 volumio volumio[890]: info: ------------------------------ 4ms Jun 23 14:28:17 volumio volumio[890]: info: ------------------------------ 1ms Jun 23 14:28:17 volumio volumio[890]: info: sendMpdCommand play took 0 milliseconds Jun 23 14:28:17 volumio volumio[890]: info: Jun 23 14:28:17 volumio volumio[890]: ---------------------------- MPD announces state update: player Jun 23 14:28:17 volumio volumio[890]: info: ControllerMpd::getState Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand status Jun 23 14:28:17 volumio volumio[890]: info: Jun 23 14:28:17 volumio volumio[890]: ---------------------------- MPD announces state update: player Jun 23 14:28:17 volumio volumio[890]: info: sendMpdCommand status took 1 milliseconds Jun 23 14:28:17 volumio volumio[890]: info: ControllerMpd::getState Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand status Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::parseState Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 14:28:17 volumio volumio[890]: info: sendMpdCommand status took 2 milliseconds Jun 23 14:28:17 volumio volumio[890]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::parseState Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::parseTrackInfo Jun 23 14:28:17 volumio volumio[890]: info: ControllerMpd::pushState Jun 23 14:28:17 volumio volumio[890]: info: CoreCommandRouter::servicePushState Jun 23 14:28:17 volumio volumio[890]: info: CorePlayQueue::getTrack 19 Jun 23 14:28:17 volumio volumio[890]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":305,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"最终信仰","artist":"尚雯婕","album":"我是歌手 第一期","uri":"USB/微PE工具箱/0 试音 测试文件/最终信仰-16bit 48K.flac","trackType":"flac"} Jun 23 14:28:17 volumio volumio[890]: verbose: CURRENT POSITION 19 Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::syncState stateService play Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::syncState currentStatus stop Jun 23 14:28:17 volumio volumio[890]: info: ------------------------------ 5ms Jun 23 14:28:17 volumio volumio[890]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 23 14:28:17 volumio volumio[890]: verbose: ControllerMpd::parseTrackInfo Jun 23 14:28:17 volumio volumio[890]: info: ControllerMpd::pushState Jun 23 14:28:17 volumio volumio[890]: info: CoreCommandRouter::servicePushState Jun 23 14:28:17 volumio volumio[890]: info: CorePlayQueue::getTrack 19 Jun 23 14:28:17 volumio volumio[890]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":305,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"最终信仰","artist":"尚雯婕","album":"我是歌手 第一期","uri":"USB/微PE工具箱/0 试音 测试文件/最终信仰-16bit 48K.flac","trackType":"flac"} Jun 23 14:28:17 volumio volumio[890]: verbose: CURRENT POSITION 19 Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::syncState stateService play Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::syncState currentStatus play Jun 23 14:28:17 volumio volumio[890]: info: Received an update from plugin. extracting info from payload Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::pushState Jun 23 14:28:17 volumio volumio[890]: info: CorePlayQueue::getTrack 19 Jun 23 14:28:17 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 14:28:17 volumio volumio[890]: info: CoreCommandRouter::volumioPushState Jun 23 14:28:17 volumio volumio[890]: info: CoreStateMachine::pushState Jun 23 14:28:17 volumio volumio[890]: info: CorePlayQueue::getTrack 19 Jun 23 14:28:17 volumio volumio[890]: info: CoreCommandRouter::volumioPushState Jun 23 14:28:17 volumio volumio[890]: info: ------------------------------ 8ms Jun 23 14:28:31 volumio volumio[890]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 14:28:32 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 23 14:28:32 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 14:28:32 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 23 14:28:32 volumio volumio[890]: info: Getting Alsa Cards List without I2S DAC Jun 23 14:28:32 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 23 14:28:32 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 14:28:32 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 14:28:32 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 14:28:32 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 14:28:32 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 14:28:32 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 14:28:32 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 14:28:32 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 14:28:32 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 23 14:28:32 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 23 14:28:50 volumio volumio[890]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 23 14:28:50 volumio volumio[890]: info: Preparing to save Alsa Options, stopping services first Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::volumioGetState Jun 23 14:28:50 volumio volumio[890]: info: CorePlayQueue::getTrack 19 Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::volumioPause Jun 23 14:28:50 volumio volumio[890]: info: CoreStateMachine::pause Jun 23 14:28:50 volumio volumio[890]: info: CoreStateMachine::stPlaybackTimer Jun 23 14:28:50 volumio volumio[890]: info: CoreStateMachine::servicePause Jun 23 14:28:50 volumio volumio[890]: info: CorePlayQueue::getTrack 19 Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::servicePause Jun 23 14:28:50 volumio volumio[890]: info: ControllerMpd::pause Jun 23 14:28:50 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand pause Jun 23 14:28:50 volumio volumio[890]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"hifiberry-dac","label":"HiFiBerry DAC"}} Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 14:28:50 volumio volumio[890]: info: Device 2 does not have any Mixer Control Available Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 23 14:28:50 volumio volumio[890]: info: Updating Volume Controller Parameters: Device: 2 Name: HiFiBerry DAC Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 23 14:28:50 volumio volumio[890]: info: Disabling external Volume Control Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 14:28:50 volumio volumio[890]: info: CoreStateMachine::pushState Jun 23 14:28:50 volumio volumio[890]: info: CorePlayQueue::getTrack 19 Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::volumioPushState Jun 23 14:28:50 volumio volumio[890]: info: Preparing to generate the ALSA configuration file Jun 23 14:28:50 volumio volumio[890]: info: Jun 23 14:28:50 volumio volumio[890]: ---------------------------- MPD announces state update: player Jun 23 14:28:50 volumio volumio[890]: info: sendMpdCommand pause took 179 milliseconds Jun 23 14:28:50 volumio volumio[890]: info: ControllerMpd::getState Jun 23 14:28:50 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand status Jun 23 14:28:50 volumio volumio[890]: info: sendMpdCommand status took 3 milliseconds Jun 23 14:28:50 volumio volumio[890]: verbose: ControllerMpd::parseState Jun 23 14:28:50 volumio volumio[890]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 23 14:28:50 volumio volumio[890]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 23 14:28:50 volumio volumio[890]: verbose: ControllerMpd::parseTrackInfo Jun 23 14:28:50 volumio volumio[890]: info: ControllerMpd::pushState Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::servicePushState Jun 23 14:28:50 volumio volumio[890]: info: CorePlayQueue::getTrack 19 Jun 23 14:28:50 volumio volumio[890]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":33969,"duration":305,"samplerate":"48 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"891 Kbps","isStreaming":false,"title":"最终信仰","artist":"尚雯婕","album":"我是歌手 第一期","uri":"USB/微PE工具箱/0 试音 测试文件/最终信仰-16bit 48K.flac","trackType":"flac"} Jun 23 14:28:50 volumio volumio[890]: verbose: CURRENT POSITION 19 Jun 23 14:28:50 volumio volumio[890]: info: CoreStateMachine::syncState stateService pause Jun 23 14:28:50 volumio volumio[890]: info: CoreStateMachine::syncState currentStatus pause Jun 23 14:28:50 volumio volumio[890]: info: CoreStateMachine::pushState Jun 23 14:28:50 volumio volumio[890]: info: CorePlayQueue::getTrack 19 Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 14:28:50 volumio volumio[890]: info: CoreCommandRouter::volumioPushState Jun 23 14:28:50 volumio volumio[890]: info: CoreStateMachine::stPlaybackTimer Jun 23 14:28:50 volumio volumio[890]: info: ------------------------------ 8ms Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 23 14:28:51 volumio volumio[890]: info: Getting Alsa Cards List without I2S DAC Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 23 14:28:51 volumio volumio[890]: info: Asound.conf file unchanged, so no further update is needed Jun 23 14:28:51 volumio volumio[890]: info: Output device has changed, restarting MPD Jun 23 14:28:51 volumio sudo[30311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 23 14:28:51 volumio sudo[30311]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 14:28:51 volumio sudo[30311]: pam_unix(sudo:session): session closed for user root Jun 23 14:28:51 volumio volumio[890]: info: Output device has changed, restarting Shairport Sync Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 14:28:51 volumio sudo[30314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 23 14:28:51 volumio sudo[30314]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 14:28:51 volumio systemd[1]: Stopping Music Player Daemon... Jun 23 14:28:51 volumio volumio[890]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 23 14:28:51 volumio systemd[1]: mpd.service: Succeeded. Jun 23 14:28:51 volumio systemd[1]: Stopped Music Player Daemon. Jun 23 14:28:51 volumio volumio[890]: info: MPD Permissions set Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:28:51 volumio volumio[890]: info: Starting Shairport Sync Jun 23 14:28:51 volumio systemd[1]: Starting Music Player Daemon... Jun 23 14:28:51 volumio sudo[30326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 23 14:28:51 volumio sudo[30326]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 14:28:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 23 14:28:51 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 23 14:28:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 23 14:28:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 23 14:28:51 volumio sudo[30326]: pam_unix(sudo:session): session closed for user root Jun 23 14:28:51 volumio volumio[890]: info: Shairport-Sync Started Jun 23 14:28:51 volumio sudo[30319]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 23 14:28:51 volumio sudo[30319]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 14:28:51 volumio sudo[30319]: pam_unix(sudo:session): session closed for user root Jun 23 14:28:52 volumio mpd[30332]: Jun 23 14:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 23 14:28:52 volumio systemd[1]: Started Music Player Daemon. Jun 23 14:28:52 volumio sudo[30314]: pam_unix(sudo:session): session closed for user root Jun 23 14:28:52 volumio volumio[890]: error: updateQueue error: null Jun 23 14:28:54 volumio volumio[890]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 23 14:28:54 volumio volumio[890]: info: CoreStateMachine::getcurrentVolume Jun 23 14:28:54 volumio volumio[890]: info: CoreCommandRouter::volumioRetrievevolume Jun 23 14:28:54 volumio volumio[890]: info: CoreStateMachine::pushState Jun 23 14:28:54 volumio volumio[890]: info: CorePlayQueue::getTrack 19 Jun 23 14:28:54 volumio volumio[890]: info: CoreCommandRouter::volumioPushState Jun 23 14:29:02 volumio volumio[890]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jun 23 14:29:02 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jun 23 14:29:02 volumio sudo[30355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 23 14:29:02 volumio sudo[30355]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 14:29:02 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:29:02 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:29:02 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:29:02 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:29:02 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:29:02 volumio sudo[30355]: pam_unix(sudo:session): session closed for user root Jun 23 14:29:02 volumio sudo[30358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 23 14:29:02 volumio sudo[30358]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 14:29:02 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 14:29:02 volumio volumio[890]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 14:29:02 volumio volumio[890]: info: MPD Permissions set Jun 23 14:29:02 volumio systemd[1]: Stopping Music Player Daemon... Jun 23 14:29:02 volumio systemd[1]: mpd.service: Succeeded. Jun 23 14:29:02 volumio systemd[1]: Stopped Music Player Daemon. Jun 23 14:29:02 volumio systemd[1]: Starting Music Player Daemon... Jun 23 14:29:02 volumio sudo[30364]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 23 14:29:02 volumio sudo[30364]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 14:29:02 volumio sudo[30364]: pam_unix(sudo:session): session closed for user root Jun 23 14:29:03 volumio volumio[890]: error: Upnp client error: Error: This socket has been ended by the other party Jun 23 14:29:03 volumio mpd[30369]: Jun 23 14:29 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 23 14:29:03 volumio systemd[1]: Started Music Player Daemon. Jun 23 14:29:03 volumio sudo[30358]: pam_unix(sudo:session): session closed for user root Jun 23 14:29:03 volumio volumio[890]: error: updateQueue error: null Jun 23 14:29:03 volumio volumio[890]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 23 14:29:03 volumio volumio[890]: Error: connect ETIMEDOUT 114.43.24.59:443 Jun 23 14:29:03 volumio volumio[890]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jun 23 14:29:03 volumio volumio[890]: errno: -110, Jun 23 14:29:03 volumio volumio[890]: code: 'ETIMEDOUT', Jun 23 14:29:03 volumio volumio[890]: syscall: 'connect', Jun 23 14:29:03 volumio volumio[890]: address: '114.43.24.59', Jun 23 14:29:03 volumio volumio[890]: port: 443 Jun 23 14:29:03 volumio volumio[890]: } Jun 23 14:29:03 volumio volumio[890]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 23 14:29:04 volumio sudo[30381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-23 14:28 Jun 23 14:29:04 volumio sudo[30381]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"