-- Logs begin at Sun 2024-10-13 08:23:47 UTC, end at Sun 2024-10-13 08:29:11 UTC. -- Oct 13 08:28:15 volumio volumio[1448]: info: VolumeController::SetAlsaVolume50 Oct 13 08:28:16 volumio volumio[1448]: info: CoreStateMachine::pushState Oct 13 08:28:16 volumio volumio[1448]: info: CorePlayQueue::getTrack 0 Oct 13 08:28:16 volumio volumio[1448]: info: CoreCommandRouter::volumioPushState Oct 13 08:28:16 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 08:28:16 volumio volumio[1448]: info: VolumeController::SetAlsaVolume30 Oct 13 08:28:16 volumio volumio[1448]: info: CoreStateMachine::pushState Oct 13 08:28:16 volumio volumio[1448]: info: CorePlayQueue::getTrack 0 Oct 13 08:28:16 volumio volumio[1448]: info: CoreCommandRouter::volumioPushState Oct 13 08:28:16 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 08:28:16 volumio volumio[1448]: info: VolumeController::SetAlsaVolume30 Oct 13 08:28:16 volumio volumio[1448]: info: CoreStateMachine::pushState Oct 13 08:28:16 volumio volumio[1448]: info: CorePlayQueue::getTrack 0 Oct 13 08:28:16 volumio volumio[1448]: info: CoreCommandRouter::volumioPushState Oct 13 08:28:16 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 08:28:16 volumio volumio[1448]: info: VolumeController::SetAlsaVolume45 Oct 13 08:28:16 volumio volumio[1448]: info: CoreStateMachine::pushState Oct 13 08:28:16 volumio volumio[1448]: info: CorePlayQueue::getTrack 0 Oct 13 08:28:16 volumio volumio[1448]: info: CoreCommandRouter::volumioPushState Oct 13 08:28:16 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 08:28:16 volumio volumio[1448]: info: VolumeController::SetAlsaVolume50 Oct 13 08:28:16 volumio volumio[1448]: info: CoreStateMachine::pushState Oct 13 08:28:16 volumio volumio[1448]: info: CorePlayQueue::getTrack 0 Oct 13 08:28:16 volumio volumio[1448]: info: CoreCommandRouter::volumioPushState Oct 13 08:28:16 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 08:28:16 volumio volumio[1448]: info: VolumeController::SetAlsaVolume50 Oct 13 08:28:16 volumio volumio[1448]: info: CoreStateMachine::pushState Oct 13 08:28:16 volumio volumio[1448]: info: CorePlayQueue::getTrack 0 Oct 13 08:28:16 volumio volumio[1448]: info: CoreCommandRouter::volumioPushState Oct 13 08:28:16 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 08:28:20 volumio kernel: usb 1-1.2: USB disconnect, device number 5 Oct 13 08:28:20 volumio udisks-glue[751]: Device file /dev/sda1 unmounted from /media/1F0C-2C51 Oct 13 08:28:20 volumio udisks-glue[751]: Device file /dev/sda1 removed Oct 13 08:28:20 volumio udisks-glue[751]: Device file /dev/sda removed Oct 13 08:28:20 volumio udisks-glue[751]: error: Cannot assign requested address Oct 13 08:28:20 volumio udisks-glue[751]: error: Cannot assign requested address Oct 13 08:28:29 volumio kernel: usb 1-1.2: new high-speed USB device number 6 using dwc_otg Oct 13 08:28:29 volumio kernel: usb 1-1.2: New USB device found, idVendor=1058, idProduct=1010, bcdDevice= 1.75 Oct 13 08:28:29 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 13 08:28:29 volumio kernel: usb 1-1.2: Product: External HDD Oct 13 08:28:29 volumio kernel: usb 1-1.2: Manufacturer: Western Digital Oct 13 08:28:29 volumio kernel: usb 1-1.2: SerialNumber: 57442D575845304138394B33353234 Oct 13 08:28:29 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Oct 13 08:28:29 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Oct 13 08:28:30 volumio kernel: scsi 0:0:0:0: Direct-Access WD 3200BEV External 1.75 PQ: 0 ANSI: 4 Oct 13 08:28:30 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Oct 13 08:28:30 volumio kernel: sd 0:0:0:0: [sda] 625142448 512-byte logical blocks: (320 GB/298 GiB) Oct 13 08:28:30 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 13 08:28:30 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00 Oct 13 08:28:30 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Oct 13 08:28:30 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Oct 13 08:28:30 volumio kernel: sda: sda1 Oct 13 08:28:30 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Oct 13 08:28:30 volumio udisks-glue[751]: Device file /dev/sda inserted Oct 13 08:28:30 volumio udisks-glue[751]: Device file /dev/sda1 inserted Oct 13 08:28:30 volumio udisks-glue[751]: Trying to automount /dev/sda1... Oct 13 08:28:30 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 13 08:28:30 volumio udisks-glue[751]: Successfully automounted /dev/sda1 at /media/1F0C-2C51 Oct 13 08:28:30 volumio udisks-glue[751]: Device file /dev/sda1 mounted at /media/1F0C-2C51 Oct 13 08:28:30 volumio udisks-glue[751]: error: Cannot assign requested address Oct 13 08:28:32 volumio sshd[2008]: pam_unix(sshd:session): session closed for user volumio Oct 13 08:28:32 volumio sshd[2009]: pam_unix(sshd:session): session closed for user volumio Oct 13 08:28:32 volumio systemd-logind[783]: Removed session c3. Oct 13 08:28:32 volumio systemd-logind[783]: Removed session c2. Oct 13 08:28:41 volumio volumio[1448]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 08:28:41 volumio volumio[1448]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 08:28:41 volumio volumio[1448]: info: Retrieving Cloud Streaming UI Oct 13 08:28:41 volumio volumio[1448]: info: Getting Tidal Cloud Configuration Oct 13 08:28:41 volumio volumio[1448]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 08:28:41 volumio volumio[1448]: info: Getting Qobuz Cloud Configuration Oct 13 08:28:41 volumio volumio[1448]: info: Asking plugin for UI Config Oct 13 08:28:41 volumio volumio[1448]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 08:28:41 volumio volumio[1448]: info: Getting Spotify Cloud Configuration Oct 13 08:28:41 volumio volumio[1448]: info: Asking plugin for UI Config Oct 13 08:28:41 volumio volumio[1448]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 08:28:41 volumio volumio[1448]: info: Saving Spotify Acccount Oct 13 08:28:41 volumio volumio[1448]: info: Got Tidal Cloud Configuration Oct 13 08:28:41 volumio volumio[1448]: info: Got it Oct 13 08:28:41 volumio volumio[1448]: info: Got it Oct 13 08:28:41 volumio volumio[1448]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 13 08:28:41 volumio volumio[1448]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 13 08:28:41 volumio volumio[1448]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 13 08:28:41 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 13 08:28:41 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 13 08:28:41 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 13 08:28:41 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 13 08:28:41 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:28:41 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:28:41 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:28:41 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 08:28:41 volumio volumio[1448]: info: CoreCommandRouter::volumioGetBrowseSources Oct 13 08:28:43 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 08:28:44 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 13 08:28:47 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 08:28:51 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 08:28:55 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 08:28:56 volumio kernel: usb 1-1.2: USB disconnect, device number 6 Oct 13 08:28:56 volumio udisks-glue[751]: Device file /dev/sda1 unmounted from /media/1F0C-2C51 Oct 13 08:28:56 volumio udisks-glue[751]: Device file /dev/sda1 removed Oct 13 08:28:56 volumio udisks-glue[751]: Device file /dev/sda removed Oct 13 08:28:56 volumio udisks-glue[751]: error: Cannot assign requested address Oct 13 08:28:56 volumio udisks-glue[751]: error: Cannot assign requested address Oct 13 08:28:59 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 08:29:02 volumio kernel: usb 1-1.3: new high-speed USB device number 7 using dwc_otg Oct 13 08:29:02 volumio kernel: usb 1-1.3: New USB device found, idVendor=1058, idProduct=1010, bcdDevice= 1.75 Oct 13 08:29:02 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 13 08:29:02 volumio kernel: usb 1-1.3: Product: External HDD Oct 13 08:29:02 volumio kernel: usb 1-1.3: Manufacturer: Western Digital Oct 13 08:29:02 volumio kernel: usb 1-1.3: SerialNumber: 57442D575845304138394B33353234 Oct 13 08:29:02 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Oct 13 08:29:02 volumio kernel: scsi host0: usb-storage 1-1.3:1.0 Oct 13 08:29:03 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 08:29:03 volumio kernel: scsi 0:0:0:0: Direct-Access WD 3200BEV External 1.75 PQ: 0 ANSI: 4 Oct 13 08:29:03 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Oct 13 08:29:03 volumio kernel: sd 0:0:0:0: [sda] 625142448 512-byte logical blocks: (320 GB/298 GiB) Oct 13 08:29:03 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 13 08:29:03 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 23 00 00 00 Oct 13 08:29:03 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Oct 13 08:29:03 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Oct 13 08:29:03 volumio kernel: sda: sda1 Oct 13 08:29:03 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI disk Oct 13 08:29:04 volumio udisks-glue[751]: Device file /dev/sda inserted Oct 13 08:29:04 volumio kernel: usb 1-1.3: reset high-speed USB device number 7 using dwc_otg Oct 13 08:29:04 volumio kernel: sd 0:0:0:0: [sda] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=0x00 Oct 13 08:29:04 volumio kernel: sd 0:0:0:0: [sda] tag#0 CDB: opcode=0x28 28 00 00 02 54 67 00 00 48 00 Oct 13 08:29:04 volumio kernel: print_req_error: I/O error, dev sda, sector 152679 Oct 13 08:29:04 volumio udisks-glue[751]: Device file /dev/sda1 inserted Oct 13 08:29:04 volumio udisks-glue[751]: Trying to automount /dev/sda1... Oct 13 08:29:04 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 13 08:29:04 volumio udisks-glue[751]: Successfully automounted /dev/sda1 at /media/1F0C-2C51 Oct 13 08:29:04 volumio udisks-glue[751]: Device file /dev/sda1 mounted at /media/1F0C-2C51 Oct 13 08:29:04 volumio udisks-glue[751]: error: Cannot assign requested address Oct 13 08:29:07 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 13 08:29:10 volumio volumio[1448]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 13 08:29:10 volumio volumio[1448]: verbose: ControllerMpd::sendMpdCommand rescan Oct 13 08:29:10 volumio volumio[1448]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 08:29:10 volumio volumio[1448]: TypeError: Cannot read property 'then' of undefined Oct 13 08:29:10 volumio volumio[1448]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:330:6) Oct 13 08:29:10 volumio volumio[1448]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1825:15) Oct 13 08:29:10 volumio volumio[1448]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1052:32) Oct 13 08:29:10 volumio volumio[1448]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:907:26) Oct 13 08:29:10 volumio volumio[1448]: at emitTwo (events.js:126:13) Oct 13 08:29:10 volumio volumio[1448]: at Socket.emit (events.js:214:7) Oct 13 08:29:10 volumio volumio[1448]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 13 08:29:10 volumio volumio[1448]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Oct 13 08:29:10 volumio volumio[1448]: at process._tickCallback (internal/process/next_tick.js:180:9) Oct 13 08:29:10 volumio volumio[1448]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 08:29:11 volumio sudo[2142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-13 08:28 Oct 13 08:29:11 volumio sudo[2142]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"