-- Logs begin at Thu 2020-09-24 20:42:43 UTC, end at Tue 2024-08-20 07:07:47 UTC. -- Aug 20 07:06:31 volumio kernel: usb 1-1.2: USB disconnect, device number 3 Aug 20 07:06:31 volumio kernel: usb 1-1.2: cannot submit urb (err = -19) Aug 20 07:06:31 volumio volumio[3114]: info: Aug 20 07:06:31 volumio volumio[3114]: ---------------------------- MPD announces state update: player Aug 20 07:06:31 volumio volumio[3114]: info: ControllerMpd::getState Aug 20 07:06:31 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand status Aug 20 07:06:31 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 20 07:06:31 volumio volumio[3114]: verbose: ControllerMpd::parseState Aug 20 07:06:31 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 07:06:31 volumio volumio[3114]: verbose: ControllerMpd::parseTrackInfo Aug 20 07:06:31 volumio volumio[3114]: info: ControllerMpd::pushState Aug 20 07:06:31 volumio volumio[3114]: info: CoreCommandRouter::servicePushState Aug 20 07:06:31 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:06:31 volumio volumio[3114]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":59112,"duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"マイ・ウェイ(ブレリア)【DSD】","artist":"沖 仁","album":"Concierto [コンシエルト] ～魂祭～【DSD】","uri":"USB/66C3-C16B/沖 仁-Concierto [コンシエルト] ～魂祭～【DSD-[Hi-Res]-DSD64/10. マイ・ウェイ(ブレリア)【DSD】.dsf","trackType":"dsf"} Aug 20 07:06:31 volumio volumio[3114]: verbose: CURRENT POSITION 0 Aug 20 07:06:31 volumio volumio[3114]: info: CoreStateMachine::syncState stateService pause Aug 20 07:06:31 volumio volumio[3114]: info: CoreStateMachine::syncState currentStatus play Aug 20 07:06:31 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:06:31 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:06:31 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:06:31 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:06:31 volumio volumio[3114]: info: ------------------------------ 16ms Aug 20 07:06:31 volumio volumio[3114]: info: Aug 20 07:06:31 volumio volumio[3114]: ---------------------------- USB Audio Device Detached Aug 20 07:06:31 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Aug 20 07:06:31 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 20 07:06:31 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 20 07:06:31 volumio kernel: usb 1-1.2: new high-speed USB device number 7 using xhci_hcd Aug 20 07:06:31 volumio kernel: usb 1-1.2: New USB device found, idVendor=20b1, idProduct=3023, bcdDevice= 6.60 Aug 20 07:06:31 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Aug 20 07:06:31 volumio kernel: usb 1-1.2: Product: X1S USB DAC Aug 20 07:06:31 volumio kernel: usb 1-1.2: Manufacturer: aune Aug 20 07:06:32 volumio kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Aug 20 07:06:32 volumio kernel: input: aune X1S USB DAC as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2/1-1.2:1.3/0003:20B1:3023.0002/input/input1 Aug 20 07:06:32 volumio kernel: hid-generic 0003:20B1:3023.0002: input,hidraw0: USB HID v1.10 Device [aune X1S USB DAC] on usb-0000:01:00.0-1.2/input3 Aug 20 07:06:32 volumio volumio[3114]: info: Aug 20 07:06:32 volumio volumio[3114]: ---------------------------- USB Audio Device Attached Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::Close All Modals sent Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 20 07:06:32 volumio volumio[3114]: info: Output device has changed, restarting MPD Aug 20 07:06:32 volumio sudo[9932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 20 07:06:32 volumio volumio[3114]: info: Output device has changed, restarting Shairport Sync Aug 20 07:06:32 volumio sudo[9932]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 07:06:32 volumio sudo[9935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 20 07:06:32 volumio sudo[9932]: pam_unix(sudo:session): session closed for user root Aug 20 07:06:32 volumio sudo[9935]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 07:06:32 volumio systemd[1]: Stopping Music Player Daemon... Aug 20 07:06:32 volumio volumio[3114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 20 07:06:32 volumio volumio[3114]: info: Setting mixer X1S USB DAC Output for card X1S USB DAC Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 20 07:06:32 volumio volumio[3114]: info: Updating Volume Controller Parameters: Device: 5 Name: Mixer: X1S USB DAC Output Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 20 07:06:32 volumio systemd[1]: Starting Music Player Daemon... Aug 20 07:06:32 volumio volumio[3114]: info: MPD Permissions set Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 20 07:06:32 volumio volumio[3114]: info: VolumeController:: Volume=100 Mute =false Aug 20 07:06:32 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:06:32 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:06:32 volumio volumio[3114]: info: Starting Shairport Sync Aug 20 07:06:32 volumio sudo[9969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 20 07:06:32 volumio sudo[9969]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 07:06:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 20 07:06:32 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 20 07:06:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 20 07:06:32 volumio sudo[9969]: pam_unix(sudo:session): session closed for user root Aug 20 07:06:32 volumio volumio[3114]: info: Shairport-Sync Started Aug 20 07:06:32 volumio systemd[1]: Started Music Player Daemon. Aug 20 07:06:32 volumio sudo[9935]: pam_unix(sudo:session): session closed for user root Aug 20 07:06:32 volumio volumio[3114]: error: updateQueue error: null Aug 20 07:06:32 volumio volumio[3114]: error: Upnp client error: Error: This socket has been ended by the other party Aug 20 07:06:32 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Aug 20 07:06:34 volumio volumio[3114]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 20 07:06:34 volumio volumio[3114]: info: CoreStateMachine::getcurrentVolume Aug 20 07:06:34 volumio volumio[3114]: info: CoreCommandRouter::volumioRetrievevolume Aug 20 07:06:34 volumio volumio[3114]: info: VolumeController:: Volume=100 Mute =false Aug 20 07:06:34 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:06:34 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:06:34 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:06:34 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:06:34 volumio volumio[3114]: info: CoreStateMachine::updateTrackBlock Aug 20 07:06:34 volumio volumio[3114]: info: CorePlayQueue::getTrackBlock Aug 20 07:06:34 volumio volumio[3114]: info: CoreCommandRouter::volumioRetrievevolume Aug 20 07:06:34 volumio volumio[3114]: info: VolumeController:: Volume=100 Mute =false Aug 20 07:06:34 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:06:34 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:06:34 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:06:34 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::ClearQueue Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::stop Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 07:06:40 volumio volumio[3114]: UNSET VOLATILE Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::stPlaybackTimer Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::updateTrackBlock Aug 20 07:06:40 volumio volumio[3114]: info: CorePlayQueue::getTrackBlock Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:06:40 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::serviceStop Aug 20 07:06:40 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::serviceStop Aug 20 07:06:40 volumio volumio[3114]: info: ControllerMpd::stop Aug 20 07:06:40 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 07:06:40 volumio volumio[3114]: info: CorePlayQueue::clearPlayQueue Aug 20 07:06:40 volumio volumio[3114]: info: CorePlayQueue::saveQueue Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::volumioPushQueue Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::addQueueItems Aug 20 07:06:40 volumio volumio[3114]: info: CorePlayQueue::addQueueItems Aug 20 07:06:40 volumio volumio[3114]: info: Adding Item to queue: albums://%E6%B2%96%20%E4%BB%81/Concierto%20%5B%E3%82%B3%E3%83%B3%E3%82%B7%E3%82%A8%E3%83%AB%E3%83%88%5D%20%EF%BD%9E%E9%AD%82%E7%A5%AD%EF%BD%9E%E3%80%90DSD%E3%80%91 Aug 20 07:06:40 volumio volumio[3114]: info: Exploding uri albums://%E6%B2%96%20%E4%BB%81/Concierto%20%5B%E3%82%B3%E3%83%B3%E3%82%B7%E3%82%A8%E3%83%AB%E3%83%88%5D%20%EF%BD%9E%E9%AD%82%E7%A5%AD%EF%BD%9E%E3%80%90DSD%E3%80%91 in service mpd Aug 20 07:06:40 volumio volumio[3114]: info: CorePlayQueue::saveQueue Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::volumioPushQueue Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::updateTrackBlock Aug 20 07:06:40 volumio volumio[3114]: info: CorePlayQueue::getTrackBlock Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::volumioPlay Aug 20 07:06:40 volumio volumio[3114]: UNSET VOLATILE Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::play index 0 Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::stop Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 07:06:40 volumio volumio[3114]: UNSET VOLATILE Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::play index undefined Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 07:06:40 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::startPlaybackTimer Aug 20 07:06:40 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:06:40 volumio volumio[3114]: verbose: ControllerMpd::clearAddPlayTracks USB/66C3-C16B/沖 仁-Concierto [コンシエルト] ～魂祭～【DSD-[Hi-Res]-DSD64/10. マイ・ウェイ(ブレリア)【DSD】.dsf Aug 20 07:06:40 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 07:06:40 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand clear Aug 20 07:06:40 volumio volumio[3114]: info: Aug 20 07:06:40 volumio volumio[3114]: ---------------------------- MPD announces system playlist update Aug 20 07:06:40 volumio volumio[3114]: info: Ignoring MPD Status Update Aug 20 07:06:40 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand add "USB/66C3-C16B/沖 仁-Concierto [コンシエルト] ～魂祭～【DSD-[Hi-Res]-DSD64/10. マイ・ウェイ(ブレリア)【DSD】.dsf" Aug 20 07:06:40 volumio volumio[3114]: error: updateQueue error: null Aug 20 07:06:40 volumio volumio[3114]: info: Aug 20 07:06:40 volumio volumio[3114]: ---------------------------- MPD announces system playlist update Aug 20 07:06:40 volumio volumio[3114]: info: Ignoring MPD Status Update Aug 20 07:06:40 volumio volumio[3114]: info: ------------------------------ 3ms Aug 20 07:06:40 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand play Aug 20 07:06:40 volumio volumio[3114]: info: ------------------------------ 2ms Aug 20 07:06:40 volumio volumio[3114]: info: Aug 20 07:06:40 volumio volumio[3114]: ---------------------------- MPD announces state update: player Aug 20 07:06:40 volumio volumio[3114]: info: ControllerMpd::getState Aug 20 07:06:40 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand status Aug 20 07:06:40 volumio volumio[3114]: info: Aug 20 07:06:40 volumio volumio[3114]: ---------------------------- MPD announces state update: player Aug 20 07:06:40 volumio volumio[3114]: info: ControllerMpd::getState Aug 20 07:06:40 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand status Aug 20 07:06:40 volumio volumio[3114]: verbose: ControllerMpd::parseState Aug 20 07:06:40 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 07:06:40 volumio volumio[3114]: verbose: ControllerMpd::parseState Aug 20 07:06:40 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 07:06:40 volumio volumio[3114]: verbose: ControllerMpd::parseTrackInfo Aug 20 07:06:40 volumio volumio[3114]: info: ControllerMpd::pushState Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::servicePushState Aug 20 07:06:40 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:06:40 volumio volumio[3114]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"マイ・ウェイ(ブレリア)【DSD】","artist":"沖 仁","album":"Concierto [コンシエルト] ～魂祭～【DSD】","uri":"USB/66C3-C16B/沖 仁-Concierto [コンシエルト] ～魂祭～【DSD-[Hi-Res]-DSD64/10. マイ・ウェイ(ブレリア)【DSD】.dsf","trackType":"dsf"} Aug 20 07:06:40 volumio volumio[3114]: verbose: CURRENT POSITION 0 Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::syncState stateService play Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::syncState currentStatus stop Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:06:40 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:06:40 volumio volumio[3114]: info: ------------------------------ 41ms Aug 20 07:06:40 volumio volumio[3114]: verbose: ControllerMpd::parseTrackInfo Aug 20 07:06:40 volumio volumio[3114]: info: ControllerMpd::pushState Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::servicePushState Aug 20 07:06:40 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:06:40 volumio volumio[3114]: verbose: STATE SERVICE {"status":"play","position":0,"seek":493,"duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"マイ・ウェイ(ブレリア)【DSD】","artist":"沖 仁","album":"Concierto [コンシエルト] ～魂祭～【DSD】","uri":"USB/66C3-C16B/沖 仁-Concierto [コンシエルト] ～魂祭～【DSD-[Hi-Res]-DSD64/10. マイ・ウェイ(ブレリア)【DSD】.dsf","trackType":"dsf"} Aug 20 07:06:40 volumio volumio[3114]: verbose: CURRENT POSITION 0 Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::syncState stateService play Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::syncState currentStatus play Aug 20 07:06:40 volumio volumio[3114]: info: Received an update from plugin. extracting info from payload Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:06:40 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:06:40 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:06:40 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:06:40 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:06:40 volumio volumio[3114]: info: ------------------------------ 16ms Aug 20 07:06:46 volumio volumio[3114]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 20 07:06:46 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 20 07:06:46 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 20 07:06:46 volumio sudo[10004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 20 07:06:46 volumio sudo[10004]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 07:06:46 volumio sudo[10010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 20 07:06:46 volumio sudo[10004]: pam_unix(sudo:session): session closed for user root Aug 20 07:06:46 volumio sudo[10010]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 07:06:46 volumio sudo[10021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 20 07:06:46 volumio sudo[10010]: pam_unix(sudo:session): session closed for user root Aug 20 07:06:46 volumio sudo[10029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 20 07:06:46 volumio sudo[10021]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 07:06:46 volumio sudo[10029]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 07:06:46 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 20 07:06:46 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 20 07:06:46 volumio sudo[10044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 20 07:06:46 volumio sudo[10021]: pam_unix(sudo:session): session closed for user root Aug 20 07:06:46 volumio sudo[10029]: pam_unix(sudo:session): session closed for user root Aug 20 07:06:46 volumio sudo[10044]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 07:06:46 volumio sudo[10049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 20 07:06:46 volumio sudo[10049]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 07:06:46 volumio sudo[10044]: pam_unix(sudo:session): session closed for user root Aug 20 07:06:46 volumio sudo[10065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 20 07:06:46 volumio sudo[10049]: pam_unix(sudo:session): session closed for user root Aug 20 07:06:46 volumio sudo[10065]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 07:06:47 volumio sudo[10065]: pam_unix(sudo:session): session closed for user root Aug 20 07:06:48 volumio volumio[3114]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 20 07:06:48 volumio volumio[3114]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 20 07:06:48 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 20 07:06:48 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 20 07:06:48 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 20 07:06:48 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 20 07:06:48 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:06:48 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:06:48 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:06:48 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:06:48 volumio volumio[3114]: info: CoreCommandRouter::volumioGetBrowseSources Aug 20 07:06:48 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 07:06:48 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 20 07:06:52 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 20 07:06:53 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 07:06:53 volumio volumio[3114]: info: CURURI: music-library Aug 20 07:06:58 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Aug 20 07:06:58 volumio volumio[3114]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/ELISA/EONIAN%20-%E3%82%A4%E3%82%AA%E3%83%8B%E3%82%A2%E3%83%B3-/27fae7cf-77d8-453c-92d9-7235be6d998c.png' Aug 20 07:06:58 volumio volumio[3114]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%AE%87%E5%A4%9A%E7%94%B0%E3%83%92%E3%82%AB%E3%83%AB/%E5%88%9D%E6%81%8B/8670fd5e-574b-4eac-80dd-9c5dd2543448.png' Aug 20 07:07:00 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 07:07:00 volumio volumio[3114]: info: CURURI: albums:// Aug 20 07:07:00 volumio volumio[3114]: listAlbums - loading Albums from cache Aug 20 07:07:12 volumio volumio[3114]: error: Cannot download Available plugins list: Error: ETIMEDOUT Aug 20 07:07:16 volumio volumio[3114]: info: Aug 20 07:07:16 volumio volumio[3114]: ---------------------------- MPD announces state update: player Aug 20 07:07:16 volumio kernel: usb 1-1.2: USB disconnect, device number 7 Aug 20 07:07:16 volumio kernel: usb 1-1.2: cannot submit urb (err = -19) Aug 20 07:07:16 volumio volumio[3114]: info: ControllerMpd::getState Aug 20 07:07:16 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand status Aug 20 07:07:16 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 20 07:07:16 volumio volumio[3114]: verbose: ControllerMpd::parseState Aug 20 07:07:16 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 07:07:16 volumio volumio[3114]: verbose: ControllerMpd::parseTrackInfo Aug 20 07:07:16 volumio volumio[3114]: info: ControllerMpd::pushState Aug 20 07:07:16 volumio volumio[3114]: info: CoreCommandRouter::servicePushState Aug 20 07:07:16 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:16 volumio volumio[3114]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":36681,"duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"マイ・ウェイ(ブレリア)【DSD】","artist":"沖 仁","album":"Concierto [コンシエルト] ～魂祭～【DSD】","uri":"USB/66C3-C16B/沖 仁-Concierto [コンシエルト] ～魂祭～【DSD-[Hi-Res]-DSD64/10. マイ・ウェイ(ブレリア)【DSD】.dsf","trackType":"dsf"} Aug 20 07:07:16 volumio volumio[3114]: verbose: CURRENT POSITION 0 Aug 20 07:07:16 volumio volumio[3114]: info: CoreStateMachine::syncState stateService pause Aug 20 07:07:16 volumio volumio[3114]: info: CoreStateMachine::syncState currentStatus play Aug 20 07:07:16 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:07:16 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:16 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:07:16 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:07:16 volumio volumio[3114]: info: ------------------------------ 13ms Aug 20 07:07:16 volumio volumio[3114]: info: Aug 20 07:07:16 volumio volumio[3114]: ---------------------------- USB Audio Device Detached Aug 20 07:07:16 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Aug 20 07:07:16 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 20 07:07:16 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 20 07:07:16 volumio kernel: usb 1-1.2: new high-speed USB device number 8 using xhci_hcd Aug 20 07:07:17 volumio kernel: usb 1-1.2: New USB device found, idVendor=20b1, idProduct=3023, bcdDevice= 6.60 Aug 20 07:07:17 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Aug 20 07:07:17 volumio kernel: usb 1-1.2: Product: X1S USB DAC Aug 20 07:07:17 volumio kernel: usb 1-1.2: Manufacturer: aune Aug 20 07:07:17 volumio kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Aug 20 07:07:17 volumio kernel: input: aune X1S USB DAC as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2/1-1.2:1.3/0003:20B1:3023.0003/input/input2 Aug 20 07:07:17 volumio kernel: hid-generic 0003:20B1:3023.0003: input,hidraw0: USB HID v1.10 Device [aune X1S USB DAC] on usb-0000:01:00.0-1.2/input3 Aug 20 07:07:17 volumio volumio[3114]: info: Aug 20 07:07:17 volumio volumio[3114]: ---------------------------- USB Audio Device Attached Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::Close All Modals sent Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 20 07:07:17 volumio volumio[3114]: info: Output device has changed, restarting MPD Aug 20 07:07:17 volumio sudo[10148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 20 07:07:17 volumio volumio[3114]: info: Output device has changed, restarting Shairport Sync Aug 20 07:07:17 volumio sudo[10148]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 07:07:17 volumio sudo[10148]: pam_unix(sudo:session): session closed for user root Aug 20 07:07:17 volumio sudo[10151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 20 07:07:17 volumio sudo[10151]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 07:07:17 volumio systemd[1]: Stopping Music Player Daemon... Aug 20 07:07:17 volumio volumio[3114]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 20 07:07:17 volumio volumio[3114]: info: Setting mixer X1S USB DAC Output for card X1S USB DAC Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 20 07:07:17 volumio volumio[3114]: info: Updating Volume Controller Parameters: Device: 5 Name: Mixer: X1S USB DAC Output Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Aug 20 07:07:17 volumio systemd[1]: Starting Music Player Daemon... Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 20 07:07:17 volumio volumio[3114]: info: MPD Permissions set Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 20 07:07:17 volumio volumio[3114]: info: Starting Shairport Sync Aug 20 07:07:17 volumio volumio[3114]: info: VolumeController:: Volume=100 Mute =false Aug 20 07:07:17 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:07:17 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:07:17 volumio sudo[10184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 20 07:07:17 volumio sudo[10184]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 20 07:07:17 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 20 07:07:17 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Aug 20 07:07:17 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 20 07:07:17 volumio sudo[10184]: pam_unix(sudo:session): session closed for user root Aug 20 07:07:17 volumio volumio[3114]: info: Shairport-Sync Started Aug 20 07:07:17 volumio systemd[1]: Started Music Player Daemon. Aug 20 07:07:17 volumio sudo[10151]: pam_unix(sudo:session): session closed for user root Aug 20 07:07:17 volumio volumio[3114]: error: updateQueue error: null Aug 20 07:07:17 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Aug 20 07:07:18 volumio volumio[3114]: error: Upnp client error: Error: This socket has been ended by the other party Aug 20 07:07:19 volumio volumio[3114]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 20 07:07:19 volumio volumio[3114]: info: CoreStateMachine::getcurrentVolume Aug 20 07:07:19 volumio volumio[3114]: info: CoreCommandRouter::volumioRetrievevolume Aug 20 07:07:19 volumio volumio[3114]: info: VolumeController:: Volume=100 Mute =false Aug 20 07:07:19 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:07:19 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:19 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:07:19 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:07:19 volumio volumio[3114]: info: CoreStateMachine::updateTrackBlock Aug 20 07:07:19 volumio volumio[3114]: info: CorePlayQueue::getTrackBlock Aug 20 07:07:19 volumio volumio[3114]: info: CoreCommandRouter::volumioRetrievevolume Aug 20 07:07:20 volumio volumio[3114]: info: VolumeController:: Volume=100 Mute =false Aug 20 07:07:20 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:07:20 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:20 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:07:20 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:07:24 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 07:07:24 volumio volumio[3114]: info: CURURI: music-library Aug 20 07:07:25 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 07:07:25 volumio volumio[3114]: info: CURURI: music-library/USB Aug 20 07:07:27 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 07:07:27 volumio volumio[3114]: info: CURURI: music-library Aug 20 07:07:30 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 20 07:07:30 volumio volumio[3114]: info: CURURI: albums:// Aug 20 07:07:30 volumio volumio[3114]: listAlbums - loading Albums from cache Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::ClearQueue Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::stop Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 07:07:31 volumio volumio[3114]: UNSET VOLATILE Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::stPlaybackTimer Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::updateTrackBlock Aug 20 07:07:31 volumio volumio[3114]: info: CorePlayQueue::getTrackBlock Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:07:31 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::serviceStop Aug 20 07:07:31 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::serviceStop Aug 20 07:07:31 volumio volumio[3114]: info: ControllerMpd::stop Aug 20 07:07:31 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 07:07:31 volumio volumio[3114]: info: CorePlayQueue::clearPlayQueue Aug 20 07:07:31 volumio volumio[3114]: info: CorePlayQueue::saveQueue Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::volumioPushQueue Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::addQueueItems Aug 20 07:07:31 volumio volumio[3114]: info: CorePlayQueue::addQueueItems Aug 20 07:07:31 volumio volumio[3114]: info: Adding Item to queue: albums://%E6%B2%96%20%E4%BB%81/Concierto%20%5B%E3%82%B3%E3%83%B3%E3%82%B7%E3%82%A8%E3%83%AB%E3%83%88%5D%20%EF%BD%9E%E9%AD%82%E7%A5%AD%EF%BD%9E%E3%80%90DSD%E3%80%91 Aug 20 07:07:31 volumio volumio[3114]: info: Exploding uri albums://%E6%B2%96%20%E4%BB%81/Concierto%20%5B%E3%82%B3%E3%83%B3%E3%82%B7%E3%82%A8%E3%83%AB%E3%83%88%5D%20%EF%BD%9E%E9%AD%82%E7%A5%AD%EF%BD%9E%E3%80%90DSD%E3%80%91 in service mpd Aug 20 07:07:31 volumio volumio[3114]: info: CorePlayQueue::saveQueue Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::volumioPushQueue Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::updateTrackBlock Aug 20 07:07:31 volumio volumio[3114]: info: CorePlayQueue::getTrackBlock Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::volumioPlay Aug 20 07:07:31 volumio volumio[3114]: UNSET VOLATILE Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::play index 0 Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::stop Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 07:07:31 volumio volumio[3114]: UNSET VOLATILE Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::play index undefined Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 07:07:31 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::startPlaybackTimer Aug 20 07:07:31 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:31 volumio volumio[3114]: verbose: ControllerMpd::clearAddPlayTracks USB/66C3-C16B/沖 仁-Concierto [コンシエルト] ～魂祭～【DSD-[Hi-Res]-DSD64/10. マイ・ウェイ(ブレリア)【DSD】.dsf Aug 20 07:07:31 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 07:07:31 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand clear Aug 20 07:07:31 volumio volumio[3114]: info: Aug 20 07:07:31 volumio volumio[3114]: ---------------------------- MPD announces system playlist update Aug 20 07:07:31 volumio volumio[3114]: info: Ignoring MPD Status Update Aug 20 07:07:31 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand add "USB/66C3-C16B/沖 仁-Concierto [コンシエルト] ～魂祭～【DSD-[Hi-Res]-DSD64/10. マイ・ウェイ(ブレリア)【DSD】.dsf" Aug 20 07:07:31 volumio volumio[3114]: error: updateQueue error: null Aug 20 07:07:31 volumio volumio[3114]: info: Aug 20 07:07:31 volumio volumio[3114]: ---------------------------- MPD announces system playlist update Aug 20 07:07:31 volumio volumio[3114]: info: Ignoring MPD Status Update Aug 20 07:07:31 volumio volumio[3114]: info: ------------------------------ 3ms Aug 20 07:07:31 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand play Aug 20 07:07:31 volumio volumio[3114]: info: ------------------------------ 2ms Aug 20 07:07:31 volumio volumio[3114]: info: Aug 20 07:07:31 volumio volumio[3114]: ---------------------------- MPD announces state update: player Aug 20 07:07:31 volumio volumio[3114]: info: ControllerMpd::getState Aug 20 07:07:31 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand status Aug 20 07:07:31 volumio volumio[3114]: info: Aug 20 07:07:31 volumio volumio[3114]: ---------------------------- MPD announces state update: player Aug 20 07:07:31 volumio volumio[3114]: info: ControllerMpd::getState Aug 20 07:07:31 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand status Aug 20 07:07:31 volumio volumio[3114]: verbose: ControllerMpd::parseState Aug 20 07:07:31 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 07:07:31 volumio volumio[3114]: verbose: ControllerMpd::parseState Aug 20 07:07:31 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 07:07:31 volumio volumio[3114]: verbose: ControllerMpd::parseTrackInfo Aug 20 07:07:31 volumio volumio[3114]: info: ControllerMpd::pushState Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::servicePushState Aug 20 07:07:31 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:31 volumio volumio[3114]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"マイ・ウェイ(ブレリア)【DSD】","artist":"沖 仁","album":"Concierto [コンシエルト] ～魂祭～【DSD】","uri":"USB/66C3-C16B/沖 仁-Concierto [コンシエルト] ～魂祭～【DSD-[Hi-Res]-DSD64/10. マイ・ウェイ(ブレリア)【DSD】.dsf","trackType":"dsf"} Aug 20 07:07:31 volumio volumio[3114]: verbose: CURRENT POSITION 0 Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::syncState stateService play Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::syncState currentStatus stop Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:07:31 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:07:31 volumio volumio[3114]: info: ------------------------------ 48ms Aug 20 07:07:31 volumio volumio[3114]: verbose: ControllerMpd::parseTrackInfo Aug 20 07:07:31 volumio volumio[3114]: info: ControllerMpd::pushState Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::servicePushState Aug 20 07:07:31 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:31 volumio volumio[3114]: verbose: STATE SERVICE {"status":"play","position":0,"seek":284,"duration":317,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2822 Kbps","isStreaming":false,"title":"マイ・ウェイ(ブレリア)【DSD】","artist":"沖 仁","album":"Concierto [コンシエルト] ～魂祭～【DSD】","uri":"USB/66C3-C16B/沖 仁-Concierto [コンシエルト] ～魂祭～【DSD-[Hi-Res]-DSD64/10. マイ・ウェイ(ブレリア)【DSD】.dsf","trackType":"dsf"} Aug 20 07:07:31 volumio volumio[3114]: verbose: CURRENT POSITION 0 Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::syncState stateService play Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::syncState currentStatus play Aug 20 07:07:31 volumio volumio[3114]: info: Received an update from plugin. extracting info from payload Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:07:31 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:07:31 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:07:31 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:07:31 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:07:31 volumio volumio[3114]: info: ------------------------------ 23ms Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::ClearQueue Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::stop Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 07:07:39 volumio volumio[3114]: UNSET VOLATILE Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::stPlaybackTimer Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::updateTrackBlock Aug 20 07:07:39 volumio volumio[3114]: info: CorePlayQueue::getTrackBlock Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:07:39 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::serviceStop Aug 20 07:07:39 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::serviceStop Aug 20 07:07:39 volumio volumio[3114]: info: ControllerMpd::stop Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 07:07:39 volumio volumio[3114]: info: CorePlayQueue::clearPlayQueue Aug 20 07:07:39 volumio volumio[3114]: info: CorePlayQueue::saveQueue Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::volumioPushQueue Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::addQueueItems Aug 20 07:07:39 volumio volumio[3114]: info: CorePlayQueue::addQueueItems Aug 20 07:07:39 volumio volumio[3114]: info: Adding Item to queue: albums://Aimer(%E3%82%A8%E3%83%A1)/Midnight%20Sun Aug 20 07:07:39 volumio volumio[3114]: info: Exploding uri albums://Aimer(%E3%82%A8%E3%83%A1)/Midnight%20Sun in service mpd Aug 20 07:07:39 volumio volumio[3114]: info: CorePlayQueue::saveQueue Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::volumioPushQueue Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::updateTrackBlock Aug 20 07:07:39 volumio volumio[3114]: info: CorePlayQueue::getTrackBlock Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::volumioPlay Aug 20 07:07:39 volumio volumio[3114]: UNSET VOLATILE Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::play index 0 Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::stop Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 07:07:39 volumio volumio[3114]: UNSET VOLATILE Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::play index undefined Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 20 07:07:39 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::startPlaybackTimer Aug 20 07:07:39 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::clearAddPlayTracks USB/66C3-C16B/Aimer(エメ)-Midnight Sun-[Hi-Res]-96kHz-24bit/01 WHEN YOU WISH UPON A STAR -prologue-.flac Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand stop Aug 20 07:07:39 volumio volumio[3114]: info: Aug 20 07:07:39 volumio volumio[3114]: ---------------------------- MPD announces state update: player Aug 20 07:07:39 volumio volumio[3114]: info: ControllerMpd::getState Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand status Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand clear Aug 20 07:07:39 volumio volumio[3114]: info: Aug 20 07:07:39 volumio volumio[3114]: ---------------------------- MPD announces system playlist update Aug 20 07:07:39 volumio volumio[3114]: info: Ignoring MPD Status Update Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::parseState Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand add "USB/66C3-C16B/Aimer(エメ)-Midnight Sun-[Hi-Res]-96kHz-24bit/01 WHEN YOU WISH UPON A STAR -prologue-.flac" Aug 20 07:07:39 volumio volumio[3114]: error: updateQueue error: null Aug 20 07:07:39 volumio volumio[3114]: info: Aug 20 07:07:39 volumio volumio[3114]: ---------------------------- MPD announces system playlist update Aug 20 07:07:39 volumio volumio[3114]: info: Ignoring MPD Status Update Aug 20 07:07:39 volumio volumio[3114]: info: ------------------------------ 2ms Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::parseTrackInfo Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand play Aug 20 07:07:39 volumio volumio[3114]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 20 07:07:39 volumio volumio[3114]: info: ------------------------------ 5ms Aug 20 07:07:39 volumio volumio[3114]: info: ------------------------------ 2ms Aug 20 07:07:39 volumio volumio[3114]: info: Aug 20 07:07:39 volumio volumio[3114]: ---------------------------- MPD announces state update: player Aug 20 07:07:39 volumio volumio[3114]: info: ControllerMpd::getState Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand status Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::parseState Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 07:07:39 volumio volumio[3114]: info: Aug 20 07:07:39 volumio volumio[3114]: ---------------------------- MPD announces state update: player Aug 20 07:07:39 volumio volumio[3114]: info: ControllerMpd::getState Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand status Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::parseTrackInfo Aug 20 07:07:39 volumio volumio[3114]: info: ControllerMpd::pushState Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::servicePushState Aug 20 07:07:39 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:39 volumio volumio[3114]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":74,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"WHEN YOU WISH UPON A STAR -prologue-","artist":"Aimer(エメ)","album":"Midnight Sun","uri":"USB/66C3-C16B/Aimer(エメ)-Midnight Sun-[Hi-Res]-96kHz-24bit/01 WHEN YOU WISH UPON A STAR -prologue-.flac","trackType":"flac"} Aug 20 07:07:39 volumio volumio[3114]: verbose: CURRENT POSITION 0 Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::syncState stateService play Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::syncState currentStatus stop Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:07:39 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:07:39 volumio volumio[3114]: info: ------------------------------ 41ms Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::parseState Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 20 07:07:39 volumio volumio[3114]: verbose: ControllerMpd::parseTrackInfo Aug 20 07:07:39 volumio volumio[3114]: info: ControllerMpd::pushState Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::servicePushState Aug 20 07:07:39 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:39 volumio volumio[3114]: verbose: STATE SERVICE {"status":"play","position":0,"seek":453,"duration":74,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4614 Kbps","isStreaming":false,"title":"WHEN YOU WISH UPON A STAR -prologue-","artist":"Aimer(エメ)","album":"Midnight Sun","uri":"USB/66C3-C16B/Aimer(エメ)-Midnight Sun-[Hi-Res]-96kHz-24bit/01 WHEN YOU WISH UPON A STAR -prologue-.flac","trackType":"flac"} Aug 20 07:07:39 volumio volumio[3114]: verbose: CURRENT POSITION 0 Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::syncState stateService play Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::syncState currentStatus play Aug 20 07:07:39 volumio volumio[3114]: info: Received an update from plugin. extracting info from payload Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:07:39 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:07:39 volumio volumio[3114]: info: CoreStateMachine::pushState Aug 20 07:07:39 volumio volumio[3114]: info: CorePlayQueue::getTrack 0 Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::volumioPushState Aug 20 07:07:39 volumio volumio[3114]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 20 07:07:39 volumio volumio[3114]: info: ------------------------------ 31ms Aug 20 07:07:47 volumio volumio[3114]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 07:07:47 volumio volumio[3114]: { Error: connect ETIMEDOUT 202.160.128.195:80 Aug 20 07:07:47 volumio volumio[3114]: at Object._errnoException (util.js:1022:11) Aug 20 07:07:47 volumio volumio[3114]: at _exceptionWithHostPort (util.js:1044:20) Aug 20 07:07:47 volumio volumio[3114]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 20 07:07:47 volumio volumio[3114]: code: 'ETIMEDOUT', Aug 20 07:07:47 volumio volumio[3114]: errno: 'ETIMEDOUT', Aug 20 07:07:47 volumio volumio[3114]: syscall: 'connect', Aug 20 07:07:47 volumio volumio[3114]: address: '202.160.128.195', Aug 20 07:07:47 volumio volumio[3114]: port: 80 } Aug 20 07:07:47 volumio volumio[3114]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 20 07:07:47 volumio sudo[10224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-20 07:06 Aug 20 07:07:47 volumio sudo[10224]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="74e4cc9de715c64d553d35948d017f973a622b6d" VOLUMIO_FE_VERSION="2be6c28eb9de74ec1f9662ca333f7bd51a232c33" VOLUMIO_BE_VERSION="259a7f2894e1376413ffac331be84e0e9a6173dd" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 24 22:42:43 CEST 2020" VOLUMIO_VERSION="2.834" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="e41ef0f29aa50c1af109d3064a380c69"