-- Logs begin at Thu 2025-06-12 12:03:21 CST, end at Thu 2025-06-12 12:12:17 CST. -- Jun 12 12:11:04 volumio-x86 kernel: usb 2-1.4.2: new high-speed USB device number 6 using ehci-pci Jun 12 12:11:04 volumio-x86 volumio[1116]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 12 12:11:04 volumio-x86 volumio[1116]: info: Retrying Login Due to Network Error Jun 12 12:11:04 volumio-x86 kernel: usb 2-1.4.2: New USB device found, idVendor=152a, idProduct=887f, bcdDevice= 3.07 Jun 12 12:11:04 volumio-x86 kernel: usb 2-1.4.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jun 12 12:11:04 volumio-x86 kernel: usb 2-1.4.2: Product: ShiKuang USB Audio Jun 12 12:11:04 volumio-x86 kernel: usb 2-1.4.2: Manufacturer: XMOS Jun 12 12:11:04 volumio-x86 kernel: usb 2-1.4.2: 1:3 : unsupported format bits 0x100000000 Jun 12 12:11:04 volumio-x86 kernel: usbcore: registered new interface driver snd-usb-audio Jun 12 12:11:04 volumio-x86 volumio[1116]: info: Jun 12 12:11:04 volumio-x86 volumio[1116]: ---------------------------- USB Audio Device Attached Jun 12 12:11:04 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 12 12:11:04 volumio-x86 systemd-udevd[4482]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Jun 12 12:11:14 volumio-x86 volumio[1116]: info: MyVolumio login type: Token Jun 12 12:11:16 volumio-x86 sudo[4384]: pam_unix(sudo:session): session closed for user root Jun 12 12:11:26 volumio-x86 volumio[1116]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 Jun 12 12:11:26 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioGetState Jun 12 12:11:26 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:33 volumio-x86 volumio[1116]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 12:11:33 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 12 12:11:33 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 12:11:33 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 12 12:11:33 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 12:11:33 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 12:11:33 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 12:11:33 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 12:11:33 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 12:11:33 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 12:11:33 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 12:11:33 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 12 12:11:33 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 12 12:11:37 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioGetState Jun 12 12:11:37 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:37 volumio-x86 sudo[4618]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jun 12 12:11:37 volumio-x86 sudo[4618]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 12:11:39 volumio-x86 volumio[1116]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Jun 12 12:11:39 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioGetState Jun 12 12:11:39 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:39 volumio-x86 volumio[1116]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Jun 12 12:11:39 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioGetState Jun 12 12:11:39 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:44 volumio-x86 volumio[1116]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 12 12:11:44 volumio-x86 volumio[1116]: info: Retrying Login Due to Network Error Jun 12 12:11:46 volumio-x86 sudo[4618]: pam_unix(sudo:session): session closed for user root Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Preparing to save Alsa Options, stopping services first Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioGetState Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPause Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::pause Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::stPlaybackTimer Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::servicePause Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::servicePause Jun 12 12:11:49 volumio-x86 volumio[1116]: info: ControllerMpd::pause Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand pause Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"ShiKuang USB Audio"}} Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Device 5 does not have any Mixer Control Available Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Updating Volume Controller Parameters: Device: 5 Name: ShiKuang USB Audio Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Disabling external Volume Control Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Preparing to generate the ALSA configuration file Jun 12 12:11:49 volumio-x86 volumio[1116]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Reading ALSA contributions from plugins. Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Jun 12 12:11:49 volumio-x86 volumio[1116]: ---------------------------- MPD announces state update: player Jun 12 12:11:49 volumio-x86 volumio[1116]: info: sendMpdCommand pause took 170 milliseconds Jun 12 12:11:49 volumio-x86 volumio[1116]: info: ControllerMpd::getState Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Jun 12 12:11:49 volumio-x86 volumio[1116]: ---------------------------- MPD announces state update: player Jun 12 12:11:49 volumio-x86 volumio[1116]: info: ControllerMpd::getState Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Jun 12 12:11:49 volumio-x86 volumio[1116]: ---------------------------- MPD announces state update: player Jun 12 12:11:49 volumio-x86 volumio[1116]: info: ControllerMpd::getState Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Jun 12 12:11:49 volumio-x86 volumio[1116]: info: sendMpdCommand status took 7 milliseconds Jun 12 12:11:49 volumio-x86 volumio[1116]: info: sendMpdCommand status took 6 milliseconds Jun 12 12:11:49 volumio-x86 volumio[1116]: info: sendMpdCommand status took 5 milliseconds Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseState Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseState Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseState Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 12:11:49 volumio-x86 volumio[1116]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 12 12:11:49 volumio-x86 volumio[1116]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 12 12:11:49 volumio-x86 volumio[1116]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseTrackInfo Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseTrackInfo Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseTrackInfo Jun 12 12:11:49 volumio-x86 volumio[1116]: info: ControllerMpd::pushState Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::servicePushState Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":85183,"duration":259,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. 01 爱你一世到来生.wav","artist":null,"album":null,"uri":"INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/01. 01 爱你一世到来生.wav","trackType":"wav"} Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: CURRENT POSITION 0 Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState stateService pause Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState currentStatus pause Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::stPlaybackTimer Jun 12 12:11:49 volumio-x86 volumio[1116]: info: ControllerMpd::pushState Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::servicePushState Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":85183,"duration":259,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. 01 爱你一世到来生.wav","artist":null,"album":null,"uri":"INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/01. 01 爱你一世到来生.wav","trackType":"wav"} Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: CURRENT POSITION 0 Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState stateService pause Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState currentStatus pause Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::stPlaybackTimer Jun 12 12:11:49 volumio-x86 volumio[1116]: info: ControllerMpd::pushState Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::servicePushState Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":85183,"duration":259,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. 01 爱你一世到来生.wav","artist":null,"album":null,"uri":"INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/01. 01 爱你一世到来生.wav","trackType":"wav"} Jun 12 12:11:49 volumio-x86 volumio[1116]: verbose: CURRENT POSITION 0 Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState stateService pause Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState currentStatus pause Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreStateMachine::stPlaybackTimer Jun 12 12:11:49 volumio-x86 volumio[1116]: info: ------------------------------ 35ms Jun 12 12:11:49 volumio-x86 volumio[1116]: info: ------------------------------ 34ms Jun 12 12:11:49 volumio-x86 volumio[1116]: info: ------------------------------ 34ms Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Asound.conf file written Jun 12 12:11:49 volumio-x86 sudo[4706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 12 12:11:49 volumio-x86 sudo[4706]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 12:11:49 volumio-x86 sudo[4706]: pam_unix(sudo:session): session closed for user root Jun 12 12:11:49 volumio-x86 volumio[1116]: Found hardware: "HDA-Intel" "ATI R6xx HDMI" "HDA:1002aa01,00aa0100,00100700" "0x103c" "0x8267" Jun 12 12:11:49 volumio-x86 volumio[1116]: Hardware is initialized using a generic method Jun 12 12:11:49 volumio-x86 volumio[1116]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #13 (No such file or directory) Jun 12 12:11:49 volumio-x86 volumio[1116]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #14 (No such file or directory) Jun 12 12:11:49 volumio-x86 volumio[1116]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #15 (No such file or directory) Jun 12 12:11:49 volumio-x86 volumio[1116]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #16 (No such file or directory) Jun 12 12:11:49 volumio-x86 volumio[1116]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #17 (No such file or directory) Jun 12 12:11:49 volumio-x86 volumio[1116]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #18 (No such file or directory) Jun 12 12:11:49 volumio-x86 volumio[1116]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #21 (No such file or directory) Jun 12 12:11:49 volumio-x86 volumio[1116]: Found hardware: "HDA-Intel" "Realtek ALC256" "HDA:10ec0256,103c8267,00100002" "0x103c" "0x8267" Jun 12 12:11:49 volumio-x86 volumio[1116]: Hardware is initialized using a generic method Jun 12 12:11:49 volumio-x86 volumio[1116]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #1 (No such file or directory) Jun 12 12:11:49 volumio-x86 volumio[1116]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #2 (No such file or directory) Jun 12 12:11:49 volumio-x86 volumio[1116]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #3 (No such file or directory) Jun 12 12:11:49 volumio-x86 volumio[1116]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #4 (No such file or directory) Jun 12 12:11:49 volumio-x86 volumio[1116]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #5 (No such file or directory) Jun 12 12:11:49 volumio-x86 volumio[1116]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #6 (No such file or directory) Jun 12 12:11:49 volumio-x86 volumio[1116]: /usr/sbin/alsactl: set_control:1325: failed to obtain info for control #7 (No such file or directory) Jun 12 12:11:49 volumio-x86 volumio[1116]: No state is present for card Audio Jun 12 12:11:49 volumio-x86 volumio[1116]: Found hardware: "USB-Audio" "USB Mixer" "USB152a:887f" "" "" Jun 12 12:11:49 volumio-x86 volumio[1116]: Hardware is initialized using a generic method Jun 12 12:11:49 volumio-x86 volumio[1116]: No state is present for card Audio Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Output device has changed, restarting MPD Jun 12 12:11:49 volumio-x86 sudo[4711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 12:11:49 volumio-x86 sudo[4711]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Output device has changed, restarting Shairport Sync Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 12:11:49 volumio-x86 sudo[4711]: pam_unix(sudo:session): session closed for user root Jun 12 12:11:49 volumio-x86 sudo[4714]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 12:11:49 volumio-x86 sudo[4714]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 12:11:49 volumio-x86 systemd[1]: Stopping Music Player Daemon... Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 12 12:11:49 volumio-x86 systemd[1]: mpd.service: Succeeded. Jun 12 12:11:49 volumio-x86 systemd[1]: Stopped Music Player Daemon. Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Output device has changed, restarting MPD Jun 12 12:11:49 volumio-x86 systemd[1]: Starting Music Player Daemon... Jun 12 12:11:49 volumio-x86 sudo[4721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 12 12:11:49 volumio-x86 sudo[4721]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 12:11:49 volumio-x86 sudo[4721]: pam_unix(sudo:session): session closed for user root Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Output device has changed, restarting Shairport Sync Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 12:11:49 volumio-x86 sudo[4724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 12 12:11:49 volumio-x86 sudo[4724]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 12:11:49 volumio-x86 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 12 12:11:49 volumio-x86 systemd[1]: mpd.service: Succeeded. Jun 12 12:11:49 volumio-x86 systemd[1]: Stopped Music Player Daemon. Jun 12 12:11:49 volumio-x86 systemd[1]: Starting Music Player Daemon... Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 12 12:11:49 volumio-x86 volumio[1116]: info: MPD Permissions set Jun 12 12:11:49 volumio-x86 volumio[1116]: info: MPD Permissions set Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 sudo[4729]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 12 12:11:49 volumio-x86 sudo[4729]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 12:11:49 volumio-x86 sudo[4729]: pam_unix(sudo:session): session closed for user root Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 12 12:11:49 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Starting Shairport Sync Jun 12 12:11:49 volumio-x86 volumio[1116]: info: Starting Shairport Sync Jun 12 12:11:50 volumio-x86 sudo[4747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 12 12:11:50 volumio-x86 sudo[4747]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 12:11:50 volumio-x86 sudo[4745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 12 12:11:50 volumio-x86 sudo[4745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 12 12:11:50 volumio-x86 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 12 12:11:50 volumio-x86 systemd[1]: shairport-sync.service: Succeeded. Jun 12 12:11:50 volumio-x86 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 12 12:11:50 volumio-x86 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 12 12:11:50 volumio-x86 sudo[4747]: pam_unix(sudo:session): session closed for user root Jun 12 12:11:50 volumio-x86 sudo[4745]: pam_unix(sudo:session): session closed for user root Jun 12 12:11:50 volumio-x86 volumio[1116]: info: Shairport-Sync Started Jun 12 12:11:50 volumio-x86 volumio[1116]: info: Shairport-Sync Started Jun 12 12:11:50 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:50 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:50 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 12 12:11:50 volumio-x86 mpd[4739]: Jun 12 12:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 12 12:11:50 volumio-x86 systemd[1]: Started Music Player Daemon. Jun 12 12:11:50 volumio-x86 sudo[4714]: pam_unix(sudo:session): session closed for user root Jun 12 12:11:50 volumio-x86 sudo[4724]: pam_unix(sudo:session): session closed for user root Jun 12 12:11:50 volumio-x86 volumio[1116]: error: updateQueue error: null Jun 12 12:11:50 volumio-x86 volumio[1116]: error: updateQueue error: null Jun 12 12:11:53 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 12 12:11:53 volumio-x86 volumio[1116]: info: CoreStateMachine::getcurrentVolume Jun 12 12:11:53 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioRetrievevolume Jun 12 12:11:53 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:11:53 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:53 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:11:54 volumio-x86 volumio[1116]: info: MyVolumio login type: Token Jun 12 12:11:55 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPlay Jun 12 12:11:55 volumio-x86 volumio[1116]: info: CoreStateMachine::play index undefined Jun 12 12:11:55 volumio-x86 volumio[1116]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 12:11:55 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:55 volumio-x86 volumio[1116]: info: CoreStateMachine::startPlaybackTimer Jun 12 12:11:55 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:55 volumio-x86 volumio[1116]: info: ControllerMpd::resume Jun 12 12:11:55 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand play Jun 12 12:11:55 volumio-x86 volumio[1116]: info: sendMpdCommand play took 0 milliseconds Jun 12 12:11:56 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPlay Jun 12 12:11:56 volumio-x86 volumio[1116]: info: CoreStateMachine::play index undefined Jun 12 12:11:56 volumio-x86 volumio[1116]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 12:11:56 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:56 volumio-x86 volumio[1116]: info: CoreStateMachine::startPlaybackTimer Jun 12 12:11:56 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:56 volumio-x86 volumio[1116]: info: ControllerMpd::resume Jun 12 12:11:56 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand play Jun 12 12:11:56 volumio-x86 volumio[1116]: info: sendMpdCommand play took 1 milliseconds Jun 12 12:11:57 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPlay Jun 12 12:11:57 volumio-x86 volumio[1116]: info: CoreStateMachine::play index undefined Jun 12 12:11:57 volumio-x86 volumio[1116]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 12:11:57 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:57 volumio-x86 volumio[1116]: info: CoreStateMachine::startPlaybackTimer Jun 12 12:11:57 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:11:57 volumio-x86 volumio[1116]: info: ControllerMpd::resume Jun 12 12:11:57 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand play Jun 12 12:11:57 volumio-x86 volumio[1116]: info: sendMpdCommand play took 5 milliseconds Jun 12 12:12:04 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPlay Jun 12 12:12:04 volumio-x86 volumio[1116]: info: CoreStateMachine::play index undefined Jun 12 12:12:04 volumio-x86 volumio[1116]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 12:12:04 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:12:04 volumio-x86 volumio[1116]: info: CoreStateMachine::startPlaybackTimer Jun 12 12:12:04 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:12:04 volumio-x86 volumio[1116]: info: ControllerMpd::resume Jun 12 12:12:04 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand play Jun 12 12:12:04 volumio-x86 volumio[1116]: info: sendMpdCommand play took 1 milliseconds Jun 12 12:12:07 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioGetState Jun 12 12:12:07 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:12:07 volumio-x86 screensaver.sh[760]: 4525 Jun 12 12:12:07 volumio-x86 volumio[1116]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Jun 12 12:12:07 volumio-x86 volumio[1116]: No protocol specified Jun 12 12:12:07 volumio-x86 volumio[1116]: Exception in thread Thread-4: Jun 12 12:12:07 volumio-x86 volumio[1116]: Traceback (most recent call last): Jun 12 12:12:07 volumio-x86 volumio[1116]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Jun 12 12:12:07 volumio-x86 volumio[1116]: self.run() Jun 12 12:12:07 volumio-x86 volumio[1116]: File "/usr/lib/python3.7/threading.py", line 865, in run Jun 12 12:12:07 volumio-x86 volumio[1116]: self._target(*self._args, **self._kwargs) Jun 12 12:12:07 volumio-x86 volumio[1116]: File "./screensaver/volumio_peppymeter.py", line 113, in meter_thread Jun 12 12:12:07 volumio-x86 volumio[1116]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/peppymeter.py", line 145, in init_display Jun 12 12:12:07 volumio-x86 volumio[1116]: pygame.display.init() Jun 12 12:12:07 volumio-x86 volumio[1116]: pygame.error: Unable to open a console terminal Jun 12 12:12:07 volumio-x86 volumio[1116]: Terminated Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Preload queue cleared Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::ClearQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::stop Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::updateTrackBlock Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrackBlock Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::stPlaybackTimer Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::serviceStop Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 0 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::serviceStop Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::stop Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand stop Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::clearPlayQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::saveQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::addQueueItems Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::addQueueItems Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Preload queue cleared Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/01. 01 爱你一世到来生.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/01. 01 爱你一世到来生.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/02. 02 演员.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/02. 02 演员.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::saveQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::updateTrackBlock Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrackBlock Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPlay Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::play index 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::addQueueItems Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::addQueueItems Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Preload queue cleared Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/04. 04 你是如此难以忘记.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/04. 04 你是如此难以忘记.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/05. 05 唱一遍一遍.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/05. 05 唱一遍一遍.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/06. 06 一次就好.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/06. 06 一次就好.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/07. 07 我是不是该安静的走开.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/07. 07 我是不是该安静的走开.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/08. 08 晚秋.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/08. 08 晚秋.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/09. 09 情人的眼泪.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/09. 09 情人的眼泪.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/10. 10 都是夜归人.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/10. 10 都是夜归人.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/11. 11 驿动的心.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/11. 11 驿动的心.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/12. 12 多情.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/12. 12 多情.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/13. 13 一生痴恋.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/13. 13 一生痴恋.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::stop Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::saveQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::play index undefined Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::updateTrackBlock Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrackBlock Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::startPlaybackTimer Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand stop Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand stop took 49 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand stop took 7 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand clear Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces system playlist update Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Ignoring MPD Status Update Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand clear took 4 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav" Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces system playlist update Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Ignoring MPD Status Update Jun 12 12:12:13 volumio-x86 volumio[1116]: error: updateQueue error: null Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces system playlist update Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Ignoring MPD Status Update Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ------------------------------ 10ms Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand add "INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav" took 8 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ------------------------------ 8ms Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand play Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces system playlist update Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Ignoring MPD Status Update Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ------------------------------ 7ms Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand play took 3 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ------------------------------ 3ms Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces state update: player Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::getState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces state update: player Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::getState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand status took 27 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces state update: player Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::getState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand status took 39 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces state update: player Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::getState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand status took 7 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand status took 7 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseTrackInfo Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseTrackInfo Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::servicePushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":292,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03. 03 等你等到我心痛.wav","artist":null,"album":null,"uri":"INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav","trackType":"wav"} Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: CURRENT POSITION 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState stateService play Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState currentStatus stop Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::servicePushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":292,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03. 03 等你等到我心痛.wav","artist":null,"album":null,"uri":"INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav","trackType":"wav"} Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: CURRENT POSITION 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState stateService play Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState currentStatus play Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Received an update from plugin. extracting info from payload Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ------------------------------ 56ms Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ------------------------------ 62ms Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseTrackInfo Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::servicePushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26,"duration":292,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03. 03 等你等到我心痛.wav","artist":null,"album":null,"uri":"INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav","trackType":"wav"} Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: CURRENT POSITION 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState stateService play Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState currentStatus play Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Received an update from plugin. extracting info from payload Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::servicePushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47,"duration":292,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03. 03 等你等到我心痛.wav","artist":null,"album":null,"uri":"INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav","trackType":"wav"} Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: CURRENT POSITION 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState stateService play Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState currentStatus play Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Received an update from plugin. extracting info from payload Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ------------------------------ 59ms Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ------------------------------ 57ms Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Preload queue cleared Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::ClearQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::stop Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::stPlaybackTimer Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::updateTrackBlock Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrackBlock Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::serviceStop Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::serviceStop Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::stop Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand stop Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::clearPlayQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::saveQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::addQueueItems Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::addQueueItems Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Preload queue cleared Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/01. 01 爱你一世到来生.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/01. 01 爱你一世到来生.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/02. 02 演员.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/02. 02 演员.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::saveQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::updateTrackBlock Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrackBlock Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPlay Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::play index 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::addQueueItems Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::addQueueItems Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Preload queue cleared Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/04. 04 你是如此难以忘记.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/04. 04 你是如此难以忘记.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/05. 05 唱一遍一遍.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/05. 05 唱一遍一遍.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/06. 06 一次就好.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/06. 06 一次就好.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/07. 07 我是不是该安静的走开.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/07. 07 我是不是该安静的走开.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/08. 08 晚秋.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/08. 08 晚秋.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/09. 09 情人的眼泪.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/09. 09 情人的眼泪.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/10. 10 都是夜归人.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/10. 10 都是夜归人.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/11. 11 驿动的心.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/11. 11 驿动的心.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/12. 12 多情.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/12. 12 多情.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Adding Item to queue: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/13. 13 一生痴恋.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Using cached record of: music-library/INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/13. 13 一生痴恋.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::stop Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::saveQueue Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::play index undefined Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::updateTrackBlock Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrackBlock Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::startPlaybackTimer Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand stop Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces state update: player Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand stop took 41 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::getState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand stop took 4 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand clear Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces state update: player Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::getState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces system playlist update Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Ignoring MPD Status Update Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand status took 5 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand clear took 4 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand status took 1 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav" Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::servicePushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: CURRENT POSITION 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState stateService stop Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState currentStatus stop Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: No code Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ------------------------------ 20ms Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces system playlist update Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Ignoring MPD Status Update Jun 12 12:12:13 volumio-x86 volumio[1116]: error: updateQueue error: null Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces system playlist update Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Ignoring MPD Status Update Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ------------------------------ 25ms Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand playlistinfo took 24 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand add "INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav" took 24 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ------------------------------ 7ms Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseTrackInfo Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand play Jun 12 12:12:13 volumio-x86 volumio[1116]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ------------------------------ 35ms Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces system playlist update Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Ignoring MPD Status Update Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces state update: player Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ------------------------------ 25ms Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand play took 21 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ------------------------------ 20ms Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::getState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces state update: player Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::getState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces state update: player Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::getState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand status took 4 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand status took 2 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 12:12:13 volumio-x86 volumio[1116]: info: Jun 12 12:12:13 volumio-x86 volumio[1116]: ---------------------------- MPD announces state update: player Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::getState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand status Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand status took 3 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand status took 6 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseTrackInfo Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseTrackInfo Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseState Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseTrackInfo Jun 12 12:12:13 volumio-x86 volumio[1116]: info: ControllerMpd::pushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreCommandRouter::servicePushState Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":10,"duration":292,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03. 03 等你等到我心痛.wav","artist":null,"album":null,"uri":"INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav","trackType":"wav"} Jun 12 12:12:13 volumio-x86 volumio[1116]: verbose: CURRENT POSITION 2 Jun 12 12:12:13 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState stateService play Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState currentStatus stop Jun 12 12:12:14 volumio-x86 volumio[1116]: info: ControllerMpd::pushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreCommandRouter::servicePushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:14 volumio-x86 volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":15,"duration":292,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03. 03 等你等到我心痛.wav","artist":null,"album":null,"uri":"INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav","trackType":"wav"} Jun 12 12:12:14 volumio-x86 volumio[1116]: verbose: CURRENT POSITION 2 Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState stateService play Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState currentStatus play Jun 12 12:12:14 volumio-x86 volumio[1116]: info: Received an update from plugin. extracting info from payload Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: ControllerMpd::pushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreCommandRouter::servicePushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:14 volumio-x86 volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26,"duration":292,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03. 03 等你等到我心痛.wav","artist":null,"album":null,"uri":"INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav","trackType":"wav"} Jun 12 12:12:14 volumio-x86 volumio[1116]: verbose: CURRENT POSITION 2 Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState stateService play Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState currentStatus play Jun 12 12:12:14 volumio-x86 volumio[1116]: info: Received an update from plugin. extracting info from payload Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: ------------------------------ 31ms Jun 12 12:12:14 volumio-x86 volumio[1116]: info: ------------------------------ 32ms Jun 12 12:12:14 volumio-x86 volumio[1116]: info: ------------------------------ 32ms Jun 12 12:12:14 volumio-x86 volumio[1116]: info: sendMpdCommand playlistinfo took 39 milliseconds Jun 12 12:12:14 volumio-x86 volumio[1116]: verbose: ControllerMpd::parseTrackInfo Jun 12 12:12:14 volumio-x86 volumio[1116]: info: ControllerMpd::pushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreCommandRouter::servicePushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:14 volumio-x86 volumio[1116]: verbose: STATE SERVICE {"status":"play","position":0,"seek":36,"duration":292,"samplerate":"96 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03. 03 等你等到我心痛.wav","artist":null,"album":null,"uri":"INTERNAL/孙露 《情人的眼泪》DSD[WAV] 24bit 96khz/03. 03 等你等到我心痛.wav","trackType":"wav"} Jun 12 12:12:14 volumio-x86 volumio[1116]: verbose: CURRENT POSITION 2 Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState stateService play Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreStateMachine::syncState currentStatus play Jun 12 12:12:14 volumio-x86 volumio[1116]: info: Received an update from plugin. extracting info from payload Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreStateMachine::pushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CorePlayQueue::getTrack 2 Jun 12 12:12:14 volumio-x86 volumio[1116]: info: CoreCommandRouter::volumioPushState Jun 12 12:12:14 volumio-x86 volumio[1116]: info: ------------------------------ 72ms Jun 12 12:12:17 volumio-x86 volumio[1116]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 12:12:17 volumio-x86 volumio[1116]: Error: connect ETIMEDOUT 69.63.186.31:443 Jun 12 12:12:17 volumio-x86 volumio[1116]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 12 12:12:17 volumio-x86 volumio[1116]: errno: -110, Jun 12 12:12:17 volumio-x86 volumio[1116]: code: 'ETIMEDOUT', Jun 12 12:12:17 volumio-x86 volumio[1116]: syscall: 'connect', Jun 12 12:12:17 volumio-x86 volumio[1116]: address: '69.63.186.31', Jun 12 12:12:17 volumio-x86 volumio[1116]: port: 443 Jun 12 12:12:17 volumio-x86 volumio[1116]: } Jun 12 12:12:17 volumio-x86 volumio[1116]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 12 12:12:17 volumio-x86 sudo[4816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-12 12:11 Jun 12 12:12:17 volumio-x86 sudo[4816]: 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"