-- Logs begin at Thu 2019-02-14 18:12:01 CST, end at Tue 2025-04-15 13:41:10 CST. -- Apr 15 13:40:00 volumio kernel: usb 1-1.2.3.1: new full-speed USB device number 12 using dwc_otg Apr 15 13:40:00 volumio kernel: usb 1-1.2.3.1: New USB device found, idVendor=0d22, idProduct=2870, bcdDevice= 1.03 Apr 15 13:40:00 volumio kernel: usb 1-1.2.3.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Apr 15 13:40:00 volumio kernel: usb 1-1.2.3.1: Product: GH70 Gaming Headset Apr 15 13:40:00 volumio kernel: usb 1-1.2.3.1: Manufacturer: C-Media Electronics Inc. Apr 15 13:40:00 volumio kernel: input: C-Media Electronics Inc. GH70 Gaming Headset as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2.3/1-1.2.3.1/1-1.2.3.1:1.3/0003:0D22:2870.0008/input/input5 Apr 15 13:40:00 volumio kernel: hid-generic 0003:0D22:2870.0008: input,hidraw7: USB HID v1.11 Device [C-Media Electronics Inc. GH70 Gaming Headset] on usb-3f980000.usb-1.2.3.1/input3 Apr 15 13:40:01 volumio systemd-udevd[1940]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 15 13:40:01 volumio kernel: usbcore: registered new interface driver snd-usb-audio Apr 15 13:40:01 volumio systemd-udevd[1939]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Apr 15 13:40:01 volumio volumio[975]: info: No valid Plugin REST Endpoint Apr 15 13:40:02 volumio volumio[975]: info: Apr 15 13:40:02 volumio volumio[975]: ---------------------------- USB Audio Device Attached Apr 15 13:40:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 15 13:40:04 volumio kernel: usb 1-1.2.3.2: reset full-speed USB device number 11 using dwc_otg Apr 15 13:40:05 volumio kernel: usb 1-1.2.3.2: reset full-speed USB device number 11 using dwc_otg Apr 15 13:40:05 volumio kernel: usb 1-1.2.3.2: device descriptor read/64, error -32 Apr 15 13:40:19 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Apr 15 13:40:26 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 13:40:27 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 15 13:40:27 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 13:40:27 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 15 13:40:27 volumio volumio[975]: info: Getting Alsa Cards List without I2S DAC Apr 15 13:40:27 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Apr 15 13:40:27 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 13:40:27 volumio volumio[975]: Invalid card number. Apr 15 13:40:27 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 13:40:27 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 13:40:27 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 13:40:27 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 13:40:27 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 13:40:27 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 13:40:27 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 13:40:27 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 15 13:40:27 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 15 13:40:35 volumio volumio[975]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 15 13:40:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 15 13:40:35 volumio volumio[975]: info: Preparing to save Alsa Options, stopping services first Apr 15 13:40:35 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Apr 15 13:40:35 volumio volumio[975]: info: CoreCommandRouter::volumioStop Apr 15 13:40:35 volumio volumio[975]: info: CoreStateMachine::stop Apr 15 13:40:35 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 13:40:35 volumio volumio[975]: info: CoreStateMachine::stPlaybackTimer Apr 15 13:40:35 volumio volumio[975]: info: CoreStateMachine::updateTrackBlock Apr 15 13:40:35 volumio volumio[975]: info: CorePlayQueue::getTrackBlock Apr 15 13:40:35 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:40:35 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:40:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 13:40:35 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:40:35 volumio volumio[975]: info: CoreStateMachine::serviceStop Apr 15 13:40:35 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:40:35 volumio volumio[975]: info: CoreCommandRouter::serviceStop Apr 15 13:40:35 volumio volumio[975]: info: [1744695635988] ControllerWebradio::stop Apr 15 13:40:35 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 13:40:35 volumio volumio[975]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"GH70 Gaming Headset"},"i2s":false,"i2sid":{"value":"iqaudio-dacplus","label":"ST400 Dac (PCM5122) - Amp"}} Apr 15 13:40:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 15 13:40:35 volumio volumio[975]: info: Disabling I2S DAC: Apr 15 13:40:35 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , disableI2SDAC Apr 15 13:40:36 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 13:40:36 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 15 13:40:36 volumio volumio[975]: info: Setting mixer Headphone for card GH70 Gaming Headset Apr 15 13:40:36 volumio volumio[975]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 15 13:40:36 volumio volumio[975]: info: Updating Volume Controller Parameters: Device: 5 Name: GH70 Gaming Headset Mixer: Headphone Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 15 13:40:36 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 15 13:40:36 volumio volumio[975]: info: Disabling external Volume Control Apr 15 13:40:36 volumio volumio[975]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 13:40:36 volumio volumio[975]: info: Preparing to generate the ALSA configuration file Apr 15 13:40:36 volumio volumio[975]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Apr 15 13:40:36 volumio volumio[975]: info: Reading ALSA contributions from plugins. Apr 15 13:40:37 volumio volumio[975]: info: Apr 15 13:40:37 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 15 13:40:37 volumio volumio[975]: info: sendMpdCommand stop took 1060 milliseconds Apr 15 13:40:37 volumio volumio[975]: info: ControllerMpd::getState Apr 15 13:40:37 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 15 13:40:37 volumio volumio[975]: info: sendMpdCommand status took 102 milliseconds Apr 15 13:40:37 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 15 13:40:37 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 13:40:37 volumio volumio[975]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 15 13:40:37 volumio volumio[975]: info: sendMpdCommand playlistinfo took 84 milliseconds Apr 15 13:40:37 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 15 13:40:37 volumio volumio[975]: info: ControllerMpd::pushState Apr 15 13:40:37 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 15 13:40:37 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:40:37 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:40:37 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 13:40:37 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:40:37 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:40:37 volumio volumio[975]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 15 13:40:37 volumio volumio[975]: info: ------------------------------ 270ms Apr 15 13:40:37 volumio sudo[2029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Apr 15 13:40:37 volumio sudo[2029]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 13:40:37 volumio sudo[2029]: pam_unix(sudo:session): session closed for user root Apr 15 13:40:37 volumio sudo[2032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r gpio-irgpio_pin=25gpio_pull=up Apr 15 13:40:37 volumio sudo[2032]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 13:40:37 volumio sudo[2032]: pam_unix(sudo:session): session closed for user root Apr 15 13:40:37 volumio volumio[975]: * Overlay 'gpio-irgpio_pin=25gpio_pull=up' is not loaded Apr 15 13:40:37 volumio volumio[975]: error: Cannot remove overlay Apr 15 13:40:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 15 13:40:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 13:40:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 15 13:40:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 13:40:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 13:40:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 13:40:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 13:40:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 13:40:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 13:40:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 13:40:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 15 13:40:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 15 13:40:38 volumio volumio[975]: info: VolumeController:: Volume=35 Mute =false Apr 15 13:40:38 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:40:38 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:40:38 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 13:40:38 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:40:38 volumio volumio[975]: info: Asound.conf file written Apr 15 13:40:38 volumio sudo[2046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 15 13:40:38 volumio sudo[2046]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 13:40:38 volumio sudo[2046]: pam_unix(sudo:session): session closed for user root Apr 15 13:40:39 volumio volumio[975]: No state is present for card Headset Apr 15 13:40:39 volumio volumio[975]: Found hardware: "USB-Audio" "USB Mixer" "USB0d22:2870" "" "" Apr 15 13:40:39 volumio volumio[975]: Hardware is initialized using a generic method Apr 15 13:40:39 volumio volumio[975]: No state is present for card Headset Apr 15 13:40:39 volumio volumio[975]: info: Output device has changed, restarting MPD Apr 15 13:40:39 volumio volumio[975]: info: Output device has changed, restarting Shairport Sync Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 13:40:39 volumio sudo[2051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 13:40:39 volumio sudo[2051]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 13:40:39 volumio sudo[2051]: pam_unix(sudo:session): session closed for user root Apr 15 13:40:39 volumio sudo[2053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 13:40:39 volumio sudo[2053]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 13:40:39 volumio volumio[975]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 13:40:39 volumio volumio[975]: info: Output device has changed, restarting MPD Apr 15 13:40:39 volumio systemd[1]: Stopping Music Player Daemon... Apr 15 13:40:39 volumio volumio[975]: info: Output device has changed, restarting Shairport Sync Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 13:40:39 volumio sudo[2060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 13:40:39 volumio sudo[2060]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 13:40:39 volumio sudo[2060]: pam_unix(sudo:session): session closed for user root Apr 15 13:40:39 volumio sudo[2062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 13:40:39 volumio sudo[2062]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 13:40:39 volumio volumio[975]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 15 13:40:39 volumio volumio[975]: info: MPD Permissions set Apr 15 13:40:39 volumio volumio[975]: info: MPD Permissions set Apr 15 13:40:39 volumio volumio[975]: info: peppy_screensaver: Start PeppyMeter Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:39 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:40 volumio volumio[975]: info: Starting Shairport Sync Apr 15 13:40:40 volumio volumio[975]: info: Starting Shairport Sync Apr 15 13:40:40 volumio sudo[2077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 13:40:40 volumio sudo[2077]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 13:40:40 volumio sudo[2079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 15 13:40:40 volumio sudo[2079]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 13:40:40 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:40 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 13:40:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 15 13:40:40 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 15 13:40:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 15 13:40:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 15 13:40:40 volumio sudo[2079]: pam_unix(sudo:session): session closed for user root Apr 15 13:40:40 volumio sudo[2077]: pam_unix(sudo:session): session closed for user root Apr 15 13:40:40 volumio volumio[975]: info: Shairport-Sync Started Apr 15 13:40:40 volumio volumio[975]: info: Shairport-Sync Started Apr 15 13:40:40 volumio volumio[975]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 15 13:40:40 volumio volumio[975]: info: CoreStateMachine::getcurrentVolume Apr 15 13:40:40 volumio volumio[975]: info: CoreCommandRouter::volumioRetrievevolume Apr 15 13:40:40 volumio volumio[975]: info: VolumeController:: Volume=35 Mute =false Apr 15 13:40:40 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:40:40 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:40:40 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:40:40 volumio volumio[975]: info: CoreStateMachine::updateTrackBlock Apr 15 13:40:40 volumio volumio[975]: info: CorePlayQueue::getTrackBlock Apr 15 13:40:40 volumio volumio[975]: info: CoreCommandRouter::volumioRetrievevolume Apr 15 13:40:41 volumio volumio[975]: info: VolumeController:: Volume=35 Mute =false Apr 15 13:40:41 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:40:41 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:40:41 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:40:41 volumio systemd[1]: mpd.service: Succeeded. Apr 15 13:40:41 volumio systemd[1]: Stopped Music Player Daemon. Apr 15 13:40:42 volumio systemd[1]: Starting Music Player Daemon... Apr 15 13:40:42 volumio sudo[2089]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 13:40:42 volumio sudo[2089]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 13:40:42 volumio sudo[2089]: pam_unix(sudo:session): session closed for user root Apr 15 13:40:45 volumio volumio[975]: info: CoreCommandRouter::volumioPlay Apr 15 13:40:45 volumio volumio[975]: info: CoreStateMachine::play index undefined Apr 15 13:40:45 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 13:40:45 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:40:45 volumio volumio[975]: info: CoreStateMachine::startPlaybackTimer Apr 15 13:40:45 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:40:45 volumio volumio[975]: info: [1744695645840] ControllerWebradio::clearAddPlayTrack Apr 15 13:40:45 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 13:40:45 volumio volumio[975]: error: MPD error: Error: This socket has been ended by the other party Apr 15 13:40:45 volumio volumio[975]: error: This socket has been ended by the other party {"code":"EPIPE"} Apr 15 13:40:45 volumio volumio[975]: error: MPD error: Error: This socket has been ended by the other party Apr 15 13:40:45 volumio volumio[975]: error: This socket has been ended by the other party {"code":"EPIPE"} Apr 15 13:40:45 volumio volumio[975]: error: MPD error: Error: This socket has been ended by the other party Apr 15 13:40:45 volumio volumio[975]: error: This socket has been ended by the other party {"code":"EPIPE"} Apr 15 13:40:46 volumio mpd[2091]: Apr 15 13:40 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 15 13:40:46 volumio systemd[1]: Started Music Player Daemon. Apr 15 13:40:46 volumio sudo[2062]: pam_unix(sudo:session): session closed for user root Apr 15 13:40:46 volumio sudo[2053]: pam_unix(sudo:session): session closed for user root Apr 15 13:40:46 volumio volumio[975]: error: MPD error: The expression evaluated to a falsy value: Apr 15 13:40:46 volumio volumio[975]: assert.ok(self.idling) Apr 15 13:40:46 volumio volumio[975]: error: The expression evaluated to a falsy value: Apr 15 13:40:46 volumio volumio[975]: assert.ok(self.idling) Apr 15 13:40:46 volumio volumio[975]: error: updateQueue error: null Apr 15 13:40:52 volumio kernel: usb 1-1.2.3.2: reset full-speed USB device number 11 using dwc_otg Apr 15 13:40:52 volumio kernel: usb 1-1.2.3.2: device descriptor read/64, error -32 Apr 15 13:40:53 volumio kernel: usb 1-1.2.3.2: device descriptor read/all, error -32 Apr 15 13:40:53 volumio kernel: usb 1-1.2.3.2: reset full-speed USB device number 11 using dwc_otg Apr 15 13:40:53 volumio kernel: usb 1-1.2.3.2: device descriptor read/64, error -32 Apr 15 13:40:53 volumio kernel: usb 1-1.2.3.2: device descriptor read/64, error -32 Apr 15 13:40:53 volumio kernel: usb 1-1.2.3.2: reset full-speed USB device number 11 using dwc_otg Apr 15 13:40:54 volumio kernel: usb 1-1.2.3.2: device not accepting address 11, error -32 Apr 15 13:40:54 volumio kernel: usb 1-1.2.3.2: reset full-speed USB device number 11 using dwc_otg Apr 15 13:40:54 volumio kernel: usb 1-1.2.3.2: device descriptor read/8, error -32 Apr 15 13:40:54 volumio kernel: usb 1-1.2.3.2: device descriptor read/8, error -32 Apr 15 13:40:54 volumio kernel: usb 1-1.2.3.2: USB disconnect, device number 11 Apr 15 13:40:54 volumio kernel: usb 1-1.2.3.2: new full-speed USB device number 13 using dwc_otg Apr 15 13:40:54 volumio kernel: usb 1-1.2.3.2: device descriptor read/64, error -32 Apr 15 13:40:55 volumio kernel: usb 1-1.2.3.2: device descriptor read/64, error -32 Apr 15 13:40:55 volumio kernel: usb 1-1.2.3.2: new full-speed USB device number 14 using dwc_otg Apr 15 13:40:55 volumio kernel: usb 1-1.2.3.2: New USB device found, idVendor=0d22, idProduct=2871, bcdDevice= 1.01 Apr 15 13:40:55 volumio kernel: usb 1-1.2.3.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 15 13:40:55 volumio kernel: usb 1-1.2.3.2: Product: USB-HID Device Apr 15 13:40:55 volumio kernel: usb 1-1.2.3.2: Manufacturer: HOLTEK Apr 15 13:40:55 volumio kernel: usb 1-1.2.3.2: SerialNumber: 000000000000 Apr 15 13:40:55 volumio kernel: hid-generic 0003:0D22:2871.0009: hiddev97,hidraw4: USB HID v1.11 Device [HOLTEK USB-HID Device] on usb-3f980000.usb-1.2.3.2/input0 Apr 15 13:40:55 volumio kernel: hid-generic 0003:0D22:2871.000A: hiddev98,hidraw5: USB HID v1.11 Device [HOLTEK USB-HID Device] on usb-3f980000.usb-1.2.3.2/input1 Apr 15 13:40:55 volumio kernel: input: HOLTEK USB-HID Device as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2.3/1-1.2.3.2/1-1.2.3.2:1.2/0003:0D22:2871.000B/input/input6 Apr 15 13:40:55 volumio kernel: hid-generic 0003:0D22:2871.000B: input,hidraw6: USB HID v1.11 Mouse [HOLTEK USB-HID Device] on usb-3f980000.usb-1.2.3.2/input2 Apr 15 13:40:55 volumio systemd-udevd[2098]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 15 13:40:56 volumio systemd-udevd[2094]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 15 13:40:59 volumio volumio[975]: info: CoreCommandRouter::volumioPlay Apr 15 13:40:59 volumio volumio[975]: info: CoreStateMachine::play index undefined Apr 15 13:40:59 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 13:40:59 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:40:59 volumio volumio[975]: info: CoreStateMachine::startPlaybackTimer Apr 15 13:40:59 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:40:59 volumio volumio[975]: info: [1744695659226] ControllerWebradio::clearAddPlayTrack Apr 15 13:40:59 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 13:40:59 volumio volumio[975]: info: sendMpdCommand stop took 4 milliseconds Apr 15 13:40:59 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 13:40:59 volumio volumio[975]: info: Apr 15 13:40:59 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:40:59 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:40:59 volumio volumio[975]: info: sendMpdCommand clear took 5 milliseconds Apr 15 13:40:59 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Apr 15 13:40:59 volumio volumio[975]: info: Apr 15 13:40:59 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:40:59 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:40:59 volumio volumio[975]: error: updateQueue error: null Apr 15 13:40:59 volumio volumio[975]: info: ------------------------------ 16ms Apr 15 13:41:00 volumio volumio[975]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 761 milliseconds Apr 15 13:41:00 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 13:41:00 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand play Apr 15 13:41:00 volumio volumio[975]: info: Apr 15 13:41:00 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:00 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:00 volumio volumio[975]: info: ------------------------------ 770ms Apr 15 13:41:00 volumio volumio[975]: info: Apr 15 13:41:00 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:00 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:00 volumio volumio[975]: info: sendMpdCommand play took 19 milliseconds Apr 15 13:41:00 volumio volumio[975]: info: ------------------------------ 17ms Apr 15 13:41:00 volumio volumio[975]: info: ------------------------------ 10ms Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::volumioPlay Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::play index undefined Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 13:41:02 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::startPlaybackTimer Apr 15 13:41:02 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:02 volumio volumio[975]: info: [1744695662092] ControllerWebradio::clearAddPlayTrack Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 13:41:02 volumio volumio[975]: info: sendMpdCommand stop took 4 milliseconds Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 13:41:02 volumio volumio[975]: info: Apr 15 13:41:02 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 15 13:41:02 volumio volumio[975]: info: Apr 15 13:41:02 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:02 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:02 volumio volumio[975]: info: ControllerMpd::getState Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 15 13:41:02 volumio volumio[975]: info: sendMpdCommand clear took 9 milliseconds Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Apr 15 13:41:02 volumio volumio[975]: info: Apr 15 13:41:02 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 15 13:41:02 volumio volumio[975]: info: ControllerMpd::getState Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 15 13:41:02 volumio volumio[975]: info: sendMpdCommand status took 42 milliseconds Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 15 13:41:02 volumio volumio[975]: info: ControllerMpd::pushState Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:02 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:02 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:02 volumio volumio[975]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 15 13:41:02 volumio volumio[975]: info: ------------------------------ 108ms Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::volumioPlay Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::play index undefined Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 13:41:02 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::startPlaybackTimer Apr 15 13:41:02 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:02 volumio volumio[975]: info: [1744695662241] ControllerWebradio::clearAddPlayTrack Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 13:41:02 volumio volumio[975]: info: Apr 15 13:41:02 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:02 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:02 volumio volumio[975]: error: updateQueue error: null Apr 15 13:41:02 volumio volumio[975]: info: Apr 15 13:41:02 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:02 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:02 volumio volumio[975]: info: ------------------------------ 310ms Apr 15 13:41:02 volumio volumio[975]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 302 milliseconds Apr 15 13:41:02 volumio volumio[975]: info: sendMpdCommand status took 298 milliseconds Apr 15 13:41:02 volumio volumio[975]: info: sendMpdCommand stop took 175 milliseconds Apr 15 13:41:02 volumio volumio[975]: info: ------------------------------ 14ms Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand play Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 13:41:02 volumio volumio[975]: info: ControllerMpd::pushState Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:02 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:02 volumio volumio[975]: 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} Apr 15 13:41:02 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::syncState stateService stop Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:02 volumio volumio[975]: info: No code Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:02 volumio volumio[975]: info: ------------------------------ 446ms Apr 15 13:41:02 volumio volumio[975]: info: Apr 15 13:41:02 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:02 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:02 volumio volumio[975]: info: Apr 15 13:41:02 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:02 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:02 volumio volumio[975]: info: Apr 15 13:41:02 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 15 13:41:02 volumio volumio[975]: error: updateQueue error: null Apr 15 13:41:02 volumio volumio[975]: info: ------------------------------ 170ms Apr 15 13:41:02 volumio volumio[975]: info: sendMpdCommand play took 160 milliseconds Apr 15 13:41:02 volumio volumio[975]: info: sendMpdCommand clear took 159 milliseconds Apr 15 13:41:02 volumio volumio[975]: info: ControllerMpd::getState Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 15 13:41:02 volumio volumio[975]: info: ------------------------------ 21ms Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" Apr 15 13:41:02 volumio volumio[975]: info: Apr 15 13:41:02 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:02 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:02 volumio volumio[975]: info: Apr 15 13:41:02 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 15 13:41:02 volumio volumio[975]: info: ControllerMpd::getState Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 15 13:41:02 volumio volumio[975]: error: updateQueue error: null Apr 15 13:41:02 volumio volumio[975]: info: ------------------------------ 74ms Apr 15 13:41:02 volumio volumio[975]: info: sendMpdCommand status took 63 milliseconds Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 15 13:41:02 volumio volumio[975]: info: ControllerMpd::pushState Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:02 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:02 volumio volumio[975]: 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} Apr 15 13:41:02 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::syncState stateService stop Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:02 volumio volumio[975]: info: No code Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:02 volumio volumio[975]: info: ------------------------------ 301ms Apr 15 13:41:02 volumio volumio[975]: info: Apr 15 13:41:02 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:02 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:02 volumio volumio[975]: info: Apr 15 13:41:02 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:02 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:02 volumio volumio[975]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99497996" took 305 milliseconds Apr 15 13:41:02 volumio volumio[975]: info: ------------------------------ 263ms Apr 15 13:41:02 volumio volumio[975]: info: sendMpdCommand status took 257 milliseconds Apr 15 13:41:02 volumio volumio[975]: info: ------------------------------ 17ms Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand play Apr 15 13:41:02 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 15 13:41:02 volumio volumio[975]: info: ControllerMpd::pushState Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:02 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:02 volumio volumio[975]: 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} Apr 15 13:41:02 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::syncState stateService stop Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:02 volumio volumio[975]: info: No code Apr 15 13:41:02 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:02 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:03 volumio volumio[975]: info: ------------------------------ 433ms Apr 15 13:41:03 volumio volumio[975]: info: ------------------------------ 249ms Apr 15 13:41:03 volumio volumio[975]: info: sendMpdCommand play took 236 milliseconds Apr 15 13:41:04 volumio volumio[975]: info: Apr 15 13:41:04 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 15 13:41:04 volumio volumio[975]: info: ControllerMpd::getState Apr 15 13:41:04 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 15 13:41:04 volumio volumio[975]: info: Apr 15 13:41:04 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 15 13:41:04 volumio volumio[975]: info: ControllerMpd::getState Apr 15 13:41:04 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 15 13:41:05 volumio volumio[975]: info: Apr 15 13:41:05 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 15 13:41:05 volumio volumio[975]: info: sendMpdCommand status took 96 milliseconds Apr 15 13:41:05 volumio volumio[975]: info: sendMpdCommand status took 90 milliseconds Apr 15 13:41:05 volumio volumio[975]: info: ControllerMpd::getState Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 13:41:05 volumio volumio[975]: info: Apr 15 13:41:05 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 15 13:41:05 volumio volumio[975]: info: ControllerMpd::getState Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 15 13:41:05 volumio volumio[975]: info: sendMpdCommand status took 47 milliseconds Apr 15 13:41:05 volumio volumio[975]: info: sendMpdCommand playlistinfo took 51 milliseconds Apr 15 13:41:05 volumio volumio[975]: info: sendMpdCommand playlistinfo took 50 milliseconds Apr 15 13:41:05 volumio volumio[975]: info: sendMpdCommand status took 33 milliseconds Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 13:41:05 volumio volumio[975]: info: ControllerMpd::pushState Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 15 13:41:05 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:05 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 11423/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 15 13:41:05 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 15 13:41:05 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 15 13:41:05 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus stop Apr 15 13:41:05 volumio volumio[975]: info: ControllerMpd::pushState Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 15 13:41:05 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:05 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 11423/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 15 13:41:05 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 15 13:41:05 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 15 13:41:05 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Apr 15 13:41:05 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 15 13:41:05 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:05 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:05 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:05 volumio volumio[975]: info: ------------------------------ 370ms Apr 15 13:41:05 volumio volumio[975]: info: ------------------------------ 438ms Apr 15 13:41:05 volumio volumio[975]: info: Apr 15 13:41:05 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:05 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:05 volumio volumio[975]: info: Apr 15 13:41:05 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 15 13:41:05 volumio volumio[975]: info: Apr 15 13:41:05 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:05 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:05 volumio volumio[975]: info: Apr 15 13:41:05 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 15 13:41:05 volumio volumio[975]: info: sendMpdCommand playlistinfo took 289 milliseconds Apr 15 13:41:05 volumio volumio[975]: info: ControllerMpd::getState Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 15 13:41:05 volumio volumio[975]: info: sendMpdCommand playlistinfo took 285 milliseconds Apr 15 13:41:05 volumio volumio[975]: info: ControllerMpd::getState Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 15 13:41:05 volumio volumio[975]: info: ControllerMpd::pushState Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 15 13:41:05 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:05 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"antenne","artist":"(#1 - 11423/500000) ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 15 13:41:05 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 15 13:41:05 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 15 13:41:05 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Apr 15 13:41:05 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 15 13:41:05 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:05 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:05 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:05 volumio volumio[975]: info: ControllerMpd::pushState Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 15 13:41:05 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:05 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ANTENNE BAYERN - Wir lieben Bayern, wir lieben Musik","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 15 13:41:05 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 15 13:41:05 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 15 13:41:05 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Apr 15 13:41:05 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 15 13:41:05 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:05 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:05 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:05 volumio volumio[975]: info: ------------------------------ 835ms Apr 15 13:41:05 volumio volumio[975]: info: ------------------------------ 809ms Apr 15 13:41:05 volumio volumio[975]: info: Apr 15 13:41:05 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:05 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:05 volumio volumio[975]: info: Apr 15 13:41:05 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 15 13:41:05 volumio volumio[975]: info: ControllerMpd::getState Apr 15 13:41:05 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 15 13:41:06 volumio volumio[975]: info: ------------------------------ 631ms Apr 15 13:41:06 volumio volumio[975]: info: sendMpdCommand status took 615 milliseconds Apr 15 13:41:06 volumio volumio[975]: info: ------------------------------ 636ms Apr 15 13:41:06 volumio volumio[975]: info: sendMpdCommand status took 610 milliseconds Apr 15 13:41:06 volumio volumio[975]: info: ------------------------------ 157ms Apr 15 13:41:06 volumio volumio[975]: info: sendMpdCommand status took 133 milliseconds Apr 15 13:41:06 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 15 13:41:06 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 13:41:06 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 15 13:41:06 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 13:41:06 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 15 13:41:06 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 13:41:06 volumio volumio[975]: info: Apr 15 13:41:06 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:06 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:06 volumio volumio[975]: info: Apr 15 13:41:06 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 15 13:41:06 volumio volumio[975]: info: ControllerMpd::getState Apr 15 13:41:06 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 15 13:41:06 volumio volumio[975]: info: sendMpdCommand playlistinfo took 59 milliseconds Apr 15 13:41:06 volumio volumio[975]: info: sendMpdCommand playlistinfo took 56 milliseconds Apr 15 13:41:06 volumio volumio[975]: info: sendMpdCommand playlistinfo took 54 milliseconds Apr 15 13:41:06 volumio volumio[975]: info: ------------------------------ 46ms Apr 15 13:41:06 volumio volumio[975]: info: sendMpdCommand status took 38 milliseconds Apr 15 13:41:06 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 15 13:41:06 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 15 13:41:06 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 15 13:41:06 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 15 13:41:06 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 13:41:06 volumio volumio[975]: info: ControllerMpd::pushState Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 15 13:41:06 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:06 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1000,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Wir lieben Bayern, wir lieben Musik","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 15 13:41:06 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Apr 15 13:41:06 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:06 volumio volumio[975]: info: ControllerMpd::pushState Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 15 13:41:06 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:06 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1000,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Wir lieben Bayern, wir lieben Musik","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 15 13:41:06 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Apr 15 13:41:06 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:06 volumio volumio[975]: info: ControllerMpd::pushState Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 15 13:41:06 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:06 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1469,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Wir lieben Bayern, wir lieben Musik","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 15 13:41:06 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Apr 15 13:41:06 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:06 volumio volumio[975]: info: ------------------------------ 1176ms Apr 15 13:41:06 volumio volumio[975]: info: ------------------------------ 1175ms Apr 15 13:41:06 volumio volumio[975]: info: ------------------------------ 700ms Apr 15 13:41:06 volumio volumio[975]: info: Apr 15 13:41:06 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:06 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:06 volumio volumio[975]: info: Apr 15 13:41:06 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 15 13:41:06 volumio volumio[975]: info: sendMpdCommand playlistinfo took 573 milliseconds Apr 15 13:41:06 volumio volumio[975]: info: ControllerMpd::getState Apr 15 13:41:06 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 15 13:41:06 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 15 13:41:06 volumio volumio[975]: info: ControllerMpd::pushState Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 15 13:41:06 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:06 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1652,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Wir lieben Bayern, wir lieben Musik","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 15 13:41:06 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Apr 15 13:41:06 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:06 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:06 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:06 volumio volumio[975]: info: ------------------------------ 834ms Apr 15 13:41:06 volumio volumio[975]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%2011423%2F500000)%20ANTENNE%20BAYERN/f125afbc-1da0-4be1-a50d-3a75eaaa5a1b.jpg' Apr 15 13:41:06 volumio volumio[975]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%2011423%2F500000)%20ANTENNE%20BAYERN/f125afbc-1da0-4be1-a50d-3a75eaaa5a1b.jpg' Apr 15 13:41:07 volumio volumio[975]: info: Apr 15 13:41:07 volumio volumio[975]: ---------------------------- MPD announces system playlist update Apr 15 13:41:07 volumio volumio[975]: info: Ignoring MPD Status Update Apr 15 13:41:07 volumio volumio[975]: info: Apr 15 13:41:07 volumio volumio[975]: ---------------------------- MPD announces state update: player Apr 15 13:41:07 volumio volumio[975]: info: ControllerMpd::getState Apr 15 13:41:07 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand status Apr 15 13:41:07 volumio volumio[975]: info: ------------------------------ 406ms Apr 15 13:41:07 volumio volumio[975]: info: sendMpdCommand status took 370 milliseconds Apr 15 13:41:07 volumio volumio[975]: info: ------------------------------ 22ms Apr 15 13:41:07 volumio volumio[975]: info: sendMpdCommand status took 14 milliseconds Apr 15 13:41:07 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 15 13:41:07 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 13:41:07 volumio volumio[975]: verbose: ControllerMpd::parseState Apr 15 13:41:07 volumio volumio[975]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 13:41:07 volumio volumio[975]: info: sendMpdCommand playlistinfo took 19 milliseconds Apr 15 13:41:07 volumio volumio[975]: info: sendMpdCommand playlistinfo took 18 milliseconds Apr 15 13:41:07 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 15 13:41:07 volumio volumio[975]: verbose: ControllerMpd::parseTrackInfo Apr 15 13:41:07 volumio volumio[975]: info: ControllerMpd::pushState Apr 15 13:41:07 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 15 13:41:07 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:07 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2303,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Wir lieben Bayern, wir lieben Musik","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 15 13:41:07 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 15 13:41:07 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 15 13:41:07 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Apr 15 13:41:07 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Apr 15 13:41:07 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 15 13:41:07 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:07 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 13:41:07 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:07 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:07 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:07 volumio volumio[975]: info: ControllerMpd::pushState Apr 15 13:41:07 volumio volumio[975]: info: CoreCommandRouter::servicePushState Apr 15 13:41:07 volumio volumio[975]: info: CorePlayQueue::getTrack 0 Apr 15 13:41:07 volumio volumio[975]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2582,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Wir lieben Bayern, wir lieben Musik","artist":"ANTENNE BAYERN","album":null,"uri":"http://stream.antenne.de:80/antenne","trackType":""} Apr 15 13:41:07 volumio volumio[975]: verbose: CURRENT POSITION 0 Apr 15 13:41:07 volumio volumio[975]: info: CoreStateMachine::syncState stateService play Apr 15 13:41:07 volumio volumio[975]: info: CoreStateMachine::syncState currentStatus play Apr 15 13:41:07 volumio volumio[975]: info: Received an update from plugin. extracting info from payload Apr 15 13:41:07 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 15 13:41:07 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:07 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:07 volumio volumio[975]: info: CoreStateMachine::pushState Apr 15 13:41:07 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Apr 15 13:41:07 volumio volumio[975]: info: ------------------------------ 673ms Apr 15 13:41:07 volumio volumio[975]: info: ------------------------------ 286ms Apr 15 13:41:07 volumio volumio[975]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 13:41:08 volumio volumio[975]: Error: connect ETIMEDOUT 108.160.166.42:80 Apr 15 13:41:08 volumio volumio[975]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Apr 15 13:41:08 volumio volumio[975]: errno: -110, Apr 15 13:41:08 volumio volumio[975]: code: 'ETIMEDOUT', Apr 15 13:41:08 volumio volumio[975]: syscall: 'connect', Apr 15 13:41:08 volumio volumio[975]: address: '108.160.166.42', Apr 15 13:41:08 volumio volumio[975]: port: 80 Apr 15 13:41:08 volumio volumio[975]: } Apr 15 13:41:08 volumio volumio[975]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 13:41:10 volumio sudo[2308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-15 13:40 Apr 15 13:41:10 volumio sudo[2308]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"