-- Logs begin at Sun 2024-12-01 02:13:09 CST, end at Sun 2024-12-01 03:48:59 CST. -- Dec 01 03:47:10 mp3 kernel: atkbd serio0: Unknown key pressed (translated set 2, code 0xd9 on isa0060/serio0). Dec 01 03:47:10 mp3 kernel: atkbd serio0: Use 'setkeycodes e059 ' to make it known. Dec 01 03:47:10 mp3 kernel: atkbd serio0: Unknown key released (translated set 2, code 0xd9 on isa0060/serio0). Dec 01 03:47:10 mp3 kernel: atkbd serio0: Use 'setkeycodes e059 ' to make it known. Dec 01 03:47:10 mp3 systemd-logind[760]: Lid opened. Dec 01 03:47:31 mp3 kernel: atkbd serio0: Unknown key pressed (translated set 2, code 0xd8 on isa0060/serio0). Dec 01 03:47:31 mp3 kernel: atkbd serio0: Use 'setkeycodes e058 ' to make it known. Dec 01 03:47:31 mp3 kernel: atkbd serio0: Unknown key released (translated set 2, code 0xd8 on isa0060/serio0). Dec 01 03:47:31 mp3 kernel: atkbd serio0: Use 'setkeycodes e058 ' to make it known. Dec 01 03:47:31 mp3 systemd-logind[760]: Lid closed. Dec 01 03:47:48 mp3 kernel: atkbd serio0: Unknown key pressed (translated set 2, code 0xd9 on isa0060/serio0). Dec 01 03:47:48 mp3 kernel: atkbd serio0: Use 'setkeycodes e059 ' to make it known. Dec 01 03:47:48 mp3 kernel: atkbd serio0: Unknown key released (translated set 2, code 0xd9 on isa0060/serio0). Dec 01 03:47:48 mp3 kernel: atkbd serio0: Use 'setkeycodes e059 ' to make it known. Dec 01 03:47:48 mp3 systemd-logind[760]: Lid opened. Dec 01 03:48:04 mp3 volumio[16243]: info: CoreCommandRouter::volumioPause Dec 01 03:48:04 mp3 volumio[16243]: info: CoreStateMachine::pause Dec 01 03:48:04 mp3 volumio[16243]: info: CoreStateMachine::stPlaybackTimer Dec 01 03:48:04 mp3 volumio[16243]: info: CoreStateMachine::servicePause Dec 01 03:48:04 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:04 mp3 volumio[16243]: info: CoreCommandRouter::servicePause Dec 01 03:48:04 mp3 volumio[16243]: info: ControllerMpd::pause Dec 01 03:48:04 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand pause Dec 01 03:48:04 mp3 volumio[16243]: info: Dec 01 03:48:04 mp3 volumio[16243]: ---------------------------- MPD announces state update: player Dec 01 03:48:04 mp3 volumio[16243]: info: sendMpdCommand pause took 1 milliseconds Dec 01 03:48:04 mp3 volumio[16243]: info: ControllerMpd::getState Dec 01 03:48:04 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand status Dec 01 03:48:04 mp3 volumio[16243]: info: sendMpdCommand status took 1 milliseconds Dec 01 03:48:04 mp3 volumio[16243]: verbose: ControllerMpd::parseState Dec 01 03:48:04 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 03:48:04 mp3 volumio[16243]: info: Dec 01 03:48:04 mp3 volumio[16243]: ---------------------------- MPD announces state update: player Dec 01 03:48:04 mp3 volumio[16243]: info: ControllerMpd::getState Dec 01 03:48:04 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand status Dec 01 03:48:04 mp3 volumio[16243]: info: Dec 01 03:48:04 mp3 volumio[16243]: ---------------------------- MPD announces state update: player Dec 01 03:48:04 mp3 volumio[16243]: info: ControllerMpd::getState Dec 01 03:48:04 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand status Dec 01 03:48:04 mp3 volumio[16243]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 01 03:48:04 mp3 volumio[16243]: info: sendMpdCommand status took 1 milliseconds Dec 01 03:48:04 mp3 volumio[16243]: info: sendMpdCommand status took 1 milliseconds Dec 01 03:48:04 mp3 volumio[16243]: verbose: ControllerMpd::parseTrackInfo Dec 01 03:48:04 mp3 volumio[16243]: verbose: ControllerMpd::parseState Dec 01 03:48:04 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 03:48:04 mp3 volumio[16243]: verbose: ControllerMpd::parseState Dec 01 03:48:04 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 03:48:04 mp3 volumio[16243]: info: ControllerMpd::pushState Dec 01 03:48:04 mp3 volumio[16243]: info: CoreCommandRouter::servicePushState Dec 01 03:48:04 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:04 mp3 volumio[16243]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":44633,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"×í¹ý·½Öª¾ÆŨ(DJÄ°Ãΰæ)","artist":"ö¬µ¤(À˸ç)","album":"04.ÈÈÃÅÖÐÎÄDJ","uri":"USB/0143-2901/05.网络流行中文DJ-240/093.醉过方知酒浓(DJ陌梦版)-霈丹(浪哥).mp3","trackType":"mp3"} Dec 01 03:48:04 mp3 volumio[16243]: verbose: CURRENT POSITION 44 Dec 01 03:48:04 mp3 volumio[16243]: info: CoreStateMachine::syncState stateService pause Dec 01 03:48:04 mp3 volumio[16243]: info: CoreStateMachine::syncState currentStatus pause Dec 01 03:48:04 mp3 volumio[16243]: info: CoreStateMachine::pushState Dec 01 03:48:04 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:04 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 03:48:04 mp3 volumio[16243]: info: CoreCommandRouter::volumioPushState Dec 01 03:48:04 mp3 volumio[16243]: info: CoreStateMachine::stPlaybackTimer Dec 01 03:48:04 mp3 volumio[16243]: info: ------------------------------ 5ms Dec 01 03:48:04 mp3 volumio[16243]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 01 03:48:04 mp3 volumio[16243]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 01 03:48:04 mp3 volumio[16243]: verbose: ControllerMpd::parseTrackInfo Dec 01 03:48:04 mp3 volumio[16243]: verbose: ControllerMpd::parseTrackInfo Dec 01 03:48:04 mp3 volumio[16243]: info: ControllerMpd::pushState Dec 01 03:48:04 mp3 volumio[16243]: info: CoreCommandRouter::servicePushState Dec 01 03:48:04 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:04 mp3 volumio[16243]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":44633,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"×í¹ý·½Öª¾ÆŨ(DJÄ°Ãΰæ)","artist":"ö¬µ¤(À˸ç)","album":"04.ÈÈÃÅÖÐÎÄDJ","uri":"USB/0143-2901/05.网络流行中文DJ-240/093.醉过方知酒浓(DJ陌梦版)-霈丹(浪哥).mp3","trackType":"mp3"} Dec 01 03:48:04 mp3 volumio[16243]: verbose: CURRENT POSITION 44 Dec 01 03:48:04 mp3 volumio[16243]: info: CoreStateMachine::syncState stateService pause Dec 01 03:48:04 mp3 volumio[16243]: info: CoreStateMachine::syncState currentStatus pause Dec 01 03:48:04 mp3 volumio[16243]: info: CoreStateMachine::pushState Dec 01 03:48:04 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:04 mp3 volumio[16243]: info: CoreCommandRouter::volumioPushState Dec 01 03:48:04 mp3 volumio[16243]: info: CoreStateMachine::stPlaybackTimer Dec 01 03:48:04 mp3 volumio[16243]: info: ControllerMpd::pushState Dec 01 03:48:04 mp3 volumio[16243]: info: CoreCommandRouter::servicePushState Dec 01 03:48:04 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:04 mp3 volumio[16243]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":44633,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"×í¹ý·½Öª¾ÆŨ(DJÄ°Ãΰæ)","artist":"ö¬µ¤(À˸ç)","album":"04.ÈÈÃÅÖÐÎÄDJ","uri":"USB/0143-2901/05.网络流行中文DJ-240/093.醉过方知酒浓(DJ陌梦版)-霈丹(浪哥).mp3","trackType":"mp3"} Dec 01 03:48:04 mp3 volumio[16243]: verbose: CURRENT POSITION 44 Dec 01 03:48:04 mp3 volumio[16243]: info: CoreStateMachine::syncState stateService pause Dec 01 03:48:04 mp3 volumio[16243]: info: CoreStateMachine::syncState currentStatus pause Dec 01 03:48:04 mp3 volumio[16243]: info: CoreStateMachine::pushState Dec 01 03:48:04 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:04 mp3 volumio[16243]: info: CoreCommandRouter::volumioPushState Dec 01 03:48:04 mp3 volumio[16243]: info: CoreStateMachine::stPlaybackTimer Dec 01 03:48:04 mp3 volumio[16243]: info: ------------------------------ 9ms Dec 01 03:48:04 mp3 volumio[16243]: info: ------------------------------ 8ms Dec 01 03:48:05 mp3 kernel: usb 1-2: timeout: still 3 active urbs on EP #7 Dec 01 03:48:09 mp3 kernel: xhci_hcd 0000:00:14.0: xHCI host not responding to stop endpoint command Dec 01 03:48:09 mp3 kernel: xhci_hcd 0000:00:14.0: xHCI host controller not responding, assume dead Dec 01 03:48:09 mp3 kernel: xhci_hcd 0000:00:14.0: HC died; cleaning up Dec 01 03:48:09 mp3 kernel: usb 1-2: USB disconnect, device number 3 Dec 01 03:48:09 mp3 kernel: usb 1-2: Not enough bandwidth for altsetting 0 Dec 01 03:48:09 mp3 kernel: usb 1-2: 2:0: usb_set_interface failed (-19) Dec 01 03:48:09 mp3 volumio[16243]: info: Dec 01 03:48:09 mp3 volumio[16243]: ---------------------------- USB Audio Device Detached Dec 01 03:48:09 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 01 03:48:09 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 01 03:48:09 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 01 03:48:09 mp3 volumio[16243]: info: No valid Plugin REST Endpoint Dec 01 03:48:22 mp3 volumio[16243]: info: CoreCommandRouter::volumioPlay Dec 01 03:48:22 mp3 volumio[16243]: info: CoreStateMachine::play index undefined Dec 01 03:48:22 mp3 volumio[16243]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 01 03:48:22 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:22 mp3 volumio[16243]: info: CoreStateMachine::startPlaybackTimer Dec 01 03:48:22 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:22 mp3 volumio[16243]: info: ControllerMpd::resume Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand play Dec 01 03:48:22 mp3 volumio[16243]: info: sendMpdCommand play took 1 milliseconds Dec 01 03:48:22 mp3 volumio[16243]: info: Dec 01 03:48:22 mp3 volumio[16243]: ---------------------------- MPD announces state update: player Dec 01 03:48:22 mp3 volumio[16243]: info: ControllerMpd::getState Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand status Dec 01 03:48:22 mp3 volumio[16243]: info: Dec 01 03:48:22 mp3 volumio[16243]: ---------------------------- MPD announces state update: player Dec 01 03:48:22 mp3 volumio[16243]: info: ControllerMpd::getState Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand status Dec 01 03:48:22 mp3 volumio[16243]: info: Dec 01 03:48:22 mp3 volumio[16243]: ---------------------------- MPD announces state update: player Dec 01 03:48:22 mp3 volumio[16243]: info: ControllerMpd::getState Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand status Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 01 03:48:22 mp3 volumio[16243]: info: sendMpdCommand status took 4 milliseconds Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 01 03:48:22 mp3 volumio[16243]: info: sendMpdCommand status took 4 milliseconds Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::parseState Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::parseState Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 01 03:48:22 mp3 volumio[16243]: info: sendMpdCommand status took 7 milliseconds Dec 01 03:48:22 mp3 volumio[16243]: info: sendMpdCommand clearerror took 4 milliseconds Dec 01 03:48:22 mp3 volumio[16243]: info: sendMpdCommand clearerror took 4 milliseconds Dec 01 03:48:22 mp3 volumio[16243]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 01 03:48:22 mp3 volumio[16243]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::parseState Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::parseTrackInfo Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::parseTrackInfo Dec 01 03:48:22 mp3 volumio[16243]: info: ControllerMpd::pushState Dec 01 03:48:22 mp3 volumio[16243]: info: CoreCommandRouter::servicePushState Dec 01 03:48:22 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:22 mp3 volumio[16243]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"×í¹ý·½Öª¾ÆŨ(DJÄ°Ãΰæ)","artist":"ö¬µ¤(À˸ç)","album":"04.ÈÈÃÅÖÐÎÄDJ","uri":"USB/0143-2901/05.网络流行中文DJ-240/093.醉过方知酒浓(DJ陌梦版)-霈丹(浪哥).mp3","trackType":"mp3"} Dec 01 03:48:22 mp3 volumio[16243]: verbose: CURRENT POSITION 44 Dec 01 03:48:22 mp3 volumio[16243]: info: CoreStateMachine::syncState stateService pause Dec 01 03:48:22 mp3 volumio[16243]: info: CoreStateMachine::syncState currentStatus pause Dec 01 03:48:22 mp3 volumio[16243]: info: CoreStateMachine::pushState Dec 01 03:48:22 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:22 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 03:48:22 mp3 volumio[16243]: info: CoreCommandRouter::volumioPushState Dec 01 03:48:22 mp3 volumio[16243]: info: CoreStateMachine::stPlaybackTimer Dec 01 03:48:22 mp3 volumio[16243]: info: ControllerMpd::pushState Dec 01 03:48:22 mp3 volumio[16243]: info: CoreCommandRouter::servicePushState Dec 01 03:48:22 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:22 mp3 volumio[16243]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"×í¹ý·½Öª¾ÆŨ(DJÄ°Ãΰæ)","artist":"ö¬µ¤(À˸ç)","album":"04.ÈÈÃÅÖÐÎÄDJ","uri":"USB/0143-2901/05.网络流行中文DJ-240/093.醉过方知酒浓(DJ陌梦版)-霈丹(浪哥).mp3","trackType":"mp3"} Dec 01 03:48:22 mp3 volumio[16243]: verbose: CURRENT POSITION 44 Dec 01 03:48:22 mp3 volumio[16243]: info: CoreStateMachine::syncState stateService pause Dec 01 03:48:22 mp3 volumio[16243]: info: CoreStateMachine::syncState currentStatus pause Dec 01 03:48:22 mp3 volumio[16243]: info: CoreStateMachine::pushState Dec 01 03:48:22 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:22 mp3 volumio[16243]: info: CoreCommandRouter::volumioPushState Dec 01 03:48:22 mp3 volumio[16243]: info: CoreStateMachine::stPlaybackTimer Dec 01 03:48:22 mp3 volumio[16243]: info: ------------------------------ 16ms Dec 01 03:48:22 mp3 volumio[16243]: info: ------------------------------ 15ms Dec 01 03:48:22 mp3 volumio[16243]: info: sendMpdCommand clearerror took 12 milliseconds Dec 01 03:48:22 mp3 volumio[16243]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 01 03:48:22 mp3 volumio[16243]: verbose: ControllerMpd::parseTrackInfo Dec 01 03:48:22 mp3 volumio[16243]: info: ControllerMpd::pushState Dec 01 03:48:22 mp3 volumio[16243]: info: CoreCommandRouter::servicePushState Dec 01 03:48:22 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:22 mp3 volumio[16243]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":212,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"×í¹ý·½Öª¾ÆŨ(DJÄ°Ãΰæ)","artist":"ö¬µ¤(À˸ç)","album":"04.ÈÈÃÅÖÐÎÄDJ","uri":"USB/0143-2901/05.网络流行中文DJ-240/093.醉过方知酒浓(DJ陌梦版)-霈丹(浪哥).mp3","trackType":"mp3"} Dec 01 03:48:22 mp3 volumio[16243]: verbose: CURRENT POSITION 44 Dec 01 03:48:22 mp3 volumio[16243]: info: CoreStateMachine::syncState stateService pause Dec 01 03:48:22 mp3 volumio[16243]: info: CoreStateMachine::syncState currentStatus pause Dec 01 03:48:22 mp3 volumio[16243]: info: CoreStateMachine::pushState Dec 01 03:48:22 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:22 mp3 volumio[16243]: info: CoreCommandRouter::volumioPushState Dec 01 03:48:22 mp3 volumio[16243]: info: CoreStateMachine::stPlaybackTimer Dec 01 03:48:22 mp3 volumio[16243]: info: ------------------------------ 22ms Dec 01 03:48:34 mp3 volumio[16243]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 03:48:34 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 01 03:48:34 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 03:48:34 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 01 03:48:34 mp3 volumio[16243]: Invalid card number. Dec 01 03:48:34 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 03:48:34 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 03:48:34 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 03:48:34 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 03:48:34 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 03:48:34 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 03:48:34 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 03:48:34 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 01 03:48:34 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 01 03:48:39 mp3 volumio[16243]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Dec 01 03:48:39 mp3 volumio[16243]: info: Preparing to save Alsa Options, stopping services first Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::volumioGetState Dec 01 03:48:39 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::volumioPause Dec 01 03:48:39 mp3 volumio[16243]: info: CoreStateMachine::pause Dec 01 03:48:39 mp3 volumio[16243]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Realtek USB2.0 Audio"}} Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 01 03:48:39 mp3 volumio[16243]: warn: Unable to locate the device Realtek USB2.0 Audio a reboot may be required. Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 01 03:48:39 mp3 volumio[16243]: Invalid card number. Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 01 03:48:39 mp3 volumio[16243]: info: Updating Volume Controller Parameters: Device: 5 Name: Realtek USB2.0 Audio Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 01 03:48:39 mp3 volumio[16243]: info: Disabling external Volume Control Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 01 03:48:39 mp3 volumio[16243]: info: Preparing to generate the ALSA configuration file Dec 01 03:48:39 mp3 volumio[16243]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 01 03:48:39 mp3 volumio[16243]: Invalid card number. Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 01 03:48:39 mp3 volumio[16243]: info: VolumeController:: Volume=undefined Mute =false Dec 01 03:48:39 mp3 volumio[16243]: info: CoreStateMachine::pushState Dec 01 03:48:39 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::volumioPushState Dec 01 03:48:39 mp3 volumio[16243]: info: Asound.conf file written Dec 01 03:48:39 mp3 sudo[19162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 01 03:48:39 mp3 sudo[19162]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 03:48:39 mp3 sudo[19162]: pam_unix(sudo:session): session closed for user root Dec 01 03:48:39 mp3 volumio[16243]: info: Output device has changed, restarting MPD Dec 01 03:48:39 mp3 sudo[19167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 03:48:39 mp3 sudo[19167]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 03:48:39 mp3 sudo[19167]: pam_unix(sudo:session): session closed for user root Dec 01 03:48:39 mp3 volumio[16243]: info: Output device has changed, restarting Shairport Sync Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 03:48:39 mp3 sudo[19170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 03:48:39 mp3 sudo[19170]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 03:48:39 mp3 systemd[1]: Stopping Music Player Daemon... Dec 01 03:48:39 mp3 volumio[16243]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 03:48:39 mp3 volumio[16243]: info: Output device has changed, restarting MPD Dec 01 03:48:39 mp3 systemd[1]: mpd.service: Succeeded. Dec 01 03:48:39 mp3 systemd[1]: Stopped Music Player Daemon. Dec 01 03:48:39 mp3 sudo[19177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 03:48:39 mp3 sudo[19177]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 03:48:39 mp3 systemd[1]: Starting Music Player Daemon... Dec 01 03:48:39 mp3 volumio[16243]: info: Output device has changed, restarting Shairport Sync Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 03:48:39 mp3 sudo[19177]: pam_unix(sudo:session): session closed for user root Dec 01 03:48:39 mp3 sudo[19180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 03:48:39 mp3 sudo[19180]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 03:48:39 mp3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 01 03:48:39 mp3 systemd[1]: mpd.service: Succeeded. Dec 01 03:48:39 mp3 systemd[1]: Stopped Music Player Daemon. Dec 01 03:48:39 mp3 systemd[1]: Starting Music Player Daemon... Dec 01 03:48:39 mp3 volumio[16243]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 03:48:39 mp3 volumio[16243]: info: MPD Permissions set Dec 01 03:48:39 mp3 volumio[16243]: info: MPD Permissions set Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 03:48:39 mp3 sudo[19185]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 01 03:48:39 mp3 sudo[19185]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 03:48:39 mp3 sudo[19185]: pam_unix(sudo:session): session closed for user root Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 01 03:48:39 mp3 volumio[16243]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 03:48:39 mp3 volumio[16243]: info: Starting Shairport Sync Dec 01 03:48:39 mp3 volumio[16243]: info: Starting Shairport Sync Dec 01 03:48:39 mp3 sudo[19202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 01 03:48:39 mp3 sudo[19202]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 03:48:39 mp3 sudo[19204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 01 03:48:39 mp3 sudo[19204]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 03:48:39 mp3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 01 03:48:39 mp3 systemd[1]: shairport-sync.service: Succeeded. Dec 01 03:48:39 mp3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 01 03:48:39 mp3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 01 03:48:39 mp3 sudo[19202]: pam_unix(sudo:session): session closed for user root Dec 01 03:48:39 mp3 volumio[16243]: info: Shairport-Sync Started Dec 01 03:48:39 mp3 sudo[19204]: pam_unix(sudo:session): session closed for user root Dec 01 03:48:39 mp3 volumio[16243]: info: Shairport-Sync Started Dec 01 03:48:39 mp3 mpd[19198]: Dec 01 03:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 01 03:48:39 mp3 systemd[1]: Started Music Player Daemon. Dec 01 03:48:39 mp3 sudo[19170]: pam_unix(sudo:session): session closed for user root Dec 01 03:48:39 mp3 sudo[19180]: pam_unix(sudo:session): session closed for user root Dec 01 03:48:39 mp3 volumio[16243]: error: MPD error: The expression evaluated to a falsy value: Dec 01 03:48:39 mp3 volumio[16243]: assert.ok(self.idling) Dec 01 03:48:39 mp3 volumio[16243]: error: The expression evaluated to a falsy value: Dec 01 03:48:39 mp3 volumio[16243]: assert.ok(self.idling) Dec 01 03:48:39 mp3 volumio[16243]: error: updateQueue error: null Dec 01 03:48:43 mp3 volumio[16243]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Dec 01 03:48:43 mp3 volumio[16243]: info: CoreStateMachine::getcurrentVolume Dec 01 03:48:43 mp3 volumio[16243]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 03:48:43 mp3 volumio[16243]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 01 03:48:43 mp3 volumio[16243]: info: VolumeController:: Volume=undefined Mute =false Dec 01 03:48:43 mp3 volumio[16243]: info: CoreStateMachine::pushState Dec 01 03:48:43 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:43 mp3 volumio[16243]: info: CoreCommandRouter::volumioPushState Dec 01 03:48:43 mp3 volumio[16243]: info: CoreStateMachine::updateTrackBlock Dec 01 03:48:43 mp3 volumio[16243]: info: CorePlayQueue::getTrackBlock Dec 01 03:48:43 mp3 volumio[16243]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 03:48:43 mp3 volumio[16243]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Dec 01 03:48:43 mp3 volumio[16243]: info: VolumeController:: Volume=undefined Mute =false Dec 01 03:48:43 mp3 volumio[16243]: info: CoreStateMachine::pushState Dec 01 03:48:43 mp3 volumio[16243]: info: CorePlayQueue::getTrack 44 Dec 01 03:48:43 mp3 volumio[16243]: info: CoreCommandRouter::volumioPushState Dec 01 03:48:58 mp3 volumio[16243]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 03:48:58 mp3 volumio[16243]: Error: read ETIMEDOUT Dec 01 03:48:58 mp3 volumio[16243]: at TCP.onStreamRead (internal/stream_base_commons.js:209:20) { Dec 01 03:48:58 mp3 volumio[16243]: errno: -110, Dec 01 03:48:58 mp3 volumio[16243]: code: 'ETIMEDOUT', Dec 01 03:48:58 mp3 volumio[16243]: syscall: 'read' Dec 01 03:48:58 mp3 volumio[16243]: } Dec 01 03:48:58 mp3 volumio[16243]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 03:48:59 mp3 sudo[19262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 03:47 Dec 01 03:48:59 mp3 sudo[19262]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"