-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Sat 2025-03-01 22:34:21 CST. -- Mar 01 22:33:54 volumio kernel: usb 1-3: USB disconnect, device number 2 Mar 01 22:33:54 volumio volumio[8294]: info: Mar 01 22:33:54 volumio volumio[8294]: ---------------------------- MPD announces state update: player Mar 01 22:33:54 volumio volumio[8294]: info: ControllerMpd::getState Mar 01 22:33:54 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand status Mar 01 22:33:54 volumio volumio[8294]: info: Mar 01 22:33:54 volumio volumio[8294]: ---------------------------- MPD announces state update: player Mar 01 22:33:54 volumio volumio[8294]: info: ControllerMpd::getState Mar 01 22:33:54 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand status Mar 01 22:33:54 volumio volumio[8294]: info: Mar 01 22:33:54 volumio volumio[8294]: ---------------------------- MPD announces state update: player Mar 01 22:33:54 volumio volumio[8294]: info: ControllerMpd::getState Mar 01 22:33:54 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand status Mar 01 22:33:54 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 01 22:33:54 volumio volumio[8294]: info: sendMpdCommand status took 11 milliseconds Mar 01 22:33:54 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 01 22:33:54 volumio volumio[8294]: info: sendMpdCommand status took 10 milliseconds Mar 01 22:33:54 volumio volumio[8294]: verbose: ControllerMpd::parseState Mar 01 22:33:54 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 22:33:54 volumio volumio[8294]: verbose: ControllerMpd::parseState Mar 01 22:33:54 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 22:33:54 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 01 22:33:54 volumio volumio[8294]: info: sendMpdCommand status took 20 milliseconds Mar 01 22:33:54 volumio volumio[8294]: info: sendMpdCommand clearerror took 14 milliseconds Mar 01 22:33:54 volumio volumio[8294]: info: sendMpdCommand clearerror took 15 milliseconds Mar 01 22:33:54 volumio volumio[8294]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 01 22:33:54 volumio volumio[8294]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 01 22:33:54 volumio volumio[8294]: verbose: ControllerMpd::parseState Mar 01 22:33:54 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 22:33:54 volumio volumio[8294]: verbose: ControllerMpd::parseTrackInfo Mar 01 22:33:54 volumio volumio[8294]: info: ControllerMpd::pushState Mar 01 22:33:54 volumio volumio[8294]: info: CoreCommandRouter::servicePushState Mar 01 22:33:54 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:54 volumio volumio[8294]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":363238,"duration":393,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Karma Girls","artist":"Indochine","album":"13","uri":"NAS/nas/Indochine - 13/07 - Indochine - Karma Girls.mp3","trackType":"mp3"} Mar 01 22:33:54 volumio volumio[8294]: verbose: CURRENT POSITION 6 Mar 01 22:33:54 volumio volumio[8294]: info: CoreStateMachine::syncState stateService pause Mar 01 22:33:54 volumio volumio[8294]: info: CoreStateMachine::syncState currentStatus play Mar 01 22:33:54 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:33:54 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:54 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:33:54 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:33:54 volumio volumio[8294]: info: ControllerMpd::pushState Mar 01 22:33:54 volumio volumio[8294]: info: CoreCommandRouter::servicePushState Mar 01 22:33:54 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:54 volumio volumio[8294]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":363238,"duration":393,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Karma Girls","artist":"Indochine","album":"13","uri":"NAS/nas/Indochine - 13/07 - Indochine - Karma Girls.mp3","trackType":"mp3"} Mar 01 22:33:54 volumio volumio[8294]: verbose: CURRENT POSITION 6 Mar 01 22:33:54 volumio volumio[8294]: info: CoreStateMachine::syncState stateService pause Mar 01 22:33:54 volumio volumio[8294]: info: CoreStateMachine::syncState currentStatus play Mar 01 22:33:54 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:33:54 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:54 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:33:54 volumio volumio[8294]: info: ------------------------------ 57ms Mar 01 22:33:54 volumio volumio[8294]: info: ------------------------------ 56ms Mar 01 22:33:54 volumio volumio[8294]: info: sendMpdCommand clearerror took 38 milliseconds Mar 01 22:33:54 volumio volumio[8294]: info: sendMpdCommand playlistinfo took 32 milliseconds Mar 01 22:33:54 volumio volumio[8294]: verbose: ControllerMpd::parseTrackInfo Mar 01 22:33:54 volumio volumio[8294]: info: ControllerMpd::pushState Mar 01 22:33:54 volumio volumio[8294]: info: CoreCommandRouter::servicePushState Mar 01 22:33:54 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:54 volumio volumio[8294]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":363238,"duration":393,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Karma Girls","artist":"Indochine","album":"13","uri":"NAS/nas/Indochine - 13/07 - Indochine - Karma Girls.mp3","trackType":"mp3"} Mar 01 22:33:54 volumio volumio[8294]: verbose: CURRENT POSITION 6 Mar 01 22:33:54 volumio volumio[8294]: info: CoreStateMachine::syncState stateService pause Mar 01 22:33:54 volumio volumio[8294]: info: CoreStateMachine::syncState currentStatus play Mar 01 22:33:54 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:33:54 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:54 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:33:54 volumio volumio[8294]: info: ------------------------------ 81ms Mar 01 22:33:54 volumio volumio[8294]: info: Mar 01 22:33:54 volumio volumio[8294]: ---------------------------- USB Audio Device Detached Mar 01 22:33:54 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Mar 01 22:33:54 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 22:33:54 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Mar 01 22:33:55 volumio kernel: usb 1-3: new full-speed USB device number 3 using xhci_hcd Mar 01 22:33:56 volumio kernel: usb 1-3: New USB device found, idVendor=08bb, idProduct=2706, bcdDevice= 1.00 Mar 01 22:33:56 volumio kernel: usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Mar 01 22:33:56 volumio kernel: usb 1-3: Product: USB Audio DAC Mar 01 22:33:56 volumio kernel: usb 1-3: Manufacturer: Burr-Brown from TI Mar 01 22:33:56 volumio kernel: input: Burr-Brown from TI USB Audio DAC as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3:1.2/0003:08BB:2706.0002/input/input5 Mar 01 22:33:56 volumio kernel: hid-generic 0003:08BB:2706.0002: input,hidraw0: USB HID v1.00 Device [Burr-Brown from TI USB Audio DAC ] on usb-0000:00:14.0-3/input2 Mar 01 22:33:56 volumio systemd-udevd[12296]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Mar 01 22:33:56 volumio volumio[8294]: info: Mar 01 22:33:56 volumio volumio[8294]: ---------------------------- USB Audio Device Attached Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Mar 01 22:33:56 volumio systemd-udevd[12299]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::Close All Modals sent Mar 01 22:33:56 volumio volumio[8294]: info: Preparing to save Alsa Options, stopping services first Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::volumioGetState Mar 01 22:33:56 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::volumioPause Mar 01 22:33:56 volumio volumio[8294]: info: CoreStateMachine::pause Mar 01 22:33:56 volumio volumio[8294]: info: CoreStateMachine::stPlaybackTimer Mar 01 22:33:56 volumio volumio[8294]: info: CoreStateMachine::servicePause Mar 01 22:33:56 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::servicePause Mar 01 22:33:56 volumio volumio[8294]: info: ControllerMpd::pause Mar 01 22:33:56 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand pause Mar 01 22:33:56 volumio volumio[8294]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"USB Audio DAC","alsacard":"DAC"},"i2s":false} Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 22:33:56 volumio volumio[8294]: info: Setting mixer PCM for card USB Audio DAC Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 01 22:33:56 volumio volumio[8294]: info: Updating Volume Controller Parameters: Device: 5 Name: USB Audio DAC Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 01 22:33:56 volumio volumio[8294]: info: Disabling external Volume Control Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 01 22:33:56 volumio volumio[8294]: info: Preparing to generate the ALSA configuration file Mar 01 22:33:56 volumio volumio[8294]: info: Mar 01 22:33:56 volumio volumio[8294]: ---------------------------- MPD announces state update: player Mar 01 22:33:56 volumio volumio[8294]: info: sendMpdCommand pause took 265 milliseconds Mar 01 22:33:56 volumio volumio[8294]: info: ControllerMpd::getState Mar 01 22:33:56 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand status Mar 01 22:33:56 volumio volumio[8294]: info: Mar 01 22:33:56 volumio volumio[8294]: ---------------------------- MPD announces state update: player Mar 01 22:33:56 volumio volumio[8294]: info: ControllerMpd::getState Mar 01 22:33:56 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand status Mar 01 22:33:56 volumio volumio[8294]: info: Mar 01 22:33:56 volumio volumio[8294]: ---------------------------- MPD announces state update: player Mar 01 22:33:56 volumio volumio[8294]: info: ControllerMpd::getState Mar 01 22:33:56 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand status Mar 01 22:33:56 volumio volumio[8294]: info: sendMpdCommand status took 28 milliseconds Mar 01 22:33:56 volumio volumio[8294]: info: sendMpdCommand status took 27 milliseconds Mar 01 22:33:56 volumio volumio[8294]: info: sendMpdCommand status took 26 milliseconds Mar 01 22:33:56 volumio volumio[8294]: verbose: ControllerMpd::parseState Mar 01 22:33:56 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 22:33:56 volumio volumio[8294]: verbose: ControllerMpd::parseState Mar 01 22:33:56 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 22:33:56 volumio volumio[8294]: verbose: ControllerMpd::parseState Mar 01 22:33:56 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 22:33:56 volumio volumio[8294]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 01 22:33:56 volumio volumio[8294]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 01 22:33:56 volumio volumio[8294]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 01 22:33:56 volumio volumio[8294]: verbose: ControllerMpd::parseTrackInfo Mar 01 22:33:56 volumio volumio[8294]: verbose: ControllerMpd::parseTrackInfo Mar 01 22:33:56 volumio volumio[8294]: info: ControllerMpd::pushState Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::servicePushState Mar 01 22:33:56 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:56 volumio volumio[8294]: verbose: STATE SERVICE {"status":"play","position":0,"seek":364115,"duration":393,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Karma Girls","artist":"Indochine","album":"13","uri":"NAS/nas/Indochine - 13/07 - Indochine - Karma Girls.mp3","trackType":"mp3"} Mar 01 22:33:56 volumio volumio[8294]: verbose: CURRENT POSITION 6 Mar 01 22:33:56 volumio volumio[8294]: info: CoreStateMachine::syncState stateService play Mar 01 22:33:56 volumio volumio[8294]: info: CoreStateMachine::syncState currentStatus pause Mar 01 22:33:56 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:33:56 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:33:56 volumio volumio[8294]: info: ControllerMpd::pushState Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::servicePushState Mar 01 22:33:56 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:56 volumio volumio[8294]: verbose: STATE SERVICE {"status":"play","position":0,"seek":364115,"duration":393,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Karma Girls","artist":"Indochine","album":"13","uri":"NAS/nas/Indochine - 13/07 - Indochine - Karma Girls.mp3","trackType":"mp3"} Mar 01 22:33:56 volumio volumio[8294]: verbose: CURRENT POSITION 6 Mar 01 22:33:56 volumio volumio[8294]: info: CoreStateMachine::syncState stateService play Mar 01 22:33:56 volumio volumio[8294]: info: CoreStateMachine::syncState currentStatus play Mar 01 22:33:56 volumio volumio[8294]: info: Received an update from plugin. extracting info from payload Mar 01 22:33:56 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:33:56 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:33:56 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:33:56 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:33:56 volumio volumio[8294]: info: ControllerMpd::pushState Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::servicePushState Mar 01 22:33:56 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:56 volumio volumio[8294]: verbose: STATE SERVICE {"status":"play","position":0,"seek":364115,"duration":393,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Karma Girls","artist":"Indochine","album":"13","uri":"NAS/nas/Indochine - 13/07 - Indochine - Karma Girls.mp3","trackType":"mp3"} Mar 01 22:33:56 volumio volumio[8294]: verbose: CURRENT POSITION 6 Mar 01 22:33:56 volumio volumio[8294]: info: CoreStateMachine::syncState stateService play Mar 01 22:33:56 volumio volumio[8294]: info: CoreStateMachine::syncState currentStatus play Mar 01 22:33:56 volumio volumio[8294]: info: Received an update from plugin. extracting info from payload Mar 01 22:33:56 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:33:56 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:33:56 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:33:56 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:33:56 volumio volumio[8294]: info: ------------------------------ 91ms Mar 01 22:33:56 volumio volumio[8294]: info: ------------------------------ 90ms Mar 01 22:33:56 volumio volumio[8294]: info: ------------------------------ 90ms Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 01 22:33:56 volumio volumio[8294]: info: VolumeController:: Volume=46 Mute =false Mar 01 22:33:56 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:33:56 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:33:56 volumio volumio[8294]: info: Asound.conf file unchanged, so no further update is needed Mar 01 22:33:56 volumio volumio[8294]: info: Output device has changed, restarting MPD Mar 01 22:33:56 volumio sudo[12323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 01 22:33:56 volumio sudo[12323]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 22:33:56 volumio volumio[8294]: info: Output device has changed, restarting Shairport Sync Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 22:33:56 volumio sudo[12323]: pam_unix(sudo:session): session closed for user root Mar 01 22:33:56 volumio sudo[12326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 01 22:33:56 volumio sudo[12326]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 22:33:56 volumio systemd[1]: Stopping Music Player Daemon... Mar 01 22:33:56 volumio volumio[8294]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 01 22:33:56 volumio volumio[8294]: info: MPD Permissions set Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 22:33:56 volumio systemd[1]: mpd.service: Succeeded. Mar 01 22:33:56 volumio systemd[1]: Stopped Music Player Daemon. Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 01 22:33:56 volumio systemd[1]: Starting Music Player Daemon... Mar 01 22:33:56 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 01 22:33:56 volumio volumio[8294]: info: Starting Shairport Sync Mar 01 22:33:56 volumio sudo[12340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 01 22:33:56 volumio sudo[12340]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 22:33:56 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 01 22:33:57 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 01 22:33:57 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 01 22:33:57 volumio sudo[12332]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 01 22:33:57 volumio sudo[12332]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 01 22:33:57 volumio sudo[12332]: pam_unix(sudo:session): session closed for user root Mar 01 22:33:57 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 01 22:33:57 volumio sudo[12340]: pam_unix(sudo:session): session closed for user root Mar 01 22:33:57 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Mar 01 22:33:57 volumio volumio[8294]: info: Shairport-Sync Started Mar 01 22:33:57 volumio mpd[12344]: Mar 01 22:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 01 22:33:57 volumio systemd[1]: Started Music Player Daemon. Mar 01 22:33:57 volumio sudo[12326]: pam_unix(sudo:session): session closed for user root Mar 01 22:33:57 volumio volumio[8294]: error: updateQueue error: null Mar 01 22:34:00 volumio volumio[8294]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 01 22:34:00 volumio volumio[8294]: info: CoreStateMachine::getcurrentVolume Mar 01 22:34:00 volumio volumio[8294]: info: CoreCommandRouter::volumioRetrievevolume Mar 01 22:34:00 volumio volumio[8294]: info: VolumeController:: Volume=46 Mute =false Mar 01 22:34:00 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:00 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:00 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:00 volumio volumio[8294]: info: CoreStateMachine::updateTrackBlock Mar 01 22:34:00 volumio volumio[8294]: info: CorePlayQueue::getTrackBlock Mar 01 22:34:00 volumio volumio[8294]: info: CoreCommandRouter::volumioRetrievevolume Mar 01 22:34:00 volumio volumio[8294]: info: VolumeController:: Volume=46 Mute =false Mar 01 22:34:00 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:00 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:00 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:04 volumio volumio[8294]: info: VolumeController::SetAlsaVolume37 Mar 01 22:34:04 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:04 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:04 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:34:04 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:04 volumio volumio[8294]: info: VolumeController::SetAlsaVolume8 Mar 01 22:34:04 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:04 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:04 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:34:04 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:06 volumio volumio[8294]: info: CoreCommandRouter::volumioPause Mar 01 22:34:06 volumio volumio[8294]: info: CoreStateMachine::pause Mar 01 22:34:06 volumio volumio[8294]: info: CoreStateMachine::stPlaybackTimer Mar 01 22:34:06 volumio volumio[8294]: info: CoreStateMachine::servicePause Mar 01 22:34:06 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:06 volumio volumio[8294]: info: CoreCommandRouter::servicePause Mar 01 22:34:06 volumio volumio[8294]: info: ControllerMpd::pause Mar 01 22:34:06 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand pause Mar 01 22:34:06 volumio volumio[8294]: info: sendMpdCommand pause took 2 milliseconds Mar 01 22:34:07 volumio volumio[8294]: info: CoreCommandRouter::volumioPause Mar 01 22:34:07 volumio volumio[8294]: info: CoreStateMachine::pause Mar 01 22:34:08 volumio volumio[8294]: info: CoreCommandRouter::volumioPrevious Mar 01 22:34:08 volumio volumio[8294]: info: CoreStateMachine::previous Mar 01 22:34:08 volumio volumio[8294]: info: CoreStateMachine::updateTrackBlock Mar 01 22:34:08 volumio volumio[8294]: info: CorePlayQueue::getTrackBlock Mar 01 22:34:08 volumio volumio[8294]: info: CoreStateMachine::serviceClearAddPlay Mar 01 22:34:08 volumio volumio[8294]: info: CoreCommandRouter::serviceClearAddPlayTracks Mar 01 22:34:08 volumio volumio[8294]: error: WARNING: No clearAddPlayTracks method for service mpd Mar 01 22:34:09 volumio volumio[8294]: info: CoreCommandRouter::volumioNext Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::next Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::stop Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::updateTrackBlock Mar 01 22:34:09 volumio volumio[8294]: info: CorePlayQueue::getTrackBlock Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::stPlaybackTimer Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:09 volumio volumio[8294]: info: CorePlayQueue::getTrack 5 Mar 01 22:34:09 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:34:09 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::serviceStop Mar 01 22:34:09 volumio volumio[8294]: info: CorePlayQueue::getTrack 5 Mar 01 22:34:09 volumio volumio[8294]: info: CoreCommandRouter::serviceStop Mar 01 22:34:09 volumio volumio[8294]: info: ControllerMpd::stop Mar 01 22:34:09 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 22:34:09 volumio volumio[8294]: info: sendMpdCommand stop took 15 milliseconds Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::play index undefined Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 01 22:34:09 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::startPlaybackTimer Mar 01 22:34:09 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:09 volumio volumio[8294]: verbose: ControllerMpd::clearAddPlayTracks NAS/nas/Indochine - 13/07 - Indochine - Karma Girls.mp3 Mar 01 22:34:09 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand stop Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::updateTrackBlock Mar 01 22:34:09 volumio volumio[8294]: info: CorePlayQueue::getTrackBlock Mar 01 22:34:09 volumio volumio[8294]: info: sendMpdCommand stop took 3 milliseconds Mar 01 22:34:09 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand clear Mar 01 22:34:09 volumio volumio[8294]: info: Mar 01 22:34:09 volumio volumio[8294]: ---------------------------- MPD announces system playlist update Mar 01 22:34:09 volumio volumio[8294]: info: Ignoring MPD Status Update Mar 01 22:34:09 volumio volumio[8294]: info: sendMpdCommand clear took 2 milliseconds Mar 01 22:34:09 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand add "NAS/nas/Indochine - 13/07 - Indochine - Karma Girls.mp3" Mar 01 22:34:09 volumio volumio[8294]: error: updateQueue error: null Mar 01 22:34:09 volumio volumio[8294]: info: Mar 01 22:34:09 volumio volumio[8294]: ---------------------------- MPD announces system playlist update Mar 01 22:34:09 volumio volumio[8294]: info: Ignoring MPD Status Update Mar 01 22:34:09 volumio volumio[8294]: info: ------------------------------ 6ms Mar 01 22:34:09 volumio volumio[8294]: info: sendMpdCommand add "NAS/nas/Indochine - 13/07 - Indochine - Karma Girls.mp3" took 3 milliseconds Mar 01 22:34:09 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand play Mar 01 22:34:09 volumio volumio[8294]: info: ------------------------------ 6ms Mar 01 22:34:09 volumio volumio[8294]: info: sendMpdCommand play took 4 milliseconds Mar 01 22:34:09 volumio volumio[8294]: info: Mar 01 22:34:09 volumio volumio[8294]: ---------------------------- MPD announces state update: player Mar 01 22:34:09 volumio volumio[8294]: info: ControllerMpd::getState Mar 01 22:34:09 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand status Mar 01 22:34:09 volumio volumio[8294]: info: Mar 01 22:34:09 volumio volumio[8294]: ---------------------------- MPD announces state update: player Mar 01 22:34:09 volumio volumio[8294]: info: sendMpdCommand status took 3 milliseconds Mar 01 22:34:09 volumio volumio[8294]: info: ControllerMpd::getState Mar 01 22:34:09 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand status Mar 01 22:34:09 volumio volumio[8294]: verbose: ControllerMpd::parseState Mar 01 22:34:09 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 22:34:09 volumio volumio[8294]: info: sendMpdCommand status took 2 milliseconds Mar 01 22:34:09 volumio volumio[8294]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 01 22:34:09 volumio volumio[8294]: verbose: ControllerMpd::parseState Mar 01 22:34:09 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 22:34:09 volumio volumio[8294]: verbose: ControllerMpd::parseTrackInfo Mar 01 22:34:09 volumio volumio[8294]: info: ControllerMpd::pushState Mar 01 22:34:09 volumio volumio[8294]: info: CoreCommandRouter::servicePushState Mar 01 22:34:09 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:09 volumio volumio[8294]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":393,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Karma Girls","artist":"Indochine","album":"13","uri":"NAS/nas/Indochine - 13/07 - Indochine - Karma Girls.mp3","trackType":"mp3"} Mar 01 22:34:09 volumio volumio[8294]: verbose: CURRENT POSITION 6 Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::syncState stateService play Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::syncState currentStatus stop Mar 01 22:34:09 volumio volumio[8294]: info: ------------------------------ 10ms Mar 01 22:34:09 volumio volumio[8294]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 01 22:34:09 volumio volumio[8294]: verbose: ControllerMpd::parseTrackInfo Mar 01 22:34:09 volumio volumio[8294]: info: ControllerMpd::pushState Mar 01 22:34:09 volumio volumio[8294]: info: CoreCommandRouter::servicePushState Mar 01 22:34:09 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:09 volumio volumio[8294]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":393,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Karma Girls","artist":"Indochine","album":"13","uri":"NAS/nas/Indochine - 13/07 - Indochine - Karma Girls.mp3","trackType":"mp3"} Mar 01 22:34:09 volumio volumio[8294]: verbose: CURRENT POSITION 6 Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::syncState stateService play Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::syncState currentStatus play Mar 01 22:34:09 volumio volumio[8294]: info: Received an update from plugin. extracting info from payload Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:09 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:09 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:34:09 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:09 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:09 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:09 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:09 volumio volumio[8294]: info: ------------------------------ 30ms Mar 01 22:34:12 volumio volumio[8294]: info: VolumeController::SetAlsaVolume13 Mar 01 22:34:12 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:12 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:12 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:34:12 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:13 volumio volumio[8294]: info: VolumeController::SetAlsaVolume5 Mar 01 22:34:13 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:13 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:13 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:34:13 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:13 volumio volumio[8294]: info: VolumeController::SetAlsaVolume1 Mar 01 22:34:13 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:13 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:13 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:34:13 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:14 volumio volumio[8294]: info: VolumeController::SetAlsaVolume2 Mar 01 22:34:14 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:14 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:14 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:34:14 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:14 volumio volumio[8294]: info: VolumeController::SetAlsaVolume3 Mar 01 22:34:14 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:14 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:14 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:34:14 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:14 volumio volumio[8294]: info: VolumeController::SetAlsaVolume4 Mar 01 22:34:14 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:14 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:14 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:34:14 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:14 volumio volumio[8294]: info: VolumeController::SetAlsaVolume5 Mar 01 22:34:14 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:14 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:14 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:34:14 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:16 volumio volumio[8294]: info: CoreCommandRouter::volumioSeek Mar 01 22:34:16 volumio volumio[8294]: info: CoreStateMachine::seek Mar 01 22:34:16 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:16 volumio volumio[8294]: info: TRACKBLOCK {"uri":"mnt/NAS/nas/Indochine - 13/07 - Indochine - Karma Girls.mp3","service":"mpd","name":"Karma Girls","artist":"Indochine","album":"13","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=292&web=Indochine/13/extralarge&path=%2FNAS%2Fnas%2FIndochine%20-%2013&metadata=false","duration":393,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Mar 01 22:34:16 volumio volumio[8294]: info: CoreStateMachine::startPlaybackTimer Mar 01 22:34:16 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:16 volumio volumio[8294]: info: ControllerMpd::seek Mar 01 22:34:16 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:16 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:16 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:34:16 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:16 volumio volumio[8294]: error: null Mar 01 22:34:16 volumio volumio[8294]: info: Mar 01 22:34:16 volumio volumio[8294]: ---------------------------- MPD announces state update: player Mar 01 22:34:16 volumio volumio[8294]: info: ControllerMpd::getState Mar 01 22:34:16 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand status Mar 01 22:34:16 volumio volumio[8294]: info: sendMpdCommand status took 2 milliseconds Mar 01 22:34:17 volumio volumio[8294]: verbose: ControllerMpd::parseState Mar 01 22:34:17 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 22:34:17 volumio volumio[8294]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 01 22:34:17 volumio volumio[8294]: verbose: ControllerMpd::parseTrackInfo Mar 01 22:34:17 volumio volumio[8294]: info: ControllerMpd::pushState Mar 01 22:34:17 volumio volumio[8294]: info: CoreCommandRouter::servicePushState Mar 01 22:34:17 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:17 volumio volumio[8294]: verbose: STATE SERVICE {"status":"play","position":0,"seek":173000,"duration":393,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Karma Girls","artist":"Indochine","album":"13","uri":"NAS/nas/Indochine - 13/07 - Indochine - Karma Girls.mp3","trackType":"mp3"} Mar 01 22:34:17 volumio volumio[8294]: verbose: CURRENT POSITION 6 Mar 01 22:34:17 volumio volumio[8294]: info: CoreStateMachine::syncState stateService play Mar 01 22:34:17 volumio volumio[8294]: info: CoreStateMachine::syncState currentStatus play Mar 01 22:34:17 volumio volumio[8294]: info: Received an update from plugin. extracting info from payload Mar 01 22:34:17 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:17 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:17 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:17 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:17 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:17 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:17 volumio volumio[8294]: info: ------------------------------ 22ms Mar 01 22:34:20 volumio volumio[8294]: info: CoreCommandRouter::volumioSeek Mar 01 22:34:20 volumio volumio[8294]: info: CoreStateMachine::seek Mar 01 22:34:20 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:20 volumio volumio[8294]: info: TRACKBLOCK {"uri":"mnt/NAS/nas/Indochine - 13/07 - Indochine - Karma Girls.mp3","service":"mpd","name":"Karma Girls","artist":"Indochine","album":"13","type":"track","tracknumber":0,"albumart":"/albumart?cacheid=292&web=Indochine/13/extralarge&path=%2FNAS%2Fnas%2FIndochine%20-%2013&metadata=false","duration":393,"samplerate":"44.1 kHz","bitdepth":"24 bit","trackType":"mp3","channels":2} Mar 01 22:34:20 volumio volumio[8294]: info: CoreStateMachine::startPlaybackTimer Mar 01 22:34:20 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:20 volumio volumio[8294]: info: ControllerMpd::seek Mar 01 22:34:20 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:20 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:20 volumio volumio[8294]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 01 22:34:20 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:20 volumio volumio[8294]: error: null Mar 01 22:34:20 volumio volumio[8294]: info: Mar 01 22:34:20 volumio volumio[8294]: ---------------------------- MPD announces state update: player Mar 01 22:34:20 volumio volumio[8294]: info: ControllerMpd::getState Mar 01 22:34:20 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand status Mar 01 22:34:20 volumio volumio[8294]: info: sendMpdCommand status took 4 milliseconds Mar 01 22:34:20 volumio volumio[8294]: verbose: ControllerMpd::parseState Mar 01 22:34:20 volumio volumio[8294]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 01 22:34:20 volumio volumio[8294]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 01 22:34:20 volumio volumio[8294]: verbose: ControllerMpd::parseTrackInfo Mar 01 22:34:20 volumio volumio[8294]: info: ControllerMpd::pushState Mar 01 22:34:20 volumio volumio[8294]: info: CoreCommandRouter::servicePushState Mar 01 22:34:20 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:20 volumio volumio[8294]: verbose: STATE SERVICE {"status":"play","position":0,"seek":240000,"duration":393,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Karma Girls","artist":"Indochine","album":"13","uri":"NAS/nas/Indochine - 13/07 - Indochine - Karma Girls.mp3","trackType":"mp3"} Mar 01 22:34:20 volumio volumio[8294]: verbose: CURRENT POSITION 6 Mar 01 22:34:20 volumio volumio[8294]: info: CoreStateMachine::syncState stateService play Mar 01 22:34:20 volumio volumio[8294]: info: CoreStateMachine::syncState currentStatus play Mar 01 22:34:20 volumio volumio[8294]: info: Received an update from plugin. extracting info from payload Mar 01 22:34:20 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:20 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:20 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:20 volumio volumio[8294]: info: CoreStateMachine::pushState Mar 01 22:34:20 volumio volumio[8294]: info: CorePlayQueue::getTrack 6 Mar 01 22:34:20 volumio volumio[8294]: info: CoreCommandRouter::volumioPushState Mar 01 22:34:20 volumio volumio[8294]: info: ------------------------------ 42ms Mar 01 22:34:21 volumio volumio[8294]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 22:34:21 volumio volumio[8294]: Error: connect ETIMEDOUT 104.23.124.189:80 Mar 01 22:34:21 volumio volumio[8294]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 01 22:34:21 volumio volumio[8294]: errno: -110, Mar 01 22:34:21 volumio volumio[8294]: code: 'ETIMEDOUT', Mar 01 22:34:21 volumio volumio[8294]: syscall: 'connect', Mar 01 22:34:21 volumio volumio[8294]: address: '104.23.124.189', Mar 01 22:34:21 volumio volumio[8294]: port: 80 Mar 01 22:34:21 volumio volumio[8294]: } Mar 01 22:34:21 volumio volumio[8294]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 01 22:34:21 volumio sudo[12415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-01 22:33 Mar 01 22:34:21 volumio sudo[12415]: 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"