-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Tue 2025-07-01 07:54:14 UTC. -- Jul 01 07:53:32 volumio volumio[899]: info: Jul 01 07:53:32 volumio volumio[899]: ---------------------------- MPD announces state update: player Jul 01 07:53:32 volumio volumio[899]: info: ControllerMpd::getState Jul 01 07:53:32 volumio volumio[899]: verbose: ControllerMpd::sendMpdCommand status Jul 01 07:53:32 volumio kernel: usb 1-1.1: USB disconnect, device number 11 Jul 01 07:53:32 volumio kernel: usb 1-1.1: cannot submit urb (err = -19) Jul 01 07:53:32 volumio volumio[899]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 01 07:53:32 volumio volumio[899]: info: sendMpdCommand status took 4 milliseconds Jul 01 07:53:32 volumio volumio[899]: verbose: ControllerMpd::parseState Jul 01 07:53:32 volumio volumio[899]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 01 07:53:32 volumio volumio[899]: info: sendMpdCommand clearerror took 2 milliseconds Jul 01 07:53:32 volumio volumio[899]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 01 07:53:32 volumio volumio[899]: verbose: ControllerMpd::parseTrackInfo Jul 01 07:53:32 volumio volumio[899]: info: ControllerMpd::pushState Jul 01 07:53:32 volumio volumio[899]: info: CoreCommandRouter::servicePushState Jul 01 07:53:32 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Jul 01 07:53:32 volumio volumio[899]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":78413,"duration":266,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":7,"random":false,"updatedb":false,"repeat":false,"bitrate":"2478 Kbps","isStreaming":false,"title":"天下秦风.flac","artist":null,"album":null,"uri":"NAS/music/天下秦风.flac","trackType":"flac"} Jul 01 07:53:32 volumio volumio[899]: verbose: CURRENT POSITION 0 Jul 01 07:53:32 volumio volumio[899]: info: CoreStateMachine::syncState stateService pause Jul 01 07:53:32 volumio volumio[899]: info: CoreStateMachine::syncState currentStatus play Jul 01 07:53:32 volumio volumio[899]: info: CoreStateMachine::pushState Jul 01 07:53:32 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Jul 01 07:53:32 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 07:53:32 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Jul 01 07:53:32 volumio volumio[899]: info: ------------------------------ 12ms Jul 01 07:53:32 volumio volumio[899]: info: Jul 01 07:53:32 volumio volumio[899]: ---------------------------- USB Audio Device Detached Jul 01 07:53:32 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jul 01 07:53:32 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jul 01 07:53:33 volumio kernel: usb 1-1.1: new high-speed USB device number 12 using xhci_hcd Jul 01 07:53:33 volumio kernel: usb 1-1.1: New USB device found, idVendor=20b1, idProduct=3086, bcdDevice= 3.10 Jul 01 07:53:33 volumio kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jul 01 07:53:33 volumio kernel: usb 1-1.1: Product: Singxer USB Audio 2.0 G2 Jul 01 07:53:33 volumio kernel: usb 1-1.1: Manufacturer: Singxer Jul 01 07:53:33 volumio kernel: usb 1-1.1: 1:2 : unsupported format bits 0x100000000 Jul 01 07:53:33 volumio kernel: usb 1-1.1: Warning! Unlikely big volume range (=512), cval->res is probably wrong. Jul 01 07:53:33 volumio kernel: usb 1-1.1: [10] FU [PCM Playback Volume] ch = 2, val = -16384/0/32 Jul 01 07:53:33 volumio systemd-udevd[1858]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Jul 01 07:53:33 volumio volumio[899]: info: Jul 01 07:53:33 volumio volumio[899]: ---------------------------- USB Audio Device Attached Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::Close All Modals sent Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 01 07:53:33 volumio volumio[899]: info: Setting mixer PCM for card Singxer USB Audio 2.0 G2 Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 01 07:53:33 volumio volumio[899]: info: Updating Volume Controller Parameters: Device: 5 Name: Singxer USB Audio 2.0 G2 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 01 07:53:33 volumio volumio[899]: info: Preparing to generate the ALSA configuration file Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 01 07:53:33 volumio volumio[899]: info: Asound.conf file unchanged, so no further update is needed Jul 01 07:53:33 volumio volumio[899]: info: Output device has changed, restarting MPD Jul 01 07:53:33 volumio sudo[1883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 01 07:53:33 volumio sudo[1883]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 07:53:33 volumio volumio[899]: info: Output device has changed, restarting Shairport Sync Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 07:53:33 volumio sudo[1883]: pam_unix(sudo:session): session closed for user root Jul 01 07:53:33 volumio sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 01 07:53:33 volumio sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 07:53:33 volumio systemd[1]: Stopping Music Player Daemon... Jul 01 07:53:33 volumio volumio[899]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 01 07:53:33 volumio volumio[899]: info: MPD Permissions set Jul 01 07:53:33 volumio volumio[899]: info: VolumeController:: Volume=41 Mute =false Jul 01 07:53:33 volumio volumio[899]: info: CoreStateMachine::pushState Jul 01 07:53:33 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 07:53:33 volumio systemd[1]: mpd.service: Succeeded. Jul 01 07:53:33 volumio systemd[1]: Stopped Music Player Daemon. Jul 01 07:53:33 volumio systemd[1]: Starting Music Player Daemon... Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 01 07:53:33 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 01 07:53:33 volumio volumio[899]: info: Starting Shairport Sync Jul 01 07:53:33 volumio sudo[1897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 01 07:53:33 volumio sudo[1897]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 01 07:53:33 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 01 07:53:33 volumio systemd[1]: shairport-sync.service: Succeeded. Jul 01 07:53:33 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 01 07:53:33 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 01 07:53:33 volumio sudo[1897]: pam_unix(sudo:session): session closed for user root Jul 01 07:53:33 volumio volumio[899]: info: Shairport-Sync Started Jul 01 07:53:34 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jul 01 07:53:34 volumio mpd[1903]: Jul 01 07:53 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 01 07:53:34 volumio mpd[1903]: Jul 01 07:53 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 01 07:53:34 volumio mpd[1903]: Jul 01 07:53 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 01 07:53:34 volumio systemd[1]: Started Music Player Daemon. Jul 01 07:53:34 volumio sudo[1886]: pam_unix(sudo:session): session closed for user root Jul 01 07:53:34 volumio volumio[899]: error: updateQueue error: null Jul 01 07:53:36 volumio volumio[899]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 01 07:53:36 volumio volumio[899]: info: CoreStateMachine::getcurrentVolume Jul 01 07:53:36 volumio volumio[899]: info: CoreCommandRouter::volumioRetrievevolume Jul 01 07:53:36 volumio volumio[899]: info: VolumeController:: Volume=41 Mute =false Jul 01 07:53:36 volumio volumio[899]: info: CoreStateMachine::pushState Jul 01 07:53:36 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Jul 01 07:53:36 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 01 07:53:36 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Jul 01 07:53:36 volumio volumio[899]: info: CoreStateMachine::updateTrackBlock Jul 01 07:53:36 volumio volumio[899]: info: CorePlayQueue::getTrackBlock Jul 01 07:53:36 volumio volumio[899]: info: CoreCommandRouter::volumioRetrievevolume Jul 01 07:53:36 volumio volumio[899]: info: VolumeController:: Volume=41 Mute =false Jul 01 07:53:36 volumio volumio[899]: info: CoreStateMachine::pushState Jul 01 07:53:36 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Jul 01 07:53:36 volumio volumio[899]: info: CoreCommandRouter::volumioPushState Jul 01 07:53:39 volumio kernel: usb 1-1.1: USB disconnect, device number 12 Jul 01 07:53:39 volumio volumio[899]: info: Jul 01 07:53:39 volumio volumio[899]: ---------------------------- USB Audio Device Detached Jul 01 07:53:39 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jul 01 07:53:39 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 01 07:53:39 volumio volumio[899]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jul 01 07:53:40 volumio kernel: usb 1-1-port1: Cannot enable. Maybe the USB cable is bad? Jul 01 07:53:41 volumio kernel: usb 1-1-port1: Cannot enable. Maybe the USB cable is bad? Jul 01 07:53:41 volumio kernel: usb 1-1-port1: attempt power cycle Jul 01 07:53:42 volumio kernel: usb 1-1-port1: Cannot enable. Maybe the USB cable is bad? Jul 01 07:53:43 volumio kernel: usb 1-1-port1: Cannot enable. Maybe the USB cable is bad? Jul 01 07:53:43 volumio kernel: usb 1-1-port1: unable to enumerate USB device Jul 01 07:53:47 volumio dhcpcd[829]: eth0: carrier lost Jul 01 07:53:47 volumio dhcpcd[718]: eth0: carrier lost Jul 01 07:53:47 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Down Jul 01 07:53:47 volumio avahi-daemon[478]: Withdrawing address record for 192.168.110.122 on eth0. Jul 01 07:53:47 volumio avahi-daemon[478]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.110.122. Jul 01 07:53:47 volumio avahi-daemon[478]: Interface eth0.IPv4 no longer relevant for mDNS. Jul 01 07:53:47 volumio dhcpcd[718]: eth0: deleting route to 192.168.110.0/24 Jul 01 07:53:47 volumio dhcpcd[718]: eth0: deleting default route via 192.168.110.1 Jul 01 07:53:47 volumio volumio[899]: info: mDNS: A device disapperared from network Jul 01 07:53:47 volumio volumio[899]: info: mDNS: Device volumio disapperared from network Jul 01 07:53:47 volumio dhcpcd[829]: eth0: deleting route to 192.168.110.0/24 Jul 01 07:53:47 volumio dhcpcd[829]: eth0: deleting default route via 192.168.110.1 Jul 01 07:53:47 volumio ifplugd(eth0)[623]: Link beat lost. Jul 01 07:53:48 volumio ntpd[631]: Deleting interface #3 eth0, 192.168.110.122#123, interface stats: received=144, sent=163, dropped=0, active_time=694 secs Jul 01 07:53:48 volumio ntpd[631]: 108.59.2.24 local addr 192.168.110.122 -> Jul 01 07:53:48 volumio ntpd[631]: 119.28.183.184 local addr 192.168.110.122 -> Jul 01 07:53:48 volumio ntpd[631]: 162.159.200.1 local addr 192.168.110.122 -> Jul 01 07:53:48 volumio ntpd[631]: 84.16.73.33 local addr 192.168.110.122 -> Jul 01 07:53:48 volumio ntpd[631]: 202.118.1.130 local addr 192.168.110.122 -> Jul 01 07:53:48 volumio ntpd[631]: 162.159.200.123 local addr 192.168.110.122 -> Jul 01 07:53:48 volumio ntpd[631]: 193.182.111.14 local addr 192.168.110.122 -> Jul 01 07:53:48 volumio ntpd[631]: 5.79.108.34 local addr 192.168.110.122 -> Jul 01 07:53:48 volumio ntpd[631]: 185.209.85.222 local addr 192.168.110.122 -> Jul 01 07:53:49 volumio dhcpcd[829]: eth0: carrier acquired Jul 01 07:53:49 volumio dhcpcd[718]: eth0: carrier acquired Jul 01 07:53:49 volumio dhcpcd[829]: eth0: IAID 32:88:07:02 Jul 01 07:53:49 volumio dhcpcd[718]: eth0: IAID 32:88:07:02 Jul 01 07:53:49 volumio kernel: bcmgenet fd580000.ethernet eth0: Link is Up - 100Mbps/Full - flow control rx/tx Jul 01 07:53:49 volumio dhcpcd[829]: eth0: soliciting an IPv6 router Jul 01 07:53:49 volumio dhcpcd[718]: eth0: soliciting a DHCP lease Jul 01 07:53:49 volumio dhcpcd[718]: eth0: offered 192.168.110.122 from 192.168.110.1 Jul 01 07:53:49 volumio dhcpcd[718]: eth0: probing address 192.168.110.122/24 Jul 01 07:53:49 volumio ifplugd(eth0)[623]: Link beat detected. Jul 01 07:53:49 volumio dhcpcd[829]: eth0: soliciting a DHCP lease Jul 01 07:53:49 volumio dhcpcd[718]: eth0: soliciting an IPv6 router Jul 01 07:53:49 volumio dhcpcd[829]: eth0: offered 192.168.110.122 from 192.168.110.1 Jul 01 07:53:49 volumio dhcpcd[829]: eth0: probing address 192.168.110.122/24 Jul 01 07:53:53 volumio dhcpcd[718]: eth0: leased 192.168.110.122 for 1800 seconds Jul 01 07:53:53 volumio avahi-daemon[478]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.110.122. Jul 01 07:53:53 volumio avahi-daemon[478]: New relevant interface eth0.IPv4 for mDNS. Jul 01 07:53:53 volumio dhcpcd[718]: eth0: adding route to 192.168.110.0/24 Jul 01 07:53:53 volumio avahi-daemon[478]: Registering new address record for 192.168.110.122 on eth0.IPv4. Jul 01 07:53:53 volumio dhcpcd[718]: eth0: adding default route via 192.168.110.1 Jul 01 07:53:54 volumio dhcpcd[829]: eth0: leased 192.168.110.122 for 1800 seconds Jul 01 07:53:54 volumio dhcpcd[829]: eth0: adding route to 192.168.110.0/24 Jul 01 07:53:54 volumio dhcpcd[829]: eth0: adding default route via 192.168.110.1 Jul 01 07:53:55 volumio ntpd[631]: Listen normally on 5 eth0 192.168.110.122:123 Jul 01 07:53:55 volumio ntpd[631]: new interface(s) found: waking up resolver Jul 01 07:53:57 volumio volumio[899]: info: Discovery: adding c064e50a-5d25-40ca-9b3b-469d181d7c5e Jul 01 07:53:57 volumio volumio[899]: info: mDNS: Found device Volumio Jul 01 07:53:57 volumio volumio[899]: info: CoreCommandRouter::volumioGetState Jul 01 07:53:57 volumio volumio[899]: info: CorePlayQueue::getTrack 0 Jul 01 07:54:07 volumio ntpd[631]: Soliciting pool server 193.182.111.143 Jul 01 07:54:08 volumio ntpd[631]: Soliciting pool server 116.203.151.74 Jul 01 07:54:09 volumio ntpd[631]: Soliciting pool server 84.16.67.12 Jul 01 07:54:10 volumio ntpd[631]: Soliciting pool server 111.230.189.174 Jul 01 07:54:14 volumio volumio[899]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 07:54:14 volumio volumio[899]: Error: connect ETIMEDOUT 69.171.247.32:443 Jul 01 07:54:14 volumio volumio[899]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 01 07:54:14 volumio volumio[899]: errno: -110, Jul 01 07:54:14 volumio volumio[899]: code: 'ETIMEDOUT', Jul 01 07:54:14 volumio volumio[899]: syscall: 'connect', Jul 01 07:54:14 volumio volumio[899]: address: '69.171.247.32', Jul 01 07:54:14 volumio volumio[899]: port: 443 Jul 01 07:54:14 volumio volumio[899]: } Jul 01 07:54:14 volumio volumio[899]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 01 07:54:14 volumio sudo[2056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-01 07:53 Jul 01 07:54:14 volumio sudo[2056]: 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="9eadfb08439e8c37bef9a38c0641d736c1391e0c" VOLUMIO_FE_VERSION="e6f7875b0e497e33187ca2553d7998c4aeb06af9" VOLUMIO_FE3_VERSION="8d9f9503eeacae3e8f349def90b368b32462d83b" VOLUMIO_BE_VERSION="1cdccc4d006cc26abc6312edbeae58f3081200d4" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 24 Feb 2022 07:16:13 PM CET" VOLUMIO_VERSION="3.233" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="88d67d58bfcedcd908dd7b955a1fa5bd"