-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Wed 2024-10-02 10:52:35 UTC. -- Oct 02 10:51:06 volumios volumio[923]: info: CoreCommandRouter::volumioPlay Oct 02 10:51:06 volumios volumio[923]: info: CoreStateMachine::play index 0 Oct 02 10:51:06 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 10:51:06 volumios volumio[923]: info: CoreStateMachine::stop Oct 02 10:51:06 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 10:51:06 volumios volumio[923]: info: CoreStateMachine::play index undefined Oct 02 10:51:06 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 10:51:06 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:51:06 volumios volumio[923]: info: CoreStateMachine::startPlaybackTimer Oct 02 10:51:06 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:51:06 volumios volumio[923]: info: [1727866266533] ControllerWebradio::clearAddPlayTrack Oct 02 10:51:06 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 10:51:08 volumios volumio[923]: info: CoreCommandRouter::volumioPlay Oct 02 10:51:08 volumios volumio[923]: info: CoreStateMachine::play index 0 Oct 02 10:51:08 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 10:51:08 volumios volumio[923]: info: CoreStateMachine::stop Oct 02 10:51:08 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 10:51:08 volumios volumio[923]: info: CoreStateMachine::play index undefined Oct 02 10:51:08 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 10:51:08 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:51:08 volumios volumio[923]: info: CoreStateMachine::startPlaybackTimer Oct 02 10:51:08 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:51:08 volumios volumio[923]: info: [1727866268103] ControllerWebradio::clearAddPlayTrack Oct 02 10:51:08 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 10:51:08 volumios volumio[923]: info: CoreCommandRouter::volumioPlay Oct 02 10:51:08 volumios volumio[923]: info: CoreStateMachine::play index 0 Oct 02 10:51:08 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 10:51:08 volumios volumio[923]: info: CoreStateMachine::stop Oct 02 10:51:08 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 10:51:08 volumios volumio[923]: info: CoreStateMachine::play index undefined Oct 02 10:51:08 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 10:51:08 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:51:08 volumios volumio[923]: info: CoreStateMachine::startPlaybackTimer Oct 02 10:51:08 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:51:08 volumios volumio[923]: info: [1727866268433] ControllerWebradio::clearAddPlayTrack Oct 02 10:51:08 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand stop Oct 02 10:51:09 volumios volumio[923]: info: CoreCommandRouter::volumioClearQueue Oct 02 10:51:09 volumios volumio[923]: info: CoreStateMachine::ClearQueue Oct 02 10:51:09 volumios volumio[923]: info: CoreStateMachine::stop Oct 02 10:51:09 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 10:51:09 volumios volumio[923]: info: CorePlayQueue::clearPlayQueue Oct 02 10:51:09 volumios volumio[923]: info: CorePlayQueue::saveQueue Oct 02 10:51:09 volumios volumio[923]: info: CoreCommandRouter::volumioPushState Oct 02 10:51:09 volumios volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 02 10:51:11 volumios volumio[923]: info: CoreCommandRouter::volumioClearQueue Oct 02 10:51:11 volumios volumio[923]: info: CoreStateMachine::ClearQueue Oct 02 10:51:11 volumios volumio[923]: info: CoreStateMachine::stop Oct 02 10:51:11 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 02 10:51:11 volumios volumio[923]: info: CorePlayQueue::clearPlayQueue Oct 02 10:51:11 volumios volumio[923]: info: CorePlayQueue::saveQueue Oct 02 10:51:11 volumios volumio[923]: info: CoreCommandRouter::volumioPushState Oct 02 10:51:11 volumios volumio[923]: info: CoreCommandRouter::volumioPushQueue Oct 02 10:51:19 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 02 10:51:19 volumios volumio[923]: info: CURURI: music-library Oct 02 10:51:26 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: info: ------------------------------ 50058ms Oct 02 10:51:26 volumios volumio[923]: info: ------------------------------ 50057ms Oct 02 10:51:26 volumios volumio[923]: info: ------------------------------ 50055ms Oct 02 10:51:26 volumios volumio[923]: info: ------------------------------ 50054ms Oct 02 10:51:26 volumios volumio[923]: info: ------------------------------ 50055ms Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand add "http://89.16.185.174:8000/stream" Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand add "http://89.16.185.174:8004/stream" Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand stop took 47753 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand stop took 46752 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand stop took 45903 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand stop took 45149 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand stop took 43793 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand stop took 42040 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand stop took 41573 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand stop took 41064 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand stop took 40733 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand stop took 40463 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand stop took 40232 milliseconds Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:51:26 volumios volumio[923]: error: Failed LSINFO: null Oct 02 10:51:26 volumios volumio[923]: error: Failed LSINFO: null Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand add "http://89.16.185.174:8000/stream" took 40058 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand stop took 39946 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand stop took 20055 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand stop took 18485 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand stop took 18155 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand add "http://89.16.185.174:8000/stream" took 11 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand add "http://89.16.185.174:8000/stream" took 11 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand add "http://89.16.185.174:8004/stream" took 11 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand clear took 9 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand clear took 10 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand clear took 11 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand clear took 10 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: ------------------------------ 9ms Oct 02 10:51:26 volumios volumio[923]: info: ------------------------------ 8ms Oct 02 10:51:26 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand clear Oct 02 10:51:26 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:51:26 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:51:26 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:51:26 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand load "http://89.16.185.174:8003/stream" Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8250/class-flac.flac" Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand load "http://mscp3.live-streams.nl:8360/flac.flac" Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand load "http://vps.radioduepuntozero.it:8000/stream.ogg.m3u" Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand load "http://shoutcast.radio24.it:8000/" Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand load "http://vps.radioduepuntozero.it:8000/stream.ogg.m3u" Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:51:26 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:51:26 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:51:26 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:51:26 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: error: updateQueue error: null Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: Oct 02 10:51:26 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:51:26 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:51:26 volumios volumio[923]: info: ------------------------------ 20ms Oct 02 10:51:26 volumios volumio[923]: info: ------------------------------ 19ms Oct 02 10:51:26 volumios volumio[923]: info: ------------------------------ 18ms Oct 02 10:51:26 volumios volumio[923]: info: ------------------------------ 18ms Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand play took 15 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand clear took 15 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand clear took 15 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand clear took 14 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand play took 14 milliseconds Oct 02 10:51:26 volumios volumio[923]: info: sendMpdCommand play took 13 milliseconds Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand load "http://shoutcast.radio24.it:8000/" Oct 02 10:51:26 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand load "http://shoutcast.radio24.it:8000/" Oct 02 10:51:36 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand add "http://89.16.185.174:8003/stream" Oct 02 10:51:37 volumios kernel: Under-voltage detected! (0x00050005) Oct 02 10:51:51 volumios volumio[923]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-2/2-2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/301c6d67-c339-42f3-8971-4bd1407bb553 /dev/disk/by-uuid/E4B2C5B0B2C5878E /dev/disk/by-partlabel/Basic\\x20data\\x20partition /dev/disk/by-id/usb-Samsung_SSD_870_EVO_500G_235678C218CA-0:0-part2 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:2:1.0-scsi-0:0:0:0-part2 /dev/disk/by-label/Новый\\x20том","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-2/2-2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","ID_BUS":"usb","ID_FS_LABEL":"Новый_том","ID_FS_LABEL_ENC":"Новый\\x20том","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"E4B2C5B0B2C5878E","ID_FS_UUID_ENC":"E4B2C5B0B2C5878E","ID_INSTANCE":"0:0","ID_MODEL":"SSD_870_EVO_500G","ID_MODEL_ENC":"SSD\\x20870\\x20EVO\\x20500G","ID_MODEL_ID":"a2a4","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NAME":"Basic\\x20data\\x20partition","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"264192","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"976506880","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"301c6d67-c339-42f3-8971-4bd1407bb553","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"f602065c-d599-48b7-9bdc-5e6db531b6da","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_2_1_0-scsi-0_0_0_0","ID_REVISION":"4101","ID_SERIAL":"Samsung_SSD_870_EVO_500G_235678C218CA-0:0","ID_SERIAL_SHORT":"235678C218CA","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Samsung","ID_VENDOR_ENC":"Samsung\\x20","ID_VENDOR_ID":"7825","MAJOR":"8","MINOR":"2","PARTN":"2","PARTNAME":"Basic data partition","SEQNUM":"1681","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"13923627"} Oct 02 10:51:51 volumios kernel: usb 2-2: USB disconnect, device number 2 Oct 02 10:51:51 volumios kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Oct 02 10:51:51 volumios sudo[1489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda2 Oct 02 10:51:51 volumios sudo[1489]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 10:51:51 volumios ntfs-3g[987]: Unmounting /dev/sda2 (Новый том) Oct 02 10:51:51 volumios ntfs-3g[987]: Failed to sync device /dev/sda2: Input/output error Oct 02 10:51:51 volumios ntfs-3g[987]: Failed to close volume /dev/sda2: Input/output error Oct 02 10:51:51 volumios sudo[1489]: pam_unix(sudo:session): session closed for user root Oct 02 10:51:51 volumios systemd[1]: media-\xd0\x9d\xd0\xbe\xd0\xb2\xd1\x8b\xd0\xb9_\xd1\x82\xd0\xbe\xd0\xbc.mount: Succeeded. Oct 02 10:51:51 volumios volumio[923]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-2/2-2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-partlabel/Microsoft\\x20reserved\\x20partition /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-partuuid/698521d7-8744-4c73-be7a-f549264b7c82 /dev/disk/by-id/usb-Samsung_SSD_870_EVO_500G_235678C218CA-0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-2/2-2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","ID_BUS":"usb","ID_INSTANCE":"0:0","ID_MODEL":"SSD_870_EVO_500G","ID_MODEL_ENC":"SSD\\x20870\\x20EVO\\x20500G","ID_MODEL_ID":"a2a4","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NAME":"Microsoft\\x20reserved\\x20partition","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"34","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"262144","ID_PART_ENTRY_TYPE":"e3c9e316-0b5c-4db8-817d-f92df00215ae","ID_PART_ENTRY_UUID":"698521d7-8744-4c73-be7a-f549264b7c82","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"f602065c-d599-48b7-9bdc-5e6db531b6da","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_2_1_0-scsi-0_0_0_0","ID_REVISION":"4101","ID_SERIAL":"Samsung_SSD_870_EVO_500G_235678C218CA-0:0","ID_SERIAL_SHORT":"235678C218CA","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Samsung","ID_VENDOR_ENC":"Samsung\\x20","ID_VENDOR_ID":"7825","MAJOR":"8","MINOR":"1","PARTN":"1","PARTNAME":"Microsoft reserved partition","SEQNUM":"1682","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"13934129"} Oct 02 10:51:51 volumios volumio[923]: error: Cannot associate FS Label, not mounting Oct 02 10:51:52 volumios kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=0x00 Oct 02 10:51:52 volumios kernel: Voltage normalised (0x00000000) Oct 02 10:51:55 volumios volumio[923]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/Новый_том Oct 02 10:51:56 volumios kernel: usb 1-1.2: new high-speed USB device number 4 using xhci_hcd Oct 02 10:51:57 volumios kernel: usb 2-2: new SuperSpeed Gen 1 USB device number 3 using xhci_hcd Oct 02 10:51:57 volumios kernel: usb 2-2: New USB device found, idVendor=7825, idProduct=a2a4, bcdDevice=41.01 Oct 02 10:51:57 volumios kernel: usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 02 10:51:57 volumios kernel: usb 2-2: Product: Best USB Device Oct 02 10:51:57 volumios kernel: usb 2-2: Manufacturer: ULT-Best Oct 02 10:51:57 volumios kernel: usb 2-2: SerialNumber: 235678C218CA Oct 02 10:51:57 volumios kernel: scsi host0: uas Oct 02 10:51:57 volumios kernel: scsi 0:0:0:0: Direct-Access Samsung SSD 870 EVO 500G 4101 PQ: 0 ANSI: 6 Oct 02 10:51:57 volumios kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Oct 02 10:51:57 volumios kernel: sd 0:0:0:0: [sda] 976773168 512-byte logical blocks: (500 GB/466 GiB) Oct 02 10:51:57 volumios kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Oct 02 10:51:57 volumios kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 02 10:51:57 volumios kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Oct 02 10:51:57 volumios kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Oct 02 10:51:57 volumios kernel: sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes not a multiple of physical block size (4096 bytes) Oct 02 10:51:57 volumios kernel: sda: sda1 sda2 Oct 02 10:51:57 volumios kernel: sd 0:0:0:0: [sda] Attached SCSI disk Oct 02 10:52:03 volumios kernel: usb 1-1.4: USB disconnect, device number 3 Oct 02 10:52:06 volumios kernel: usb 1-1.4: new high-speed USB device number 5 using xhci_hcd Oct 02 10:52:07 volumios kernel: usb 1-1.4: New USB device found, idVendor=249c, idProduct=930b, bcdDevice= 6.61 Oct 02 10:52:07 volumios kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Oct 02 10:52:07 volumios kernel: usb 1-1.4: Product: hiFaceTWO UAC2 Oct 02 10:52:07 volumios kernel: usb 1-1.4: Manufacturer: M2Tech Oct 02 10:52:21 volumios volumio[923]: info: Scanning removed location : "USB/Новый_том" Oct 02 10:52:22 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:52:22 volumios volumio[923]: error: updateQueue error: null Oct 02 10:52:22 volumios volumio[923]: error: updateQueue error: null Oct 02 10:52:22 volumios volumio[923]: error: updateQueue error: null Oct 02 10:52:22 volumios volumio[923]: error: updateQueue error: null Oct 02 10:52:22 volumios volumio[923]: error: updateQueue error: null Oct 02 10:52:22 volumios volumio[923]: error: updateQueue error: null Oct 02 10:52:22 volumios volumio[923]: error: updateQueue error: null Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8250/class-flac.flac" Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8250/class-flac.flac" Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8250/class-flac.flac" Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac" Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand add "http://vps.radioduepuntozero.it:8000/stream.ogg.m3u" Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand add "http://shoutcast.radio24.it:8000/" Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand add "http://vps.radioduepuntozero.it:8000/stream.ogg.m3u" Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 55406ms Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 55406ms Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 55405ms Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 55401ms Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 55401ms Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand add "http://shoutcast.radio24.it:8000/" Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand add "http://89.16.185.174:8003/stream" took 45395 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:52:22 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:52:22 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:52:22 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:52:22 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:52:22 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces state update: update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: info: ControllerMpd::getState Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8250/class-flac.flac" took 16 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8250/class-flac.flac" took 16 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8250/class-flac.flac" took 16 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand add "http://mscp3.live-streams.nl:8360/flac.flac" took 16 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand add "http://vps.radioduepuntozero.it:8000/stream.ogg.m3u" took 16 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand add "http://shoutcast.radio24.it:8000/" took 16 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand add "http://shoutcast.radio24.it:8000/" took 16 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand add "http://vps.radioduepuntozero.it:8000/stream.ogg.m3u" took 16 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand add "http://vps.radioduepuntozero.it:8000/stream.ogg.m3u" took 17 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand add "http://vps.radioduepuntozero.it:8000/stream.ogg.m3u" took 17 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand add "http://shoutcast.radio24.it:8000/" took 16 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand add "http://shoutcast.radio24.it:8000/" took 16 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand add "http://shoutcast.radio24.it:8000/" took 16 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand add "http://shoutcast.radio24.it:8000/" took 16 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 18ms Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand play took 14 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 13ms Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 14ms Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 13ms Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand play Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces state update: update Oct 02 10:52:22 volumios volumio[923]: info: ControllerMpd::getState Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces state update: update Oct 02 10:52:22 volumios volumio[923]: info: ControllerMpd::getState Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces system playlist update Oct 02 10:52:22 volumios volumio[923]: info: Ignoring MPD Status Update Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- MPD announces state update: update Oct 02 10:52:22 volumios volumio[923]: info: ControllerMpd::getState Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand status Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- USB Audio Device Detached Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand status took 77 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand status took 72 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 76ms Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 75ms Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 76ms Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 75ms Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand play took 70 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand play took 69 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand play took 70 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand play took 69 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand play took 68 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand status took 66 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 66ms Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand status took 66 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 66ms Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand status took 65 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 65ms Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand status took 64 milliseconds Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::parseState Oct 02 10:52:22 volumios volumio[923]: info: Command Router : Notfying DB Updatefalse Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::Close All Modals sent Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::parseState Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::parseState Oct 02 10:52:22 volumios volumio[923]: info: Command Router : Notfying DB Updatefalse Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::Close All Modals sent Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::parseState Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::parseState Oct 02 10:52:22 volumios volumio[923]: info: Command Router : Notfying DB Updatefalse Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::Close All Modals sent Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::parseState Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::parseState Oct 02 10:52:22 volumios volumio[923]: info: Command Router : Notfying DB Updatefalse Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::Close All Modals sent Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::parseState Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 02 10:52:22 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:52:22 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:52:22 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand playlistinfo took 129 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand playlistinfo took 109 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand playlistinfo took 88 milliseconds Oct 02 10:52:22 volumios volumio[923]: info: sendMpdCommand playlistinfo took 44 milliseconds Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 02 10:52:22 volumios volumio[923]: verbose: ControllerMpd::parseTrackInfo Oct 02 10:52:22 volumios volumio[923]: info: ControllerMpd::pushState Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::servicePushState Oct 02 10:52:22 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:52:22 volumios volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://89.16.185.174:8003/stream","trackType":""} Oct 02 10:52:22 volumios volumio[923]: verbose: CURRENT POSITION 0 Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::syncState stateService play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::syncState currentStatus stop Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::pushState Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::volumioPushState Oct 02 10:52:22 volumios volumio[923]: info: ControllerMpd::pushState Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::servicePushState Oct 02 10:52:22 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:52:22 volumios volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://89.16.185.174:8003/stream","trackType":""} Oct 02 10:52:22 volumios volumio[923]: verbose: CURRENT POSITION 0 Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::syncState stateService play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::syncState currentStatus play Oct 02 10:52:22 volumios volumio[923]: info: Received an update from plugin. extracting info from payload Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::pushState Oct 02 10:52:22 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:52:22 volumios volumio[923]: info: ControllerMpd::pushState Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::servicePushState Oct 02 10:52:22 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:52:22 volumios volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://89.16.185.174:8003/stream","trackType":""} Oct 02 10:52:22 volumios volumio[923]: verbose: CURRENT POSITION 0 Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::syncState stateService play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::syncState currentStatus play Oct 02 10:52:22 volumios volumio[923]: info: Received an update from plugin. extracting info from payload Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::pushState Oct 02 10:52:22 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:52:22 volumios volumio[923]: info: ControllerMpd::pushState Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::servicePushState Oct 02 10:52:22 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:52:22 volumios volumio[923]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://89.16.185.174:8003/stream","trackType":""} Oct 02 10:52:22 volumios volumio[923]: verbose: CURRENT POSITION 0 Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::syncState stateService play Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::syncState currentStatus play Oct 02 10:52:22 volumios volumio[923]: info: Received an update from plugin. extracting info from payload Oct 02 10:52:22 volumios volumio[923]: info: CoreStateMachine::pushState Oct 02 10:52:22 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 305ms Oct 02 10:52:22 volumios volumio[923]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 02 10:52:22 volumios volumio[923]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 02 10:52:22 volumios volumio[923]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 295ms Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 295ms Oct 02 10:52:22 volumios volumio[923]: info: ------------------------------ 295ms Oct 02 10:52:22 volumios volumio[923]: info: Oct 02 10:52:22 volumios volumio[923]: ---------------------------- USB Audio Device Attached Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::Close All Modals sent Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 10:52:22 volumios volumio[923]: info: Setting mixer hiFaceTWO UAC2 Output for card hiFaceTWO UAC2 Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 02 10:52:22 volumios volumio[923]: info: Updating Volume Controller Parameters: Device: 5 Name: hiFaceTWO UAC2 Mixer: hiFaceTWO UAC2 Output Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 02 10:52:22 volumios volumio[923]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 10:52:22 volumios volumio[923]: info: Preparing to generate the ALSA configuration file Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 10:52:23 volumios volumio[923]: info: Asound.conf file unchanged, so no further update is needed Oct 02 10:52:23 volumios volumio[923]: info: Output device has changed, restarting MPD Oct 02 10:52:23 volumios sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 10:52:23 volumios sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 10:52:23 volumios volumio[923]: info: Output device has changed, restarting Shairport Sync Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 10:52:23 volumios sudo[1541]: pam_unix(sudo:session): session closed for user root Oct 02 10:52:23 volumios sudo[1544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 10:52:23 volumios sudo[1544]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 10:52:23 volumios systemd[1]: Stopping Music Player Daemon... Oct 02 10:52:23 volumios volumio[923]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 02 10:52:23 volumios systemd[1]: mpd.service: Succeeded. Oct 02 10:52:23 volumios systemd[1]: Stopped Music Player Daemon. Oct 02 10:52:23 volumios volumio[923]: info: MPD Permissions set Oct 02 10:52:23 volumios volumio[923]: info: VolumeController:: Volume=100 Mute =false Oct 02 10:52:23 volumios volumio[923]: info: CoreStateMachine::pushState Oct 02 10:52:23 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:52:23 volumios systemd[1]: Starting Music Player Daemon... Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 10:52:23 volumios volumio[923]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 10:52:23 volumios volumio[923]: error: Cannot associate FS Label, not mounting Oct 02 10:52:23 volumios volumio[923]: info: Mounting Device Новый_том Oct 02 10:52:23 volumios kernel: rpi_firmware_get_throttled: 1 callbacks suppressed Oct 02 10:52:23 volumios kernel: Under-voltage detected! (0x00050005) Oct 02 10:52:23 volumios sudo[1560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda2 /mnt/USB/Новый_том -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 02 10:52:23 volumios sudo[1560]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 10:52:23 volumios ntfs-3g[1564]: Version 2017.3.23AR.3 integrated FUSE 28 Oct 02 10:52:23 volumios ntfs-3g[1564]: Mounted /dev/sda2 (Read-Write, label "Новый том", NTFS 3.1) Oct 02 10:52:23 volumios ntfs-3g[1564]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 02 10:52:23 volumios ntfs-3g[1564]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda2,blkdev,blksize=4096 Oct 02 10:52:23 volumios ntfs-3g[1564]: Global ownership and permissions enforced, configuration type 7 Oct 02 10:52:23 volumios sudo[1560]: pam_unix(sudo:session): session closed for user root Oct 02 10:52:23 volumios volumio[923]: info: Starting Shairport Sync Oct 02 10:52:23 volumios sudo[1566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 10:52:23 volumios sudo[1566]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 10:52:23 volumios systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 02 10:52:23 volumios systemd[1]: shairport-sync.service: Succeeded. Oct 02 10:52:23 volumios systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 02 10:52:24 volumios systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 02 10:52:24 volumios sudo[1566]: pam_unix(sudo:session): session closed for user root Oct 02 10:52:24 volumios mpd[1563]: Oct 02 10:52 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 02 10:52:24 volumios mpd[1563]: Oct 02 10:52 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 02 10:52:24 volumios mpd[1563]: Oct 02 10:52 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 02 10:52:24 volumios systemd[1]: Started Music Player Daemon. Oct 02 10:52:24 volumios sudo[1544]: pam_unix(sudo:session): session closed for user root Oct 02 10:52:24 volumios volumio[923]: info: Scanning new location : "USB/Новый_том" Oct 02 10:52:24 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:52:24 volumios volumio[923]: info: Shairport-Sync Started Oct 02 10:52:24 volumios volumio[923]: error: updateQueue error: null Oct 02 10:52:26 volumios volumio[923]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 02 10:52:26 volumios volumio[923]: info: CoreStateMachine::getcurrentVolume Oct 02 10:52:26 volumios volumio[923]: info: CoreCommandRouter::volumioRetrievevolume Oct 02 10:52:26 volumios volumio[923]: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:52:26 volumios volumio[923]: error: Upnp client error: Error: This socket has been ended by the other party Oct 02 10:52:26 volumios volumio[923]: info: VolumeController:: Volume=100 Mute =false Oct 02 10:52:26 volumios volumio[923]: info: CoreStateMachine::pushState Oct 02 10:52:26 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:52:26 volumios volumio[923]: info: CoreStateMachine::updateTrackBlock Oct 02 10:52:26 volumios volumio[923]: info: CorePlayQueue::getTrackBlock Oct 02 10:52:26 volumios volumio[923]: info: CoreCommandRouter::volumioRetrievevolume Oct 02 10:52:27 volumios volumio[923]: info: VolumeController:: Volume=100 Mute =false Oct 02 10:52:27 volumios volumio[923]: info: CoreStateMachine::pushState Oct 02 10:52:27 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:52:34 volumios volumio[923]: info: CoreCommandRouter::volumioToggle Oct 02 10:52:34 volumios volumio[923]: info: CorePlayQueue::getTrack 0 Oct 02 10:52:34 volumios volumio[923]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 10:52:34 volumios volumio[923]: TypeError: Cannot read property 'name' of undefined Oct 02 10:52:34 volumios volumio[923]: at CoreStateMachine.getState (/volumio/app/statemachine.js:122:48) Oct 02 10:52:34 volumios volumio[923]: at CoreCommandRouter.volumioToggle (/volumio/app/index.js:1366:33) Oct 02 10:52:34 volumios volumio[923]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:228:33) Oct 02 10:52:34 volumios volumio[923]: at Socket.emit (events.js:315:20) Oct 02 10:52:34 volumios volumio[923]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 02 10:52:34 volumios volumio[923]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 02 10:52:34 volumios volumio[923]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 10:52:35 volumios sudo[1592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-02 10:51 Oct 02 10:52:35 volumios sudo[1592]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 10:43:40 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4bc817297375b72097a1fade15c6518b"