-- Logs begin at Tue 2024-10-15 16:52:38 CST, end at Sat 2024-11-02 12:00:47 CST. -- Nov 02 11:59:01 volumio kernel: usb 1-1.2: new high-speed USB device number 4 using xhci_hcd Nov 02 11:59:01 volumio kernel: usb 1-1.2: New USB device found, idVendor=154e, idProduct=1003, bcdDevice= 1.00 Nov 02 11:59:01 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Nov 02 11:59:01 volumio kernel: usb 1-1.2: Product: DA-300USB Nov 02 11:59:01 volumio kernel: usb 1-1.2: Manufacturer: D & M Holdings Inc. Nov 02 11:59:02 volumio kernel: hid-generic 0003:154E:1003.0002: hiddev96,hidraw0: USB HID v1.10 Device [D & M Holdings Inc. DA-300USB] on usb-0000:01:00.0-1.2/input2 Nov 02 11:59:02 volumio volumio[977]: info: Nov 02 11:59:02 volumio volumio[977]: ---------------------------- USB Audio Device Attached Nov 02 11:59:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Nov 02 11:59:02 volumio volumio[977]: info: CoreCommandRouter::Close All Modals sent Nov 02 11:59:02 volumio volumio[977]: info: Preparing to save Alsa Options, stopping services first Nov 02 11:59:02 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Nov 02 11:59:02 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Nov 02 11:59:02 volumio volumio[977]: info: CoreCommandRouter::volumioPause Nov 02 11:59:02 volumio volumio[977]: info: CoreStateMachine::pause Nov 02 11:59:02 volumio volumio[977]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"DA-300USB","alsacard":"DA300USB"},"i2s":false} Nov 02 11:59:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 11:59:02 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 11:59:02 volumio volumio[977]: info: Setting mixer PCM for card DA-300USB Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 02 11:59:03 volumio volumio[977]: info: Updating Volume Controller Parameters: Device: 5 Name: DA-300USB Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 5 Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 02 11:59:03 volumio volumio[977]: info: Disabling external Volume Control Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 11:59:03 volumio volumio[977]: info: Preparing to generate the ALSA configuration file Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 02 11:59:03 volumio volumio[977]: info: Asound.conf file unchanged, so no further update is needed Nov 02 11:59:03 volumio volumio[977]: info: Output device has changed, restarting MPD Nov 02 11:59:03 volumio sudo[4503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 11:59:03 volumio sudo[4503]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 11:59:03 volumio volumio[977]: info: Output device has changed, restarting Shairport Sync Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 11:59:03 volumio sudo[4503]: pam_unix(sudo:session): session closed for user root Nov 02 11:59:03 volumio sudo[4506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 11:59:03 volumio sudo[4506]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 11:59:03 volumio systemd[1]: Stopping Music Player Daemon... Nov 02 11:59:03 volumio volumio[977]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 11:59:03 volumio volumio[977]: info: VolumeController:: Volume=100 Mute =false Nov 02 11:59:03 volumio volumio[977]: info: CoreStateMachine::pushState Nov 02 11:59:03 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Nov 02 11:59:03 volumio systemd[1]: mpd.service: Succeeded. Nov 02 11:59:03 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 11:59:03 volumio volumio[977]: info: MPD Permissions set Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 11:59:03 volumio volumio[977]: info: Starting Shairport Sync Nov 02 11:59:03 volumio systemd[1]: Starting Music Player Daemon... Nov 02 11:59:03 volumio sudo[4517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 11:59:03 volumio sudo[4517]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 11:59:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 11:59:03 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 11:59:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 11:59:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 11:59:03 volumio sudo[4517]: pam_unix(sudo:session): session closed for user root Nov 02 11:59:03 volumio volumio[977]: info: Shairport-Sync Started Nov 02 11:59:03 volumio sudo[4511]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 11:59:03 volumio sudo[4511]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 11:59:03 volumio sudo[4511]: pam_unix(sudo:session): session closed for user root Nov 02 11:59:03 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Nov 02 11:59:04 volumio mpd[4524]: Nov 02 11:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 11:59:04 volumio systemd[1]: Started Music Player Daemon. Nov 02 11:59:04 volumio sudo[4506]: pam_unix(sudo:session): session closed for user root Nov 02 11:59:04 volumio volumio[977]: error: updateQueue error: null Nov 02 11:59:07 volumio volumio[977]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 02 11:59:07 volumio volumio[977]: info: CoreStateMachine::getcurrentVolume Nov 02 11:59:07 volumio volumio[977]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 11:59:07 volumio volumio[977]: info: VolumeController:: Volume=100 Mute =false Nov 02 11:59:07 volumio volumio[977]: info: CoreStateMachine::pushState Nov 02 11:59:07 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Nov 02 11:59:07 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:59:07 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Nov 02 11:59:07 volumio volumio[977]: info: CoreStateMachine::updateTrackBlock Nov 02 11:59:07 volumio volumio[977]: info: CorePlayQueue::getTrackBlock Nov 02 11:59:07 volumio volumio[977]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 11:59:07 volumio volumio[977]: info: VolumeController:: Volume=100 Mute =false Nov 02 11:59:07 volumio volumio[977]: info: CoreStateMachine::pushState Nov 02 11:59:07 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Nov 02 11:59:07 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Nov 02 11:59:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 11:59:08 volumio volumio[977]: info: VolumeController:: Setting startup Volume 60 Nov 02 11:59:08 volumio volumio[977]: info: VolumeController::SetAlsaVolume60 Nov 02 11:59:08 volumio volumio[977]: info: CoreStateMachine::pushState Nov 02 11:59:08 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Nov 02 11:59:08 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:59:08 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Nov 02 11:59:09 volumio volumio[977]: info: CoreCommandRouter::volumioPlay Nov 02 11:59:09 volumio volumio[977]: info: CoreStateMachine::play index undefined Nov 02 11:59:09 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 11:59:09 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Nov 02 11:59:09 volumio volumio[977]: info: CoreStateMachine::startPlaybackTimer Nov 02 11:59:09 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Nov 02 11:59:09 volumio volumio[977]: verbose: ControllerMpd::clearAddPlayTracks NAS/Nas/Lossless/FLAC/网易云音乐/蔡依林 - 我知道你很难过.flac Nov 02 11:59:09 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 11:59:09 volumio volumio[977]: info: sendMpdCommand stop took 1 milliseconds Nov 02 11:59:09 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand clear Nov 02 11:59:09 volumio volumio[977]: info: Nov 02 11:59:09 volumio volumio[977]: ---------------------------- MPD announces system playlist update Nov 02 11:59:09 volumio volumio[977]: info: Ignoring MPD Status Update Nov 02 11:59:09 volumio volumio[977]: info: sendMpdCommand clear took 1 milliseconds Nov 02 11:59:09 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand add "NAS/Nas/Lossless/FLAC/网易云音乐/蔡依林 - 我知道你很难过.flac" Nov 02 11:59:09 volumio volumio[977]: error: updateQueue error: null Nov 02 11:59:09 volumio volumio[977]: info: Nov 02 11:59:09 volumio volumio[977]: ---------------------------- MPD announces system playlist update Nov 02 11:59:09 volumio volumio[977]: info: Ignoring MPD Status Update Nov 02 11:59:09 volumio volumio[977]: info: ------------------------------ 2ms Nov 02 11:59:09 volumio volumio[977]: info: sendMpdCommand add "NAS/Nas/Lossless/FLAC/网易云音乐/蔡依林 - 我知道你很难过.flac" took 2 milliseconds Nov 02 11:59:09 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand play Nov 02 11:59:09 volumio volumio[977]: info: ------------------------------ 2ms Nov 02 11:59:09 volumio volumio[977]: info: sendMpdCommand play took 1 milliseconds Nov 02 11:59:09 volumio volumio[977]: info: Nov 02 11:59:09 volumio volumio[977]: ---------------------------- MPD announces state update: player Nov 02 11:59:09 volumio volumio[977]: info: ControllerMpd::getState Nov 02 11:59:09 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Nov 02 11:59:10 volumio volumio[977]: info: Nov 02 11:59:10 volumio volumio[977]: ---------------------------- MPD announces state update: player Nov 02 11:59:10 volumio volumio[977]: info: sendMpdCommand status took 377 milliseconds Nov 02 11:59:10 volumio volumio[977]: info: ControllerMpd::getState Nov 02 11:59:10 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Nov 02 11:59:10 volumio volumio[977]: verbose: ControllerMpd::parseState Nov 02 11:59:10 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 11:59:10 volumio volumio[977]: info: sendMpdCommand status took 1 milliseconds Nov 02 11:59:10 volumio volumio[977]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 02 11:59:10 volumio volumio[977]: verbose: ControllerMpd::parseState Nov 02 11:59:10 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 11:59:10 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Nov 02 11:59:10 volumio volumio[977]: info: ControllerMpd::pushState Nov 02 11:59:10 volumio volumio[977]: info: CoreCommandRouter::servicePushState Nov 02 11:59:10 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Nov 02 11:59:10 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"我知道你很难过","artist":"蔡依林","album":"J女神 影音典藏精选","uri":"NAS/Nas/Lossless/FLAC/网易云音乐/蔡依林 - 我知道你很难过.flac","trackType":"flac"} Nov 02 11:59:10 volumio volumio[977]: verbose: CURRENT POSITION 1 Nov 02 11:59:10 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Nov 02 11:59:10 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Nov 02 11:59:10 volumio volumio[977]: info: ------------------------------ 382ms Nov 02 11:59:10 volumio volumio[977]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 02 11:59:10 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Nov 02 11:59:10 volumio volumio[977]: info: ControllerMpd::pushState Nov 02 11:59:10 volumio volumio[977]: info: CoreCommandRouter::servicePushState Nov 02 11:59:10 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Nov 02 11:59:10 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"我知道你很难过","artist":"蔡依林","album":"J女神 影音典藏精选","uri":"NAS/Nas/Lossless/FLAC/网易云音乐/蔡依林 - 我知道你很难过.flac","trackType":"flac"} Nov 02 11:59:10 volumio volumio[977]: verbose: CURRENT POSITION 1 Nov 02 11:59:10 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Nov 02 11:59:10 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Nov 02 11:59:10 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Nov 02 11:59:10 volumio volumio[977]: info: CoreStateMachine::pushState Nov 02 11:59:10 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Nov 02 11:59:10 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:59:10 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Nov 02 11:59:10 volumio volumio[977]: info: CoreStateMachine::pushState Nov 02 11:59:10 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Nov 02 11:59:10 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Nov 02 11:59:10 volumio volumio[977]: info: ------------------------------ 12ms Nov 02 11:59:24 volumio volumio[977]: info: CoreCommandRouter::volumioGetState Nov 02 11:59:24 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Nov 02 11:59:28 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 02 11:59:28 volumio volumio[977]: info: CURURI: music-library/NAS/Nas/Lossless/FLAC Nov 02 11:59:28 volumio volumio[977]: info: Preload queue cleared Nov 02 11:59:41 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Nov 02 11:59:41 volumio volumio[977]: info: CURURI: music-library/NAS/Nas/Lossless/FLAC/Single Tracks Nov 02 11:59:41 volumio volumio[977]: info: Preload queue cleared Nov 02 11:59:41 volumio volumio[977]: info: Preloading song: music-library/NAS/Nas/Lossless/FLAC/Single Tracks/001夜深沉.flac Nov 02 11:59:41 volumio volumio[977]: info: Preloading song: music-library/NAS/Nas/Lossless/FLAC/Single Tracks/Hotel.California.flac Nov 02 11:59:41 volumio volumio[977]: info: Preloading song: music-library/NAS/Nas/Lossless/FLAC/Single Tracks/莫文蔚-0如果没有你.flac Nov 02 11:59:41 volumio volumio[977]: info: Preloading song: music-library/NAS/Nas/Lossless/FLAC/Single Tracks/01. 夜深沉.wav Nov 02 11:59:41 volumio volumio[977]: info: Preloading song: music-library/NAS/Nas/Lossless/FLAC/Single Tracks/戴荃-悟空.flac Nov 02 11:59:41 volumio volumio[977]: info: Exploding uri music-library/NAS/Nas/Lossless/FLAC/Single Tracks/001夜深沉.flac in service mpd Nov 02 11:59:41 volumio volumio[977]: info: ALBUMART /albumart?cacheid=661&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FLossless%2FFLAC%2FSingle%20Tracks%2F001%E5%A4%9C%E6%B7%B1%E6%B2%89.flac&metadata=false Nov 02 11:59:41 volumio volumio[977]: info: URI /mnt/NAS/Nas/Lossless/FLAC/Single Tracks/001夜深沉.flac Nov 02 11:59:41 volumio volumio[977]: info: Exploding uri music-library/NAS/Nas/Lossless/FLAC/Single Tracks/Hotel.California.flac in service mpd Nov 02 11:59:41 volumio volumio[977]: info: ALBUMART /albumart?cacheid=661&web=//extralarge&path=%2Fmnt%2FNAS%2FNas%2FLossless%2FFLAC%2FSingle%20Tracks%2FHotel.California.flac&metadata=false Nov 02 11:59:41 volumio volumio[977]: info: URI /mnt/NAS/Nas/Lossless/FLAC/Single Tracks/Hotel.California.flac Nov 02 11:59:41 volumio volumio[977]: info: Exploding uri music-library/NAS/Nas/Lossless/FLAC/Single Tracks/莫文蔚-0如果没有你.flac in service mpd Nov 02 11:59:41 volumio volumio[977]: info: ALBUMART /albumart?cacheid=661&web=%5B51ape.com%5D%E8%8E%AB%E6%96%87%E8%94%9A//extralarge&path=%2Fmnt%2FNAS%2FNas%2FLossless%2FFLAC%2FSingle%20Tracks%2F%E8%8E%AB%E6%96%87%E8%94%9A-0%E5%A6%82%E6%9E%9C%E6%B2%A1%E6%9C%89%E4%BD%A0.flac&metadata=false Nov 02 11:59:41 volumio volumio[977]: info: URI /mnt/NAS/Nas/Lossless/FLAC/Single Tracks/莫文蔚-0如果没有你.flac Nov 02 11:59:41 volumio volumio[977]: info: Exploding uri music-library/NAS/Nas/Lossless/FLAC/Single Tracks/01. 夜深沉.wav in service mpd Nov 02 11:59:41 volumio volumio[977]: info: ALBUMART /albumart?cacheid=661&web=%3F%3F/%3F%3F%3F/extralarge&path=%2Fmnt%2FNAS%2FNas%2FLossless%2FFLAC%2FSingle%20Tracks%2F01.%20%E5%A4%9C%E6%B7%B1%E6%B2%89.wav&metadata=false Nov 02 11:59:41 volumio volumio[977]: info: URI /mnt/NAS/Nas/Lossless/FLAC/Single Tracks/01. 夜深沉.wav Nov 02 11:59:41 volumio volumio[977]: info: Exploding uri music-library/NAS/Nas/Lossless/FLAC/Single Tracks/戴荃-悟空.flac in service mpd Nov 02 11:59:41 volumio volumio[977]: info: ALBUMART /albumart?cacheid=661&web=%E6%88%B4%E8%8D%83/%E4%B8%AD%E5%9B%BD%E5%A5%BD%E6%AD%8C%E6%9B%B2%E7%AC%AC%E4%BA%8C%E5%AD%A3%20%E7%AC%AC5%E6%9C%9F/extralarge&path=%2Fmnt%2FNAS%2FNas%2FLossless%2FFLAC%2FSingle%20Tracks%2F%E6%88%B4%E8%8D%83-%E6%82%9F%E7%A9%BA.flac&metadata=false Nov 02 11:59:41 volumio volumio[977]: info: URI /mnt/NAS/Nas/Lossless/FLAC/Single Tracks/戴荃-悟空.flac Nov 02 11:59:44 volumio volumio[977]: info: Preload queue cleared Nov 02 11:59:44 volumio volumio[977]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::ClearQueue Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::stop Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::stPlaybackTimer Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::updateTrackBlock Nov 02 11:59:44 volumio volumio[977]: info: CorePlayQueue::getTrackBlock Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::pushState Nov 02 11:59:44 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Nov 02 11:59:44 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:59:44 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::serviceStop Nov 02 11:59:44 volumio volumio[977]: info: CorePlayQueue::getTrack 1 Nov 02 11:59:44 volumio volumio[977]: info: CoreCommandRouter::serviceStop Nov 02 11:59:44 volumio volumio[977]: info: ControllerMpd::stop Nov 02 11:59:44 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 11:59:44 volumio volumio[977]: info: CorePlayQueue::clearPlayQueue Nov 02 11:59:44 volumio volumio[977]: info: CorePlayQueue::saveQueue Nov 02 11:59:44 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::addQueueItems Nov 02 11:59:44 volumio volumio[977]: info: CorePlayQueue::addQueueItems Nov 02 11:59:44 volumio volumio[977]: info: Preload queue cleared Nov 02 11:59:44 volumio volumio[977]: info: Adding Item to queue: music-library/NAS/Nas/Lossless/FLAC/Single Tracks/001夜深沉.flac Nov 02 11:59:44 volumio volumio[977]: info: Using cached record of: music-library/NAS/Nas/Lossless/FLAC/Single Tracks/001夜深沉.flac Nov 02 11:59:44 volumio volumio[977]: info: Adding Item to queue: music-library/NAS/Nas/Lossless/FLAC/Single Tracks/Hotel.California.flac Nov 02 11:59:44 volumio volumio[977]: info: Using cached record of: music-library/NAS/Nas/Lossless/FLAC/Single Tracks/Hotel.California.flac Nov 02 11:59:44 volumio volumio[977]: info: Adding Item to queue: music-library/NAS/Nas/Lossless/FLAC/Single Tracks/莫文蔚-0如果没有你.flac Nov 02 11:59:44 volumio volumio[977]: info: Using cached record of: music-library/NAS/Nas/Lossless/FLAC/Single Tracks/莫文蔚-0如果没有你.flac Nov 02 11:59:44 volumio volumio[977]: info: Adding Item to queue: music-library/NAS/Nas/Lossless/FLAC/Single Tracks/01. 夜深沉.wav Nov 02 11:59:44 volumio volumio[977]: info: Using cached record of: music-library/NAS/Nas/Lossless/FLAC/Single Tracks/01. 夜深沉.wav Nov 02 11:59:44 volumio volumio[977]: info: Adding Item to queue: music-library/NAS/Nas/Lossless/FLAC/Single Tracks/戴荃-悟空.flac Nov 02 11:59:44 volumio volumio[977]: info: Using cached record of: music-library/NAS/Nas/Lossless/FLAC/Single Tracks/戴荃-悟空.flac Nov 02 11:59:44 volumio volumio[977]: info: CoreCommandRouter::volumioPushQueue Nov 02 11:59:44 volumio volumio[977]: info: CorePlayQueue::saveQueue Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::updateTrackBlock Nov 02 11:59:44 volumio volumio[977]: info: CorePlayQueue::getTrackBlock Nov 02 11:59:44 volumio volumio[977]: info: CoreCommandRouter::volumioPlay Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::play index 0 Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::stop Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::play index undefined Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 11:59:44 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Nov 02 11:59:44 volumio volumio[977]: info: CoreStateMachine::startPlaybackTimer Nov 02 11:59:44 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Nov 02 11:59:44 volumio volumio[977]: verbose: ControllerMpd::clearAddPlayTracks NAS/Nas/Lossless/FLAC/Single Tracks/001夜深沉.flac Nov 02 11:59:44 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 11:59:44 volumio volumio[977]: info: sendMpdCommand stop took 33 milliseconds Nov 02 11:59:44 volumio volumio[977]: info: Nov 02 11:59:44 volumio volumio[977]: ---------------------------- MPD announces state update: player Nov 02 11:59:44 volumio volumio[977]: info: ControllerMpd::getState Nov 02 11:59:44 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Nov 02 11:59:44 volumio volumio[977]: info: sendMpdCommand stop took 24 milliseconds Nov 02 11:59:44 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand clear Nov 02 11:59:44 volumio volumio[977]: info: Nov 02 11:59:44 volumio volumio[977]: ---------------------------- MPD announces system playlist update Nov 02 11:59:44 volumio volumio[977]: info: Ignoring MPD Status Update Nov 02 11:59:44 volumio volumio[977]: info: sendMpdCommand status took 1 milliseconds Nov 02 11:59:44 volumio volumio[977]: info: sendMpdCommand clear took 1 milliseconds Nov 02 11:59:44 volumio volumio[977]: verbose: ControllerMpd::parseState Nov 02 11:59:44 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 11:59:44 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand add "NAS/Nas/Lossless/FLAC/Single Tracks/001夜深沉.flac" Nov 02 11:59:44 volumio volumio[977]: error: updateQueue error: null Nov 02 11:59:44 volumio volumio[977]: info: Nov 02 11:59:44 volumio volumio[977]: ---------------------------- MPD announces system playlist update Nov 02 11:59:44 volumio volumio[977]: info: Ignoring MPD Status Update Nov 02 11:59:44 volumio volumio[977]: info: ------------------------------ 2ms Nov 02 11:59:44 volumio volumio[977]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 02 11:59:44 volumio volumio[977]: info: sendMpdCommand add "NAS/Nas/Lossless/FLAC/Single Tracks/001夜深沉.flac" took 2 milliseconds Nov 02 11:59:44 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Nov 02 11:59:44 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand play Nov 02 11:59:44 volumio volumio[977]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 02 11:59:44 volumio volumio[977]: info: ------------------------------ 6ms Nov 02 11:59:44 volumio volumio[977]: info: ------------------------------ 5ms Nov 02 11:59:44 volumio volumio[977]: info: sendMpdCommand play took 4 milliseconds Nov 02 11:59:44 volumio volumio[977]: info: Nov 02 11:59:44 volumio volumio[977]: ---------------------------- MPD announces state update: player Nov 02 11:59:44 volumio volumio[977]: info: ControllerMpd::getState Nov 02 11:59:44 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Nov 02 11:59:45 volumio volumio[977]: info: Nov 02 11:59:45 volumio volumio[977]: ---------------------------- MPD announces state update: player Nov 02 11:59:45 volumio volumio[977]: info: sendMpdCommand status took 370 milliseconds Nov 02 11:59:45 volumio volumio[977]: info: ControllerMpd::getState Nov 02 11:59:45 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand status Nov 02 11:59:45 volumio volumio[977]: verbose: ControllerMpd::parseState Nov 02 11:59:45 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 11:59:45 volumio volumio[977]: info: sendMpdCommand status took 3 milliseconds Nov 02 11:59:45 volumio volumio[977]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 02 11:59:45 volumio volumio[977]: verbose: ControllerMpd::parseState Nov 02 11:59:45 volumio volumio[977]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 11:59:45 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Nov 02 11:59:45 volumio volumio[977]: info: ControllerMpd::pushState Nov 02 11:59:45 volumio volumio[977]: info: CoreCommandRouter::servicePushState Nov 02 11:59:45 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Nov 02 11:59:45 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":387,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2560 Kbps","isStreaming":false,"title":"001夜深沉.flac","artist":null,"album":null,"uri":"NAS/Nas/Lossless/FLAC/Single Tracks/001夜深沉.flac","trackType":"flac"} Nov 02 11:59:45 volumio volumio[977]: verbose: CURRENT POSITION 0 Nov 02 11:59:45 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Nov 02 11:59:45 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus stop Nov 02 11:59:45 volumio volumio[977]: info: ------------------------------ 376ms Nov 02 11:59:45 volumio volumio[977]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 02 11:59:45 volumio volumio[977]: verbose: ControllerMpd::parseTrackInfo Nov 02 11:59:45 volumio volumio[977]: info: ControllerMpd::pushState Nov 02 11:59:45 volumio volumio[977]: info: CoreCommandRouter::servicePushState Nov 02 11:59:45 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Nov 02 11:59:45 volumio volumio[977]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":387,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2478 Kbps","isStreaming":false,"title":"001夜深沉.flac","artist":null,"album":null,"uri":"NAS/Nas/Lossless/FLAC/Single Tracks/001夜深沉.flac","trackType":"flac"} Nov 02 11:59:45 volumio volumio[977]: verbose: CURRENT POSITION 0 Nov 02 11:59:45 volumio volumio[977]: info: CoreStateMachine::syncState stateService play Nov 02 11:59:45 volumio volumio[977]: info: CoreStateMachine::syncState currentStatus play Nov 02 11:59:45 volumio volumio[977]: info: Received an update from plugin. extracting info from payload Nov 02 11:59:45 volumio volumio[977]: info: CoreStateMachine::pushState Nov 02 11:59:45 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Nov 02 11:59:45 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:59:45 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Nov 02 11:59:45 volumio volumio[977]: info: CoreStateMachine::pushState Nov 02 11:59:45 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Nov 02 11:59:45 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Nov 02 11:59:45 volumio volumio[977]: info: ------------------------------ 15ms Nov 02 11:59:49 volumio volumio[977]: info: VolumeController::SetAlsaVolume78 Nov 02 11:59:49 volumio volumio[977]: info: CoreStateMachine::pushState Nov 02 11:59:49 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Nov 02 11:59:49 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:59:49 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Nov 02 11:59:50 volumio volumio[977]: info: VolumeController::SetAlsaVolume+ Nov 02 11:59:50 volumio volumio[977]: info: CoreStateMachine::pushState Nov 02 11:59:50 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Nov 02 11:59:50 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:59:50 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Nov 02 11:59:51 volumio volumio[977]: info: VolumeController::SetAlsaVolume+ Nov 02 11:59:52 volumio volumio[977]: info: CoreStateMachine::pushState Nov 02 11:59:52 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Nov 02 11:59:52 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:59:52 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Nov 02 11:59:52 volumio volumio[977]: info: VolumeController::SetAlsaVolume+ Nov 02 11:59:52 volumio volumio[977]: info: CoreStateMachine::pushState Nov 02 11:59:52 volumio volumio[977]: info: CorePlayQueue::getTrack 0 Nov 02 11:59:52 volumio volumio[977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:59:52 volumio volumio[977]: info: CoreCommandRouter::volumioPushState Nov 02 12:00:47 volumio volumio[977]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 12:00:47 volumio volumio[977]: Error: connect ETIMEDOUT 88.191.249.182:443 Nov 02 12:00:47 volumio volumio[977]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Nov 02 12:00:47 volumio volumio[977]: errno: -110, Nov 02 12:00:47 volumio volumio[977]: code: 'ETIMEDOUT', Nov 02 12:00:47 volumio volumio[977]: syscall: 'connect', Nov 02 12:00:47 volumio volumio[977]: address: '88.191.249.182', Nov 02 12:00:47 volumio volumio[977]: port: 443 Nov 02 12:00:47 volumio volumio[977]: } Nov 02 12:00:47 volumio volumio[977]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 12:00:47 volumio sudo[4590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 11:59 Nov 02 12:00:47 volumio sudo[4590]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"