-- Logs begin at Tue 2024-09-24 03:05:25 CEST, end at Fri 2024-10-04 13:14:28 CEST. -- Oct 04 13:13:03 tomsvolumio sudo[681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 04 13:13:03 tomsvolumio sudo[681]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 13:13:03 tomsvolumio sudo[681]: pam_unix(sudo:session): session closed for user root Oct 04 13:13:03 tomsvolumio sudo[687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 04 13:13:03 tomsvolumio sudo[687]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 13:13:03 tomsvolumio sudo[687]: pam_unix(sudo:session): session closed for user root Oct 04 13:13:03 tomsvolumio volumio[987]: verbose: New Socket.io Connection to 192.168.100.78 from 192.168.100.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 04 13:13:04 tomsvolumio sudo[690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 04 13:13:04 tomsvolumio sudo[690]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 13:13:04 tomsvolumio sudo[690]: pam_unix(sudo:session): session closed for user root Oct 04 13:13:04 tomsvolumio sudo[693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 04 13:13:04 tomsvolumio sudo[693]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 13:13:04 tomsvolumio sudo[693]: pam_unix(sudo:session): session closed for user root Oct 04 13:13:04 tomsvolumio volumio[987]: verbose: New Socket.io Connection to 192.168.100.78 from 192.168.100.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 04 13:13:04 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioGetState Oct 04 13:13:04 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:04 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 04 13:13:04 tomsvolumio volumio[987]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 04 13:13:04 tomsvolumio volumio[987]: info: Listing playlists Oct 04 13:13:04 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 04 13:13:04 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 04 13:13:04 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 04 13:13:04 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 04 13:13:06 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 04 13:13:06 tomsvolumio volumio[987]: info: Received Get System Info Oct 04 13:13:06 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 13:13:06 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 13:13:06 tomsvolumio volumio[987]: info: Discovery: Getting this device information Oct 04 13:13:06 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioGetState Oct 04 13:13:06 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:06 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 13:13:06 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 04 13:13:06 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioGetVisibleSources Oct 04 13:13:06 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 13:13:06 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Oct 04 13:13:06 tomsvolumio volumio[987]: info: CoreStateMachine::getQueue Oct 04 13:13:06 tomsvolumio volumio[987]: info: CorePlayQueue::getQueue Oct 04 13:13:08 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: cd_controller , handleBrowseUri Oct 04 13:13:08 tomsvolumio volumio[987]: info: Preload queue cleared Oct 04 13:13:08 tomsvolumio volumio[987]: info: Preloading song: cdaudio/track9 Oct 04 13:13:08 tomsvolumio volumio[987]: info: Preloading song: cdaudio/track10 Oct 04 13:13:08 tomsvolumio volumio[987]: info: Preloading song: cdaudio/track11 Oct 04 13:13:08 tomsvolumio volumio[987]: info: Preloading song: cdaudio/track12 Oct 04 13:13:08 tomsvolumio volumio[987]: info: Preloading song: cdaudio/track13 Oct 04 13:13:08 tomsvolumio volumio[987]: info: Exploding uri cdaudio/track9 in service cd_controller Oct 04 13:13:08 tomsvolumio volumio[987]: info: Exploding uri cdaudio/track10 in service cd_controller Oct 04 13:13:08 tomsvolumio volumio[987]: info: Exploding uri cdaudio/track11 in service cd_controller Oct 04 13:13:08 tomsvolumio volumio[987]: info: Exploding uri cdaudio/track12 in service cd_controller Oct 04 13:13:08 tomsvolumio volumio[987]: info: Exploding uri cdaudio/track13 in service cd_controller Oct 04 13:13:20 tomsvolumio volumio[987]: info: Executing endpoint metavolumio Oct 04 13:13:20 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 13:13:20 tomsvolumio volumio[987]: info: Executing endpoint metavolumio Oct 04 13:13:20 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 13:13:23 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 04 13:13:23 tomsvolumio volumio[987]: info: Received Get System Info Oct 04 13:13:23 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 13:13:23 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 13:13:23 tomsvolumio volumio[987]: info: Discovery: Getting this device information Oct 04 13:13:23 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioGetState Oct 04 13:13:23 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:23 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 13:13:27 tomsvolumio volumio[987]: info: CALLMETHOD: music_service cd_controller getRipInfo Oct 04 13:13:27 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: cd_controller , getRipInfo Oct 04 13:13:29 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 04 13:13:29 tomsvolumio volumio[987]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 04 13:13:29 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 04 13:13:29 tomsvolumio volumio[987]: info: Received Get System Version Oct 04 13:13:29 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 04 13:13:29 tomsvolumio volumio[987]: info: Received Get System Info Oct 04 13:13:29 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 13:13:29 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 13:13:29 tomsvolumio volumio[987]: info: Discovery: Getting this device information Oct 04 13:13:29 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioGetState Oct 04 13:13:29 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:29 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 13:13:34 tomsvolumio volumio[987]: info: CALLMETHOD: music_service cd_controller ripCD [object Object] Oct 04 13:13:34 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: cd_controller , ripCD Oct 04 13:13:34 tomsvolumio volumio[987]: info: Set CD Speed to 12X Oct 04 13:13:34 tomsvolumio volumio[987]: info: Starting CD RIP TO DESTINATION: /mnt/USB/Seagate_Expansion_Drive Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: Cannot enable. Maybe the USB cable is bad? Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot disable (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: Cannot enable. Maybe the USB cable is bad? Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot disable (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: Cannot enable. Maybe the USB cable is bad? Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot disable (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot reset (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: Cannot enable. Maybe the USB cable is bad? Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot disable (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.4-port4: cannot disable (err = -71) Oct 04 13:13:40 tomsvolumio kernel: hub 1-1.4:1.0: hub_ext_port_status failed (err = -71) Oct 04 13:13:40 tomsvolumio kernel: usb 1-1-port2: over-current change #3 Oct 04 13:13:40 tomsvolumio kernel: usb 1-1.3: USB disconnect, device number 15 Oct 04 13:13:40 tomsvolumio kernel: device offline error, dev sda, sector 264544 op 0x1:(WRITE) flags 0x0 phys_seg 1 prio class 2 Oct 04 13:13:40 tomsvolumio kernel: Buffer I/O error on dev sda2, logical block 44, lost async page write Oct 04 13:13:40 tomsvolumio kernel: device offline error, dev sda, sector 6348136 op 0x1:(WRITE) flags 0x0 phys_seg 2 prio class 2 Oct 04 13:13:40 tomsvolumio kernel: Buffer I/O error on dev sda2, logical block 760493, lost async page write Oct 04 13:13:40 tomsvolumio kernel: Buffer I/O error on dev sda2, logical block 760494, lost async page write Oct 04 13:13:40 tomsvolumio kernel: device offline error, dev sda, sector 6555640 op 0x1:(WRITE) flags 0x0 phys_seg 1 prio class 2 Oct 04 13:13:40 tomsvolumio kernel: Buffer I/O error on dev sda2, logical block 786431, lost async page write Oct 04 13:13:40 tomsvolumio kernel: device offline error, dev sda, sector 6566008 op 0x1:(WRITE) flags 0x0 phys_seg 1 prio class 2 Oct 04 13:13:40 tomsvolumio kernel: Buffer I/O error on dev sda2, logical block 787727, lost async page write Oct 04 13:13:40 tomsvolumio kernel: device offline error, dev sda, sector 6566096 op 0x1:(WRITE) flags 0x0 phys_seg 1 prio class 2 Oct 04 13:13:40 tomsvolumio kernel: Buffer I/O error on dev sda2, logical block 787738, lost async page write Oct 04 13:13:40 tomsvolumio kernel: device offline error, dev sda, sector 1015210120 op 0x1:(WRITE) flags 0x0 phys_seg 1 prio class 2 Oct 04 13:13:40 tomsvolumio kernel: Buffer I/O error on dev sda2, logical block 126868241, lost async page write Oct 04 13:13:40 tomsvolumio kernel: device offline error, dev sda, sector 1015242880 op 0x1:(WRITE) flags 0x4000 phys_seg 30 prio class 2 Oct 04 13:13:40 tomsvolumio kernel: Buffer I/O error on dev sda2, logical block 126872336, lost async page write Oct 04 13:13:40 tomsvolumio kernel: Buffer I/O error on dev sda2, logical block 126872337, lost async page write Oct 04 13:13:40 tomsvolumio kernel: Buffer I/O error on dev sda2, logical block 126872338, lost async page write Oct 04 13:13:40 tomsvolumio kernel: device offline error, dev sda, sector 1015243120 op 0x1:(WRITE) flags 0x4000 phys_seg 30 prio class 2 Oct 04 13:13:40 tomsvolumio kernel: device offline error, dev sda, sector 1015243360 op 0x1:(WRITE) flags 0x4000 phys_seg 30 prio class 2 Oct 04 13:13:40 tomsvolumio kernel: device offline error, dev sda, sector 1015243600 op 0x1:(WRITE) flags 0x4000 phys_seg 30 prio class 2 Oct 04 13:13:40 tomsvolumio volumio[987]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/wwn-0x5000c500b47a9681-part1 /dev/disk/by-partuuid/8d613e12-6084-4e26-8bc9-e622091d5184 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.3:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/ata-ST4000DM004-2CV104_ZFN1VCVY-part1 /dev/disk/by-partlabel/Microsoft\\x20reserved\\x20partition","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"44","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_HPA":"1","ID_ATA_FEATURE_SET_HPA_ENABLED":"1","ID_ATA_FEATURE_SET_PM":"1","ID_ATA_FEATURE_SET_PM_ENABLED":"1","ID_ATA_FEATURE_SET_PUIS":"1","ID_ATA_FEATURE_SET_PUIS_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"492","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"492","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"5425","ID_ATA_SATA":"1","ID_ATA_SATA_SIGNAL_RATE_GEN1":"1","ID_ATA_SATA_SIGNAL_RATE_GEN2":"1","ID_ATA_WRITE_CACHE":"1","ID_ATA_WRITE_CACHE_ENABLED":"1","ID_BUS":"ata","ID_MODEL":"ST4000DM004-2CV104","ID_MODEL_ENC":"ST4000DM004-2CV104\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","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":"8d613e12-6084-4e26-8bc9-e622091d5184","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"8a6319e9-e981-475f-baa3-d9a5afd52290","ID_PATH":"platform-3f980000.usb-usb-0:1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"0001","ID_SERIAL":"ST4000DM004-2CV104_ZFN1VCVY","ID_SERIAL_SHORT":"ZFN1VCVY","ID_TYPE":"disk","ID_WWN":"0x5000c500b47a9681","ID_WWN_WITH_EXTENSION":"0x5000c500b47a9681","MAJOR":"8","MINOR":"1","PARTN":"1","PARTNAME":"Microsoft reserved partition","SEQNUM":"2743","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"242180786936"} Oct 04 13:13:40 tomsvolumio volumio[987]: error: Cannot associate FS Label, not mounting Oct 04 13:13:41 tomsvolumio kernel: hwmon hwmon1: Undervoltage detected! Oct 04 13:13:41 tomsvolumio go-librespot[30430]: time="2024-10-04T13:13:41+02:00" level=error msg="did not receive last pong ack from accesspoint, 121s passed" Oct 04 13:13:41 tomsvolumio go-librespot[30430]: time="2024-10-04T13:13:41+02:00" level=error msg="failed receiving packet" error="failed reading packet header: read tcp 192.168.100.78:50142->34.158.1.133:80: use of closed network connection" Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: Cannot enable. Maybe the USB cable is bad? Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot disable (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: Cannot enable. Maybe the USB cable is bad? Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot disable (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: Cannot enable. Maybe the USB cable is bad? Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot disable (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot reset (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: Cannot enable. Maybe the USB cable is bad? Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot disable (err = -71) Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4-port2: cannot disable (err = -71) Oct 04 13:13:43 tomsvolumio kernel: hub 1-1.4:1.0: hub_ext_port_status failed (err = -71) Oct 04 13:13:43 tomsvolumio kernel: hub 1-1.4:1.0: hub_ext_port_status failed (err = -71) Oct 04 13:13:43 tomsvolumio volumio[987]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/Seagate\\x20Expansion\\x20Drive /dev/disk/by-path/platform-3f980000.usb-usb-0:1.3:1.0-scsi-0:0:0:0-part2 /dev/disk/by-partlabel/Basic\\x20data\\x20partition /dev/disk/by-partuuid/3782cf88-cf4e-4ad5-a33f-00dadb2544ae /dev/disk/by-id/ata-ST4000DM004-2CV104_ZFN1VCVY-part2 /dev/disk/by-uuid/18B62C49B62C29A8 /dev/disk/by-id/wwn-0x5000c500b47a9681-part2","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"44","ID_ATA":"1","ID_ATA_DOWNLOAD_MICROCODE":"1","ID_ATA_FEATURE_SET_HPA":"1","ID_ATA_FEATURE_SET_HPA_ENABLED":"1","ID_ATA_FEATURE_SET_PM":"1","ID_ATA_FEATURE_SET_PM_ENABLED":"1","ID_ATA_FEATURE_SET_PUIS":"1","ID_ATA_FEATURE_SET_PUIS_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY":"1","ID_ATA_FEATURE_SET_SECURITY_ENABLED":"0","ID_ATA_FEATURE_SET_SECURITY_ENHANCED_ERASE_UNIT_MIN":"492","ID_ATA_FEATURE_SET_SECURITY_ERASE_UNIT_MIN":"492","ID_ATA_FEATURE_SET_SMART":"1","ID_ATA_FEATURE_SET_SMART_ENABLED":"1","ID_ATA_ROTATION_RATE_RPM":"5425","ID_ATA_SATA":"1","ID_ATA_SATA_SIGNAL_RATE_GEN1":"1","ID_ATA_SATA_SIGNAL_RATE_GEN2":"1","ID_ATA_WRITE_CACHE":"1","ID_ATA_WRITE_CACHE_ENABLED":"1","ID_BUS":"ata","ID_FS_LABEL":"Seagate_Expansion_Drive","ID_FS_LABEL_ENC":"Seagate\\x20Expansion\\x20Drive","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"18B62C49B62C29A8","ID_FS_UUID_ENC":"18B62C49B62C29A8","ID_MODEL":"ST4000DM004-2CV104","ID_MODEL_ENC":"ST4000DM004-2CV104\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","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":"7813771264","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"3782cf88-cf4e-4ad5-a33f-00dadb2544ae","ID_PART_TABLE_TYPE":"atari","ID_PART_TABLE_UUID":"8a6319e9-e981-475f-baa3-d9a5afd52290","ID_PATH":"platform-3f980000.usb-usb-0:1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"0001","ID_SERIAL":"ST4000DM004-2CV104_ZFN1VCVY","ID_SERIAL_SHORT":"ZFN1VCVY","ID_TYPE":"disk","ID_WWN":"0x5000c500b47a9681","ID_WWN_WITH_EXTENSION":"0x5000c500b47a9681","MAJOR":"8","MINOR":"2","PARTN":"2","PARTNAME":"Basic data partition","SEQNUM":"2745","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"242180787454"} Oct 04 13:13:43 tomsvolumio sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda2 Oct 04 13:13:43 tomsvolumio sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 13:13:43 tomsvolumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Oct 04 13:13:43 tomsvolumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=DRIVER_OK Oct 04 13:13:43 tomsvolumio ntfs-3g[19506]: Unmounting /dev/sda2 (Seagate Expansion Drive) Oct 04 13:13:43 tomsvolumio sudo[1277]: pam_unix(sudo:session): session closed for user root Oct 04 13:13:43 tomsvolumio volumio[987]: umount: /media/Seagate_Expansion_Drive: target is busy. Oct 04 13:13:43 tomsvolumio volumio[987]: error: Failed to umount Seagate_Expansion_Drive: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda2" Oct 04 13:13:43 tomsvolumio volumio[987]: umount: /media/Seagate_Expansion_Drive: target is busy. Oct 04 13:13:43 tomsvolumio ntfs-3g[19506]: Failed to sync device /dev/sda2: Input/output error Oct 04 13:13:43 tomsvolumio ntfs-3g[19506]: Failed to fsync device /dev/sda2: Input/output error Oct 04 13:13:43 tomsvolumio ntfs-3g[19506]: Failed to close volume /dev/sda2: Device or resource busy Oct 04 13:13:43 tomsvolumio go-librespot[30430]: time="2024-10-04T13:13:43+02:00" level=debug msg="completed keyexchange" Oct 04 13:13:43 tomsvolumio volumio[987]: info: Executing endpoint cdPostRip Oct 04 13:13:43 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: cd_controller , notifyPostRip Oct 04 13:13:43 tomsvolumio volumio[987]: info: CD RIP Terminated Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.3: new high-speed USB device number 28 using dwc_otg Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.3: New USB device found, idVendor=0bc2, idProduct=3323, bcdDevice= 1.00 Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.3: New USB device strings: Mfr=2, Product=3, SerialNumber=1 Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.3: Product: Expansion+ Desk Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.3: Manufacturer: Seagate Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.3: SerialNumber: NAAC037H Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.3: The driver for the USB controller dwc_otg_hcd does not support scatter-gather which is Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.3: required by the UAS driver. Please try an other USB controller if you wish to use UAS. Oct 04 13:13:43 tomsvolumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Oct 04 13:13:43 tomsvolumio kernel: scsi host3: usb-storage 1-1.3:1.0 Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4: USB disconnect, device number 24 Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4.2: USB disconnect, device number 26 Oct 04 13:13:43 tomsvolumio volumio[987]: info: Set CD Speed to 4X Oct 04 13:13:43 tomsvolumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Oct 04 13:13:43 tomsvolumio volumio[987]: info: Oct 04 13:13:43 tomsvolumio volumio[987]: ---------------------------- MPD announces state update: update Oct 04 13:13:43 tomsvolumio volumio[987]: info: ControllerMpd::getState Oct 04 13:13:43 tomsvolumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Oct 04 13:13:43 tomsvolumio volumio[987]: info: Updating MPD DB after CD rip Oct 04 13:13:43 tomsvolumio volumio[987]: info: Syncing changes to disk Oct 04 13:13:43 tomsvolumio volumio[987]: info: Cleaning abcde folders after CD rip Oct 04 13:13:43 tomsvolumio volumio[987]: error: Cannot Clean after rip: Error: Command failed: /bin/rm -rf /mnt/USB/Seagate_Expansion_Drive/abcde.* Oct 04 13:13:43 tomsvolumio volumio[987]: /bin/rm: cannot remove '/mnt/USB/Seagate_Expansion_Drive/abcde.*': Transport endpoint is not connected Oct 04 13:13:43 tomsvolumio volumio[987]: info: MPD Database updated - AlbumList cache refreshed Oct 04 13:13:43 tomsvolumio volumio[987]: info: Oct 04 13:13:43 tomsvolumio volumio[987]: ---------------------------- MPD announces state update: database Oct 04 13:13:43 tomsvolumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Oct 04 13:13:43 tomsvolumio volumio[987]: info: Oct 04 13:13:43 tomsvolumio volumio[987]: ---------------------------- MPD announces state update: update Oct 04 13:13:43 tomsvolumio volumio[987]: info: ControllerMpd::getState Oct 04 13:13:43 tomsvolumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Oct 04 13:13:43 tomsvolumio volumio[987]: info: ControllerMpd::getState Oct 04 13:13:43 tomsvolumio volumio[987]: verbose: ControllerMpd::sendMpdCommand status Oct 04 13:13:43 tomsvolumio volumio[987]: info: sendMpdCommand status took 21 milliseconds Oct 04 13:13:43 tomsvolumio volumio[987]: info: sendMpdCommand status took 19 milliseconds Oct 04 13:13:43 tomsvolumio volumio[987]: verbose: ControllerMpd::parseState Oct 04 13:13:43 tomsvolumio kernel: usb 1-1.4.4: USB disconnect, device number 27 Oct 04 13:13:43 tomsvolumio volumio[987]: info: Command Router : Notfying DB Updatefalse Oct 04 13:13:43 tomsvolumio volumio[987]: info: CoreCommandRouter::Close All Modals sent Oct 04 13:13:43 tomsvolumio volumio[987]: verbose: ControllerMpd::parseState Oct 04 13:13:43 tomsvolumio volumio[987]: info: ControllerMpd::pushState Oct 04 13:13:43 tomsvolumio volumio[987]: info: CoreCommandRouter::servicePushState Oct 04 13:13:43 tomsvolumio volumio[987]: info: CoreStateMachine::pushState Oct 04 13:13:43 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:43 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioPushState Oct 04 13:13:43 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output update for this device Oct 04 13:13:43 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output Oct 04 13:13:43 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:43 tomsvolumio volumio[987]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 04 13:13:43 tomsvolumio volumio[987]: verbose: CURRENT POSITION 0 Oct 04 13:13:43 tomsvolumio volumio[987]: info: CoreStateMachine::syncState stateService stop Oct 04 13:13:43 tomsvolumio volumio[987]: info: CoreStateMachine::syncState currentStatus stop Oct 04 13:13:43 tomsvolumio volumio[987]: info: CoreStateMachine::pushState Oct 04 13:13:43 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:43 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioPushState Oct 04 13:13:43 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output update for this device Oct 04 13:13:43 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output Oct 04 13:13:43 tomsvolumio volumio[987]: info: No code Oct 04 13:13:43 tomsvolumio volumio[987]: info: CoreStateMachine::pushState Oct 04 13:13:43 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:43 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioPushState Oct 04 13:13:43 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output update for this device Oct 04 13:13:43 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output Oct 04 13:13:43 tomsvolumio volumio[987]: info: ------------------------------ 103ms Oct 04 13:13:43 tomsvolumio go-librespot[30430]: time="2024-10-04T13:13:43+02:00" level=debug msg="completed challenge" Oct 04 13:13:43 tomsvolumio volumio[987]: info: sendMpdCommand status took 152 milliseconds Oct 04 13:13:43 tomsvolumio volumio[987]: info: sendMpdCommand status took 151 milliseconds Oct 04 13:13:43 tomsvolumio volumio[987]: info: sendMpdCommand status took 149 milliseconds Oct 04 13:13:43 tomsvolumio volumio[987]: verbose: ControllerMpd::parseState Oct 04 13:13:44 tomsvolumio volumio[987]: info: Command Router : Notfying DB Updatefalse Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreCommandRouter::Close All Modals sent Oct 04 13:13:44 tomsvolumio volumio[987]: verbose: ControllerMpd::parseState Oct 04 13:13:44 tomsvolumio volumio[987]: verbose: ControllerMpd::parseState Oct 04 13:13:44 tomsvolumio volumio[987]: info: ControllerMpd::pushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreCommandRouter::servicePushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreStateMachine::pushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioPushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output update for this device Oct 04 13:13:44 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output Oct 04 13:13:44 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:44 tomsvolumio volumio[987]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 04 13:13:44 tomsvolumio volumio[987]: verbose: CURRENT POSITION 0 Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreStateMachine::syncState stateService stop Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreStateMachine::syncState currentStatus stop Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreStateMachine::pushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioPushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output update for this device Oct 04 13:13:44 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output Oct 04 13:13:44 tomsvolumio volumio[987]: info: No code Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreStateMachine::pushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioPushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output update for this device Oct 04 13:13:44 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output Oct 04 13:13:44 tomsvolumio volumio[987]: info: ControllerMpd::pushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreCommandRouter::servicePushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreStateMachine::pushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioPushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output update for this device Oct 04 13:13:44 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output Oct 04 13:13:44 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:44 tomsvolumio volumio[987]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 04 13:13:44 tomsvolumio volumio[987]: verbose: CURRENT POSITION 0 Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreStateMachine::syncState stateService stop Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreStateMachine::syncState currentStatus stop Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreStateMachine::pushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioPushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output update for this device Oct 04 13:13:44 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output Oct 04 13:13:44 tomsvolumio volumio[987]: info: No code Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreStateMachine::pushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:44 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioPushState Oct 04 13:13:44 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output update for this device Oct 04 13:13:44 tomsvolumio volumio[987]: info: MRS: Pushing multiroomSync output Oct 04 13:13:44 tomsvolumio volumio[987]: info: ------------------------------ 228ms Oct 04 13:13:44 tomsvolumio volumio[987]: info: ------------------------------ 228ms Oct 04 13:13:44 tomsvolumio volumio[987]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Oct 04 13:13:44 tomsvolumio volumio[987]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Oct 04 13:13:44 tomsvolumio volumio[987]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Oct 04 13:13:44 tomsvolumio volumio[987]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Oct 04 13:13:44 tomsvolumio volumio[987]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Oct 04 13:13:44 tomsvolumio volumio[987]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Oct 04 13:13:44 tomsvolumio volumio[987]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Oct 04 13:13:44 tomsvolumio volumio[987]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Oct 04 13:13:44 tomsvolumio volumio[987]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Oct 04 13:13:44 tomsvolumio go-librespot[30430]: time="2024-10-04T13:13:44+02:00" level=debug msg="authenticated as s658grllrcjmxp19vh599u58w" Oct 04 13:13:44 tomsvolumio go-librespot[30430]: time="2024-10-04T13:13:44+02:00" level=debug msg="re-established accesspoint connection" Oct 04 13:13:44 tomsvolumio go-librespot[30430]: time="2024-10-04T13:13:44+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 04 13:13:44 tomsvolumio go-librespot[30430]: time="2024-10-04T13:13:44+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 04 13:13:44 tomsvolumio go-librespot[30430]: time="2024-10-04T13:13:44+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 04 13:13:44 tomsvolumio go-librespot[30430]: time="2024-10-04T13:13:44+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 04 13:13:44 tomsvolumio go-librespot[30430]: time="2024-10-04T13:13:44+02:00" level=debug msg="autoplay enabled: false" Oct 04 13:13:44 tomsvolumio go-librespot[30430]: time="2024-10-04T13:13:44+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 505" Oct 04 13:13:44 tomsvolumio kernel: usb 1-1.4: new high-speed USB device number 29 using dwc_otg Oct 04 13:13:44 tomsvolumio kernel: usb 1-1.4: New USB device found, idVendor=14cd, idProduct=8601, bcdDevice= 0.00 Oct 04 13:13:44 tomsvolumio kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Oct 04 13:13:44 tomsvolumio kernel: usb 1-1.4: Product: USB 2.0 Hub Oct 04 13:13:44 tomsvolumio kernel: usb 1-1.4: Manufacturer: USB Device Oct 04 13:13:44 tomsvolumio kernel: hub 1-1.4:1.0: USB hub found Oct 04 13:13:44 tomsvolumio kernel: hub 1-1.4:1.0: 4 ports detected Oct 04 13:13:44 tomsvolumio kernel: usb 1-1.5: USB disconnect, device number 25 Oct 04 13:13:44 tomsvolumio kernel: scsi 3:0:0:0: Direct-Access Seagate Expansion+ Desk 9401 PQ: 0 ANSI: 6 Oct 04 13:13:44 tomsvolumio kernel: sd 3:0:0:0: Attached scsi generic sg0 type 0 Oct 04 13:13:44 tomsvolumio kernel: sd 3:0:0:0: [sdb] Spinning up disk... Oct 04 13:13:44 tomsvolumio kernel: usb 1-1.5: new high-speed USB device number 30 using dwc_otg Oct 04 13:13:44 tomsvolumio kernel: usb 1-1.5: New USB device found, idVendor=2357, idProduct=0109, bcdDevice= 2.00 Oct 04 13:13:44 tomsvolumio kernel: usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 04 13:13:44 tomsvolumio kernel: usb 1-1.5: Product: 802.11n NIC Oct 04 13:13:44 tomsvolumio kernel: usb 1-1.5: Manufacturer: Realtek Oct 04 13:13:44 tomsvolumio kernel: usb 1-1.5: SerialNumber: 00e04c000001 Oct 04 13:13:45 tomsvolumio kernel: usb 1-1.4.2: new high-speed USB device number 31 using dwc_otg Oct 04 13:13:45 tomsvolumio kernel: usb 1-1.4.2: New USB device found, idVendor=14cd, idProduct=1212, bcdDevice= 1.00 Oct 04 13:13:45 tomsvolumio kernel: usb 1-1.4.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Oct 04 13:13:45 tomsvolumio kernel: usb 1-1.4.2: Product: Mass Storage Device Oct 04 13:13:45 tomsvolumio kernel: usb 1-1.4.2: Manufacturer: Generic Oct 04 13:13:45 tomsvolumio kernel: usb 1-1.4.2: SerialNumber: 121220160204 Oct 04 13:13:45 tomsvolumio kernel: usb-storage 1-1.4.2:1.0: USB Mass Storage device detected Oct 04 13:13:45 tomsvolumio kernel: scsi host1: usb-storage 1-1.4.2:1.0 Oct 04 13:13:45 tomsvolumio kernel: usb 1-1.4.4: new high-speed USB device number 32 using dwc_otg Oct 04 13:13:45 tomsvolumio kernel: usb 1-1.4.4: New USB device found, idVendor=152d, idProduct=0562, bcdDevice= 4.09 Oct 04 13:13:45 tomsvolumio kernel: usb 1-1.4.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 04 13:13:45 tomsvolumio kernel: usb 1-1.4.4: Product: JMS579 Oct 04 13:13:45 tomsvolumio kernel: usb 1-1.4.4: Manufacturer: JMicron Oct 04 13:13:45 tomsvolumio kernel: usb 1-1.4.4: SerialNumber: DD56419883915 Oct 04 13:13:45 tomsvolumio kernel: usb-storage 1-1.4.4:1.0: USB Mass Storage device detected Oct 04 13:13:45 tomsvolumio kernel: scsi host2: usb-storage 1-1.4.4:1.0 Oct 04 13:13:46 tomsvolumio kernel: . Oct 04 13:13:46 tomsvolumio kernel: scsi 1:0:0:0: Direct-Access Mass Storage Device 1.00 PQ: 0 ANSI: 0 CCS Oct 04 13:13:46 tomsvolumio kernel: sd 1:0:0:0: Attached scsi generic sg1 type 0 Oct 04 13:13:46 tomsvolumio kernel: sd 1:0:0:0: [sdc] Media removed, stopped polling Oct 04 13:13:46 tomsvolumio kernel: sd 1:0:0:0: [sdc] Attached SCSI removable disk Oct 04 13:13:46 tomsvolumio kernel: scsi 2:0:0:0: CD-ROM PLDS DVD+-RW DS-8A8SH KD11 PQ: 0 ANSI: 0 Oct 04 13:13:46 tomsvolumio kernel: sr 2:0:0:0: Power-on or device reset occurred Oct 04 13:13:46 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioGetState Oct 04 13:13:46 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:46 tomsvolumio volumio[987]: info: Clearing queue from CD entries Oct 04 13:13:46 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioPushQueue Oct 04 13:13:46 tomsvolumio kernel: sr 2:0:0:0: [sr0] scsi3-mmc drive: 24x/24x writer dvd-ram cd/rw xa/form2 cdda tray Oct 04 13:13:46 tomsvolumio kernel: sr 2:0:0:0: Attached scsi CD-ROM sr0 Oct 04 13:13:46 tomsvolumio kernel: sr 2:0:0:0: Attached scsi generic sg2 type 5 Oct 04 13:13:46 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Oct 04 13:13:46 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 13:13:46 tomsvolumio volumio[987]: Cannot find translation for source Spotify Oct 04 13:13:46 tomsvolumio volumio[987]: Cannot find translation for source TIDAL Oct 04 13:13:46 tomsvolumio kernel: .ready Oct 04 13:13:46 tomsvolumio kernel: sd 3:0:0:0: [sdb] Very big device. Trying to use READ CAPACITY(16). Oct 04 13:13:46 tomsvolumio kernel: sd 3:0:0:0: [sdb] 7814037167 512-byte logical blocks: (4.00 TB/3.64 TiB) Oct 04 13:13:46 tomsvolumio kernel: sd 3:0:0:0: [sdb] Write Protect is off Oct 04 13:13:46 tomsvolumio kernel: sd 3:0:0:0: [sdb] Mode Sense: 4f 00 00 00 Oct 04 13:13:46 tomsvolumio kernel: sd 3:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Oct 04 13:13:46 tomsvolumio kernel: sdb: sdb1 sdb2 Oct 04 13:13:46 tomsvolumio kernel: sd 3:0:0:0: [sdb] Attached SCSI disk Oct 04 13:13:47 tomsvolumio volumio[987]: error: Cannot associate FS Label, not mounting Oct 04 13:13:47 tomsvolumio volumio[987]: info: Mounting Device Seagate_Expansion_Drive Oct 04 13:13:47 tomsvolumio volumio[987]: /bin/mkdir: cannot create directory ‘/mnt/USB/Seagate_Expansion_Drive’: File exists Oct 04 13:13:47 tomsvolumio volumio[987]: error: Failed to create folder Error: Command failed: /bin/mkdir -m 777 "/mnt/USB/Seagate_Expansion_Drive" Oct 04 13:13:47 tomsvolumio volumio[987]: /bin/mkdir: cannot create directory ‘/mnt/USB/Seagate_Expansion_Drive’: File exists Oct 04 13:13:47 tomsvolumio sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/Seagate_Expansion_Drive -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 04 13:13:47 tomsvolumio sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 13:13:50 tomsvolumio sudo[1360]: pam_unix(sudo:session): session closed for user root Oct 04 13:13:50 tomsvolumio volumio[987]: ntfs-3g-mount: failed to access mountpoint /media/Seagate_Expansion_Drive: Transport endpoint is not connected Oct 04 13:13:50 tomsvolumio volumio[987]: error: Failed to mount Seagate_Expansion_Drive: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb2" "/mnt/USB/Seagate_Expansion_Drive" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 04 13:13:50 tomsvolumio volumio[987]: ntfs-3g-mount: failed to access mountpoint /media/Seagate_Expansion_Drive: Transport endpoint is not connected Oct 04 13:13:50 tomsvolumio volumio[987]: info: CD Rip Successfully started Oct 04 13:13:50 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioGetState Oct 04 13:13:50 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:13:50 tomsvolumio volumio[987]: info: Clearing queue from CD entries Oct 04 13:13:50 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioPushQueue Oct 04 13:13:50 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD Oct 04 13:13:50 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 13:13:50 tomsvolumio volumio[987]: Cannot find translation for source Spotify Oct 04 13:13:50 tomsvolumio volumio[987]: Cannot find translation for source TIDAL Oct 04 13:13:51 tomsvolumio sudo[1379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /dev/sr0 Oct 04 13:13:51 tomsvolumio sudo[1379]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 13:13:51 tomsvolumio sudo[1379]: pam_unix(sudo:session): session closed for user root Oct 04 13:13:51 tomsvolumio sudo[1382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /dev/sr1 Oct 04 13:13:51 tomsvolumio sudo[1382]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 13:13:51 tomsvolumio sudo[1382]: pam_unix(sudo:session): session closed for user root Oct 04 13:13:51 tomsvolumio volumio[987]: /bin/chmod: cannot access '/dev/sr1': No such file or directory Oct 04 13:13:51 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 13:13:51 tomsvolumio volumio[987]: info: [1728040431708] CoreMusicLibrary::Adding element Audio CD Oct 04 13:13:51 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 13:13:51 tomsvolumio volumio[987]: Cannot find translation for source Spotify Oct 04 13:13:51 tomsvolumio volumio[987]: Cannot find translation for source TIDAL Oct 04 13:13:51 tomsvolumio volumio[987]: Cannot find translation for source Audio CD Oct 04 13:13:51 tomsvolumio volumio[987]: info: Set CD Speed to 4X Oct 04 13:13:51 tomsvolumio kernel: hwmon hwmon1: Voltage normalised Oct 04 13:13:55 tomsvolumio volumio[987]: info: CDDB Entry Already exists Oct 04 13:13:55 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 04 13:13:55 tomsvolumio volumio[987]: info: [1728040435446] CoreMusicLibrary::Adding element Audio CD Oct 04 13:13:55 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 13:13:55 tomsvolumio volumio[987]: Cannot find translation for source Spotify Oct 04 13:13:55 tomsvolumio volumio[987]: Cannot find translation for source TIDAL Oct 04 13:13:55 tomsvolumio volumio[987]: Cannot find translation for source Audio CD Oct 04 13:13:56 tomsvolumio kernel: hwmon hwmon1: Undervoltage detected! Oct 04 13:14:02 tomsvolumio kernel: hwmon hwmon1: Voltage normalised Oct 04 13:14:14 tomsvolumio sudo[1860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 04 13:14:14 tomsvolumio sudo[1860]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 13:14:14 tomsvolumio sudo[1860]: pam_unix(sudo:session): session closed for user root Oct 04 13:14:14 tomsvolumio sudo[1863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 04 13:14:14 tomsvolumio sudo[1863]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 13:14:14 tomsvolumio sudo[1863]: pam_unix(sudo:session): session closed for user root Oct 04 13:14:14 tomsvolumio volumio[987]: verbose: New Socket.io Connection to 192.168.100.78 from 192.168.100.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 04 13:14:15 tomsvolumio sudo[1866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 04 13:14:15 tomsvolumio sudo[1866]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 13:14:15 tomsvolumio sudo[1866]: pam_unix(sudo:session): session closed for user root Oct 04 13:14:15 tomsvolumio sudo[1869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 04 13:14:15 tomsvolumio sudo[1869]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 04 13:14:15 tomsvolumio sudo[1869]: pam_unix(sudo:session): session closed for user root Oct 04 13:14:15 tomsvolumio volumio[987]: verbose: New Socket.io Connection to 192.168.100.78 from 192.168.100.44 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:131.0) Gecko/20100101 Firefox/131.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 04 13:14:15 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioGetState Oct 04 13:14:15 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:14:15 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 04 13:14:15 tomsvolumio volumio[987]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 04 13:14:15 tomsvolumio volumio[987]: info: Listing playlists Oct 04 13:14:15 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 04 13:14:15 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 04 13:14:15 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 04 13:14:15 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 04 13:14:17 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 04 13:14:17 tomsvolumio volumio[987]: info: Received Get System Info Oct 04 13:14:17 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 13:14:17 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 13:14:17 tomsvolumio volumio[987]: info: Discovery: Getting this device information Oct 04 13:14:17 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioGetState Oct 04 13:14:17 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:14:17 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 13:14:17 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 04 13:14:18 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioGetQueue Oct 04 13:14:18 tomsvolumio volumio[987]: info: CoreStateMachine::getQueue Oct 04 13:14:18 tomsvolumio volumio[987]: info: CorePlayQueue::getQueue Oct 04 13:14:18 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioGetVisibleSources Oct 04 13:14:18 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 04 13:14:21 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 04 13:14:21 tomsvolumio volumio[987]: info: Received Get System Info Oct 04 13:14:21 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 04 13:14:21 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 04 13:14:21 tomsvolumio volumio[987]: info: Discovery: Getting this device information Oct 04 13:14:21 tomsvolumio volumio[987]: info: CoreCommandRouter::volumioGetState Oct 04 13:14:21 tomsvolumio volumio[987]: info: CorePlayQueue::getTrack 0 Oct 04 13:14:21 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 04 13:14:21 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: cd_controller , handleBrowseUri Oct 04 13:14:21 tomsvolumio volumio[987]: info: Preload queue cleared Oct 04 13:14:21 tomsvolumio volumio[987]: info: Executing endpoint metavolumio Oct 04 13:14:21 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 13:14:21 tomsvolumio volumio[987]: info: Executing endpoint metavolumio Oct 04 13:14:21 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 04 13:14:23 tomsvolumio kernel: hwmon hwmon1: Undervoltage detected! Oct 04 13:14:27 tomsvolumio kernel: hwmon hwmon1: Voltage normalised Oct 04 13:14:27 tomsvolumio volumio[987]: info: CALLMETHOD: music_service cd_controller getRipInfo Oct 04 13:14:27 tomsvolumio volumio[987]: info: CoreCommandRouter::executeOnPlugin: cd_controller , getRipInfo Oct 04 13:14:27 tomsvolumio volumio[987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 13:14:27 tomsvolumio volumio[987]: Error: ENOTCONN: socket is not connected, lstat '/mnt/USB/Seagate_Expansion_Drive' Oct 04 13:14:27 tomsvolumio volumio[987]: at Object.lstatSync (fs.js:1077:3) Oct 04 13:14:27 tomsvolumio volumio[987]: at Object.lstatSync (/volumio/node_modules/graceful-fs/polyfills.js:308:16) Oct 04 13:14:27 tomsvolumio volumio[987]: at /myvolumio/plugins/music_service/cd_controller/cd_controller_real:1:14222 Oct 04 13:14:27 tomsvolumio volumio[987]: at go$readdir$cb (/volumio/node_modules/graceful-fs/graceful-fs.js:195:14) Oct 04 13:14:27 tomsvolumio volumio[987]: at FSReqCallback.oncomplete (fs.js:171:23) { Oct 04 13:14:27 tomsvolumio volumio[987]: errno: -107, Oct 04 13:14:27 tomsvolumio volumio[987]: syscall: 'lstat', Oct 04 13:14:27 tomsvolumio volumio[987]: code: 'ENOTCONN', Oct 04 13:14:27 tomsvolumio volumio[987]: path: '/mnt/USB/Seagate_Expansion_Drive' Oct 04 13:14:27 tomsvolumio volumio[987]: } Oct 04 13:14:27 tomsvolumio volumio[987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 04 13:14:28 tomsvolumio sudo[1882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-04 13:13 Oct 04 13:14:28 tomsvolumio sudo[1882]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"