-- Logs begin at Thu 2019-02-14 13:12:00 +03, end at Sat 2024-08-24 14:11:43 +03. -- Aug 24 14:10:39 volumio kernel: usb 1-1.3: new high-speed USB device number 10 using dwc_otg Aug 24 14:10:39 volumio kernel: usb 1-1.3: New USB device found, idVendor=152a, idProduct=8852, bcdDevice= 3.05 Aug 24 14:10:39 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 24 14:10:39 volumio kernel: usb 1-1.3: Product: XingCore USB Hi-Resolution Audio Aug 24 14:10:39 volumio kernel: usb 1-1.3: Manufacturer: XingCore Aug 24 14:10:39 volumio kernel: usb 1-1.3: 1:2 : unsupported format bits 0x100000000 Aug 24 14:10:39 volumio kernel: usb 1-1.3: Warning! Unlikely big volume range (=512), cval->res is probably wrong. Aug 24 14:10:39 volumio kernel: usb 1-1.3: [10] FU [PCM Playback Volume] ch = 2, val = -16384/0/32 Aug 24 14:10:39 volumio volumio[851]: info: Aug 24 14:10:39 volumio volumio[851]: ---------------------------- USB Audio Device Attached Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::Close All Modals sent Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 24 14:10:39 volumio volumio[851]: info: Setting mixer PCM for card XingCore USB Hi-Resolution Audi Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 24 14:10:39 volumio volumio[851]: info: Updating Volume Controller Parameters: Device: 5 Name: XingCore USB Hi-Resolution Audi Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 2 Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 24 14:10:39 volumio volumio[851]: info: Disabling external Volume Control Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 24 14:10:39 volumio volumio[851]: info: Preparing to generate the ALSA configuration file Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 24 14:10:39 volumio volumio[851]: info: VolumeController:: Volume=68 Mute =false Aug 24 14:10:39 volumio volumio[851]: info: CoreStateMachine::pushState Aug 24 14:10:39 volumio volumio[851]: info: CorePlayQueue::getTrack 8 Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::volumioPushState Aug 24 14:10:39 volumio volumio[851]: info: Asound.conf file unchanged, so no further update is needed Aug 24 14:10:39 volumio volumio[851]: info: Output device has changed, restarting MPD Aug 24 14:10:39 volumio volumio[851]: info: Output device has changed, restarting Shairport Sync Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 14:10:39 volumio sudo[4985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 24 14:10:39 volumio sudo[4985]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 14:10:39 volumio sudo[4985]: pam_unix(sudo:session): session closed for user root Aug 24 14:10:39 volumio sudo[4987]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 24 14:10:39 volumio sudo[4987]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 14:10:39 volumio systemd[1]: Stopping Music Player Daemon... Aug 24 14:10:39 volumio volumio[851]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 24 14:10:39 volumio volumio[851]: info: MPD Permissions set Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 14:10:39 volumio systemd[1]: mpd.service: Succeeded. Aug 24 14:10:39 volumio systemd[1]: Stopped Music Player Daemon. Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 24 14:10:39 volumio volumio[851]: info: Starting Shairport Sync Aug 24 14:10:39 volumio systemd[1]: Starting Music Player Daemon... Aug 24 14:10:39 volumio sudo[4999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 24 14:10:39 volumio sudo[4999]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 24 14:10:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 24 14:10:39 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 24 14:10:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 24 14:10:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 24 14:10:39 volumio sudo[4999]: pam_unix(sudo:session): session closed for user root Aug 24 14:10:39 volumio volumio[851]: info: Shairport-Sync Started Aug 24 14:10:40 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Aug 24 14:10:40 volumio mpd[4994]: Aug 24 14:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 24 14:10:40 volumio systemd[1]: Started Music Player Daemon. Aug 24 14:10:40 volumio sudo[4987]: pam_unix(sudo:session): session closed for user root Aug 24 14:10:40 volumio volumio[851]: error: updateQueue error: null Aug 24 14:10:43 volumio volumio[851]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 24 14:10:43 volumio volumio[851]: info: CoreStateMachine::getcurrentVolume Aug 24 14:10:43 volumio volumio[851]: info: CoreCommandRouter::volumioRetrievevolume Aug 24 14:10:43 volumio volumio[851]: info: VolumeController:: Volume=68 Mute =false Aug 24 14:10:43 volumio volumio[851]: info: CoreStateMachine::pushState Aug 24 14:10:43 volumio volumio[851]: info: CorePlayQueue::getTrack 8 Aug 24 14:10:43 volumio volumio[851]: info: CoreCommandRouter::volumioPushState Aug 24 14:10:43 volumio volumio[851]: info: CoreStateMachine::updateTrackBlock Aug 24 14:10:43 volumio volumio[851]: info: CorePlayQueue::getTrackBlock Aug 24 14:10:43 volumio volumio[851]: info: CoreCommandRouter::volumioRetrievevolume Aug 24 14:10:43 volumio volumio[851]: info: VolumeController:: Volume=68 Mute =false Aug 24 14:10:43 volumio volumio[851]: info: CoreStateMachine::pushState Aug 24 14:10:43 volumio volumio[851]: info: CorePlayQueue::getTrack 8 Aug 24 14:10:43 volumio volumio[851]: info: CoreCommandRouter::volumioPushState Aug 24 14:11:08 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 24 14:11:08 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 24 14:11:08 volumio volumio[851]: info: Discovery: Getting this device information Aug 24 14:11:08 volumio volumio[851]: info: CoreCommandRouter::volumioGetState Aug 24 14:11:08 volumio volumio[851]: info: CorePlayQueue::getTrack 8 Aug 24 14:11:08 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 24 14:11:10 volumio volumio[851]: verbose: New Socket.io Connection to 192.168.100.35:3000 from 192.168.100.32 UA: Dart/3.4 (dart:io) Total Clients: 5 Aug 24 14:11:19 volumio volumio[851]: info: CoreCommandRouter::volumioPlay Aug 24 14:11:19 volumio volumio[851]: info: CoreStateMachine::play index undefined Aug 24 14:11:19 volumio volumio[851]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 14:11:19 volumio volumio[851]: info: CorePlayQueue::getTrack 8 Aug 24 14:11:19 volumio volumio[851]: info: CoreStateMachine::startPlaybackTimer Aug 24 14:11:19 volumio volumio[851]: info: CorePlayQueue::getTrack 8 Aug 24 14:11:19 volumio volumio[851]: info: ControllerMpd::resume Aug 24 14:11:19 volumio volumio[851]: verbose: ControllerMpd::sendMpdCommand play Aug 24 14:11:19 volumio volumio[851]: info: sendMpdCommand play took 1 milliseconds Aug 24 14:11:23 volumio volumio[851]: info: CoreCommandRouter::volumioPlay Aug 24 14:11:23 volumio volumio[851]: info: CoreStateMachine::play index undefined Aug 24 14:11:23 volumio volumio[851]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 14:11:23 volumio volumio[851]: info: CorePlayQueue::getTrack 8 Aug 24 14:11:23 volumio volumio[851]: info: CoreStateMachine::startPlaybackTimer Aug 24 14:11:23 volumio volumio[851]: info: CorePlayQueue::getTrack 8 Aug 24 14:11:23 volumio volumio[851]: info: ControllerMpd::resume Aug 24 14:11:23 volumio volumio[851]: verbose: ControllerMpd::sendMpdCommand play Aug 24 14:11:23 volumio volumio[851]: info: sendMpdCommand play took 1 milliseconds Aug 24 14:11:26 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 24 14:11:26 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 24 14:11:26 volumio volumio[851]: info: Discovery: Getting this device information Aug 24 14:11:26 volumio volumio[851]: info: CoreCommandRouter::volumioGetState Aug 24 14:11:26 volumio volumio[851]: info: CorePlayQueue::getTrack 8 Aug 24 14:11:26 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 24 14:11:40 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 24 14:11:40 volumio volumio[851]: info: CURURI: music-library/USB/B4D46C82D46C4926/Music/Al Bano & Romina Power - I Grandi Success (Box, 3CD) Aug 24 14:11:40 volumio volumio[851]: info: Preload queue cleared Aug 24 14:11:43 volumio volumio[851]: info: Preload queue cleared Aug 24 14:11:43 volumio volumio[851]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 24 14:11:43 volumio volumio[851]: info: CoreStateMachine::ClearQueue Aug 24 14:11:43 volumio volumio[851]: info: CoreStateMachine::stop Aug 24 14:11:43 volumio volumio[851]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 24 14:11:43 volumio volumio[851]: info: CoreStateMachine::updateTrackBlock Aug 24 14:11:43 volumio volumio[851]: info: CorePlayQueue::getTrackBlock Aug 24 14:11:43 volumio volumio[851]: info: CoreStateMachine::stPlaybackTimer Aug 24 14:11:43 volumio volumio[851]: info: CoreStateMachine::pushState Aug 24 14:11:43 volumio volumio[851]: info: CorePlayQueue::getTrack 8 Aug 24 14:11:43 volumio volumio[851]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 24 14:11:43 volumio volumio[851]: info: CoreCommandRouter::volumioPushState Aug 24 14:11:43 volumio volumio[851]: info: CoreStateMachine::serviceStop Aug 24 14:11:43 volumio volumio[851]: info: CorePlayQueue::getTrack 8 Aug 24 14:11:43 volumio volumio[851]: info: CoreCommandRouter::serviceStop Aug 24 14:11:43 volumio volumio[851]: info: ControllerMpd::stop Aug 24 14:11:43 volumio volumio[851]: verbose: ControllerMpd::sendMpdCommand stop Aug 24 14:11:43 volumio volumio[851]: info: CorePlayQueue::clearPlayQueue Aug 24 14:11:43 volumio volumio[851]: info: CorePlayQueue::saveQueue Aug 24 14:11:43 volumio volumio[851]: info: CoreCommandRouter::volumioPushQueue Aug 24 14:11:43 volumio volumio[851]: info: CoreStateMachine::addQueueItems Aug 24 14:11:43 volumio volumio[851]: info: CorePlayQueue::addQueueItems Aug 24 14:11:43 volumio volumio[851]: info: Preload queue cleared Aug 24 14:11:43 volumio volumio[851]: info: Adding Item to queue: music-library/USB/B4D46C82D46C4926/Music/Al Bano & Romina Power - I Grandi Success (Box, 3CD)/I Grandi Successi CD1 Aug 24 14:11:43 volumio volumio[851]: info: Exploding uri music-library/USB/B4D46C82D46C4926/Music/Al Bano & Romina Power - I Grandi Success (Box, 3CD)/I Grandi Successi CD1 in service mpd Aug 24 14:11:43 volumio volumio[851]: info: ALBUMART /albumart?cacheid=778&web=//extralarge&path=%2Fmnt%2FUSB%2FB4D46C82D46C4926%2FMusic%2FAl%20Bano%20%26%20Romina%20Power%20-%20I%20Grandi%20Success%20(Box%2C%203CD)%2FI%20Grandi%20Successi%20CD1%2FAl%20Bano%20%26%20Romina%20Power%20-%20I%20Grandi%20Successi%20CD1.ape&metadata=false Aug 24 14:11:43 volumio volumio[851]: info: URI /mnt/USB/B4D46C82D46C4926/Music/Al Bano & Romina Power - I Grandi Success (Box, 3CD)/I Grandi Successi CD1/Al Bano & Romina Power - I Grandi Successi CD1.ape Aug 24 14:11:43 volumio volumio[851]: info: ALBUMART /albumart?cacheid=778&web=Al%20Bano%20%26%20Romina%20Power/I%20Grandi%20Successi%20CD1/extralarge&path=%2Fmnt%2FUSB%2FB4D46C82D46C4926%2FMusic%2FAl%20Bano%20%26%20Romina%20Power%20-%20I%20Grandi%20Success%20(Box%2C%203CD)%2FI%20Grandi%20Successi%20CD1%2FAl%20Bano%20%26%20Romina%20Power%20-%20I%20Grandi%20Successi%20CD1.ape.cue&metadata=false Aug 24 14:11:43 volumio volumio[851]: info: URI /mnt/USB/B4D46C82D46C4926/Music/Al Bano & Romina Power - I Grandi Success (Box, 3CD)/I Grandi Successi CD1/Al Bano & Romina Power - I Grandi Successi CD1.ape.cue Aug 24 14:11:43 volumio volumio[851]: info: ALBUMART /albumart?cacheid=778&web=Al%20Bano%20%26%20Romina%20Power/I%20Grandi%20Successi%20CD1/extralarge&path=%2Fmnt%2FUSB%2FB4D46C82D46C4926%2FMusic%2FAl%20Bano%20%26%20Romina%20Power%20-%20I%20Grandi%20Success%20(Box%2C%203CD)%2FI%20Grandi%20Successi%20CD1%2FAl%20Bano%20%26%20Romina%20Power%20-%20I%20Grandi%20Successi%20CD1.ape.cue&metadata=false Aug 24 14:11:43 volumio volumio[851]: info: URI /mnt/USB/B4D46C82D46C4926/Music/Al Bano & Romina Power - I Grandi Success (Box, 3CD)/I Grandi Successi CD1/Al Bano & Romina Power - I Grandi Successi CD1.ape.cue Aug 24 14:11:43 volumio volumio[851]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 14:11:43 volumio volumio[851]: Error: Unable to resolve or reject the same promise twice Aug 24 14:11:43 volumio volumio[851]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Aug 24 14:11:43 volumio volumio[851]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Aug 24 14:11:43 volumio volumio[851]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Aug 24 14:11:43 volumio volumio[851]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Aug 24 14:11:43 volumio volumio[851]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Aug 24 14:11:43 volumio volumio[851]: at Socket.emit (events.js:315:20) Aug 24 14:11:43 volumio volumio[851]: at addChunk (internal/streams/readable.js:309:12) Aug 24 14:11:43 volumio volumio[851]: at readableAddChunk (internal/streams/readable.js:280:11) Aug 24 14:11:43 volumio volumio[851]: at Socket.Readable.push (internal/streams/readable.js:223:10) Aug 24 14:11:43 volumio volumio[851]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Aug 24 14:11:43 volumio volumio[851]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 24 14:11:43 volumio sudo[5063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-24 14:10 Aug 24 14:11:43 volumio sudo[5063]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"