-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Thu 2024-09-05 14:23:09 UTC. -- Sep 05 14:22:00 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan Sep 05 14:22:00 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 14:22:00 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 14:22:00 volumio volumio[909]: info: Discovery: Getting this device information Sep 05 14:22:00 volumio volumio[909]: info: CoreCommandRouter::volumioGetState Sep 05 14:22:00 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:00 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 14:22:01 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 05 14:22:01 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand update Sep 05 14:22:03 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:22:07 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan Sep 05 14:22:07 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:22:11 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:22:15 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan Sep 05 14:22:15 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:22:19 volumio kernel: usb 1-1.3: USB disconnect, device number 6 Sep 05 14:22:19 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:22:19 volumio volumio[909]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/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-uuid/1B04-AC8C /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3:1.0-scsi-0:0:0:0-part1 /dev/disk/by-label/STORE\\x20N\\x20GO /dev/disk/by-id/usb-Verbatim_STORE_N_GO_07162403000699-0:0-part1 /dev/disk/by-partuuid/fe59349d-01","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","ID_BUS":"usb","ID_FS_LABEL":"STORE_N_GO","ID_FS_LABEL_ENC":"STORE\\x20N\\x20GO","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"1B04-AC8C","ID_FS_UUID_ENC":"1B04-AC8C","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"STORE_N_GO","ID_MODEL_ENC":"STORE\\x20N\\x20GO\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0302","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"128","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"122879872","ID_PART_ENTRY_TYPE":"0xb","ID_PART_ENTRY_UUID":"fe59349d-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"fe59349d","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"8.07","ID_SERIAL":"Verbatim_STORE_N_GO_07162403000699-0:0","ID_SERIAL_SHORT":"07162403000699","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Verbatim","ID_VENDOR_ENC":"Verbatim","ID_VENDOR_ID":"18a5","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"1740","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3915200862"} Sep 05 14:22:19 volumio sudo[1618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Sep 05 14:22:19 volumio sudo[1618]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 14:22:19 volumio systemd[1]: media-STORE_N_GO.mount: Succeeded. Sep 05 14:22:19 volumio sudo[1618]: pam_unix(sudo:session): session closed for user root Sep 05 14:22:20 volumio kernel: usb 1-1.3: new high-speed USB device number 7 using xhci_hcd Sep 05 14:22:20 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Sep 05 14:22:21 volumio kernel: usb 1-1.3: device descriptor read/64, error -71 Sep 05 14:22:22 volumio kernel: usb 1-1-port3: Cannot enable. Maybe the USB cable is bad? Sep 05 14:22:22 volumio kernel: usb 1-1-port3: attempt power cycle Sep 05 14:22:22 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan Sep 05 14:22:22 volumio kernel: usb 1-1.3: new high-speed USB device number 9 using xhci_hcd Sep 05 14:22:23 volumio kernel: usb 1-1.3: New USB device found, idVendor=18a5, idProduct=0302, bcdDevice= 1.00 Sep 05 14:22:23 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 05 14:22:23 volumio kernel: usb 1-1.3: Product: STORE N GO Sep 05 14:22:23 volumio kernel: usb 1-1.3: Manufacturer: Verbatim Sep 05 14:22:23 volumio kernel: usb 1-1.3: SerialNumber: 07162403000699 Sep 05 14:22:23 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Sep 05 14:22:23 volumio kernel: scsi host0: usb-storage 1-1.3:1.0 Sep 05 14:22:23 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:22:23 volumio volumio[909]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/STORE_N_GO Sep 05 14:22:24 volumio kernel: usb 1-1.3: USB disconnect, device number 9 Sep 05 14:22:24 volumio kernel: usb 1-1.3: new high-speed USB device number 10 using xhci_hcd Sep 05 14:22:24 volumio kernel: usb 1-1.3: Device not responding to setup address. Sep 05 14:22:24 volumio kernel: usb 1-1.3: Device not responding to setup address. Sep 05 14:22:25 volumio kernel: usb 1-1.3: device not accepting address 10, error -71 Sep 05 14:22:25 volumio kernel: usb 1-1.3: new high-speed USB device number 11 using xhci_hcd Sep 05 14:22:25 volumio kernel: usb 1-1.3: New USB device found, idVendor=18a5, idProduct=0302, bcdDevice= 1.00 Sep 05 14:22:25 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 05 14:22:25 volumio kernel: usb 1-1.3: Product: STORE N GO Sep 05 14:22:25 volumio kernel: usb 1-1.3: Manufacturer: Verbatim Sep 05 14:22:25 volumio kernel: usb 1-1.3: SerialNumber: 07162403000699 Sep 05 14:22:25 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Sep 05 14:22:25 volumio kernel: scsi host0: usb-storage 1-1.3:1.0 Sep 05 14:22:26 volumio ntfs-3g[1070]: ntfs_attr_pread_i: ntfs_pread failed: Input/output error Sep 05 14:22:26 volumio ntfs-3g[1070]: Failed to read of MFT, mft=2908 count=1 br=-1: Input/output error Sep 05 14:22:26 volumio volumio[909]: error: MPD error: Error: read ECONNRESET Sep 05 14:22:26 volumio kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=0x00 cmd_age=72s Sep 05 14:22:26 volumio kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 00 60 1e b8 00 00 08 00 Sep 05 14:22:26 volumio kernel: blk_update_request: I/O error, dev sdb, sector 6299320 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0 Sep 05 14:22:26 volumio kernel: Buffer I/O error on dev sdb1, logical block 787159, async page read Sep 05 14:22:26 volumio kernel: usb 1-1.4: USB disconnect, device number 4 Sep 05 14:22:26 volumio volumio[909]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-WD_Elements_25A2_575836324136305256444538-0:0-part1 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.4:1.0-scsi-0:0:0:0-part1 /dev/disk/by-label/Elements /dev/disk/by-partlabel/Elements /dev/disk/by-partuuid/df90b758-551c-4967-ba3f-0ab6cf0dacc0 /dev/disk/by-uuid/26D4ED7AD4ED4C9D","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","ID_BUS":"usb","ID_FS_LABEL":"Elements","ID_FS_LABEL_ENC":"Elements","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"26D4ED7AD4ED4C9D","ID_FS_UUID_ENC":"26D4ED7AD4ED4C9D","ID_INSTANCE":"0:0","ID_MODEL":"Elements_25A2","ID_MODEL_ENC":"Elements\\x2025A2\\x20\\x20\\x20","ID_MODEL_ID":"25a2","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NAME":"Elements","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"1953454080","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"df90b758-551c-4967-ba3f-0ab6cf0dacc0","ID_PART_TABLE_TYPE":"atari","ID_PART_TABLE_UUID":"34e4ea04-bf14-410c-b778-0ccada93b027","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.4:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_4_1_0-scsi-0_0_0_0","ID_REVISION":"1026","ID_SERIAL":"WD_Elements_25A2_575836324136305256444538-0:0","ID_SERIAL_SHORT":"575836324136305256444538","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"WD","ID_VENDOR_ENC":"WD\\x20\\x20\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"1058","MAJOR":"8","MINOR":"17","PARTN":"1","PARTNAME":"Elements","SEQNUM":"1778","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"58047565"} Sep 05 14:22:26 volumio sudo[1628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 Sep 05 14:22:26 volumio sudo[1628]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 14:22:26 volumio ntfs-3g[1070]: Unmounting /dev/sdb1 (Elements) Sep 05 14:22:26 volumio ntfs-3g[1070]: Failed to sync device /dev/sdb1: Input/output error Sep 05 14:22:26 volumio ntfs-3g[1070]: Failed to close volume /dev/sdb1: Input/output error Sep 05 14:22:26 volumio systemd[1]: media-Elements.mount: Succeeded. Sep 05 14:22:26 volumio sudo[1628]: pam_unix(sudo:session): session closed for user root Sep 05 14:22:26 volumio kernel: scsi 0:0:0:0: Direct-Access Verbatim STORE N GO 8.07 PQ: 0 ANSI: 4 Sep 05 14:22:26 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 05 14:22:26 volumio kernel: sd 0:0:0:0: [sda] 122880000 512-byte logical blocks: (62.9 GB/58.6 GiB) Sep 05 14:22:26 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 05 14:22:26 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00 Sep 05 14:22:26 volumio kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Sep 05 14:22:26 volumio kernel: sda: sda1 Sep 05 14:22:26 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Sep 05 14:22:27 volumio volumio[909]: info: Mounting Device STORE_N_GO Sep 05 14:22:27 volumio sudo[1634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/STORE_N_GO -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 05 14:22:27 volumio sudo[1634]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 14:22:27 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Sep 05 14:22:27 volumio sudo[1634]: pam_unix(sudo:session): session closed for user root Sep 05 14:22:27 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Sep 05 14:22:27 volumio volumio[909]: info: Scanning new location : "USB/STORE_N_GO" Sep 05 14:22:27 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:22:30 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan Sep 05 14:22:30 volumio volumio[909]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/Elements Sep 05 14:22:30 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 14:22:30 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 14:22:30 volumio volumio[909]: info: Discovery: Getting this device information Sep 05 14:22:30 volumio volumio[909]: info: CoreCommandRouter::volumioGetState Sep 05 14:22:30 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:30 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 14:22:31 volumio volumio[909]: info: Scanning removed location : "USB/Elements" Sep 05 14:22:31 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Sep 05 14:22:31 volumio volumio[909]: info: Sep 05 14:22:31 volumio volumio[909]: ---------------------------- MPD announces state update: update Sep 05 14:22:31 volumio volumio[909]: info: ControllerMpd::getState Sep 05 14:22:31 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Sep 05 14:22:31 volumio volumio[909]: info: sendMpdCommand status took 4 milliseconds Sep 05 14:22:31 volumio volumio[909]: info: sendMpdCommand status took 3 milliseconds Sep 05 14:22:31 volumio volumio[909]: verbose: ControllerMpd::parseState Sep 05 14:22:32 volumio volumio[909]: info: Command Router : Notfying DB Updatetrue Sep 05 14:22:32 volumio volumio[909]: info: CoreCommandRouter::Close All Modals sent Sep 05 14:22:32 volumio volumio[909]: verbose: ControllerMpd::parseState Sep 05 14:22:32 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 14:22:32 volumio volumio[909]: info: MPD Database updated - AlbumList cache refreshed Sep 05 14:22:32 volumio volumio[909]: info: Sep 05 14:22:32 volumio volumio[909]: ---------------------------- MPD announces state update: database Sep 05 14:22:32 volumio volumio[909]: info: Sep 05 14:22:32 volumio volumio[909]: ---------------------------- MPD announces system playlist update Sep 05 14:22:32 volumio volumio[909]: info: Ignoring MPD Status Update Sep 05 14:22:32 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Sep 05 14:22:32 volumio volumio[909]: info: Sep 05 14:22:32 volumio volumio[909]: ---------------------------- MPD announces state update: update Sep 05 14:22:32 volumio volumio[909]: info: ControllerMpd::getState Sep 05 14:22:32 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Sep 05 14:22:32 volumio volumio[909]: info: ControllerMpd::getState Sep 05 14:22:32 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Sep 05 14:22:32 volumio volumio[909]: info: sendMpdCommand playlistinfo took 15 milliseconds Sep 05 14:22:32 volumio volumio[909]: verbose: ControllerMpd::parseTrackInfo Sep 05 14:22:32 volumio volumio[909]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 05 14:22:32 volumio volumio[909]: info: ------------------------------ 1042ms Sep 05 14:22:32 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:22:32 volumio volumio[909]: error: updateQueue error: null Sep 05 14:22:32 volumio volumio[909]: info: sendMpdCommand status took 48 milliseconds Sep 05 14:22:32 volumio volumio[909]: info: sendMpdCommand status took 46 milliseconds Sep 05 14:22:32 volumio volumio[909]: info: ------------------------------ 50ms Sep 05 14:22:32 volumio volumio[909]: info: sendMpdCommand status took 46 milliseconds Sep 05 14:22:32 volumio volumio[909]: verbose: ControllerMpd::parseState Sep 05 14:22:34 volumio volumio[909]: info: Command Router : Notfying DB Updatefalse Sep 05 14:22:34 volumio volumio[909]: info: CoreCommandRouter::Close All Modals sent Sep 05 14:22:34 volumio volumio[909]: verbose: ControllerMpd::parseState Sep 05 14:22:34 volumio volumio[909]: verbose: ControllerMpd::parseState Sep 05 14:22:34 volumio volumio[909]: info: ControllerMpd::pushState Sep 05 14:22:34 volumio volumio[909]: info: CoreCommandRouter::servicePushState Sep 05 14:22:34 volumio volumio[909]: info: CoreStateMachine::pushState Sep 05 14:22:34 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:34 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 14:22:34 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Sep 05 14:22:34 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:34 volumio volumio[909]: 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} Sep 05 14:22:34 volumio volumio[909]: verbose: CURRENT POSITION 0 Sep 05 14:22:34 volumio volumio[909]: info: CoreStateMachine::syncState stateService stop Sep 05 14:22:34 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus stop Sep 05 14:22:34 volumio volumio[909]: info: CoreStateMachine::pushState Sep 05 14:22:34 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:34 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Sep 05 14:22:34 volumio volumio[909]: info: No code Sep 05 14:22:34 volumio volumio[909]: info: CoreStateMachine::pushState Sep 05 14:22:34 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:34 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Sep 05 14:22:34 volumio volumio[909]: info: ControllerMpd::pushState Sep 05 14:22:34 volumio volumio[909]: info: CoreCommandRouter::servicePushState Sep 05 14:22:34 volumio volumio[909]: info: CoreStateMachine::pushState Sep 05 14:22:34 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:34 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Sep 05 14:22:34 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:34 volumio volumio[909]: 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} Sep 05 14:22:34 volumio volumio[909]: verbose: CURRENT POSITION 0 Sep 05 14:22:34 volumio volumio[909]: info: CoreStateMachine::syncState stateService stop Sep 05 14:22:34 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus stop Sep 05 14:22:34 volumio volumio[909]: info: CoreStateMachine::pushState Sep 05 14:22:34 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:34 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Sep 05 14:22:34 volumio volumio[909]: info: No code Sep 05 14:22:34 volumio volumio[909]: info: CoreStateMachine::pushState Sep 05 14:22:34 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:34 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Sep 05 14:22:34 volumio volumio[909]: info: ------------------------------ 1647ms Sep 05 14:22:34 volumio volumio[909]: info: ------------------------------ 1647ms Sep 05 14:22:35 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:22:37 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan Sep 05 14:22:39 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:22:43 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:22:45 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan Sep 05 14:22:47 volumio kernel: usb 1-1.3: USB disconnect, device number 11 Sep 05 14:22:47 volumio volumio[909]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/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-uuid/1B04-AC8C /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3:1.0-scsi-0:0:0:0-part1 /dev/disk/by-label/STORE\\x20N\\x20GO /dev/disk/by-id/usb-Verbatim_STORE_N_GO_07162403000699-0:0-part1 /dev/disk/by-partuuid/fe59349d-01","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.3/1-1.3:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","ID_BUS":"usb","ID_FS_LABEL":"STORE_N_GO","ID_FS_LABEL_ENC":"STORE\\x20N\\x20GO","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"1B04-AC8C","ID_FS_UUID_ENC":"1B04-AC8C","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"STORE_N_GO","ID_MODEL_ENC":"STORE\\x20N\\x20GO\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0302","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"128","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"122879872","ID_PART_ENTRY_TYPE":"0xb","ID_PART_ENTRY_UUID":"fe59349d-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"fe59349d","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"8.07","ID_SERIAL":"Verbatim_STORE_N_GO_07162403000699-0:0","ID_SERIAL_SHORT":"07162403000699","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Verbatim","ID_VENDOR_ENC":"Verbatim","ID_VENDOR_ID":"18a5","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"1806","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4003042920"} Sep 05 14:22:47 volumio sudo[1652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Sep 05 14:22:47 volumio sudo[1652]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 14:22:47 volumio systemd[1]: media-STORE_N_GO.mount: Succeeded. Sep 05 14:22:47 volumio sudo[1652]: pam_unix(sudo:session): session closed for user root Sep 05 14:22:47 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:22:47 volumio kernel: usb 1-1.3: new high-speed USB device number 12 using xhci_hcd Sep 05 14:22:47 volumio kernel: usb 1-1.3: Device not responding to setup address. Sep 05 14:22:48 volumio kernel: usb 1-1.3: Device not responding to setup address. Sep 05 14:22:48 volumio kernel: usb 1-1.3: device not accepting address 12, error -71 Sep 05 14:22:48 volumio kernel: usb 1-1.3: new high-speed USB device number 13 using xhci_hcd Sep 05 14:22:48 volumio kernel: usb 1-1.3: New USB device found, idVendor=18a5, idProduct=0302, bcdDevice= 1.00 Sep 05 14:22:48 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 05 14:22:48 volumio kernel: usb 1-1.3: Product: STORE N GO Sep 05 14:22:48 volumio kernel: usb 1-1.3: Manufacturer: Verbatim Sep 05 14:22:48 volumio kernel: usb 1-1.3: SerialNumber: 07162403000699 Sep 05 14:22:48 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Sep 05 14:22:48 volumio kernel: scsi host0: usb-storage 1-1.3:1.0 Sep 05 14:22:49 volumio kernel: usb 1-1.4: new high-speed USB device number 14 using xhci_hcd Sep 05 14:22:49 volumio kernel: usb 1-1.4: New USB device found, idVendor=1058, idProduct=25a2, bcdDevice=10.26 Sep 05 14:22:49 volumio kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Sep 05 14:22:49 volumio kernel: usb 1-1.4: Product: Elements 25A2 Sep 05 14:22:49 volumio kernel: usb 1-1.4: Manufacturer: Western Digital Sep 05 14:22:49 volumio kernel: usb 1-1.4: SerialNumber: 575836324136305256444538 Sep 05 14:22:49 volumio kernel: usb-storage 1-1.4:1.0: USB Mass Storage device detected Sep 05 14:22:49 volumio kernel: scsi host1: usb-storage 1-1.4:1.0 Sep 05 14:22:49 volumio kernel: scsi 0:0:0:0: Direct-Access Verbatim STORE N GO 8.07 PQ: 0 ANSI: 4 Sep 05 14:22:49 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Sep 05 14:22:49 volumio kernel: sd 0:0:0:0: [sda] 122880000 512-byte logical blocks: (62.9 GB/58.6 GiB) Sep 05 14:22:49 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 05 14:22:49 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00 Sep 05 14:22:49 volumio kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Sep 05 14:22:49 volumio kernel: sda: sda1 Sep 05 14:22:49 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Sep 05 14:22:50 volumio volumio[909]: info: Mounting Device STORE_N_GO Sep 05 14:22:50 volumio sudo[1665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/STORE_N_GO -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 05 14:22:50 volumio sudo[1665]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 14:22:50 volumio kernel: scsi 1:0:0:0: Direct-Access WD Elements 25A2 1026 PQ: 0 ANSI: 6 Sep 05 14:22:50 volumio kernel: sd 1:0:0:0: Attached scsi generic sg1 type 0 Sep 05 14:22:50 volumio kernel: sd 1:0:0:0: [sdb] Spinning up disk... Sep 05 14:22:50 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Sep 05 14:22:50 volumio sudo[1665]: pam_unix(sudo:session): session closed for user root Sep 05 14:22:50 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Sep 05 14:22:51 volumio volumio[909]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/STORE_N_GO Sep 05 14:22:51 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:22:52 volumio volumio[909]: info: Scanning removed location : "USB/STORE_N_GO" Sep 05 14:22:52 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Sep 05 14:22:52 volumio volumio[909]: info: Sep 05 14:22:52 volumio volumio[909]: ---------------------------- MPD announces state update: update Sep 05 14:22:52 volumio volumio[909]: info: ControllerMpd::getState Sep 05 14:22:52 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Sep 05 14:22:52 volumio volumio[909]: info: sendMpdCommand status took 5 milliseconds Sep 05 14:22:52 volumio volumio[909]: info: sendMpdCommand status took 3 milliseconds Sep 05 14:22:52 volumio volumio[909]: verbose: ControllerMpd::parseState Sep 05 14:22:53 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan Sep 05 14:22:53 volumio volumio[909]: info: Command Router : Notfying DB Updatetrue Sep 05 14:22:53 volumio volumio[909]: info: CoreCommandRouter::Close All Modals sent Sep 05 14:22:53 volumio volumio[909]: verbose: ControllerMpd::parseState Sep 05 14:22:53 volumio volumio[909]: info: ControllerMpd::pushState Sep 05 14:22:53 volumio volumio[909]: info: CoreCommandRouter::servicePushState Sep 05 14:22:53 volumio volumio[909]: info: CoreStateMachine::pushState Sep 05 14:22:53 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:53 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 14:22:53 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Sep 05 14:22:53 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:53 volumio volumio[909]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 05 14:22:53 volumio volumio[909]: verbose: CURRENT POSITION 0 Sep 05 14:22:53 volumio volumio[909]: info: CoreStateMachine::syncState stateService stop Sep 05 14:22:53 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus stop Sep 05 14:22:53 volumio volumio[909]: info: CoreStateMachine::pushState Sep 05 14:22:53 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:53 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 14:22:53 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Sep 05 14:22:53 volumio volumio[909]: info: No code Sep 05 14:22:53 volumio volumio[909]: info: CoreStateMachine::pushState Sep 05 14:22:53 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:53 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Sep 05 14:22:53 volumio volumio[909]: info: ------------------------------ 910ms Sep 05 14:22:53 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Sep 05 14:22:53 volumio volumio[909]: info: Sep 05 14:22:53 volumio volumio[909]: ---------------------------- MPD announces state update: update Sep 05 14:22:53 volumio volumio[909]: info: ControllerMpd::getState Sep 05 14:22:53 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Sep 05 14:22:53 volumio volumio[909]: info: sendMpdCommand status took 18 milliseconds Sep 05 14:22:53 volumio volumio[909]: info: sendMpdCommand status took 17 milliseconds Sep 05 14:22:53 volumio volumio[909]: verbose: ControllerMpd::parseState Sep 05 14:22:53 volumio volumio[909]: info: Command Router : Notfying DB Updatefalse Sep 05 14:22:53 volumio volumio[909]: info: CoreCommandRouter::Close All Modals sent Sep 05 14:22:53 volumio volumio[909]: verbose: ControllerMpd::parseState Sep 05 14:22:53 volumio volumio[909]: info: ControllerMpd::pushState Sep 05 14:22:53 volumio volumio[909]: info: CoreCommandRouter::servicePushState Sep 05 14:22:53 volumio volumio[909]: info: CoreStateMachine::pushState Sep 05 14:22:53 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:53 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 14:22:53 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Sep 05 14:22:53 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:53 volumio volumio[909]: 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} Sep 05 14:22:53 volumio volumio[909]: verbose: CURRENT POSITION 0 Sep 05 14:22:53 volumio volumio[909]: info: CoreStateMachine::syncState stateService stop Sep 05 14:22:53 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus stop Sep 05 14:22:53 volumio volumio[909]: info: CoreStateMachine::pushState Sep 05 14:22:53 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:53 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 14:22:53 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Sep 05 14:22:53 volumio volumio[909]: info: No code Sep 05 14:22:53 volumio volumio[909]: info: CoreStateMachine::pushState Sep 05 14:22:53 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:22:53 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Sep 05 14:22:53 volumio volumio[909]: info: ------------------------------ 125ms Sep 05 14:22:55 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:22:59 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:23:00 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 14:23:00 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 14:23:00 volumio volumio[909]: info: Discovery: Getting this device information Sep 05 14:23:00 volumio volumio[909]: info: CoreCommandRouter::volumioGetState Sep 05 14:23:00 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:23:00 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 14:23:00 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan Sep 05 14:23:02 volumio kernel: ............ready Sep 05 14:23:02 volumio kernel: sd 1:0:0:0: [sdb] 1953458176 512-byte logical blocks: (1.00 TB/931 GiB) Sep 05 14:23:02 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Sep 05 14:23:02 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 47 00 10 08 Sep 05 14:23:02 volumio kernel: sd 1:0:0:0: [sdb] No Caching mode page found Sep 05 14:23:02 volumio kernel: sd 1:0:0:0: [sdb] Assuming drive cache: write through Sep 05 14:23:02 volumio kernel: sdb: sdb1 Sep 05 14:23:02 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI disk Sep 05 14:23:03 volumio volumio[909]: info: Mounting Device Elements Sep 05 14:23:03 volumio sudo[1683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/Elements -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 05 14:23:03 volumio sudo[1683]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 14:23:05 volumio ntfs-3g[1686]: Version 2017.3.23AR.3 integrated FUSE 28 Sep 05 14:23:05 volumio ntfs-3g[1686]: Mounted /dev/sdb1 (Read-Write, label "Elements", NTFS 3.1) Sep 05 14:23:05 volumio ntfs-3g[1686]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Sep 05 14:23:05 volumio ntfs-3g[1686]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb1,blkdev,blksize=4096 Sep 05 14:23:05 volumio ntfs-3g[1686]: Global ownership and permissions enforced, configuration type 7 Sep 05 14:23:05 volumio sudo[1683]: pam_unix(sudo:session): session closed for user root Sep 05 14:23:05 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:23:05 volumio volumio[909]: info: Scanning new location : "USB/Elements" Sep 05 14:23:05 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Sep 05 14:23:05 volumio volumio[909]: info: Sep 05 14:23:05 volumio volumio[909]: ---------------------------- MPD announces state update: update Sep 05 14:23:05 volumio volumio[909]: info: ControllerMpd::getState Sep 05 14:23:05 volumio volumio[909]: verbose: ControllerMpd::sendMpdCommand status Sep 05 14:23:05 volumio volumio[909]: info: sendMpdCommand status took 6 milliseconds Sep 05 14:23:05 volumio volumio[909]: info: sendMpdCommand status took 3 milliseconds Sep 05 14:23:05 volumio volumio[909]: verbose: ControllerMpd::parseState Sep 05 14:23:05 volumio kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x00 driverbyte=0x08 cmd_age=0s Sep 05 14:23:05 volumio kernel: sd 1:0:0:0: [sdb] tag#0 Sense Key : 0xb [current] Sep 05 14:23:05 volumio kernel: sd 1:0:0:0: [sdb] tag#0 ASC=0x47 ASCQ=0x3 Sep 05 14:23:05 volumio kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 00 60 1d 08 00 00 08 00 Sep 05 14:23:05 volumio kernel: blk_update_request: I/O error, dev sdb, sector 6298888 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 0 Sep 05 14:23:06 volumio volumio[909]: info: Command Router : Notfying DB Updatetrue Sep 05 14:23:06 volumio volumio[909]: info: CoreCommandRouter::Close All Modals sent Sep 05 14:23:06 volumio volumio[909]: verbose: ControllerMpd::parseState Sep 05 14:23:06 volumio volumio[909]: info: ControllerMpd::pushState Sep 05 14:23:06 volumio volumio[909]: info: CoreCommandRouter::servicePushState Sep 05 14:23:06 volumio volumio[909]: info: CoreStateMachine::pushState Sep 05 14:23:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:23:06 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 14:23:06 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Sep 05 14:23:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:23:06 volumio volumio[909]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 05 14:23:06 volumio volumio[909]: verbose: CURRENT POSITION 0 Sep 05 14:23:06 volumio volumio[909]: info: CoreStateMachine::syncState stateService stop Sep 05 14:23:06 volumio volumio[909]: info: CoreStateMachine::syncState currentStatus stop Sep 05 14:23:06 volumio volumio[909]: info: CoreStateMachine::pushState Sep 05 14:23:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:23:06 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 14:23:06 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Sep 05 14:23:06 volumio volumio[909]: info: No code Sep 05 14:23:06 volumio volumio[909]: info: CoreStateMachine::pushState Sep 05 14:23:06 volumio volumio[909]: info: CorePlayQueue::getTrack 0 Sep 05 14:23:06 volumio volumio[909]: info: CoreCommandRouter::volumioPushState Sep 05 14:23:06 volumio volumio[909]: info: ------------------------------ 926ms Sep 05 14:23:07 volumio volumio[909]: info: Disabling MyMusic plugin upnp Sep 05 14:23:07 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 05 14:23:07 volumio sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 05 14:23:07 volumio sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 14:23:07 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Sep 05 14:23:07 volumio volumio[909]: error: Upnp client error: Error: This socket has been ended by the other party Sep 05 14:23:08 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan Sep 05 14:23:09 volumio volumio[909]: info: Enabling MyMusic plugin upnp Sep 05 14:23:09 volumio volumio[909]: info: Enabling plugin upnp Sep 05 14:23:09 volumio volumio[909]: info: Loading plugin "upnp"... Sep 05 14:23:09 volumio volumio[909]: info: [1725546189103] Starting Upmpd Daemon Sep 05 14:23:09 volumio volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 05 14:23:09 volumio volumio[909]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 14:23:09 volumio volumio[909]: Error: listen EADDRINUSE: address already in use :::6599 Sep 05 14:23:09 volumio volumio[909]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Sep 05 14:23:09 volumio volumio[909]: at listenInCluster (net.js:1366:12) Sep 05 14:23:09 volumio volumio[909]: at Server.listen (net.js:1452:7) Sep 05 14:23:09 volumio volumio[909]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 05 14:23:09 volumio volumio[909]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 05 14:23:09 volumio volumio[909]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 05 14:23:09 volumio volumio[909]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 05 14:23:09 volumio volumio[909]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Sep 05 14:23:09 volumio volumio[909]: code: 'EADDRINUSE', Sep 05 14:23:09 volumio volumio[909]: errno: -98, Sep 05 14:23:09 volumio volumio[909]: syscall: 'listen', Sep 05 14:23:09 volumio volumio[909]: address: '::', Sep 05 14:23:09 volumio volumio[909]: port: 6599 Sep 05 14:23:09 volumio volumio[909]: } Sep 05 14:23:09 volumio volumio[909]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 14:23:09 volumio sudo[1704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-05 14:22 Sep 05 14:23:09 volumio sudo[1704]: 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="cd8cf34b47ce852e70b71e6f7b6e0265f7fdb695" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="8f88153f9a18f312d56050e6613b45a852ac819a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 27 Sep 2023 11:35:49 AM CEST" VOLUMIO_VERSION="3.546" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6823c0d83061ff736b7e0dd4481bc496"