-- Logs begin at Sun 2025-06-08 02:50:03 CST, end at Sun 2025-06-08 02:54:47 CST. -- Jun 08 02:53:11 volumio volumio[1736]: 90 Jun 08 02:53:11 volumio volumio[1736]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 08 02:53:11 volumio volumio[1736]: Dload Upload Total Spent Left Speed Jun 08 02:53:11 volumio volumio[1221]: info: Jun 08 02:53:11 volumio volumio[1221]: ---------------------------- Client requests Volume 90 Jun 08 02:53:11 volumio volumio[1221]: info: VolumeController::SetAlsaVolume90 Jun 08 02:53:11 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:11 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 02:53:11 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:11 volumio volumio[1736]: [158B blob data] Jun 08 02:53:11 volumio volumio[1736]: {"time":1749322391334,"response":"volume Success"}90 Jun 08 02:53:11 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:11 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:11 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:11 volumio volumio[1736]: 80 Jun 08 02:53:11 volumio volumio[1736]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 08 02:53:11 volumio volumio[1736]: Dload Upload Total Spent Left Speed Jun 08 02:53:11 volumio volumio[1221]: info: Jun 08 02:53:11 volumio volumio[1221]: ---------------------------- Client requests Volume 80 Jun 08 02:53:11 volumio volumio[1221]: info: VolumeController::SetAlsaVolume80 Jun 08 02:53:11 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:11 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 02:53:11 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:11 volumio volumio[1736]: [158B blob data] Jun 08 02:53:11 volumio volumio[1736]: {"time":1749322391520,"response":"volume Success"}80 Jun 08 02:53:11 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:11 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:11 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:11 volumio volumio[1736]: 70 Jun 08 02:53:11 volumio volumio[1736]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 08 02:53:11 volumio volumio[1736]: Dload Upload Total Spent Left Speed Jun 08 02:53:11 volumio volumio[1221]: info: Jun 08 02:53:11 volumio volumio[1221]: ---------------------------- Client requests Volume 70 Jun 08 02:53:11 volumio volumio[1221]: info: VolumeController::SetAlsaVolume70 Jun 08 02:53:11 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:11 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 02:53:11 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:11 volumio volumio[1736]: [158B blob data] Jun 08 02:53:11 volumio volumio[1736]: {"time":1749322391623,"response":"volume Success"}70 Jun 08 02:53:11 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:11 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:11 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:15 volumio kernel: usb 1-3.4: new full-speed USB device number 8 using xhci_hcd Jun 08 02:53:15 volumio kernel: usb 1-3.4: New USB device found, idVendor=222a, idProduct=0001, bcdDevice= 0.02 Jun 08 02:53:15 volumio kernel: usb 1-3.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jun 08 02:53:15 volumio kernel: usb 1-3.4: Product: ILITEK-TP Jun 08 02:53:15 volumio kernel: usb 1-3.4: Manufacturer: ILITEK Jun 08 02:53:15 volumio kernel: input: ILITEK ILITEK-TP as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3.4/1-3.4:1.0/0003:222A:0001.0003/input/input14 Jun 08 02:53:15 volumio kernel: input: ILITEK ILITEK-TP Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3.4/1-3.4:1.0/0003:222A:0001.0003/input/input16 Jun 08 02:53:15 volumio kernel: hid-multitouch 0003:222A:0001.0003: input,hiddev0,hidraw1: USB HID v1.10 Mouse [ILITEK ILITEK-TP] on usb-0000:00:14.0-3.4/input0 Jun 08 02:53:15 volumio systemd-udevd[2753]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 08 02:53:15 volumio systemd-udevd[2755]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 08 02:53:15 volumio systemd-udevd[2759]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 08 02:53:15 volumio systemd-udevd[2754]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 08 02:53:15 volumio systemd-udevd[2756]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 08 02:53:17 volumio volumio[1736]: 80 Jun 08 02:53:17 volumio volumio[1736]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 08 02:53:17 volumio volumio[1736]: Dload Upload Total Spent Left Speed Jun 08 02:53:17 volumio volumio[1221]: info: Jun 08 02:53:17 volumio volumio[1221]: ---------------------------- Client requests Volume 80 Jun 08 02:53:17 volumio volumio[1221]: info: VolumeController::SetAlsaVolume80 Jun 08 02:53:17 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:17 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 02:53:17 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:17 volumio volumio[1736]: [158B blob data] Jun 08 02:53:17 volumio volumio[1736]: {"time":1749322397218,"response":"volume Success"}80 Jun 08 02:53:17 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:17 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:17 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:18 volumio volumio[1736]: 90 Jun 08 02:53:18 volumio volumio[1736]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 08 02:53:18 volumio volumio[1736]: Dload Upload Total Spent Left Speed Jun 08 02:53:18 volumio volumio[1221]: info: Jun 08 02:53:18 volumio volumio[1221]: ---------------------------- Client requests Volume 90 Jun 08 02:53:18 volumio volumio[1221]: info: VolumeController::SetAlsaVolume90 Jun 08 02:53:18 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 02:53:18 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:18 volumio volumio[1736]: [158B blob data] Jun 08 02:53:18 volumio volumio[1736]: {"time":1749322398180,"response":"volume Success"}90 Jun 08 02:53:18 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:18 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:18 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:18 volumio volumio[1736]: 100 Jun 08 02:53:18 volumio volumio[1736]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 08 02:53:18 volumio volumio[1736]: Dload Upload Total Spent Left Speed Jun 08 02:53:18 volumio volumio[1221]: info: Jun 08 02:53:18 volumio volumio[1221]: ---------------------------- Client requests Volume 100 Jun 08 02:53:18 volumio volumio[1221]: info: VolumeController::SetAlsaVolume100 Jun 08 02:53:18 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:18 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 02:53:18 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:18 volumio volumio[1736]: [158B blob data] Jun 08 02:53:18 volumio volumio[1736]: {"time":1749322398794,"response":"volume Success"}100 Jun 08 02:53:18 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:18 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:18 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:19 volumio volumio[1221]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 08 02:53:19 volumio volumio[1221]: info: Retrying Login Due to Network Error Jun 08 02:53:24 volumio kernel: usb 1-3.4: USB disconnect, device number 8 Jun 08 02:53:29 volumio volumio[1221]: info: MyVolumio login type: Token Jun 08 02:53:51 volumio volumio[1221]: info: Jun 08 02:53:51 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 08 02:53:51 volumio volumio[1221]: info: ControllerMpd::getState Jun 08 02:53:51 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 08 02:53:51 volumio volumio[1221]: info: Jun 08 02:53:51 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 08 02:53:51 volumio volumio[1221]: info: ControllerMpd::getState Jun 08 02:53:51 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 08 02:53:51 volumio volumio[1221]: info: Jun 08 02:53:51 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 08 02:53:51 volumio volumio[1221]: info: ControllerMpd::getState Jun 08 02:53:51 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 08 02:53:51 volumio volumio[1221]: info: sendMpdCommand status took 4 milliseconds Jun 08 02:53:51 volumio volumio[1221]: info: sendMpdCommand status took 3 milliseconds Jun 08 02:53:51 volumio volumio[1221]: info: sendMpdCommand status took 1 milliseconds Jun 08 02:53:51 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 08 02:53:51 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 02:53:51 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 08 02:53:51 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 02:53:51 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 08 02:53:51 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 02:53:51 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 08 02:53:51 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 08 02:53:51 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 08 02:53:51 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 08 02:53:51 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 08 02:53:51 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 08 02:53:51 volumio volumio[1221]: info: ControllerMpd::pushState Jun 08 02:53:51 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 08 02:53:51 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:51 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:51 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 08 02:53:51 volumio volumio[1221]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"春よ、来い","artist":"池田綾子","album":"東京カフェスタイル・ベスト・セレクション","uri":"http://58.250.129.109/amobile.music.tc.qq.com/F000001wFbvo29gc2A.flac?guid=ffffffff9e09609e0000019508f7f7d7&vkey=AAD71066981C4B115647CBD4FEC3B2EC5C90A93FF60C712BB00D5FB53B50D715B03FEAB72ABABB6E9927418270B4FC7317777D71C0FA3710__v2b9abe8b&uin=1152921505319923467&src=F000003NdVoj1bBshz.flac&redirect=1&fromtag=111114","trackType":""} Jun 08 02:53:51 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 08 02:53:51 volumio volumio[1221]: info: CoreStateMachine::syncState stateService stop Jun 08 02:53:51 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play Jun 08 02:53:51 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:51 volumio volumio[1221]: info: CoreStateMachine::stPlaybackTimer Jun 08 02:53:51 volumio volumio[1221]: info: ControllerMpd::pushState Jun 08 02:53:51 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 08 02:53:51 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:51 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 02:53:51 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:51 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 08 02:53:51 volumio volumio[1221]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"春よ、来い","artist":"池田綾子","album":"東京カフェスタイル・ベスト・セレクション","uri":"http://58.250.129.109/amobile.music.tc.qq.com/F000001wFbvo29gc2A.flac?guid=ffffffff9e09609e0000019508f7f7d7&vkey=AAD71066981C4B115647CBD4FEC3B2EC5C90A93FF60C712BB00D5FB53B50D715B03FEAB72ABABB6E9927418270B4FC7317777D71C0FA3710__v2b9abe8b&uin=1152921505319923467&src=F000003NdVoj1bBshz.flac&redirect=1&fromtag=111114","trackType":""} Jun 08 02:53:51 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 08 02:53:51 volumio volumio[1221]: info: CoreStateMachine::syncState stateService stop Jun 08 02:53:51 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus stop Jun 08 02:53:51 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:51 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:51 volumio volumio[1221]: info: No code Jun 08 02:53:51 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:51 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:51 volumio volumio[1221]: info: ControllerMpd::pushState Jun 08 02:53:51 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 08 02:53:51 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:51 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:51 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 08 02:53:51 volumio volumio[1221]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"春よ、来い","artist":"池田綾子","album":"東京カフェスタイル・ベスト・セレクション","uri":"http://58.250.129.109/amobile.music.tc.qq.com/F000001wFbvo29gc2A.flac?guid=ffffffff9e09609e0000019508f7f7d7&vkey=AAD71066981C4B115647CBD4FEC3B2EC5C90A93FF60C712BB00D5FB53B50D715B03FEAB72ABABB6E9927418270B4FC7317777D71C0FA3710__v2b9abe8b&uin=1152921505319923467&src=F000003NdVoj1bBshz.flac&redirect=1&fromtag=111114","trackType":""} Jun 08 02:53:51 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 08 02:53:51 volumio volumio[1221]: info: CoreStateMachine::syncState stateService stop Jun 08 02:53:51 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus stop Jun 08 02:53:51 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:51 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:51 volumio volumio[1221]: info: No code Jun 08 02:53:51 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:51 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:51 volumio volumio[1221]: info: ------------------------------ 77ms Jun 08 02:53:51 volumio volumio[1221]: info: ------------------------------ 80ms Jun 08 02:53:51 volumio volumio[1221]: info: ------------------------------ 83ms Jun 08 02:53:51 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:51 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:51 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:51 volumio volumio[1221]: info: Clearing queue after UPNP request Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::ClearQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::stop Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 02:53:52 volumio volumio[1221]: info: CorePlayQueue::clearPlayQueue Jun 08 02:53:52 volumio volumio[1221]: info: CorePlayQueue::saveQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioPushQueue Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: error: updateQueue error: null Jun 08 02:53:52 volumio volumio[1221]: error: updateQueue error: null Jun 08 02:53:52 volumio volumio[1221]: error: updateQueue error: null Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 4ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 3ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 2ms Jun 08 02:53:52 volumio volumio[1221]: info: Starting UPNP Playback Jun 08 02:53:52 volumio volumio[1221]: info: Preparing playback through UPNP Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioGetState Jun 08 02:53:52 volumio volumio[1221]: info: CorePlayQueue::getTrack 0 Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 9ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 7ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 5ms Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 9ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 8ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 6ms Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 10ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 7ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 5ms Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 9ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 7ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 5ms Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 14ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 11ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 10ms Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 8ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 7ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 5ms Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::getState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::getState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::getState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand status took 4 milliseconds Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::getState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand status took 6 milliseconds Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand status took 5 milliseconds Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand status took 3 milliseconds Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::getState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::getState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::getState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand status took 5 milliseconds Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 08 02:53:52 volumio volumio[1221]: verbose: In UPNP mode Jun 08 02:53:52 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唄の島 (歌之岛)","artist":"やなわらばー (调皮鬼)","album":"唄の島","uri":"http://58.250.129.109/amobile.music.tc.qq.com/M500004AZQKr0FE9lv.mp3?guid=ffffffff9e09609e0000019508f7f7d7&vkey=E9680F43067E72981842C31F674CFDD78FF8A4AD8DD4C71092B90D5BF3CD3003661F2F3BA3AE96F4ED41F7633663609969A367A6BAEECBA1__v2b9abe8c&uin=1152921505319923467&src=M500000Ons0M0AR440.mp3&redirect=1&fromtag=111042","trackType":""} Jun 08 02:53:52 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus stop Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 08 02:53:52 volumio volumio[1221]: verbose: In UPNP mode Jun 08 02:53:52 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唄の島 (歌之岛)","artist":"やなわらばー (调皮鬼)","album":"唄の島","uri":"http://58.250.129.109/amobile.music.tc.qq.com/M500004AZQKr0FE9lv.mp3?guid=ffffffff9e09609e0000019508f7f7d7&vkey=E9680F43067E72981842C31F674CFDD78FF8A4AD8DD4C71092B90D5BF3CD3003661F2F3BA3AE96F4ED41F7633663609969A367A6BAEECBA1__v2b9abe8c&uin=1152921505319923467&src=M500000Ons0M0AR440.mp3&redirect=1&fromtag=111042","trackType":""} Jun 08 02:53:52 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play Jun 08 02:53:52 volumio volumio[1221]: info: Received an update from plugin. extracting info from payload Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 08 02:53:52 volumio volumio[1221]: verbose: In UPNP mode Jun 08 02:53:52 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唄の島 (歌之岛)","artist":"やなわらばー (调皮鬼)","album":"唄の島","uri":"http://58.250.129.109/amobile.music.tc.qq.com/M500004AZQKr0FE9lv.mp3?guid=ffffffff9e09609e0000019508f7f7d7&vkey=E9680F43067E72981842C31F674CFDD78FF8A4AD8DD4C71092B90D5BF3CD3003661F2F3BA3AE96F4ED41F7633663609969A367A6BAEECBA1__v2b9abe8c&uin=1152921505319923467&src=M500000Ons0M0AR440.mp3&redirect=1&fromtag=111042","trackType":""} Jun 08 02:53:52 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play Jun 08 02:53:52 volumio volumio[1221]: info: Received an update from plugin. extracting info from payload Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 08 02:53:52 volumio volumio[1221]: verbose: In UPNP mode Jun 08 02:53:52 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"唄の島 (歌之岛)","artist":"やなわらばー (调皮鬼)","album":"唄の島","uri":"http://58.250.129.109/amobile.music.tc.qq.com/M500004AZQKr0FE9lv.mp3?guid=ffffffff9e09609e0000019508f7f7d7&vkey=E9680F43067E72981842C31F674CFDD78FF8A4AD8DD4C71092B90D5BF3CD3003661F2F3BA3AE96F4ED41F7633663609969A367A6BAEECBA1__v2b9abe8c&uin=1152921505319923467&src=M500000Ons0M0AR440.mp3&redirect=1&fromtag=111042","trackType":""} Jun 08 02:53:52 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play Jun 08 02:53:52 volumio volumio[1221]: info: Received an update from plugin. extracting info from payload Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 34ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 63ms Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 60ms Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::getState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand status took 55 milliseconds Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 55ms Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand status took 52 milliseconds Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 51 milliseconds Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 08 02:53:52 volumio volumio[1221]: verbose: In UPNP mode Jun 08 02:53:52 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":136,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"唄の島","artist":"やなわらばー","album":"《唄の島》","uri":"http://58.250.129.109/amobile.music.tc.qq.com/M500004AZQKr0FE9lv.mp3?guid=ffffffff9e09609e0000019508f7f7d7&vkey=E9680F43067E72981842C31F674CFDD78FF8A4AD8DD4C71092B90D5BF3CD3003661F2F3BA3AE96F4ED41F7633663609969A367A6BAEECBA1__v2b9abe8c&uin=1152921505319923467&src=M500000Ons0M0AR440.mp3&redirect=1&fromtag=111042","trackType":""} Jun 08 02:53:52 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play Jun 08 02:53:52 volumio volumio[1221]: info: Received an update from plugin. extracting info from payload Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 82ms Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces system playlist update Jun 08 02:53:52 volumio volumio[1221]: info: Ignoring MPD Status Update Jun 08 02:53:52 volumio volumio[1221]: info: Jun 08 02:53:52 volumio volumio[1221]: ---------------------------- MPD announces state update: player Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::getState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand status Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 43ms Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand status took 42 milliseconds Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 39 milliseconds Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 40 milliseconds Jun 08 02:53:52 volumio volumio[1221]: info: ------------------------------ 17ms Jun 08 02:53:52 volumio volumio[1221]: info: sendMpdCommand status took 16 milliseconds Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::parseState Jun 08 02:53:52 volumio volumio[1221]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 08 02:53:52 volumio volumio[1221]: verbose: In UPNP mode Jun 08 02:53:52 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":307,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"唄の島","artist":"やなわらばー","album":"《唄の島》","uri":"http://58.250.129.109/amobile.music.tc.qq.com/M500004AZQKr0FE9lv.mp3?guid=ffffffff9e09609e0000019508f7f7d7&vkey=E9680F43067E72981842C31F674CFDD78FF8A4AD8DD4C71092B90D5BF3CD3003661F2F3BA3AE96F4ED41F7633663609969A367A6BAEECBA1__v2b9abe8c&uin=1152921505319923467&src=M500000Ons0M0AR440.mp3&redirect=1&fromtag=111042","trackType":""} Jun 08 02:53:52 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play Jun 08 02:53:52 volumio volumio[1221]: info: Received an update from plugin. extracting info from payload Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:52 volumio volumio[1221]: info: ControllerMpd::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 08 02:53:52 volumio volumio[1221]: verbose: In UPNP mode Jun 08 02:53:52 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"唄の島","artist":"やなわらばー","album":"《唄の島》","uri":"http://58.250.129.109/amobile.music.tc.qq.com/M500004AZQKr0FE9lv.mp3?guid=ffffffff9e09609e0000019508f7f7d7&vkey=E9680F43067E72981842C31F674CFDD78FF8A4AD8DD4C71092B90D5BF3CD3003661F2F3BA3AE96F4ED41F7633663609969A367A6BAEECBA1__v2b9abe8c&uin=1152921505319923467&src=M500000Ons0M0AR440.mp3&redirect=1&fromtag=111042","trackType":""} Jun 08 02:53:52 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play Jun 08 02:53:52 volumio volumio[1221]: info: Received an update from plugin. extracting info from payload Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:52 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:53 volumio volumio[1221]: info: ------------------------------ 145ms Jun 08 02:53:53 volumio volumio[1221]: info: ------------------------------ 143ms Jun 08 02:53:53 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 48 milliseconds Jun 08 02:53:53 volumio volumio[1221]: info: sendMpdCommand playlistinfo took 48 milliseconds Jun 08 02:53:53 volumio volumio[1221]: verbose: ControllerMpd::parseTrackInfo Jun 08 02:53:53 volumio volumio[1221]: info: ControllerMpd::pushState Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 08 02:53:53 volumio volumio[1221]: verbose: In UPNP mode Jun 08 02:53:53 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"唄の島","artist":"やなわらばー","album":"《唄の島》","uri":"http://58.250.129.109/amobile.music.tc.qq.com/M500004AZQKr0FE9lv.mp3?guid=ffffffff9e09609e0000019508f7f7d7&vkey=E9680F43067E72981842C31F674CFDD78FF8A4AD8DD4C71092B90D5BF3CD3003661F2F3BA3AE96F4ED41F7633663609969A367A6BAEECBA1__v2b9abe8c&uin=1152921505319923467&src=M500000Ons0M0AR440.mp3&redirect=1&fromtag=111042","trackType":""} Jun 08 02:53:53 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play Jun 08 02:53:53 volumio volumio[1221]: info: Received an update from plugin. extracting info from payload Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:53 volumio volumio[1221]: info: ControllerMpd::pushState Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::servicePushState Jun 08 02:53:53 volumio volumio[1221]: verbose: In UPNP mode Jun 08 02:53:53 volumio volumio[1221]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":308,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"唄の島","artist":"やなわらばー","album":"《唄の島》","uri":"http://58.250.129.109/amobile.music.tc.qq.com/M500004AZQKr0FE9lv.mp3?guid=ffffffff9e09609e0000019508f7f7d7&vkey=E9680F43067E72981842C31F674CFDD78FF8A4AD8DD4C71092B90D5BF3CD3003661F2F3BA3AE96F4ED41F7633663609969A367A6BAEECBA1__v2b9abe8c&uin=1152921505319923467&src=M500000Ons0M0AR440.mp3&redirect=1&fromtag=111042","trackType":""} Jun 08 02:53:53 volumio volumio[1221]: verbose: CURRENT POSITION 0 Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::syncState stateService play Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::syncState currentStatus play Jun 08 02:53:53 volumio volumio[1221]: info: Received an update from plugin. extracting info from payload Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::pushState Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::volumioPushState Jun 08 02:53:53 volumio volumio[1221]: info: ------------------------------ 131ms Jun 08 02:53:53 volumio volumio[1221]: info: ------------------------------ 103ms Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:53 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:53 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:53 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:53 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:53 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:53 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:53 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:53 volumio volumio[1221]: info: CoreCommandRouter::volumioGetQueue Jun 08 02:53:53 volumio volumio[1221]: info: CoreStateMachine::getQueue Jun 08 02:53:53 volumio volumio[1221]: info: CorePlayQueue::getQueue Jun 08 02:53:59 volumio volumio[1221]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 08 02:53:59 volumio volumio[1221]: info: Retrying Login Due to Network Error Jun 08 02:54:09 volumio volumio[1221]: info: MyVolumio login type: Token Jun 08 02:54:35 volumio kernel: usb 1-3.4: new full-speed USB device number 9 using xhci_hcd Jun 08 02:54:35 volumio kernel: usb 1-3.4: New USB device found, idVendor=222a, idProduct=0001, bcdDevice= 0.02 Jun 08 02:54:35 volumio kernel: usb 1-3.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Jun 08 02:54:35 volumio kernel: usb 1-3.4: Product: ILITEK-TP Jun 08 02:54:35 volumio kernel: usb 1-3.4: Manufacturer: ILITEK Jun 08 02:54:35 volumio kernel: input: ILITEK ILITEK-TP as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3.4/1-3.4:1.0/0003:222A:0001.0004/input/input17 Jun 08 02:54:35 volumio kernel: input: ILITEK ILITEK-TP Mouse as /devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3.4/1-3.4:1.0/0003:222A:0001.0004/input/input19 Jun 08 02:54:35 volumio kernel: hid-multitouch 0003:222A:0001.0004: input,hiddev0,hidraw1: USB HID v1.10 Mouse [ILITEK ILITEK-TP] on usb-0000:00:14.0-3.4/input0 Jun 08 02:54:35 volumio systemd-udevd[3139]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 08 02:54:35 volumio systemd-udevd[3142]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 08 02:54:35 volumio systemd-udevd[3145]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 08 02:54:35 volumio systemd-udevd[3140]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 08 02:54:35 volumio systemd-udevd[3141]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 08 02:54:39 volumio volumio[1221]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jun 08 02:54:39 volumio volumio[1221]: info: Retrying Login Due to Network Error Jun 08 02:54:39 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 08 02:54:39 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 08 02:54:40 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 08 02:54:40 volumio sudo[3169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 08 02:54:40 volumio sudo[3169]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 02:54:40 volumio sudo[3174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 08 02:54:40 volumio sudo[3174]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 02:54:40 volumio sudo[3174]: pam_unix(sudo:session): session closed for user root Jun 08 02:54:40 volumio sudo[3183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 08 02:54:40 volumio sudo[3183]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 02:54:40 volumio sudo[3183]: pam_unix(sudo:session): session closed for user root Jun 08 02:54:40 volumio sudo[3189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 08 02:54:40 volumio sudo[3189]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 02:54:40 volumio sudo[3197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 08 02:54:40 volumio sudo[3197]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 02:54:40 volumio sudo[3169]: pam_unix(sudo:session): session closed for user root Jun 08 02:54:40 volumio sudo[3189]: pam_unix(sudo:session): session closed for user root Jun 08 02:54:40 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 08 02:54:40 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 08 02:54:40 volumio sudo[3197]: pam_unix(sudo:session): session closed for user root Jun 08 02:54:40 volumio sudo[3209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 08 02:54:40 volumio sudo[3209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 02:54:40 volumio sudo[3209]: pam_unix(sudo:session): session closed for user root Jun 08 02:54:40 volumio sudo[3213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 08 02:54:40 volumio sudo[3213]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 02:54:44 volumio volumio[1221]: info: CoreCommandRouter::volumioGetState Jun 08 02:54:44 volumio kernel: RTW: wlan0- hw port(0) mac_addr =a0:9f:10:38:5a:32 Jun 08 02:54:44 volumio sudo[3213]: pam_unix(sudo:session): session closed for user root Jun 08 02:54:47 volumio volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 08 02:54:47 volumio volumio[1221]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 08 02:54:47 volumio volumio[1221]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 02:54:47 volumio volumio[1221]: Error: connect ETIMEDOUT 199.16.156.38:443 Jun 08 02:54:47 volumio volumio[1221]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 08 02:54:47 volumio volumio[1221]: errno: -110, Jun 08 02:54:47 volumio volumio[1221]: code: 'ETIMEDOUT', Jun 08 02:54:47 volumio volumio[1221]: syscall: 'connect', Jun 08 02:54:47 volumio volumio[1221]: address: '199.16.156.38', Jun 08 02:54:47 volumio volumio[1221]: port: 443 Jun 08 02:54:47 volumio volumio[1221]: } Jun 08 02:54:47 volumio volumio[1221]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 02:54:47 volumio sudo[3269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-08 02:53 Jun 08 02:54:47 volumio sudo[3269]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"